[58.613892] Initializing watchdogs
[58.613941]   /dev/watchdog0
[58.628587] [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]
[58.632931] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.635473] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.638615] [001/133] (960s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[58.777340] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[62.837866] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (4.089s)
[63.981532] [002/133] (955s left) gem_flink_basic (bad-open)
[64.028883] Starting subtest: bad-open
[64.030982] Subtest bad-open: SUCCESS (0.000s)
[64.127125] [003/133] (955s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[64.233328] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[64.237460] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[64.341888] [004/133] (954s left) gem_ppgtt (shrink-vs-evict-pinned)
[64.397654] Starting subtest: shrink-vs-evict-pinned
[69.936682] Subtest shrink-vs-evict-pinned: SUCCESS (5.554s)
[70.028343] [005/133] (949s left) gem_exec_schedule (implicit-boths)
[70.103246] Starting subtest: implicit-boths
[70.105267] Starting dynamic subtest: rcs0
[70.288744] Dynamic subtest rcs0: SUCCESS (0.193s)
[70.299235] Starting dynamic subtest: vcs0
[70.393722] Dynamic subtest vcs0: SUCCESS (0.105s)
[70.404425] Starting dynamic subtest: vcs1
[70.501874] Dynamic subtest vcs1: SUCCESS (0.108s)
[70.512073] Starting dynamic subtest: vecs0
[70.607820] Dynamic subtest vecs0: SUCCESS (0.105s)
[70.618534] Starting dynamic subtest: ccs0
[70.745640] Dynamic subtest ccs0: SUCCESS (0.138s)
[70.748277] Subtest implicit-boths: SUCCESS (0.652s)
[71.983063] [006/133] (947s left) vgem_slow (nohang)
[72.061339] Starting subtest: nohang
[82.548642] Subtest nohang: SUCCESS (10.491s)
[82.609202] [FACT vgem_slow (nohang)] new: kernel.kmod_is_loaded.vgem: true
[82.612825] [007/133] (936s left) gem_exec_schedule (u-submit-late-slice)
[82.680824] Starting subtest: u-submit-late-slice
[82.683409] Starting dynamic subtest: rcs0
[82.731036] Dynamic subtest rcs0: SUCCESS (0.055s)
[82.742343] Starting dynamic subtest: bcs0
[82.773236] Dynamic subtest bcs0: SUCCESS (0.043s)
[82.782387] Starting dynamic subtest: vcs0
[82.820587] Dynamic subtest vcs0: SUCCESS (0.048s)
[82.833457] Starting dynamic subtest: vcs1
[82.889722] Dynamic subtest vcs1: SUCCESS (0.068s)
[82.900431] Starting dynamic subtest: vecs0
[82.932371] Dynamic subtest vecs0: SUCCESS (0.042s)
[82.940876] Starting dynamic subtest: ccs0
[82.977353] Dynamic subtest ccs0: SUCCESS (0.045s)
[82.980086] Subtest u-submit-late-slice: SUCCESS (0.302s)
[84.005502] [008/133] (935s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[84.115953] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[84.118352] Starting dynamic subtest: pipe-A-eDP-1
[84.141870] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[84.152908] Starting dynamic subtest: pipe-B-eDP-1
[84.174730] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[84.182390] Starting dynamic subtest: pipe-C-eDP-1
[84.204508] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[84.212297] Starting dynamic subtest: pipe-D-eDP-1
[84.234467] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[84.238566] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[86.431520] [009/133] (933s left) kms_cursor_legacy (torture-move)
[86.547075] Starting subtest: torture-move
[86.549538] Starting dynamic subtest: pipe-A
[92.467201] Dynamic subtest pipe-A: SUCCESS (5.916s)
[92.479631] Starting dynamic subtest: pipe-B
[99.407965] Dynamic subtest pipe-B: SUCCESS (6.940s)
[99.416660] Starting dynamic subtest: pipe-C
[106.872183] Dynamic subtest pipe-C: SUCCESS (7.462s)
[106.884400] Starting dynamic subtest: pipe-D
[112.801198] Dynamic subtest pipe-D: SUCCESS (5.928s)
[112.815774] Starting dynamic subtest: all-pipes
[119.323964] Dynamic subtest all-pipes: SUCCESS (6.522s)
[119.326581] Subtest torture-move: SUCCESS (32.772s)
[119.476435] [010/133] (900s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[119.591508] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[119.595880] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[119.690564] [011/133] (899s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[119.783460] Starting subtest: psr-rgb101010-draw-mmap-wc
[126.902024] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.121s)
[127.002444] [012/133] (892s left) i915_drm_fdinfo (virtual-busy-idle)
[127.040593] Starting subtest: virtual-busy-idle
[127.045208] Subtest virtual-busy-idle: SKIP (0.000s)
[127.113481] [013/133] (892s left) kms_vblank (ts-continuation-modeset)
[127.209618] Starting subtest: ts-continuation-modeset
[127.211514] Starting dynamic subtest: pipe-A-eDP-1
[128.693239] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.483s)
[128.708772] Starting dynamic subtest: pipe-D-eDP-1
[130.874116] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.177s)
[130.876315] Subtest ts-continuation-modeset: SUCCESS (3.663s)
[131.681599] [014/133] (887s left) i915_pm_rps (thresholds-idle)
[131.743949] Starting subtest: thresholds-idle
[131.748044] Subtest thresholds-idle: SKIP (0.001s)
[131.840398] [015/133] (887s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[131.933656] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[131.935896] Starting dynamic subtest: pipe-A-eDP-1
[131.958120] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[131.970290] Starting dynamic subtest: pipe-B-eDP-1
[131.991699] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[131.999476] Starting dynamic subtest: pipe-C-eDP-1
[132.020251] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[132.030919] Starting dynamic subtest: pipe-D-eDP-1
[132.052525] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[132.056597] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[134.232191] [016/133] (885s left) gem_ctx_bad_destroy (invalid-default-ctx)
[134.281795] Starting subtest: invalid-default-ctx
[134.284191] Subtest invalid-default-ctx: SUCCESS (0.000s)
[134.372571] [017/133] (885s left) gem_fence_thrash (bo-write-verify-threaded-y)
[134.409509] Starting subtest: bo-write-verify-threaded-y
[134.430739] Subtest bo-write-verify-threaded-y: SKIP (0.021s)
[134.499764] [018/133] (885s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[134.598850] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[134.603395] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[134.694376] [019/133] (885s left) kms_color_pipeline (plane-lut1d-lut1d)
[134.787290] Starting subtest: plane-lut1d-lut1d
[134.946996] Starting dynamic subtest: pipe-A-plane-0
[135.270886] Dynamic subtest pipe-A-plane-0: FAIL (0.174s)
[135.280075] Starting dynamic subtest: pipe-A-plane-1
[135.484263] Dynamic subtest pipe-A-plane-1: FAIL (0.195s)
[135.491986] Starting dynamic subtest: pipe-A-plane-2
[135.707330] Dynamic subtest pipe-A-plane-2: FAIL (0.183s)
[136.759919] Starting dynamic subtest: pipe-B-plane-0
[137.202017] Dynamic subtest pipe-B-plane-0: FAIL (0.175s)
[137.209874] Starting dynamic subtest: pipe-B-plane-1
[137.518390] Dynamic subtest pipe-B-plane-1: FAIL (0.167s)
[137.527900] Starting dynamic subtest: pipe-B-plane-2
[137.833558] Dynamic subtest pipe-B-plane-2: FAIL (0.177s)
[138.445885] Starting dynamic subtest: pipe-C-plane-0
[138.932954] Dynamic subtest pipe-C-plane-0: FAIL (0.139s)
[138.943098] Starting dynamic subtest: pipe-C-plane-1
[139.349845] Dynamic subtest pipe-C-plane-1: FAIL (0.166s)
[139.359291] Starting dynamic subtest: pipe-C-plane-2
[139.724517] Dynamic subtest pipe-C-plane-2: FAIL (0.643s)
[140.571891] Starting dynamic subtest: pipe-D-plane-0
[141.180484] Dynamic subtest pipe-D-plane-0: FAIL (0.152s)
[141.190353] Starting dynamic subtest: pipe-D-plane-1
[141.646925] Dynamic subtest pipe-D-plane-1: FAIL (0.629s)
[141.656335] Starting dynamic subtest: pipe-D-plane-2
[142.100308] Dynamic subtest pipe-D-plane-2: FAIL (0.563s)
[142.214268] Subtest plane-lut1d-lut1d: FAIL (7.427s)
[143.048821] [020/133] (876s left) gem_ctx_shared (create-shared-gtt)
[143.121316] Starting subtest: create-shared-gtt
[145.273314] Subtest create-shared-gtt: SUCCESS (2.151s)
[145.670528] [021/133] (874s left) gem_exec_create (basic)
[145.739656] Starting subtest: basic
[145.741985] Starting dynamic subtest: smem
[148.887506] Dynamic subtest smem: SUCCESS (3.146s)
[148.890068] Subtest basic: SUCCESS (3.147s)
[149.071525] [022/133] (870s left) syncobj_timeline (single-wait-signaled)
[149.131262] Starting subtest: single-wait-signaled
[149.133708] Subtest single-wait-signaled: SUCCESS (0.001s)
[149.234234] [023/133] (870s left) kms_chamelium_audio (dp-audio)
[149.643596] Subtest dp-audio: SKIP (0.000s)
[150.454185] [024/133] (869s left) gem_pwrite_snooped
[150.595507] [025/133] (869s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[150.693224] Starting subtest: fbc-rgb101010-draw-render
[155.271355] Subtest fbc-rgb101010-draw-render: SUCCESS (4.581s)
[156.461550] [026/133] (863s left) perf (gen12-invalid-class-instance)
[156.599436] Starting subtest: gen12-invalid-class-instance
[156.608889] Subtest gen12-invalid-class-instance: SUCCESS (0.011s)
[156.649269] [027/133] (863s left) kms_psr (fbc-psr-primary-page-flip)
[156.743236] Starting subtest: fbc-psr-primary-page-flip
[156.745717] Starting dynamic subtest: eDP-1
[156.750173] Dynamic subtest eDP-1: SKIP (0.000s)
[156.754377] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[156.814587] [028/133] (863s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[156.906685] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[156.911074] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[157.003487] [029/133] (863s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[157.099092] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[157.103131] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[157.197816] [030/133] (863s left) gem_exec_params (sol-reset-invalid)
[157.251668] Starting subtest: sol-reset-invalid
[157.254046] Subtest sol-reset-invalid: SUCCESS (0.002s)
[158.983265] [031/133] (861s left) gem_mmap_gtt (zero-extend)
[159.045161] Subtest zero-extend: SKIP (0.000s)
[159.105721] [032/133] (861s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[159.210750] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[160.244627] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.034s)
[160.367615] [033/133] (860s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[160.458147] Starting subtest: primary-x-tiled-reflect-x-0
[167.724060] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.266s)
[168.915070] [034/133] (851s left) kms_big_fb (linear-32bpp-rotate-0)
[169.015333] Starting subtest: linear-32bpp-rotate-0
[170.549280] Subtest linear-32bpp-rotate-0: SUCCESS (1.536s)
[170.765573] [035/133] (849s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[170.866660] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[170.869740] Starting dynamic subtest: pipe-A-default-mode
[173.558549] Dynamic subtest pipe-A-default-mode: SUCCESS (2.691s)
[173.560692] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.692s)
[174.413279] [036/133] (846s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[174.495630] Starting subtest: crc-primary-basic-yf-tiled-ccs
[174.497770] Starting dynamic subtest: pipe-A-eDP-1
[174.519545] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[174.529864] Starting dynamic subtest: pipe-B-eDP-1
[174.550123] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[174.558680] Starting dynamic subtest: pipe-C-eDP-1
[174.579274] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[174.587240] Starting dynamic subtest: pipe-D-eDP-1
[174.609484] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[174.613596] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.005s)
[176.792029] [037/133] (843s left) kms_plane (pixel-format-x-tiled-modifier)
[176.878022] Starting subtest: pixel-format-x-tiled-modifier
[176.903585] Starting dynamic subtest: pipe-A-plane-0
[177.536970] Dynamic subtest pipe-A-plane-0: SUCCESS (0.633s)
[177.545792] Starting dynamic subtest: pipe-A-plane-3
[177.992060] Dynamic subtest pipe-A-plane-3: SUCCESS (0.454s)
[178.000899] Starting dynamic subtest: pipe-A-plane-5
[178.004368] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[179.071649] Starting dynamic subtest: pipe-B-plane-0
[179.731464] Dynamic subtest pipe-B-plane-0: SUCCESS (0.666s)
[179.743472] Starting dynamic subtest: pipe-B-plane-3
[180.164171] Dynamic subtest pipe-B-plane-3: SUCCESS (0.431s)
[180.172523] Starting dynamic subtest: pipe-B-plane-5
[180.176097] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[180.487563] Subtest pixel-format-x-tiled-modifier: SUCCESS (3.611s)
[181.350042] [038/133] (839s left) kms_psr (psr2-primary-render)
[181.445175] Starting subtest: psr2-primary-render
[181.447486] Starting dynamic subtest: eDP-1
[183.160070] Dynamic subtest eDP-1: SUCCESS (1.715s)
[183.162632] Subtest psr2-primary-render: SUCCESS (1.716s)
[184.027718] [039/133] (836s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[184.146547] Starting subtest: cursor-vs-flip-atomic
[213.595912] Subtest cursor-vs-flip-atomic: SUCCESS (29.446s)
[214.441321] [040/133] (806s left) gem_ctx_freq (sysfs)
[214.512575] Starting subtest: sysfs
[214.514854] Starting dynamic subtest: gt0
[220.000985] Dynamic subtest gt0: SUCCESS (5.488s)
[220.011486] Starting dynamic subtest: gt1
[261.909926] Dynamic subtest gt1: SUCCESS (41.905s)
[261.912924] Subtest sysfs: SUCCESS (47.396s)
[262.024513] [041/133] (758s left) gem_lmem_swapping (heavy-verify-multi)
[262.067678] Subtest heavy-verify-multi: SKIP (0.000s)
[262.111428] [042/133] (758s left) kms_plane_multiple (tiling-none)
[262.211052] Starting subtest: tiling-none
[262.213369] Starting dynamic subtest: pipe-A-eDP-1
[266.012978] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.801s)
[266.023697] Starting dynamic subtest: pipe-B-eDP-1
[270.538554] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.526s)
[270.548625] Starting dynamic subtest: pipe-C-eDP-1
[274.868207] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.326s)
[274.877516] Starting dynamic subtest: pipe-D-eDP-1
[279.222206] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.352s)
[279.224456] Subtest tiling-none: SUCCESS (17.010s)
[280.055297] [043/133] (740s left) gem_partial_pwrite_pread (reads)
[280.125322] Subtest reads: SKIP (0.000s)
[280.205079] [044/133] (740s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[280.298295] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[280.302786] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[281.491660] [045/133] (739s left) core_getclient (basic)
[281.545772] Starting subtest: basic
[281.548030] Subtest basic: SUCCESS (0.000s)
[281.655427] [046/133] (739s left) gem_eio (create)
[281.892732] Starting subtest: create
[282.216266] Subtest create: SUCCESS (0.324s)
[282.372980] [047/133] (738s left) gem_exec_schedule (smoketest)
[282.549742] Starting subtest: smoketest
[282.551962] Starting dynamic subtest: rcs0
[288.136343] Dynamic subtest rcs0: SUCCESS (5.534s)
[288.147824] Starting dynamic subtest: bcs0
[293.644497] Dynamic subtest bcs0: SUCCESS (5.505s)
[293.657633] Starting dynamic subtest: vcs0
[299.143649] Dynamic subtest vcs0: SUCCESS (5.498s)
[299.158016] Starting dynamic subtest: vcs1
[304.635729] Dynamic subtest vcs1: SUCCESS (5.492s)
[304.646164] Starting dynamic subtest: vecs0
[310.132741] Dynamic subtest vecs0: SUCCESS (5.495s)
[310.144039] Starting dynamic subtest: ccs0
[315.632047] Dynamic subtest ccs0: SUCCESS (5.497s)
[315.634225] Subtest smoketest: SUCCESS (33.031s)
[315.838148] [048/133] (705s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[315.972609] Starting subtest: basic-flip-after-cursor-legacy
[316.890558] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.918s)
[317.703319] [049/133] (703s left) kms_cursor_edge_walk (64x64-top-edge)
[317.799661] Starting subtest: 64x64-top-edge
[317.801912] Starting dynamic subtest: pipe-A-eDP-1
[321.268517] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.469s)
[321.279104] Starting dynamic subtest: pipe-D-eDP-1
[324.391540] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.120s)
[324.393785] Subtest 64x64-top-edge: SUCCESS (6.592s)
[325.190667] [050/133] (696s left) syncobj_eventfd (binary-wait-before-signal)
[325.247300] Starting subtest: binary-wait-before-signal
[325.278609] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[325.353620] [051/133] (695s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[325.409940] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[325.412318] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[325.521600] [052/133] (695s left) core_auth (basic-auth)
[325.573186] Starting subtest: basic-auth
[325.575380] Subtest basic-auth: SUCCESS (0.001s)
[325.669685] [053/133] (695s left) kms_flip (flip-vs-panning)
[325.758099] Starting subtest: flip-vs-panning
[325.760374] Starting dynamic subtest: A-eDP1
[334.504242] Dynamic subtest A-eDP1: SUCCESS (8.745s)
[334.518720] Starting dynamic subtest: B-eDP1
[343.234097] Dynamic subtest B-eDP1: SUCCESS (8.727s)
[343.246150] Starting dynamic subtest: C-eDP1
[351.961080] Dynamic subtest C-eDP1: SUCCESS (8.723s)
[351.971104] Starting dynamic subtest: D-eDP1
[360.685860] Dynamic subtest D-eDP1: SUCCESS (8.725s)
[360.690978] Subtest flip-vs-panning: SUCCESS (34.931s)
[361.840987] [054/133] (659s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[361.970332] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[361.974696] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[362.117797] [055/133] (659s left) gem_cs_tlb (engines)
[362.174215] Starting subtest: engines
[362.176084] Starting dynamic subtest: rcs0
[364.326688] Dynamic subtest rcs0: SUCCESS (2.152s)
[364.336171] Starting dynamic subtest: bcs0
[366.479816] Dynamic subtest bcs0: SUCCESS (2.151s)
[366.490047] Starting dynamic subtest: vcs0
[368.633196] Dynamic subtest vcs0: SUCCESS (2.152s)
[368.642736] Starting dynamic subtest: vcs1
[370.786835] Dynamic subtest vcs1: SUCCESS (2.152s)
[370.795891] Starting dynamic subtest: vecs0
[372.941470] Dynamic subtest vecs0: SUCCESS (2.152s)
[372.952838] Starting dynamic subtest: ccs0
[375.096045] Dynamic subtest ccs0: SUCCESS (2.155s)
[375.098213] Subtest engines: SUCCESS (12.922s)
[375.205317] [056/133] (646s left) gem_vm_create (isolation)
[375.266053] Starting subtest: isolation
[375.280195] Subtest isolation: SUCCESS (0.014s)
[375.420250] [057/133] (646s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[375.518371] Starting subtest: psr-1p-pri-indfb-multidraw
[398.441201] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.925s)
[398.556852] [058/133] (622s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[398.663216] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[398.666555] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[398.759874] [059/133] (622s left) kms_content_protection (dp-mst-type-0-suspend-resume)
[398.904728] Starting subtest: dp-mst-type-0-suspend-resume
[398.908743] Subtest dp-mst-type-0-suspend-resume: SKIP (0.000s)
[399.017579] [060/133] (622s left) gen9_exec_parse (bb-start-param)
[399.071548] Subtest bb-start-param: SKIP (0.000s)
[399.127666] [061/133] (622s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[399.223618] Starting subtest: flip-vs-dpms-off-vs-modeset
[399.225634] Starting dynamic subtest: A-eDP1
[402.809737] Dynamic subtest A-eDP1: SUCCESS (3.582s)
[402.819781] Starting dynamic subtest: B-eDP1
[405.986893] Dynamic subtest B-eDP1: SUCCESS (3.177s)
[405.997130] Starting dynamic subtest: C-eDP1
[409.096748] Dynamic subtest C-eDP1: SUCCESS (3.109s)
[409.106135] Starting dynamic subtest: D-eDP1
[412.203195] Dynamic subtest D-eDP1: SUCCESS (3.104s)
[412.205515] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (12.981s)
[412.999305] [062/133] (608s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[413.095062] Starting subtest: fbc-psr2-cursor-mmap-gtt
[413.097244] Starting dynamic subtest: eDP-1
[413.101246] Dynamic subtest eDP-1: SKIP (0.000s)
[413.105308] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[413.168758] [063/133] (608s left) core_getversion (all-cards)
[413.212287] Starting subtest: all-cards
[413.218930] Subtest all-cards: SUCCESS (0.011s)
[413.258551] [064/133] (608s left) gem_ringfill (legacy-basic)
[418.907333] Starting subtest: legacy-basic
[418.909761] Starting dynamic subtest: default
[418.977272] Dynamic subtest default: SUCCESS (0.069s)
[418.989587] Starting dynamic subtest: render
[419.021833] Dynamic subtest render: SUCCESS (0.043s)
[419.041950] Starting dynamic subtest: bsd
[419.064294] Dynamic subtest bsd: SUCCESS (0.040s)
[419.076907] Starting dynamic subtest: bsd1
[419.106077] Dynamic subtest bsd1: SUCCESS (0.039s)
[419.116812] Starting dynamic subtest: bsd2
[419.145957] Dynamic subtest bsd2: SUCCESS (0.038s)
[419.158769] Starting dynamic subtest: blt
[419.191924] Dynamic subtest blt: SUCCESS (0.043s)
[419.202935] Starting dynamic subtest: vebox
[419.232939] Dynamic subtest vebox: SUCCESS (0.039s)
[419.235328] Subtest legacy-basic: SUCCESS (0.328s)
[419.312530] [065/133] (602s left) gem_media_fill (media-fill)
[419.376169] Subtest media-fill: SKIP (0.000s)
[419.494494] [066/133] (602s left) perf_pmu (render-node-busy)
[419.565160] Starting subtest: render-node-busy
[419.567896] Starting dynamic subtest: rcs0
[420.133451] Dynamic subtest rcs0: SUCCESS (0.568s)
[420.145725] Starting dynamic subtest: bcs0
[420.703816] Dynamic subtest bcs0: SUCCESS (0.568s)
[420.716117] Starting dynamic subtest: vcs0
[421.912346] Dynamic subtest vcs0: SUCCESS (1.205s)
[421.924650] Starting dynamic subtest: vcs1
[423.896490] Dynamic subtest vcs1: SUCCESS (1.982s)
[423.909068] Starting dynamic subtest: vecs0
[425.879157] Dynamic subtest vecs0: SUCCESS (1.980s)
[425.892718] Starting dynamic subtest: ccs0
[426.448301] Dynamic subtest ccs0: SUCCESS (0.568s)
[426.450812] Subtest render-node-busy: SUCCESS (6.884s)
[426.527954] [067/133] (595s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[426.636049] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[426.640540] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[426.730758] [068/133] (595s left) gem_partial_pwrite_pread (write-display)
[426.786669] Subtest write-display: SKIP (0.000s)
[426.865138] [069/133] (595s left) gem_userptr_blits (dmabuf-sync)
[426.928317] Starting subtest: dmabuf-sync
[426.932666] Subtest dmabuf-sync: SKIP (0.002s)
[427.088318] [070/133] (594s left) i915_pm_rps (basic-api)
[427.146579] Starting subtest: basic-api
[427.151069] Subtest basic-api: SKIP (0.000s)
[427.245170] [071/133] (594s left) syncobj_basic (bad-handle-to-fd)
[427.296254] Starting subtest: bad-handle-to-fd
[427.298316] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[427.414263] [072/133] (594s left) sysfs_preempt_timeout (idempotent)
[427.470265] Starting subtest: idempotent
[427.472205] Starting dynamic subtest: vecs0
[427.474343] Dynamic subtest vecs0: SUCCESS (0.000s)
[427.484022] Starting dynamic subtest: ccs0
[427.485850] Dynamic subtest ccs0: SUCCESS (0.000s)
[427.494610] Starting dynamic subtest: vcs0
[427.496346] Dynamic subtest vcs0: SUCCESS (0.000s)
[427.505462] Starting dynamic subtest: bcs0
[427.507289] Dynamic subtest bcs0: SUCCESS (0.000s)
[427.515971] Starting dynamic subtest: rcs0
[427.517838] Dynamic subtest rcs0: SUCCESS (0.000s)
[427.526567] Starting dynamic subtest: vcs1
[427.528330] Dynamic subtest vcs1: SUCCESS (0.000s)
[427.530124] Subtest idempotent: SUCCESS (0.007s)
[427.588131] [073/133] (594s left) gem_pxp (reject-modify-context-protection-off-2)
[427.707126] Starting subtest: reject-modify-context-protection-off-2
[427.709382] Subtest reject-modify-context-protection-off-2: SUCCESS (0.001s)
[427.847973] [074/133] (594s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[427.926291] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[428.006461] [075/133] (594s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[428.056956] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[428.162255] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[428.239292] [076/133] (594s left) syncobj_basic (bad-flags-handle-to-fd)
[428.313631] Starting subtest: bad-flags-handle-to-fd
[428.315809] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[428.421355] [077/133] (593s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[428.542522] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[428.546583] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[428.642121] [078/133] (593s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[428.743943] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[429.800535] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.057s)
[429.899414] [079/133] (592s left) gem_exec_balancer (sliced)
[429.962112] Starting subtest: sliced
[429.965936] Subtest sliced: SKIP (0.000s)
[430.281057] [080/133] (592s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[430.379262] Starting subtest: fbc-psr2-cursor-plane-update-sf
[430.381575] Starting dynamic subtest: pipe-A-eDP-1
[430.459590] Dynamic subtest pipe-A-eDP-1: SKIP (0.082s)
[430.470814] Starting dynamic subtest: pipe-B-eDP-1
[431.580647] Dynamic subtest pipe-B-eDP-1: SKIP (1.119s)
[431.584799] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.204s)
[432.766721] [081/133] (589s left) syncobj_timeline (multi-wait-for-submit-signaled)
[432.815286] Starting subtest: multi-wait-for-submit-signaled
[432.817443] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[432.919010] [082/133] (589s left) syncobj_wait (multi-wait-signaled)
[432.966620] Starting subtest: multi-wait-signaled
[432.968599] Subtest multi-wait-signaled: SUCCESS (0.000s)
[433.048660] [083/133] (589s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[433.144983] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[440.195678] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.053s)
[440.341340] [084/133] (582s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[440.444235] Starting subtest: yf-tiled-32bpp-rotate-90
[440.448608] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[440.538668] [085/133] (582s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[440.632223] Starting subtest: y-tiled-8bpp-rotate-270
[440.636183] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[440.737987] [086/133] (582s left) gem_exec_reloc (basic-write-read-noreloc)
[440.798106] Subtest basic-write-read-noreloc: SKIP (0.000s)
[440.872468] [087/133] (581s left) kms_dsc (dsc-with-output-formats)
[440.965064] Starting subtest: dsc-with-output-formats
[440.986148] Subtest dsc-with-output-formats: SKIP (0.001s)
[441.037058] [088/133] (581s left) kms_chamelium_color (gamma)
[441.135683] Subtest gamma: SKIP (0.000s)
[441.182525] [089/133] (581s left) kms_psr (psr-suspend)
[441.279392] Starting subtest: psr-suspend
[441.281301] Starting dynamic subtest: eDP-1
[459.259476] Dynamic subtest eDP-1: SUCCESS (3.729s)
[459.261803] Subtest psr-suspend: SUCCESS (3.730s)
[460.116587] [090/133] (562s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[460.247655] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[467.156075] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.043s)
[467.270700] [091/133] (555s left) kms_vblank (accuracy-idle)
[467.379032] Starting subtest: accuracy-idle
[467.380833] Starting dynamic subtest: pipe-A-eDP-1
[469.120718] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.759s)
[469.135931] Starting dynamic subtest: pipe-D-eDP-1
[471.525199] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.421s)
[471.527488] Subtest accuracy-idle: SUCCESS (4.184s)
[472.345054] [092/133] (550s left) kms_async_flips (async-flip-suspend-resume)
[472.431565] Starting subtest: async-flip-suspend-resume
[472.818504] Starting dynamic subtest: pipe-A-eDP-1
[490.831478] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.924s)
[491.215768] Starting dynamic subtest: pipe-B-eDP-1
[508.853346] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.950s)
[509.222907] Starting dynamic subtest: pipe-C-eDP-1
[526.843948] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.936s)
[527.224795] Starting dynamic subtest: pipe-D-eDP-1
[544.834053] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.920s)
[544.836704] Subtest async-flip-suspend-resume: SUCCESS (13.251s)
[545.984873] [093/133] (477s left) kms_psr (psr2-cursor-blt)
[546.088462] Starting subtest: psr2-cursor-blt
[546.090870] Starting dynamic subtest: eDP-1
[547.744532] Dynamic subtest eDP-1: SUCCESS (1.698s)
[547.747275] Subtest psr2-cursor-blt: SUCCESS (1.699s)
[548.603478] [094/133] (474s left) kms_plane (plane-position-covered)
[548.696563] Starting subtest: plane-position-covered
[549.224930] Starting dynamic subtest: pipe-A-plane-1
[550.498980] Dynamic subtest pipe-A-plane-1: SUCCESS (1.293s)
[550.512580] Starting dynamic subtest: pipe-A-plane-2
[551.768088] Dynamic subtest pipe-A-plane-2: SUCCESS (1.286s)
[551.779346] Starting dynamic subtest: pipe-A-plane-3
[552.998342] Dynamic subtest pipe-A-plane-3: SUCCESS (1.248s)
[553.009101] Starting dynamic subtest: pipe-A-plane-4
[554.307818] Dynamic subtest pipe-A-plane-4: SUCCESS (1.322s)
[554.320067] Starting dynamic subtest: pipe-A-plane-5
[554.335394] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[555.477925] Starting dynamic subtest: pipe-B-plane-1
[556.806557] Dynamic subtest pipe-B-plane-1: SUCCESS (1.349s)
[556.818163] Starting dynamic subtest: pipe-B-plane-2
[558.103088] Dynamic subtest pipe-B-plane-2: SUCCESS (1.305s)
[558.115716] Starting dynamic subtest: pipe-B-plane-3
[559.423130] Dynamic subtest pipe-B-plane-3: SUCCESS (1.323s)
[559.433979] Starting dynamic subtest: pipe-B-plane-4
[560.743014] Dynamic subtest pipe-B-plane-4: SUCCESS (1.327s)
[560.754358] Starting dynamic subtest: pipe-B-plane-5
[560.769959] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[560.772225] Subtest plane-position-covered: SUCCESS (12.192s)
[561.615335] [095/133] (461s left) kms_joiner (basic-force-big-joiner)
[561.710164] Starting subtest: basic-force-big-joiner
[561.715357] Subtest basic-force-big-joiner: SKIP (0.000s)
[561.776229] [096/133] (461s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[561.893955] Starting subtest: 2x-cursor-vs-flip-atomic
[561.898765] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[562.043239] [097/133] (461s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[562.424037] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[563.218903] [098/133] (460s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[563.325362] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[570.287933] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.982s)
[570.417718] [099/133] (452s left) kms_pipe_stress (stress-xrgb8888-4tiled)
[570.517580] Starting subtest: stress-xrgb8888-4tiled
[576.324386] Subtest stress-xrgb8888-4tiled: SUCCESS (5.812s)
[576.377297] [100/133] (447s left) kms_pm_dc (dc6-psr)
[577.482687] Starting subtest: dc6-psr
[580.374254] Subtest dc6-psr: SUCCESS (2.888s)
[581.189646] [101/133] (442s left) kms_cursor_crc (cursor-alpha-transparent)
[581.288186] Starting subtest: cursor-alpha-transparent
[581.290572] Starting dynamic subtest: pipe-A-eDP-1
[581.905344] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.618s)
[581.917130] Starting dynamic subtest: pipe-D-eDP-1
[583.164768] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.257s)
[583.167359] Subtest cursor-alpha-transparent: SUCCESS (1.878s)
[584.047718] [102/133] (439s left) i915_drm_fdinfo (virtual-busy-hang)
[584.097514] Starting subtest: virtual-busy-hang
[584.102128] Subtest virtual-busy-hang: SKIP (0.000s)
[584.173114] [103/133] (439s left) kms_psr2_su (page_flip-xrgb8888)
[584.226192] Subtest page_flip-XRGB8888: SKIP (0.000s)
[584.283538] [104/133] (439s left) kms_force_connector_basic (force-connector-state)
[584.359121] Starting subtest: force-connector-state
[584.991172] Subtest force-connector-state: SUCCESS (0.629s)
[585.907529] [105/133] (437s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[585.993475] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[585.998598] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[586.069805] [106/133] (437s left) gem_tiled_blits (basic)
[586.135373] Subtest basic: SKIP (0.000s)
[586.207385] [107/133] (437s left) gem_userptr_blits (access-control)
[586.284351] Starting subtest: access-control
[586.289594] Subtest access-control: SKIP (0.000s)
[586.422812] [108/133] (437s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[586.521937] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[586.526519] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[586.629744] [109/133] (437s left) gem_exec_fence (syncobj-timeline-chain-engines)
[587.197708] Starting subtest: syncobj-timeline-chain-engines
[587.313689] Subtest syncobj-timeline-chain-engines: SUCCESS (0.116s)
[588.643211] [110/133] (435s left) gem_ccs (block-copy-uncompressed)
[588.701150] Starting subtest: block-copy-uncompressed
[588.704095] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[588.724757] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s)
[588.737368] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[588.742372] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[588.755033] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[588.760411] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[588.770042] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[588.776194] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[588.778703] Subtest block-copy-uncompressed: SUCCESS (0.075s)
[588.900279] [111/133] (434s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[589.010044] Starting subtest: 4-tiled-64bpp-rotate-270
[589.030000] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[589.140234] [112/133] (434s left) gem_exec_schedule (preemptive-hang)
[589.317386] Starting subtest: preemptive-hang
[589.319910] Starting dynamic subtest: rcs0
[598.718267] Dynamic subtest rcs0: SUCCESS (9.402s)
[598.729935] Starting dynamic subtest: bcs0
[607.672352] Dynamic subtest bcs0: SUCCESS (8.953s)
[607.684953] Starting dynamic subtest: vcs0
[616.696913] Dynamic subtest vcs0: SUCCESS (9.025s)
[616.710390] Starting dynamic subtest: vcs1
[625.653452] Dynamic subtest vcs1: SUCCESS (8.957s)
[625.666139] Starting dynamic subtest: vecs0
[634.689344] Dynamic subtest vecs0: SUCCESS (9.039s)
[634.702190] Starting dynamic subtest: ccs0
[643.767371] Dynamic subtest ccs0: SUCCESS (9.077s)
[643.770287] Subtest preemptive-hang: SUCCESS (54.461s)
[644.007240] [113/133] (379s left) kms_pm_rpm (universal-planes)
[645.742482] Starting subtest: universal-planes
[645.745208] Starting dynamic subtest: plane-34
[654.186497] Dynamic subtest plane-34: SUCCESS (8.442s)
[654.200251] Starting dynamic subtest: plane-64
[662.452661] Dynamic subtest plane-64: SUCCESS (8.262s)
[662.466645] Starting dynamic subtest: plane-94
[670.766979] Dynamic subtest plane-94: SUCCESS (8.310s)
[670.780340] Starting dynamic subtest: plane-124
[679.061145] Dynamic subtest plane-124: SUCCESS (8.291s)
[679.074307] Starting dynamic subtest: plane-134
[687.325651] Dynamic subtest plane-134: SUCCESS (8.263s)
[687.342094] Starting dynamic subtest: plane-144
[695.640386] Dynamic subtest plane-144: SUCCESS (8.311s)
[695.643355] Subtest universal-planes: SUCCESS (49.898s)
[696.019047] [114/133] (327s left) gem_spin_batch (spin-all-new)
[696.077281] Starting subtest: spin-all-new
[696.093839] Subtest spin-all-new: SUCCESS (0.017s)
[697.595394] [115/133] (326s left) gem_exec_reloc (basic-gtt-read-active)
[697.660110] Subtest basic-gtt-read-active: SKIP (0.000s)
[697.731301] [116/133] (326s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[697.795379] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[697.797846] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[697.897161] [117/133] (326s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[698.016334] Starting subtest: 2x-long-flip-vs-cursor-atomic
[698.022018] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[698.160612] [118/133] (326s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[698.253164] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[698.258336] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[698.349171] [119/133] (325s left) perf_pmu (semaphore-busy)
[698.391212] Starting subtest: semaphore-busy
[698.393877] Starting dynamic subtest: rcs0
[700.028729] Dynamic subtest rcs0: SUCCESS (1.634s)
[700.042522] Starting dynamic subtest: bcs0
[701.661703] Dynamic subtest bcs0: SUCCESS (1.630s)
[701.675417] Starting dynamic subtest: vcs0
[706.057650] Dynamic subtest vcs0: SUCCESS (4.394s)
[706.071346] Starting dynamic subtest: vcs1
[712.073514] Dynamic subtest vcs1: SUCCESS (6.014s)
[712.087351] Starting dynamic subtest: vecs0
[718.089342] Dynamic subtest vecs0: SUCCESS (6.013s)
[718.102498] Starting dynamic subtest: ccs0
[721.167147] Dynamic subtest ccs0: SUCCESS (3.075s)
[721.170287] Subtest semaphore-busy: SUCCESS (22.778s)
[721.288027] [120/133] (302s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[721.393058] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[721.397620] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[721.461561] [121/133] (302s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[721.560133] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[721.565091] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[721.664513] [122/133] (302s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[721.760011] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[721.764984] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[721.872897] [123/133] (302s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[721.938932] Starting subtest: multi-wait-all-for-submit-available-submitted
[721.941182] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[722.052843] [124/133] (302s left) api_intel_allocator (two-level-inception)
[722.103114] Starting subtest: two-level-inception
[727.503868] Subtest two-level-inception: SUCCESS (5.402s)
[727.610835] [125/133] (296s left) kms_async_flips (test-time-stamp)
[727.733153] Starting subtest: test-time-stamp
[728.108016] Starting dynamic subtest: pipe-A-eDP-1
[728.936373] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.827s)
[729.309066] Starting dynamic subtest: pipe-B-eDP-1
[730.127169] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.823s)
[730.499344] Starting dynamic subtest: pipe-C-eDP-1
[731.332097] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.840s)
[731.698517] Starting dynamic subtest: pipe-D-eDP-1
[732.537188] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.845s)
[732.540391] Subtest test-time-stamp: SUCCESS (4.804s)
[733.763945] [126/133] (290s left) drm_read (empty-nonblock)
[733.901165] Starting subtest: empty-nonblock
[733.903726] Subtest empty-nonblock: SUCCESS (0.000s)
[734.028666] [127/133] (290s left) kms_lease (lease-again)
[734.123091] Starting subtest: lease-again
[734.126184] Starting dynamic subtest: pipe-A-eDP-1
[734.129096] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[734.140152] Starting dynamic subtest: pipe-B-eDP-1
[734.142406] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[734.153076] Starting dynamic subtest: pipe-C-eDP-1
[734.155381] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[734.167113] Starting dynamic subtest: pipe-D-eDP-1
[734.169220] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[734.172045] Subtest lease-again: SUCCESS (0.013s)
[734.235971] [128/133] (290s left) gem_exec_balancer (indices)
[734.295163] Starting subtest: indices
[736.554896] Subtest indices: SUCCESS (2.259s)
[736.875758] [129/133] (287s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[737.140280] Starting subtest: cursor-rapid-movement-256x85
[737.189993] Subtest cursor-rapid-movement-256x85: SKIP (0.044s)
[737.341059] [130/133] (287s left) gem_basic (create-fd-close)
[737.402084] Starting subtest: create-fd-close
[737.407208] Subtest create-fd-close: SUCCESS (0.000s)
[737.493648] [131/133] (287s left) core_auth (many-magics)
[737.547133] Starting subtest: many-magics
[738.391780] Subtest many-magics: SUCCESS (0.848s)
[738.529153] [132/133] (286s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[738.619016] Starting subtest: 2x-absolute-wf_vblank-interruptible
[738.630994] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.010s)
[738.720851] [133/133] (286s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[738.810957] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[738.812911] Starting dynamic subtest: pipe-A
[739.147992] Dynamic subtest pipe-A: SUCCESS (0.338s)
[739.158399] Starting dynamic subtest: pipe-B
[740.512584] Dynamic subtest pipe-B: SUCCESS (1.364s)
[740.524253] Starting dynamic subtest: pipe-C
[741.916633] Dynamic subtest pipe-C: SUCCESS (1.403s)
[741.927132] Starting dynamic subtest: pipe-D
[743.319383] Dynamic subtest pipe-D: SUCCESS (1.402s)
[743.321835] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.511s)
[744.458329] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_flink_basic
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_ppgtt
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: vgem_slow
results: parsing output: 6/ for test: gem_exec_schedule
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: kms_cursor_legacy
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: i915_drm_fdinfo
results: parsing output: 12/ for test: kms_vblank
results: parsing output: 13/ for test: i915_pm_rps
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: gem_ctx_bad_destroy
results: parsing output: 16/ for test: gem_fence_thrash
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_color_pipeline
results: parsing output: 19/ for test: gem_ctx_shared
results: parsing output: 20/ for test: gem_exec_create
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: kms_chamelium_audio
results: parsing output: 23/ for test: gem_pwrite_snooped
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: perf
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: gem_mmap_gtt
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: kms_rotation_crc
results: parsing output: 33/ for test: kms_big_fb
results: parsing output: 34/ for test: kms_flip_scaled_crc
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: kms_plane
results: parsing output: 37/ for test: kms_psr
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: gem_ctx_freq
results: parsing output: 40/ for test: gem_lmem_swapping
results: parsing output: 41/ for test: kms_plane_multiple
results: parsing output: 42/ for test: gem_partial_pwrite_pread
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: core_getclient
results: parsing output: 45/ for test: gem_eio
results: parsing output: 46/ for test: gem_exec_schedule
results: parsing output: 47/ for test: kms_cursor_legacy
results: parsing output: 48/ for test: kms_cursor_edge_walk
results: parsing output: 49/ for test: syncobj_eventfd
results: parsing output: 50/ for test: syncobj_wait
results: parsing output: 51/ for test: core_auth
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_cursor_legacy
results: parsing output: 54/ for test: gem_cs_tlb
results: parsing output: 55/ for test: gem_vm_create
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_content_protection
results: parsing output: 59/ for test: gen9_exec_parse
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: core_getversion
results: parsing output: 63/ for test: gem_ringfill
results: parsing output: 64/ for test: gem_media_fill
results: parsing output: 65/ for test: perf_pmu
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_partial_pwrite_pread
results: parsing output: 68/ for test: gem_userptr_blits
results: parsing output: 69/ for test: i915_pm_rps
results: parsing output: 70/ for test: syncobj_basic
results: parsing output: 71/ for test: sysfs_preempt_timeout
results: parsing output: 72/ for test: gem_pxp
results: parsing output: 73/ for test: gem_exec_reloc
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: syncobj_basic
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: gem_exec_balancer
results: parsing output: 79/ for test: kms_psr2_sf
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: syncobj_wait
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: gem_exec_reloc
results: parsing output: 86/ for test: kms_dsc
results: parsing output: 87/ for test: kms_chamelium_color
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_vblank
results: parsing output: 91/ for test: kms_async_flips
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_plane
results: parsing output: 94/ for test: kms_joiner
results: parsing output: 95/ for test: kms_cursor_legacy
results: parsing output: 96/ for test: kms_chamelium_hpd
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_pipe_stress
results: parsing output: 99/ for test: kms_pm_dc
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: i915_drm_fdinfo
results: parsing output: 102/ for test: kms_psr2_su
results: parsing output: 103/ for test: kms_force_connector_basic
results: parsing output: 104/ for test: kms_flip_scaled_crc
results: parsing output: 105/ for test: gem_tiled_blits
results: parsing output: 106/ for test: gem_userptr_blits
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_exec_fence
results: parsing output: 109/ for test: gem_ccs
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: gem_exec_schedule
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: gem_spin_batch
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_cursor_legacy
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: perf_pmu
results: parsing output: 119/ for test: kms_psr2_sf
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: api_intel_allocator
results: parsing output: 124/ for test: kms_async_flips
results: parsing output: 125/ for test: drm_read
results: parsing output: 126/ for test: kms_lease
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: gem_basic
results: parsing output: 130/ for test: core_auth
results: parsing output: 131/ for test: kms_flip
results: parsing output: 132/ for test: kms_plane_scaling
Done.