[57.242215] Initializing watchdogs
[57.242263]   /dev/watchdog0
[57.256280] [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.260565] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.262853] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.266226] [001/133] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[57.383240] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[61.905234] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.525s)
[63.096644] [002/133] (954s left) kms_psr (psr2-sprite-plane-move)
[63.190455] Starting subtest: psr2-sprite-plane-move
[63.192676] Starting dynamic subtest: eDP-1
[64.921568] Dynamic subtest eDP-1: SUCCESS (1.730s)
[64.923847] Subtest psr2-sprite-plane-move: SUCCESS (1.731s)
[65.746957] [003/133] (952s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[66.135857] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[66.973423] [004/133] (950s left) kms_psr (fbc-psr2-primary-render)
[67.215409] Starting subtest: fbc-psr2-primary-render
[67.217750] Starting dynamic subtest: eDP-1
[67.222054] Dynamic subtest eDP-1: SKIP (0.000s)
[67.226302] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[67.447158] [005/133] (950s left) kms_force_connector_basic (prune-stale-modes)
[67.598016] Starting subtest: prune-stale-modes
[68.046816] Subtest prune-stale-modes: SUCCESS (0.449s)
[68.181411] [006/133] (949s left) kms_rotation_crc (multiplane-rotation)
[68.277808] Starting subtest: multiplane-rotation
[85.660063] Subtest multiplane-rotation: SUCCESS (17.382s)
[85.773951] [007/133] (932s left) i915_pm_rps (thresholds)
[85.851800] Starting subtest: thresholds
[85.855715] Subtest thresholds: SKIP (0.001s)
[85.923931] [008/133] (932s left) kms_hdr (static-swap)
[86.013205] Starting subtest: static-swap
[86.018427] Subtest static-swap: SKIP (0.000s)
[86.086718] [009/133] (932s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[86.177190] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[86.180524] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[86.242271] [010/133] (931s left) kms_pm_dc (dc6-psr)
[87.345381] Starting subtest: dc6-psr
[90.226501] Subtest dc6-psr: SUCCESS (2.882s)
[91.105595] [011/133] (927s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[91.203037] Starting subtest: yf-tiled-32bpp-rotate-270
[91.207405] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[91.295575] [012/133] (926s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[91.383624] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[91.387735] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[91.481757] [013/133] (926s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[91.585743] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[91.590191] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[91.683282] [014/133] (926s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[91.778844] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[92.837218] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.060s)
[92.970778] [015/133] (925s left) gem_exec_fence (parallel)
[93.148940] Starting subtest: parallel
[93.151427] Starting dynamic subtest: rcs0
[95.298205] Dynamic subtest rcs0: SUCCESS (2.149s)
[95.308070] Starting dynamic subtest: bcs0
[97.448311] Dynamic subtest bcs0: SUCCESS (2.149s)
[97.456301] Starting dynamic subtest: vcs0
[99.596490] Dynamic subtest vcs0: SUCCESS (2.148s)
[99.606059] Starting dynamic subtest: vcs1
[101.755664] Dynamic subtest vcs1: SUCCESS (2.158s)
[101.767480] Starting dynamic subtest: vecs0
[103.909375] Dynamic subtest vecs0: SUCCESS (2.153s)
[103.919455] Starting dynamic subtest: ccs0
[106.065145] Dynamic subtest ccs0: SUCCESS (2.155s)
[106.067021] Subtest parallel: SUCCESS (12.916s)
[106.992868] [FACT gem_exec_fence (parallel)] new: kernel.kmod_is_loaded.vgem: true
[106.996605] [016/133] (911s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[107.043042] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[107.090605] [017/133] (911s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[107.150825] Starting subtest: map-fixed-invalidate-overlap-busy
[107.155079] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[107.264204] [018/133] (911s left) gem_mmap_offset (partial-mmap)
[107.324269] Starting subtest: partial-mmap
[107.326245] Starting dynamic subtest: smem0
[107.330846] Dynamic subtest smem0: SUCCESS (0.007s)
[107.333213] Subtest partial-mmap: SUCCESS (0.008s)
[107.446133] [019/133] (911s left) gem_ctx_shared (q-smoketest-all)
[107.506659] Starting subtest: Q-smoketest-all
[139.889318] Subtest Q-smoketest-all: SUCCESS (32.333s)
[140.999349] [020/133] (877s left) gem_pxp (regular-baseline-src-copy-readible)
[141.141051] Starting subtest: regular-baseline-src-copy-readible
[141.224943] Subtest regular-baseline-src-copy-readible: SUCCESS (0.086s)
[141.365306] [021/133] (877s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[141.472815] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[141.477287] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[141.539058] [022/133] (877s left) sw_sync (alloc_timeline)
[141.572654] Starting subtest: alloc_timeline
[141.575036] Subtest alloc_timeline: SUCCESS (0.000s)
[141.627286] [023/133] (877s left) syncobj_timeline (multi-wait-all-signaled)
[141.680614] Starting subtest: multi-wait-all-signaled
[141.682913] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[141.769696] [024/133] (876s left) syncobj_wait (wait-all-complex)
[141.821313] Starting subtest: wait-all-complex
[142.035374] Subtest wait-all-complex: SUCCESS (0.213s)
[142.120625] [025/133] (876s left) kms_vblank (wait-idle)
[142.218191] Starting subtest: wait-idle
[142.220414] Starting dynamic subtest: pipe-A-eDP-1
[143.989211] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.771s)
[144.002744] Starting dynamic subtest: pipe-D-eDP-1
[146.393776] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.403s)
[146.396089] Subtest wait-idle: SUCCESS (4.178s)
[147.198692] [026/133] (871s left) kms_chamelium_frames (hdmi-crc-fast)
[147.577812] Subtest hdmi-crc-fast: SKIP (0.000s)
[148.388674] [027/133] (870s left) kms_cursor_edge_walk (64x64-right-edge)
[148.483801] Starting subtest: 64x64-right-edge
[148.486133] Starting dynamic subtest: pipe-A-eDP-1
[151.930283] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.446s)
[151.939281] Starting dynamic subtest: pipe-D-eDP-1
[155.120591] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.189s)
[155.122899] Subtest 64x64-right-edge: SUCCESS (6.637s)
[156.014240] [028/133] (862s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[156.119254] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[156.123196] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[156.218718] [029/133] (862s left) syncobj_wait (invalid-reset-illegal-handle)
[156.280388] Starting subtest: invalid-reset-illegal-handle
[156.282714] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[156.377328] [030/133] (862s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[156.473102] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[156.477463] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[157.599907] [031/133] (861s left) gem_mmap_wc (coherency)
[157.665952] Subtest coherency: SKIP (0.000s)
[157.728079] [032/133] (861s left) perf (global-sseu-config)
[157.857251] Starting subtest: global-sseu-config
[157.861546] Subtest global-sseu-config: SKIP (0.000s)
[157.912691] [033/133] (861s left) gem_partial_pwrite_pread (write)
[157.970620] Subtest write: SKIP (0.000s)
[158.042770] [034/133] (861s left) perf_pmu (faulting-read)
[158.084904] Starting subtest: faulting-read
[158.087244] Starting dynamic subtest: wb
[158.089485] Dynamic subtest wb: SUCCESS (0.001s)
[158.102574] Starting dynamic subtest: wc
[158.104828] Dynamic subtest wc: SUCCESS (0.000s)
[158.116327] Starting dynamic subtest: uc
[158.118730] Dynamic subtest uc: SUCCESS (0.000s)
[158.121081] Subtest faulting-read: SUCCESS (0.003s)
[158.193943] [035/133] (860s left) gem_mmap_gtt (big-copy)
[158.244060] Subtest big-copy: SKIP (0.000s)
[158.306738] [036/133] (860s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[158.399823] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[158.404042] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[158.496820] [037/133] (860s left) gem_exec_params (dr4-dirt)
[158.550610] Starting subtest: DR4-dirt
[158.552727] Subtest DR4-dirt: SUCCESS (0.000s)
[158.649571] [038/133] (860s left) gem_ctx_exec (basic-close-race)
[158.719658] Starting subtest: basic-close-race
[164.669890] Subtest basic-close-race: SUCCESS (5.951s)
[164.833667] [039/133] (854s left) gem_madvise (dontneed-after-mmap)
[164.873499] Starting subtest: dontneed-after-mmap
[164.905210] Subtest dontneed-after-mmap: SUCCESS (0.032s)
[165.017389] [040/133] (854s left) gem_exec_schedule (u-submit-late-slice)
[165.097691] Starting subtest: u-submit-late-slice
[165.100121] Starting dynamic subtest: rcs0
[165.149069] Dynamic subtest rcs0: SUCCESS (0.057s)
[165.160957] Starting dynamic subtest: bcs0
[165.196428] Dynamic subtest bcs0: SUCCESS (0.047s)
[165.207768] Starting dynamic subtest: vcs0
[165.247564] Dynamic subtest vcs0: SUCCESS (0.051s)
[165.258284] Starting dynamic subtest: vcs1
[165.287996] Dynamic subtest vcs1: SUCCESS (0.040s)
[165.297622] Starting dynamic subtest: vecs0
[165.336168] Dynamic subtest vecs0: SUCCESS (0.047s)
[165.352555] Starting dynamic subtest: ccs0
[165.388941] Dynamic subtest ccs0: SUCCESS (0.052s)
[165.391872] Subtest u-submit-late-slice: SUCCESS (0.298s)
[167.029302] [041/133] (852s left) kms_flip (flip-vs-absolute-wf_vblank)
[167.125197] Starting subtest: flip-vs-absolute-wf_vblank
[167.127468] Starting dynamic subtest: A-eDP1
[176.007855] Dynamic subtest A-eDP1: SUCCESS (8.882s)
[176.018326] Starting dynamic subtest: B-eDP1
[184.910811] Dynamic subtest B-eDP1: SUCCESS (8.901s)
[184.918927] Starting dynamic subtest: C-eDP1
[193.802590] Dynamic subtest C-eDP1: SUCCESS (8.890s)
[193.813615] Starting dynamic subtest: D-eDP1
[202.698056] Dynamic subtest D-eDP1: SUCCESS (8.892s)
[202.703603] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.577s)
[203.890102] [042/133] (815s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[204.001609] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[204.006276] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[204.114204] [043/133] (815s left) kms_draw_crc (fill-fb)
[204.208093] Starting subtest: fill-fb
[204.636342] Subtest fill-fb: SUCCESS (0.431s)
[204.729419] [044/133] (814s left) gem_render_copy (y-tiled-ccs-to-linear)
[204.789721] Starting subtest: y-tiled-ccs-to-linear
[204.794021] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[204.970495] [045/133] (814s left) gem_ctx_isolation (preservation-s3)
[205.023120] Starting subtest: preservation-S3
[205.025447] Starting dynamic subtest: rcs0
[221.474686] Dynamic subtest rcs0: SUCCESS (1.412s)
[221.484831] Starting dynamic subtest: bcs0
[237.473569] Dynamic subtest bcs0: SUCCESS (1.344s)
[237.483333] Starting dynamic subtest: vcs0
[253.493099] Dynamic subtest vcs0: SUCCESS (2.234s)
[253.505267] Starting dynamic subtest: vcs1
[271.501551] Dynamic subtest vcs1: SUCCESS (4.973s)
[271.512317] Starting dynamic subtest: vecs0
[289.489782] Dynamic subtest vecs0: SUCCESS (4.989s)
[289.499560] Starting dynamic subtest: ccs0
[307.479738] Dynamic subtest ccs0: SUCCESS (3.104s)
[307.482533] Subtest preservation-S3: SUCCESS (18.062s)
[307.708169] [046/133] (711s left) i915_pm_rpm (gem-mmap-type)
[309.457572] Starting subtest: gem-mmap-type
[309.460683] Starting dynamic subtest: wb-smem0
[313.417635] Dynamic subtest wb-smem0: SUCCESS (3.959s)
[313.430562] Starting dynamic subtest: wc-smem0
[316.597484] Dynamic subtest wc-smem0: SUCCESS (3.176s)
[316.610298] Starting dynamic subtest: uc-smem0
[319.779566] Dynamic subtest uc-smem0: SUCCESS (3.181s)
[319.781979] Subtest gem-mmap-type: SUCCESS (10.320s)
[319.866140] [047/133] (699s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[319.990041] Starting subtest: cursor-vs-flip-atomic
[349.339706] Subtest cursor-vs-flip-atomic: SUCCESS (29.337s)
[350.201836] [048/133] (669s left) kms_pm_rpm (modeset-pc8-residency-stress)
[352.433968] Starting subtest: modeset-pc8-residency-stress
[352.439331] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[352.795440] [049/133] (666s left) syncobj_timeline (etime-multi-wait-available-unsubmitted)
[352.847739] Starting subtest: etime-multi-wait-available-unsubmitted
[352.952435] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.105s)
[353.030882] [050/133] (666s left) kms_properties (invalid-properties-legacy)
[353.116367] Starting subtest: invalid-properties-legacy
[353.217159] Subtest invalid-properties-legacy: SUCCESS (0.100s)
[353.291548] [051/133] (666s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[353.387838] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[353.392978] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[353.489690] [052/133] (666s left) gem_exec_balancer (hog)
[353.551927] Starting subtest: hog
[353.557503] Subtest hog: SKIP (0.000s)
[353.854731] [053/133] (666s left) gem_exec_flush (basic-uc-pro-default)
[353.912349] Starting subtest: basic-uc-pro-default
[359.378952] Subtest basic-uc-pro-default: SUCCESS (5.465s)
[359.563274] [054/133] (660s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[359.676220] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[359.678635] Starting dynamic subtest: pipe-A
[359.697641] Dynamic subtest pipe-A: SKIP (0.018s)
[359.706566] Starting dynamic subtest: pipe-B
[359.729021] Dynamic subtest pipe-B: SKIP (0.031s)
[359.738032] Starting dynamic subtest: pipe-C
[359.762162] Dynamic subtest pipe-C: SKIP (0.031s)
[359.774050] Starting dynamic subtest: pipe-D
[359.793949] Dynamic subtest pipe-D: SKIP (0.030s)
[359.798480] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.113s)
[359.878961] [055/133] (660s left) api_intel_bb (misplaced-blitter)
[359.931525] Starting subtest: misplaced-blitter
[359.937563] Subtest misplaced-blitter: SUCCESS (0.009s)
[360.067046] [056/133] (660s left) gem_eio (in-flight-external)
[360.291572] Starting subtest: in-flight-external
[361.351540] Subtest in-flight-external: SUCCESS (1.058s)
[361.471897] [057/133] (658s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[361.563378] Starting subtest: fbc-1p-pri-indfb-multidraw
[374.740973] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.178s)
[376.127260] [058/133] (644s left) gem_exec_schedule (preempt-contexts)
[376.210661] Starting subtest: preempt-contexts
[376.213409] Starting dynamic subtest: rcs0
[376.256644] Dynamic subtest rcs0: SUCCESS (0.059s)
[376.266663] Starting dynamic subtest: bcs0
[376.316350] Dynamic subtest bcs0: SUCCESS (0.058s)
[376.326664] Starting dynamic subtest: vcs0
[376.374252] Dynamic subtest vcs0: SUCCESS (0.057s)
[376.384822] Starting dynamic subtest: vcs1
[376.420835] Dynamic subtest vcs1: SUCCESS (0.046s)
[376.431464] Starting dynamic subtest: vecs0
[376.478118] Dynamic subtest vecs0: SUCCESS (0.054s)
[376.488393] Starting dynamic subtest: ccs0
[376.541655] Dynamic subtest ccs0: SUCCESS (0.064s)
[376.544513] Subtest preempt-contexts: SUCCESS (0.345s)
[377.374278] [059/133] (642s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[377.479071] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[377.481151] Starting dynamic subtest: pipe-A-eDP-1
[378.204378] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.719s)
[378.214332] Starting dynamic subtest: pipe-B-eDP-1
[379.981314] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.773s)
[379.991273] Starting dynamic subtest: pipe-C-eDP-1
[381.787229] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.804s)
[381.797453] Starting dynamic subtest: pipe-D-eDP-1
[383.585463] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.795s)
[383.588509] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.096s)
[384.704411] [060/133] (635s left) prime_vgem (basic-fence-blt)
[384.772445] Starting subtest: basic-fence-blt
[384.871352] Subtest basic-fence-blt: SUCCESS (0.099s)
[384.980156] [061/133] (635s left) kms_cursor_crc (cursor-onscreen-max-size)
[385.279671] Starting subtest: cursor-onscreen-max-size
[385.287107] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[385.400914] [062/133] (634s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[385.496412] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[385.500665] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[385.587639] [063/133] (634s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[385.654640] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[385.711499] [064/133] (634s left) kms_sequence (get-forked)
[385.817048] Starting subtest: get-forked
[385.819138] Starting dynamic subtest: pipe-A-eDP-1
[387.689685] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.870s)
[387.701470] Starting dynamic subtest: pipe-B-eDP-1
[390.227702] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.538s)
[390.239373] Starting dynamic subtest: pipe-C-eDP-1
[392.836255] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.604s)
[392.846474] Starting dynamic subtest: pipe-D-eDP-1
[395.413565] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.576s)
[395.416460] Subtest get-forked: SUCCESS (9.594s)
[396.215084] [065/133] (624s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[396.337519] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[402.982694] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.648s)
[403.096047] [066/133] (617s left) kms_pipe_stress (stress-xrgb8888-xtiled)
[403.188972] Starting subtest: stress-xrgb8888-xtiled
[409.024629] Subtest stress-xrgb8888-xtiled: SUCCESS (5.840s)
[409.076510] [067/133] (611s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[409.169063] Starting subtest: fbc-indfb-scaledprimary
[412.101675] Subtest fbc-indfb-scaledprimary: SUCCESS (2.937s)
[413.320006] [068/133] (607s left) kms_busy (basic)
[413.420449] Starting subtest: basic
[413.422725] Starting dynamic subtest: flip
[414.010048] Dynamic subtest flip: SUCCESS (0.590s)
[414.020336] Starting dynamic subtest: modeset
[417.628544] Dynamic subtest modeset: SUCCESS (3.617s)
[417.631546] Subtest basic: SUCCESS (4.210s)
[418.507513] [069/133] (602s left) gem_vm_create (execbuf)
[418.567233] Starting subtest: execbuf
[418.572172] Subtest execbuf: SUCCESS (0.008s)
[418.687618] [070/133] (601s left) i915_hangman (engine-engine-hang)
[418.762325] Starting subtest: engine-engine-hang
[418.764290] Starting dynamic subtest: rcs0
[420.243238] Dynamic subtest rcs0: SUCCESS (1.482s)
[420.256417] Starting dynamic subtest: bcs0
[422.225982] Dynamic subtest bcs0: SUCCESS (1.980s)
[422.239217] Starting dynamic subtest: vcs0
[424.275243] Dynamic subtest vcs0: SUCCESS (2.047s)
[424.289397] Starting dynamic subtest: vcs1
[426.258674] Dynamic subtest vcs1: SUCCESS (1.980s)
[426.271747] Starting dynamic subtest: vecs0
[428.242401] Dynamic subtest vecs0: SUCCESS (1.982s)
[428.256345] Starting dynamic subtest: ccs0
[430.226313] Dynamic subtest ccs0: SUCCESS (1.982s)
[430.229469] Subtest engine-engine-hang: SUCCESS (11.470s)
[430.313028] [071/133] (590s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[430.420677] Starting subtest: psr-2p-primscrn-spr-indfb-move
[430.425650] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[430.512062] [072/133] (590s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[430.647693] Starting subtest: cursor-rapid-movement-32x32
[430.691260] Subtest cursor-rapid-movement-32x32: SKIP (0.040s)
[430.988166] [073/133] (589s left) gem_exec_schedule (fifo)
[431.058281] Starting subtest: fifo
[431.061023] Starting dynamic subtest: rcs0
[431.143444] Dynamic subtest rcs0: SUCCESS (0.098s)
[431.157634] Starting dynamic subtest: vcs0
[431.223675] Dynamic subtest vcs0: SUCCESS (0.079s)
[431.236647] Starting dynamic subtest: vcs1
[431.310418] Dynamic subtest vcs1: SUCCESS (0.087s)
[431.323531] Starting dynamic subtest: vecs0
[431.393267] Dynamic subtest vecs0: SUCCESS (0.081s)
[431.405470] Starting dynamic subtest: ccs0
[431.498311] Dynamic subtest ccs0: SUCCESS (0.102s)
[431.501121] Subtest fifo: SUCCESS (0.455s)
[432.398481] [074/133] (588s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[432.500477] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[433.146290] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.648s)
[433.307751] [075/133] (587s left) gen9_exec_parse (batch-invalid-length)
[433.371547] Subtest batch-invalid-length: SKIP (0.000s)
[433.439602] [076/133] (587s left) gem_partial_pwrite_pread (writes-after-reads-display)
[433.500153] Subtest writes-after-reads-display: SKIP (0.000s)
[433.559744] [077/133] (587s left) gem_ctx_persistence (legacy-engines-hostile)
[433.631749] Starting subtest: legacy-engines-hostile
[433.634760] Starting dynamic subtest: render
[433.701693] Dynamic subtest render: SUCCESS (0.069s)
[433.714698] Starting dynamic subtest: bsd1
[433.794650] Dynamic subtest bsd1: SUCCESS (0.090s)
[433.807797] Starting dynamic subtest: bsd2
[433.894039] Dynamic subtest bsd2: SUCCESS (0.096s)
[433.906478] Starting dynamic subtest: blt
[433.982913] Dynamic subtest blt: SUCCESS (0.086s)
[433.996031] Starting dynamic subtest: vebox
[434.072644] Dynamic subtest vebox: SUCCESS (0.088s)
[434.075293] Subtest legacy-engines-hostile: SUCCESS (0.442s)
[434.152555] [078/133] (586s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[434.245202] Starting subtest: plane-lut1d-ctm3x4-lut1d
[434.367941] Starting dynamic subtest: pipe-A-plane-0
[434.690326] Dynamic subtest pipe-A-plane-0: FAIL (0.164s)
[434.699671] Starting dynamic subtest: pipe-A-plane-1
[434.920925] Dynamic subtest pipe-A-plane-1: FAIL (0.170s)
[434.931636] Starting dynamic subtest: pipe-A-plane-2
[435.191790] Dynamic subtest pipe-A-plane-2: FAIL (0.161s)
[436.045155] Starting dynamic subtest: pipe-B-plane-0
[436.532676] Dynamic subtest pipe-B-plane-0: FAIL (0.199s)
[436.543465] Starting dynamic subtest: pipe-B-plane-1
[436.932289] Dynamic subtest pipe-B-plane-1: FAIL (0.164s)
[436.943401] Starting dynamic subtest: pipe-B-plane-2
[437.293279] Dynamic subtest pipe-B-plane-2: FAIL (0.702s)
[438.263102] Starting dynamic subtest: pipe-C-plane-0
[438.864430] Dynamic subtest pipe-C-plane-0: FAIL (0.139s)
[438.876918] Starting dynamic subtest: pipe-C-plane-1
[439.344573] Dynamic subtest pipe-C-plane-1: FAIL (0.706s)
[439.354436] Starting dynamic subtest: pipe-C-plane-2
[439.821795] Dynamic subtest pipe-C-plane-2: FAIL (0.158s)
[440.463045] Starting dynamic subtest: pipe-D-plane-0
[441.126509] Dynamic subtest pipe-D-plane-0: FAIL (0.151s)
[441.136239] Starting dynamic subtest: pipe-D-plane-1
[441.639080] Dynamic subtest pipe-D-plane-1: FAIL (0.640s)
[441.648538] Starting dynamic subtest: pipe-D-plane-2
[442.178388] Dynamic subtest pipe-D-plane-2: FAIL (0.564s)
[442.180673] Subtest plane-lut1d-ctm3x4-lut1d: FAIL (7.862s)
[442.992895] [079/133] (578s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[443.100136] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[450.493528] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.396s)
[450.630868] [080/133] (570s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[450.737427] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[450.742929] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[450.840074] [081/133] (570s left) kms_properties (colorop-properties-legacy)
[450.937676] Starting subtest: colorop-properties-legacy
[450.940004] Starting dynamic subtest: pipe-A-eDP-1
[451.301493] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.361s)
[451.312938] Starting dynamic subtest: pipe-B-eDP-1
[452.319885] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.021s)
[452.333077] Starting dynamic subtest: pipe-C-eDP-1
[453.414303] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.092s)
[453.425243] Starting dynamic subtest: pipe-D-eDP-1
[454.545643] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[454.548510] Subtest colorop-properties-legacy: SUCCESS (3.609s)
[455.394093] [082/133] (565s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[455.484887] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[455.487445] Starting dynamic subtest: pipe-A-eDP-1
[456.295074] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.801s)
[456.304462] Starting dynamic subtest: pipe-B-eDP-1
[457.966248] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.670s)
[457.976474] Starting dynamic subtest: pipe-C-eDP-1
[459.433251] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.464s)
[459.444665] Starting dynamic subtest: pipe-D-eDP-1
[461.003674] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.570s)
[461.005968] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.511s)
[462.190267] [083/133] (559s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[462.289588] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[462.292423] Starting dynamic subtest: pipe-A-eDP-1
[468.145511] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.859s)
[468.155526] Starting dynamic subtest: pipe-B-eDP-1
[474.642448] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.494s)
[474.645102] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.356s)
[475.486461] [084/133] (545s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[475.540259] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[475.648637] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.109s)
[475.726172] [085/133] (545s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier)
[475.808687] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier
[475.834082] Starting dynamic subtest: pipe-A-plane-0
[476.256422] Dynamic subtest pipe-A-plane-0: SUCCESS (0.422s)
[476.266831] Starting dynamic subtest: pipe-A-plane-3
[476.513680] Dynamic subtest pipe-A-plane-3: SUCCESS (0.255s)
[476.523199] Starting dynamic subtest: pipe-A-plane-5
[476.527863] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[477.650641] Starting dynamic subtest: pipe-B-plane-0
[478.098382] Dynamic subtest pipe-B-plane-0: SUCCESS (0.455s)
[478.109490] Starting dynamic subtest: pipe-B-plane-3
[478.365519] Dynamic subtest pipe-B-plane-3: SUCCESS (0.266s)
[478.376327] Starting dynamic subtest: pipe-B-plane-5
[478.381191] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[478.673019] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.867s)
[479.490194] [086/133] (541s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[479.608828] Starting subtest: 2x-long-cursor-vs-flip-atomic
[479.613481] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[479.754600] [087/133] (541s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[479.844939] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[479.849599] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[479.940883] [088/133] (541s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[480.042164] Starting subtest: fbcpsr-rgb101010-draw-render
[486.702472] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (6.664s)
[486.818193] [089/133] (534s left) kms_dp_link_training (non-uhbr-mst)
[486.913423] Starting subtest: non-uhbr-mst
[486.923298] Subtest non-uhbr-mst: SKIP (0.000s)
[487.001052] [090/133] (534s left) gem_tiled_partial_pwrite_pread (reads)
[487.065515] Subtest reads: SKIP (0.000s)
[487.139050] [091/133] (534s left) perf (invalid-remove-userspace-config)
[487.238670] Starting subtest: invalid-remove-userspace-config
[487.248667] Subtest invalid-remove-userspace-config: SUCCESS (0.010s)
[487.292367] [092/133] (534s left) syncobj_wait (wait-any-complex)
[487.338842] Starting subtest: wait-any-complex
[487.399240] Subtest wait-any-complex: SUCCESS (0.063s)
[487.478279] [093/133] (534s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[487.555488] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[487.633381] [094/133] (534s left) sysfs_timeslice_duration (invalid)
[487.682823] Starting subtest: invalid
[487.685434] Starting dynamic subtest: vecs0
[487.688095] Dynamic subtest vecs0: SUCCESS (0.000s)
[487.699678] Starting dynamic subtest: ccs0
[487.701871] Dynamic subtest ccs0: SUCCESS (0.000s)
[487.711517] Starting dynamic subtest: vcs0
[487.713804] Dynamic subtest vcs0: SUCCESS (0.000s)
[487.723645] Starting dynamic subtest: bcs0
[487.726107] Dynamic subtest bcs0: SUCCESS (0.000s)
[487.735922] Starting dynamic subtest: rcs0
[487.737944] Dynamic subtest rcs0: SUCCESS (0.000s)
[487.750482] Starting dynamic subtest: vcs1
[487.752784] Dynamic subtest vcs1: SUCCESS (0.000s)
[487.755046] Subtest invalid: SUCCESS (0.006s)
[487.800279] [095/133] (533s left) gem_softpin (allocator-fork)
[487.860879] Starting subtest: allocator-fork
[490.088451] Subtest allocator-fork: SUCCESS (2.225s)
[490.208457] [096/133] (531s left) kms_draw_crc (draw-method-render)
[490.304396] Starting subtest: draw-method-render
[490.307067] Starting dynamic subtest: xrgb8888-untiled
[490.525098] Dynamic subtest xrgb8888-untiled: SUCCESS (0.221s)
[490.534692] Starting dynamic subtest: rgb565-untiled
[490.734144] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[490.744079] Starting dynamic subtest: xrgb2101010-untiled
[490.956503] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[490.973543] Starting dynamic subtest: xbgr16161616f-untiled
[491.190365] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[491.201350] Starting dynamic subtest: xrgb8888-xtiled
[491.312080] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[491.322254] Starting dynamic subtest: rgb565-xtiled
[491.434393] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[491.444952] Starting dynamic subtest: xrgb2101010-xtiled
[491.556498] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[491.566847] Starting dynamic subtest: xbgr16161616f-xtiled
[491.678345] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s)
[491.688405] Starting dynamic subtest: xrgb8888-4tiled
[491.812129] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[491.822126] Starting dynamic subtest: rgb565-4tiled
[491.923103] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[491.933947] Starting dynamic subtest: xrgb2101010-4tiled
[492.045432] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[492.055973] Starting dynamic subtest: xbgr16161616f-4tiled
[492.167402] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.122s)
[492.170027] Subtest draw-method-render: SUCCESS (1.867s)
[492.273822] [097/133] (529s left) kms_fence_pin_leak
[492.438373] [098/133] (529s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[492.492498] Starting subtest: invalid-single-wait-all-unsubmitted
[492.495017] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[492.599547] [099/133] (529s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[492.691333] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[492.693426] Starting dynamic subtest: pipe-A-eDP-1
[492.713532] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[492.729568] Starting dynamic subtest: pipe-B-eDP-1
[492.751162] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[492.760782] Starting dynamic subtest: pipe-C-eDP-1
[492.780967] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[492.792673] Starting dynamic subtest: pipe-D-eDP-1
[492.812702] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[492.814683] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[494.890056] [100/133] (527s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[494.981877] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[494.986924] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[495.055908] [101/133] (526s left) syncobj_timeline (transfer-timeline-point)
[495.108644] Starting subtest: transfer-timeline-point
[495.111232] Subtest transfer-timeline-point: SUCCESS (0.000s)
[495.227367] [102/133] (526s left) kms_async_flips (test-cursor-atomic)
[495.316443] Starting subtest: test-cursor-atomic
[495.701276] Starting dynamic subtest: pipe-A-eDP-1
[496.433437] Dynamic subtest pipe-A-eDP-1: SKIP (0.730s)
[496.808695] Starting dynamic subtest: pipe-B-eDP-1
[497.535473] Dynamic subtest pipe-B-eDP-1: SKIP (0.732s)
[497.917674] Starting dynamic subtest: pipe-C-eDP-1
[498.645409] Dynamic subtest pipe-C-eDP-1: SKIP (0.733s)
[499.003197] Starting dynamic subtest: pipe-D-eDP-1
[499.738084] Dynamic subtest pipe-D-eDP-1: SKIP (0.737s)
[499.743393] Subtest test-cursor-atomic: SKIP (4.417s)
[500.916966] [103/133] (521s left) gem_ccs (block-multicopy-compressed)
[500.992309] Starting subtest: block-multicopy-compressed
[500.998201] Subtest block-multicopy-compressed: SKIP (0.000s)
[501.087564] [104/133] (521s left) gem_mmap_gtt (basic-write-read)
[501.144493] Subtest basic-write-read: SKIP (0.000s)
[501.209286] [105/133] (520s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[501.310088] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[501.315227] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[501.413573] [106/133] (520s left) kms_psr (fbc-psr-sprite-blt)
[501.512567] Starting subtest: fbc-psr-sprite-blt
[501.515461] Starting dynamic subtest: eDP-1
[501.520466] Dynamic subtest eDP-1: SKIP (0.000s)
[501.525488] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[501.589569] [107/133] (520s left) kms_content_protection (lic-type-0-hdcp14)
[501.739550] Starting subtest: lic-type-0-hdcp14
[501.745162] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[501.866396] [108/133] (520s left) syncobj_basic (bad-pad-fd-to-handle)
[501.928388] Starting subtest: bad-pad-fd-to-handle
[501.930675] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[502.033459] [109/133] (520s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[502.128054] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[508.753966] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.625s)
[508.872381] [110/133] (513s left) gem_exec_balancer (sequential)
[508.943410] Starting subtest: sequential
[512.009930] Subtest sequential: SUCCESS (3.069s)
[512.346859] [111/133] (510s left) gem_mmap_gtt (cpuset-basic-small-copy)
[512.406572] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[512.468040] [112/133] (509s left) kms_plane (plane-position-covered)
[512.561110] Starting subtest: plane-position-covered
[513.084457] Starting dynamic subtest: pipe-A-plane-1
[514.328172] Dynamic subtest pipe-A-plane-1: SUCCESS (1.243s)
[514.339653] Starting dynamic subtest: pipe-A-plane-2
[515.624787] Dynamic subtest pipe-A-plane-2: SUCCESS (1.295s)
[515.635717] Starting dynamic subtest: pipe-A-plane-3
[516.915564] Dynamic subtest pipe-A-plane-3: SUCCESS (1.288s)
[516.927643] Starting dynamic subtest: pipe-A-plane-4
[518.279211] Dynamic subtest pipe-A-plane-4: SUCCESS (1.364s)
[518.289262] Starting dynamic subtest: pipe-A-plane-5
[518.305167] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[519.461797] Starting dynamic subtest: pipe-B-plane-1
[520.759350] Dynamic subtest pipe-B-plane-1: SUCCESS (1.306s)
[520.769690] Starting dynamic subtest: pipe-B-plane-2
[522.049885] Dynamic subtest pipe-B-plane-2: SUCCESS (1.291s)
[522.062042] Starting dynamic subtest: pipe-B-plane-3
[523.334571] Dynamic subtest pipe-B-plane-3: SUCCESS (1.282s)
[523.345005] Starting dynamic subtest: pipe-B-plane-4
[524.631158] Dynamic subtest pipe-B-plane-4: SUCCESS (1.296s)
[524.641469] Starting dynamic subtest: pipe-B-plane-5
[524.658693] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[524.661248] Subtest plane-position-covered: SUCCESS (12.094s)
[525.495962] [113/133] (497s left) gem_pwrite (basic-random)
[525.558498] Subtest basic-random: SKIP (0.000s)
[525.618532] [114/133] (496s left) core_setmaster (master-drop-set-user)
[525.702451] Starting subtest: master-drop-set-user
[525.715341] Subtest master-drop-set-user: SUCCESS (0.016s)
[525.756987] [115/133] (496s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[525.813273] Starting subtest: y-tiled-to-vebox-x-tiled
[525.818390] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[525.999350] [116/133] (496s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[526.054648] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[526.057516] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[526.148143] [117/133] (496s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[526.244008] Starting subtest: y-tiled-16bpp-rotate-180
[526.249060] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[526.352741] [118/133] (496s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[526.449644] Starting subtest: fbc-rgb565-draw-pwrite
[531.064672] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.618s)
[532.207482] [119/133] (490s left) kms_lease (lease-again)
[532.300685] Starting subtest: lease-again
[532.303537] Starting dynamic subtest: pipe-A-eDP-1
[532.307167] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[532.320580] Starting dynamic subtest: pipe-B-eDP-1
[532.323124] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[532.332326] Starting dynamic subtest: pipe-C-eDP-1
[532.334441] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[532.343300] Starting dynamic subtest: pipe-D-eDP-1
[532.345391] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[532.347425] Subtest lease-again: SUCCESS (0.012s)
[532.406746] [120/133] (490s left) kms_async_flips (async-flip-hang)
[532.499685] Starting subtest: async-flip-hang
[532.883477] Starting dynamic subtest: pipe-A-eDP-1
[547.726464] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.848s)
[548.104231] Starting dynamic subtest: pipe-B-eDP-1
[563.724653] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.627s)
[564.108594] Starting dynamic subtest: pipe-C-eDP-1
[579.724047] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.622s)
[580.112798] Starting dynamic subtest: pipe-D-eDP-1
[595.724837] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.628s)
[595.727533] Subtest async-flip-hang: SUCCESS (63.232s)
[596.923327] [121/133] (425s left) api_intel_allocator (alloc-simple)
[596.977659] Starting subtest: alloc-simple
[596.982041] Subtest alloc-simple: SUCCESS (0.007s)
[597.097157] [122/133] (425s left) kms_hdr (static-toggle)
[597.201280] Starting subtest: static-toggle
[597.209275] Subtest static-toggle: SKIP (0.000s)
[597.278179] [123/133] (425s left) i915_drm_fdinfo (all-busy-idle-check-all)
[597.322912] Starting subtest: all-busy-idle-check-all
[597.328045] Subtest all-busy-idle-check-all: SKIP (0.007s)
[597.408092] [124/133] (425s left) gem_exec_schedule (semaphore-user)
[597.471184] Starting subtest: semaphore-user
[597.519835] Subtest semaphore-user: SUCCESS (0.058s)
[599.273285] [125/133] (423s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[599.378419] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[599.383250] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[599.450476] [126/133] (423s left) kms_psr (pr-basic)
[599.563450] Starting subtest: pr-basic
[599.568021] Subtest pr-basic: SKIP (0.000s)
[599.634685] [127/133] (423s left) kms_big_fb (linear-32bpp-rotate-270)
[599.733283] Starting subtest: linear-32bpp-rotate-270
[599.753490] Subtest linear-32bpp-rotate-270: SKIP (0.019s)
[599.868464] [128/133] (423s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[599.972279] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[599.977020] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[600.066219] [129/133] (423s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[600.183547] Starting subtest: flip-vs-cursor-atomic-transitions
[607.706340] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (7.520s)
[608.583566] [130/133] (414s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[608.683016] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[608.685709] Starting dynamic subtest: pipe-A-eDP-1
[608.696354] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[608.711948] Starting dynamic subtest: pipe-B-eDP-1
[608.722635] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[608.732821] Starting dynamic subtest: pipe-C-eDP-1
[608.743357] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[608.753481] Starting dynamic subtest: pipe-D-eDP-1
[608.764451] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[608.769647] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[610.884152] [131/133] (412s left) gem_userptr_blits (readonly-unsync)
[610.942390] Subtest readonly-unsync: SKIP (0.000s)
[611.058061] [132/133] (412s left) gem_eio (wait-immediate)
[611.263138] Starting subtest: wait-immediate
[611.580557] Subtest wait-immediate: SUCCESS (0.320s)
[611.716194] [133/133] (411s left) gem_mmap_gtt (basic-read)
[611.768425] Subtest basic-read: SKIP (0.000s)
[611.830633] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_psr
results: parsing output: 2/ for test: kms_chamelium_hpd
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: kms_rotation_crc
results: parsing output: 6/ for test: i915_pm_rps
results: parsing output: 7/ for test: kms_hdr
results: parsing output: 8/ for test: kms_flip_scaled_crc
results: parsing output: 9/ for test: kms_pm_dc
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: gem_exec_fence
results: parsing output: 15/ for test: gem_lmem_swapping
results: parsing output: 16/ for test: gem_userptr_blits
results: parsing output: 17/ for test: gem_mmap_offset
results: parsing output: 18/ for test: gem_ctx_shared
results: parsing output: 19/ for test: gem_pxp
results: parsing output: 20/ for test: kms_psr2_sf
results: parsing output: 21/ for test: sw_sync
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: kms_vblank
results: parsing output: 25/ for test: kms_chamelium_frames
results: parsing output: 26/ for test: kms_cursor_edge_walk
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: syncobj_wait
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: gem_mmap_wc
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: gem_partial_pwrite_pread
results: parsing output: 33/ for test: perf_pmu
results: parsing output: 34/ for test: gem_mmap_gtt
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_exec_params
results: parsing output: 37/ for test: gem_ctx_exec
results: parsing output: 38/ for test: gem_madvise
results: parsing output: 39/ for test: gem_exec_schedule
results: parsing output: 40/ for test: kms_flip
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_draw_crc
results: parsing output: 43/ for test: gem_render_copy
results: parsing output: 44/ for test: gem_ctx_isolation
results: parsing output: 45/ for test: i915_pm_rpm
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: kms_pm_rpm
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: kms_properties
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_exec_balancer
results: parsing output: 52/ for test: gem_exec_flush
results: parsing output: 53/ for test: kms_plane_scaling
results: parsing output: 54/ for test: api_intel_bb
results: parsing output: 55/ for test: gem_eio
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: kms_ccs
results: parsing output: 59/ for test: prime_vgem
results: parsing output: 60/ for test: kms_cursor_crc
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_pipe_b_c_ivb
results: parsing output: 63/ for test: kms_sequence
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_pipe_stress
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_busy
results: parsing output: 68/ for test: gem_vm_create
results: parsing output: 69/ for test: i915_hangman
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: gem_exec_schedule
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: gen9_exec_parse
results: parsing output: 75/ for test: gem_partial_pwrite_pread
results: parsing output: 76/ for test: gem_ctx_persistence
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_properties
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_psr2_sf
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_plane
results: parsing output: 85/ for test: kms_cursor_legacy
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_dp_link_training
results: parsing output: 89/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 90/ for test: perf
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: gem_exec_reloc
results: parsing output: 93/ for test: sysfs_timeslice_duration
results: parsing output: 94/ for test: gem_softpin
results: parsing output: 95/ for test: kms_draw_crc
results: parsing output: 96/ for test: kms_fence_pin_leak
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: kms_ccs
results: parsing output: 99/ for test: kms_plane
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: kms_async_flips
results: parsing output: 102/ for test: gem_ccs
results: parsing output: 103/ for test: gem_mmap_gtt
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: kms_content_protection
results: parsing output: 107/ for test: syncobj_basic
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_exec_balancer
results: parsing output: 110/ for test: gem_mmap_gtt
results: parsing output: 111/ for test: kms_plane
results: parsing output: 112/ for test: gem_pwrite
results: parsing output: 113/ for test: core_setmaster
results: parsing output: 114/ for test: gem_render_copy
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_lease
results: parsing output: 119/ for test: kms_async_flips
results: parsing output: 120/ for test: api_intel_allocator
results: parsing output: 121/ for test: kms_hdr
results: parsing output: 122/ for test: i915_drm_fdinfo
results: parsing output: 123/ for test: gem_exec_schedule
results: parsing output: 124/ for test: kms_flip_scaled_crc
results: parsing output: 125/ for test: kms_psr
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: kms_ccs
results: parsing output: 130/ for test: gem_userptr_blits
results: parsing output: 131/ for test: gem_eio
results: parsing output: 132/ for test: gem_mmap_gtt
Done.