[39.539626] Initializing watchdogs
[39.539674]   /dev/watchdog0
[39.545563] [001/130] (960s left) kms_hdmi_inject (inject-audio)
[39.875739] Starting subtest: inject-audio
[39.877374] Subtest inject-audio: SKIP (0.000s)
[40.836695] [002/130] (959s left) syncobj_wait (wait-all-for-submit-snapshot)
[40.871097] Starting subtest: wait-all-for-submit-snapshot
[40.955160] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[41.031626] [003/130] (959s left) syncobj_wait (wait-all-for-submit-complex)
[41.061946] Starting subtest: wait-all-for-submit-complex
[41.278047] Subtest wait-all-for-submit-complex: SUCCESS (0.216s)
[41.346390] [004/130] (958s left) kms_plane (plane-position-hole-dpms)
[41.401931] Starting subtest: plane-position-hole-dpms
[42.863404] Starting dynamic subtest: pipe-A-plane-1
[44.957881] Dynamic subtest pipe-A-plane-1: SUCCESS (2.100s)
[44.969231] Starting dynamic subtest: pipe-A-plane-2
[47.674440] Dynamic subtest pipe-A-plane-2: SUCCESS (2.716s)
[47.686493] Starting dynamic subtest: pipe-A-plane-3
[50.341994] Dynamic subtest pipe-A-plane-3: SUCCESS (2.668s)
[50.354965] Starting dynamic subtest: pipe-A-plane-4
[53.051017] Dynamic subtest pipe-A-plane-4: SUCCESS (2.709s)
[53.057068] Starting dynamic subtest: pipe-A-plane-5
[53.061867] Dynamic subtest pipe-A-plane-5: SUCCESS (0.011s)
[54.390566] Starting dynamic subtest: pipe-B-plane-1
[57.051424] Dynamic subtest pipe-B-plane-1: SUCCESS (2.669s)
[57.077397] Starting dynamic subtest: pipe-B-plane-2
[59.752844] Dynamic subtest pipe-B-plane-2: SUCCESS (2.701s)
[59.765414] Starting dynamic subtest: pipe-B-plane-3
[62.455990] Dynamic subtest pipe-B-plane-3: SUCCESS (2.705s)
[62.467031] Starting dynamic subtest: pipe-B-plane-4
[65.147801] Dynamic subtest pipe-B-plane-4: SUCCESS (2.698s)
[65.159524] Starting dynamic subtest: pipe-B-plane-5
[65.160743] Dynamic subtest pipe-B-plane-5: SUCCESS (0.013s)
[65.164944] Subtest plane-position-hole-dpms: SUCCESS (23.766s)
[66.064920] [005/130] (934s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[66.134566] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-cpu
[71.984948] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (5.850s)
[72.112968] [006/130] (927s left) kms_rotation_crc (sprite-rotation-180)
[72.166556] Starting subtest: sprite-rotation-180
[78.569719] Subtest sprite-rotation-180: SUCCESS (6.409s)
[79.876842] [007/130] (920s left) kms_psr (fbc-psr-sprite-blt)
[79.940850] Starting subtest: fbc-psr-sprite-blt
[79.941652] Starting dynamic subtest: eDP-1
[79.943151] Dynamic subtest eDP-1: SKIP (0.000s)
[79.944541] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[80.010023] [008/130] (920s left) gem_exec_capture (capture)
[80.054822] Starting subtest: capture
[80.055676] Starting dynamic subtest: rcs0-smem
[80.878161] Dynamic subtest rcs0-smem: SUCCESS (0.823s)
[80.885883] Starting dynamic subtest: bcs0-smem
[81.697235] Dynamic subtest bcs0-smem: SUCCESS (0.820s)
[81.703240] Starting dynamic subtest: vcs0-smem
[83.029886] Dynamic subtest vcs0-smem: SUCCESS (1.333s)
[83.035941] Starting dynamic subtest: vecs0-smem
[83.896353] Dynamic subtest vecs0-smem: FAIL (0.849s)
[83.897032] Subtest capture: FAIL (3.826s)
[83.988016] [009/130] (916s left) gem_ctx_persistence (hostile)
[84.028484] Starting subtest: hostile
[85.496756] Subtest hostile: FAIL (1.440s)
[85.585711] [010/130] (914s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[85.649919] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[93.128209] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.482s)
[93.252173] [011/130] (906s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[93.314908] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[96.516792] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.202s)
[96.676123] [012/130] (903s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[96.709623] Starting subtest: cpuset-medium-copy-odd
[103.388737] Subtest cpuset-medium-copy-odd: SUCCESS (6.681s)
[103.504963] [013/130] (896s left) prime_vgem (fence-flip-hang)
[103.630423] Subtest fence-flip-hang: SKIP (0.000s)
[103.719436] [014/130] (896s left) i915_pm_rps (thresholds)
[103.756289] Starting subtest: thresholds
[103.758576] Subtest thresholds: SKIP (0.001s)
[103.836342] [015/130] (896s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[103.909617] Starting subtest: y-tiled-64bpp-rotate-0
[105.912938] Subtest y-tiled-64bpp-rotate-0: SUCCESS (2.004s)
[106.061628] [016/130] (894s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[106.118134] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[106.120219] Starting dynamic subtest: pipe-A-default-mode
[109.230913] Dynamic subtest pipe-A-default-mode: SUCCESS (3.111s)
[109.231735] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (3.113s)
[110.153537] [017/130] (890s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[110.213920] Starting subtest: fbc-pr-sprite-mmap-cpu
[110.215533] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[110.286940] [018/130] (889s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[110.397105] Starting subtest: cursorB-vs-flipA-toggle
[110.401781] Subtest cursorB-vs-flipA-toggle: SKIP (0.004s)
[110.551479] [019/130] (889s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[110.618194] Starting subtest: yf-tiled-32bpp-rotate-90
[110.619634] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[110.700516] [020/130] (889s left) gem_ccs (suspend-resume)
[110.734348] Starting subtest: suspend-resume
[110.736047] Subtest suspend-resume: SKIP (0.000s)
[110.797998] [021/130] (889s left) syncobj_timeline (32bits-limit)
[110.827989] Starting subtest: 32bits-limit
[110.828794] Subtest 32bits-limit: SUCCESS (0.001s)
[110.897412] [022/130] (889s left) kms_psr (psr-cursor-blt)
[110.950915] Starting subtest: psr-cursor-blt
[110.951875] Starting dynamic subtest: eDP-1
[112.902291] Dynamic subtest eDP-1: SUCCESS (1.951s)
[112.903117] Subtest psr-cursor-blt: SUCCESS (1.952s)
[113.863309] [023/130] (886s left) kms_cursor_crc (cursor-onscreen-128x128)
[113.927057] Starting subtest: cursor-onscreen-128x128
[113.931150] Starting dynamic subtest: pipe-A-eDP-1
[116.463649] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.533s)
[116.498501] Starting dynamic subtest: pipe-C-eDP-1
[119.712408] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.216s)
[119.713698] Subtest cursor-onscreen-128x128: SUCCESS (5.781s)
[120.648239] [024/130] (879s left) syncobj_wait (multi-wait-submitted-signaled)
[120.681513] Starting subtest: multi-wait-submitted-signaled
[120.682294] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[120.746230] [025/130] (879s left) gem_tiled_blits (normal)
[120.856048] Starting subtest: normal
[144.237344] Subtest normal: SUCCESS (23.371s)
[144.341753] [026/130] (856s left) api_intel_bb (intel-bb-blit-none)
[144.429195] Starting subtest: intel-bb-blit-none
[148.549215] Subtest intel-bb-blit-none: SUCCESS (4.112s)
[148.653944] [027/130] (851s left) syncobj_wait (signal)
[148.693110] Starting subtest: signal
[148.694033] Subtest signal: SUCCESS (0.000s)
[148.774295] [028/130] (851s left) prime_mmap (test_reprime)
[148.805277] Starting subtest: test_reprime
[148.805999] Starting dynamic subtest: test_reprime-smem
[148.846220] Dynamic subtest test_reprime-smem: SUCCESS (0.042s)
[148.847102] Subtest test_reprime: SUCCESS (0.042s)
[148.917602] [029/130] (851s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[148.990197] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[148.991735] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[149.069995] [030/130] (851s left) kms_chamelium_hpd (vga-hpd-fast)
[149.445815] Subtest vga-hpd-fast: SKIP (0.000s)
[150.366580] [031/130] (850s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[150.483402] Starting subtest: basic-flip-before-cursor-atomic
[151.712427] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.229s)
[152.643962] [032/130] (847s left) prime_mmap_kms (buffer-sharing)
[152.703899] Starting subtest: buffer-sharing
[153.199224] Subtest buffer-sharing: SUCCESS (0.495s)
[154.119133] [033/130] (846s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[157.729400] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[157.733900] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.003s)
[158.663145] [034/130] (841s left) kms_vblank (query-forked-hang)
[158.720977] Starting subtest: query-forked-hang
[158.723243] Starting dynamic subtest: pipe-A-eDP-1
[184.861629] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.137s)
[184.869702] Starting dynamic subtest: pipe-C-eDP-1
[209.886393] Dynamic subtest pipe-C-eDP-1: SUCCESS (25.022s)
[209.887178] Subtest query-forked-hang: SUCCESS (51.164s)
[210.825226] [035/130] (789s left) gem_bad_reloc (negative-reloc-bltcopy)
[210.861731] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[210.901946] [036/130] (789s left) gem_userptr_blits (create-destroy-unsync)
[210.937037] Subtest create-destroy-unsync: SKIP (0.000s)
[211.056319] [037/130] (789s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[214.685103] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[214.690965] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.003s)
[215.621955] [038/130] (784s left) fbdev (nullptr)
[215.680432] Starting subtest: nullptr
[215.681610] Subtest nullptr: SUCCESS (0.000s)
[215.754706] [039/130] (784s left) kms_psr (fbc-psr2-cursor-blt)
[215.816243] Starting subtest: fbc-psr2-cursor-blt
[215.817235] Starting dynamic subtest: eDP-1
[215.818866] Dynamic subtest eDP-1: SKIP (0.000s)
[215.820284] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[215.886574] [040/130] (784s left) core_setmaster (master-drop-set-root)
[216.675604] Starting subtest: master-drop-set-root
[216.686396] Subtest master-drop-set-root: SUCCESS (0.010s)
[216.715410] [041/130] (783s left) i915_pm_rpm (system-suspend-devices)
[218.382226] Starting subtest: system-suspend-devices
[226.045136] Subtest system-suspend-devices: SUCCESS (7.662s)
[226.546996] [042/130] (774s left) kms_feature_discovery (display-1x)
[226.607138] Starting subtest: display-1x
[226.608024] Subtest display-1x: SUCCESS (0.000s)
[226.678676] [043/130] (774s left) dmabuf_sync_file (export-multiwait)
[226.717747] Starting subtest: export-multiwait
[226.719231] Subtest export-multiwait: SUCCESS (0.001s)
[226.780115] [044/130] (773s left) vgem_basic (create)
[226.816911] Starting subtest: create
[226.817766] Subtest create: SUCCESS (0.000s)
[226.876576] [045/130] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[226.936764] Starting subtest: psr-2p-primscrn-cur-indfb-move
[226.938611] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[227.030251] [046/130] (773s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[227.087855] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[227.089484] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[227.205820] [047/130] (773s left) gem_sync (basic-all)
[227.243167] Starting subtest: basic-all
[229.489397] Subtest basic-all: SUCCESS (2.245s)
[229.682482] [048/130] (771s left) kms_joiner (basic-big-joiner)
[229.743420] Starting subtest: basic-big-joiner
[229.745026] Subtest basic-big-joiner: SKIP (0.000s)
[229.813308] [049/130] (770s left) kms_cursor_crc (cursor-random-32x10)
[229.866424] Starting subtest: cursor-random-32x10
[229.921360] Subtest cursor-random-32x10: SKIP (0.053s)
[230.021328] [050/130] (770s left) syncobj_timeline (wait-all-interrupted)
[230.052364] Starting subtest: wait-all-interrupted
[230.155079] Subtest wait-all-interrupted: SUCCESS (0.102s)
[230.234414] [051/130] (770s left) syncobj_timeline (wait-for-submit-complex)
[230.265016] Starting subtest: wait-for-submit-complex
[230.327996] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[230.412545] [052/130] (770s left) kms_plane_scaling (planes-downscale-factor-0-75)
[230.461850] Starting subtest: planes-downscale-factor-0-75
[230.462625] Starting dynamic subtest: pipe-A
[230.583275] Dynamic subtest pipe-A: SUCCESS (0.120s)
[230.603173] Starting dynamic subtest: pipe-B
[231.902196] Dynamic subtest pipe-B: SUCCESS (1.315s)
[231.922403] Starting dynamic subtest: pipe-C
[233.164728] Dynamic subtest pipe-C: SUCCESS (1.264s)
[233.166049] Subtest planes-downscale-factor-0-75: SUCCESS (2.703s)
[234.442470] [053/130] (766s left) kms_async_flips (alternate-sync-async-flip)
[234.507764] Starting subtest: alternate-sync-async-flip
[234.910038] Starting dynamic subtest: pipe-A-eDP-1
[237.770919] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.860s)
[238.196790] Starting dynamic subtest: pipe-B-eDP-1
[241.084272] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.902s)
[241.512123] Starting dynamic subtest: pipe-C-eDP-1
[244.412383] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.918s)
[244.413974] Subtest alternate-sync-async-flip: SUCCESS (9.905s)
[245.769947] [054/130] (755s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[245.833240] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[245.835119] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[245.919633] [055/130] (754s left) kms_hdr (static-swap)
[245.982063] Starting subtest: static-swap
[247.182701] Starting dynamic subtest: pipe-A-eDP-1
[253.485435] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.303s)
[253.487541] Subtest static-swap: SUCCESS (7.505s)
[253.601635] [056/130] (747s left) gem_ppgtt (shrink-vs-evict-any)
[253.640979] Starting subtest: shrink-vs-evict-any
[259.066441] Subtest shrink-vs-evict-any: SUCCESS (5.391s)
[259.120831] [057/130] (741s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[259.188090] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[260.687670] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.499s)
[260.851047] [058/130] (740s left) gem_mmap_gtt (bad-object)
[260.887281] Starting subtest: bad-object
[260.888054] Subtest bad-object: SUCCESS (0.000s)
[260.982089] [059/130] (740s left) kms_cursor_edge_walk (128x128-right-edge)
[261.064661] Starting subtest: 128x128-right-edge
[261.065529] Starting dynamic subtest: pipe-A-eDP-1
[265.668088] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.601s)
[265.680852] Starting dynamic subtest: pipe-C-eDP-1
[270.006709] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.337s)
[270.007996] Subtest 128x128-right-edge: SUCCESS (8.942s)
[270.923126] [060/130] (730s left) gen3_render_tiledx_blits
[270.984451] [061/130] (730s left) gem_spin_batch (user-each)
[271.026262] Starting subtest: user-each
[274.290473] Subtest user-each: SUCCESS (3.258s)
[274.520569] [062/130] (726s left) gem_exec_schedule (implicit-write-read)
[274.565491] Starting subtest: implicit-write-read
[274.566312] Starting dynamic subtest: rcs0
[274.634666] Dynamic subtest rcs0: SUCCESS (0.068s)
[274.651466] Starting dynamic subtest: bcs0
[274.718759] Dynamic subtest bcs0: SUCCESS (0.082s)
[274.733723] Starting dynamic subtest: vcs0
[274.799093] Dynamic subtest vcs0: SUCCESS (0.079s)
[274.813223] Starting dynamic subtest: vecs0
[274.879296] Dynamic subtest vecs0: SUCCESS (0.078s)
[274.880756] Subtest implicit-write-read: SUCCESS (0.314s)
[275.246247] [063/130] (725s left) gem_ctx_isolation (dirty-create)
[275.283310] Starting subtest: dirty-create
[275.284408] Starting dynamic subtest: rcs0
[275.434533] Dynamic subtest rcs0: SUCCESS (0.151s)
[275.444292] Starting dynamic subtest: bcs0
[275.617857] Dynamic subtest bcs0: SUCCESS (0.183s)
[275.627108] Starting dynamic subtest: vcs0
[275.809441] Dynamic subtest vcs0: SUCCESS (0.191s)
[275.819549] Starting dynamic subtest: vecs0
[275.985570] Dynamic subtest vecs0: SUCCESS (0.175s)
[275.986522] Subtest dirty-create: SUCCESS (0.702s)
[276.242171] [064/130] (724s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[276.304880] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[276.306665] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[276.387765] [065/130] (724s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[276.441611] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[276.442646] Starting dynamic subtest: pipe-A-default-mode
[277.786554] Dynamic subtest pipe-A-default-mode: SKIP (1.342s)
[277.788984] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (1.345s)
[277.954834] [066/130] (723s left) kms_chamelium_edid (dp-edid-resolution-list)
[278.325153] Subtest dp-edid-resolution-list: SKIP (0.000s)
[279.203581] [067/130] (721s left) kms_psr (pr-sprite-render)
[279.261994] Starting subtest: pr-sprite-render
[279.263856] Subtest pr-sprite-render: SKIP (0.000s)
[279.343736] [068/130] (721s left) syncobj_wait (invalid-reset-bad-pad)
[279.377633] Starting subtest: invalid-reset-bad-pad
[279.378500] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[279.459302] [069/130] (721s left) i915_hangman (error-state-basic)
[279.500242] Starting subtest: error-state-basic
[279.621896] Subtest error-state-basic: SUCCESS (0.122s)
[279.706715] [070/130] (721s left) perf_pmu (render-node-busy)
[279.744972] Starting subtest: render-node-busy
[279.745840] Starting dynamic subtest: rcs0
[280.320270] Dynamic subtest rcs0: SUCCESS (0.574s)
[280.331904] Starting dynamic subtest: bcs0
[280.882250] Dynamic subtest bcs0: SUCCESS (0.560s)
[280.893235] Starting dynamic subtest: vcs0
[281.432445] Dynamic subtest vcs0: SUCCESS (0.548s)
[281.450783] Starting dynamic subtest: vecs0
[281.992490] Dynamic subtest vecs0: SUCCESS (0.558s)
[281.994369] Subtest render-node-busy: SUCCESS (2.247s)
[282.074782] [071/130] (719s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[282.138944] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[282.140978] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[282.245811] [072/130] (718s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[282.308463] Starting subtest: 4-tiled-32bpp-rotate-180
[282.310035] Subtest 4-tiled-32bpp-rotate-180: SKIP (0.000s)
[282.393091] [073/130] (718s left) kms_flip (2x-plain-flip)
[282.451540] Starting subtest: 2x-plain-flip
[282.455933] Subtest 2x-plain-flip: SKIP (0.004s)
[282.528247] [074/130] (718s left) gem_mmap_gtt (basic-copy)
[282.558421] Starting subtest: basic-copy
[283.460612] Subtest basic-copy: SUCCESS (0.894s)
[283.548548] [075/130] (717s left) gem_exec_schedule (preempt-user)
[283.587666] Starting subtest: preempt-user
[283.588787] Starting dynamic subtest: rcs0
[283.610152] Dynamic subtest rcs0: SUCCESS (0.020s)
[283.622088] Starting dynamic subtest: bcs0
[283.628952] Dynamic subtest bcs0: SUCCESS (0.020s)
[283.644687] Starting dynamic subtest: vcs0
[283.653420] Dynamic subtest vcs0: SUCCESS (0.024s)
[283.665188] Starting dynamic subtest: vecs0
[283.672446] Dynamic subtest vecs0: SUCCESS (0.019s)
[283.673232] Subtest preempt-user: SUCCESS (0.085s)
[284.023070] [076/130] (717s left) gem_exec_balancer (full-late)
[284.065147] Starting subtest: full-late
[285.164259] Subtest full-late: SUCCESS (1.099s)
[286.200024] [077/130] (715s left) gem_exec_reloc (basic-gtt-cpu-active)
[286.240789] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[286.285956] [078/130] (715s left) core_auth (basic-auth)
[286.318485] Starting subtest: basic-auth
[286.319378] Subtest basic-auth: SUCCESS (0.001s)
[286.382353] [079/130] (714s left) gem_workarounds (suspend-resume-fd)
[286.427356] Starting subtest: suspend-resume-fd
[302.497174] Subtest suspend-resume-fd: SUCCESS (2.317s)
[302.587494] [080/130] (712s left) gem_render_copy_redux (normal)
[302.620528] Starting subtest: normal
[303.035907] Subtest normal: SUCCESS (0.415s)
[303.151118] [081/130] (712s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[303.209960] Starting subtest: compare-crc-sanitycheck-xr24
[303.210857] Starting dynamic subtest: pipe-A-eDP-1
[303.969971] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.758s)
[303.992159] Starting dynamic subtest: pipe-B-eDP-1
[305.531261] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.560s)
[305.554206] Starting dynamic subtest: pipe-C-eDP-1
[307.039362] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.507s)
[307.040731] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.831s)
[307.939963] [082/130] (707s left) gem_tiled_partial_pwrite_pread (reads)
[307.974817] Subtest reads: SKIP (0.000s)
[308.020284] [083/130] (707s left) gem_exec_params (dr1-dirt)
[308.052596] Starting subtest: DR1-dirt
[308.053538] Subtest DR1-dirt: SUCCESS (0.000s)
[308.153960] [084/130] (707s left) gen7_exec_parse (cmd-crossing-page)
[308.184941] Subtest cmd-crossing-page: SKIP (0.000s)
[308.224786] [085/130] (707s left) kms_cdclk (mode-transition)
[308.279208] Starting subtest: mode-transition
[308.280152] Starting dynamic subtest: pipe-A-eDP-1
[308.588471] Dynamic subtest pipe-A-eDP-1: SKIP (0.308s)
[308.606531] Starting dynamic subtest: pipe-B-eDP-1
[308.608870] Dynamic subtest pipe-B-eDP-1: SKIP (0.004s)
[308.627685] Starting dynamic subtest: pipe-C-eDP-1
[308.629623] Dynamic subtest pipe-C-eDP-1: SKIP (0.003s)
[308.631943] Subtest mode-transition: SKIP (0.319s)
[309.508514] [086/130] (705s left) gem_exec_whisper (basic-fds-forked)
[309.551760] Starting subtest: basic-fds-forked
[315.624665] Subtest basic-fds-forked: SUCCESS (6.077s)
[315.810863] [087/130] (699s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[315.881762] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[315.884274] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[315.994624] [088/130] (699s left) gem_exec_balancer (bonded-false-hang)
[316.987514] Starting subtest: bonded-false-hang
[316.988967] Subtest bonded-false-hang: SKIP (0.000s)
[317.072790] [089/130] (698s left) gem_exec_fence (basic-busy)
[317.231257] Starting subtest: basic-busy
[317.232091] Starting dynamic subtest: rcs0
[317.284912] Dynamic subtest rcs0: SUCCESS (0.057s)
[317.300338] Starting dynamic subtest: bcs0
[317.333398] Dynamic subtest bcs0: SUCCESS (0.047s)
[317.350869] Starting dynamic subtest: vcs0
[317.384605] Dynamic subtest vcs0: SUCCESS (0.049s)
[317.402720] Starting dynamic subtest: vecs0
[317.432775] Dynamic subtest vecs0: SUCCESS (0.046s)
[317.434476] Subtest basic-busy: SUCCESS (0.206s)
[317.931125] [090/130] (697s left) gem_reg_read (bad-register)
[317.962712] Starting subtest: bad-register
[317.963502] Subtest bad-register: SUCCESS (0.000s)
[318.039565] [091/130] (697s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[318.099923] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[318.101699] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[318.195064] [092/130] (697s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[318.249677] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[318.250516] Starting dynamic subtest: pipe-A-default-mode
[318.565390] Dynamic subtest pipe-A-default-mode: SKIP (0.313s)
[318.566900] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.315s)
[319.493395] [093/130] (695s left) perf (create-destroy-userspace-config)
[319.596936] Starting subtest: create-destroy-userspace-config
[319.613735] Subtest create-destroy-userspace-config: SUCCESS (0.017s)
[319.642759] [094/130] (695s left) kms_pm_backlight (fade-with-dpms)
[319.698781] Starting subtest: fade-with-dpms
[321.918782] Starting dynamic subtest: eDP-1
[324.554115] Dynamic subtest eDP-1: SUCCESS (2.638s)
[324.554993] Subtest fade-with-dpms: SUCCESS (4.861s)
[325.479631] [095/130] (689s left) gem_spin_batch (engines)
[325.521095] Starting subtest: engines
[325.522027] Starting dynamic subtest: rcs0
[328.764823] Dynamic subtest rcs0: SUCCESS (3.235s)
[328.787990] Starting dynamic subtest: bcs0
[332.027797] Dynamic subtest bcs0: SUCCESS (3.237s)
[332.040135] Starting dynamic subtest: vcs0
[335.231206] Dynamic subtest vcs0: SUCCESS (3.237s)
[335.251303] Starting dynamic subtest: vecs0
[338.476965] Dynamic subtest vecs0: SUCCESS (3.237s)
[338.478846] Subtest engines: SUCCESS (12.954s)
[338.697368] [096/130] (676s left) kms_vrr (flip-basic)
[338.761351] Starting subtest: flip-basic
[338.763951] Subtest flip-basic: SKIP (0.000s)
[338.849380] [097/130] (676s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[338.949843] Starting subtest: short-busy-flip-before-cursor-toggle
[338.952471] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[339.093134] [098/130] (676s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[339.200604] Starting subtest: cursorA-vs-flipA-legacy
[350.321451] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.125s)
[351.236910] [099/130] (664s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[351.273401] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[351.274317] Starting dynamic subtest: smem
[351.368556] Dynamic subtest smem: SUCCESS (0.095s)
[351.369414] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SUCCESS (0.096s)
[351.604322] [100/130] (663s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[351.660503] Starting subtest: crc-primary-basic-y-tiled-ccs
[351.663544] Starting dynamic subtest: pipe-A-eDP-1
[351.672489] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[351.682978] Starting dynamic subtest: pipe-B-eDP-1
[351.689758] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[351.698569] Starting dynamic subtest: pipe-C-eDP-1
[351.705335] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[351.706622] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.008s)
[351.733157] [101/130] (663s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[351.790266] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[351.791685] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[351.891067] [102/130] (663s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[351.957716] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[353.377696] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.421s)
[353.507291] [103/130] (662s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[353.567747] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[353.569629] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[353.656923] [104/130] (661s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[353.714934] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[353.715932] Starting dynamic subtest: pipe-A-eDP-1
[353.724972] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[353.734649] Starting dynamic subtest: pipe-B-eDP-1
[353.742316] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[353.754990] Starting dynamic subtest: pipe-C-eDP-1
[353.762351] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[353.763833] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.006s)
[353.779436] [105/130] (661s left) perf_pmu (busy-double-start)
[353.808334] Starting subtest: busy-double-start
[353.809215] Starting dynamic subtest: rcs0
[357.418478] Dynamic subtest rcs0: FAIL (3.587s)
[357.434582] Starting dynamic subtest: bcs0
[360.953810] Dynamic subtest bcs0: SUCCESS (3.559s)
[360.965832] Starting dynamic subtest: vcs0
[364.551013] Dynamic subtest vcs0: FAIL (3.559s)
[364.566322] Starting dynamic subtest: vecs0
[368.109204] Dynamic subtest vecs0: FAIL (3.556s)
[368.110569] Subtest busy-double-start: FAIL (14.265s)
[368.189992] [106/130] (647s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[368.246134] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[368.248395] Starting dynamic subtest: pipe-A-eDP-1
[368.256085] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[368.265752] Starting dynamic subtest: pipe-B-eDP-1
[368.272761] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[368.282375] Starting dynamic subtest: pipe-C-eDP-1
[368.289496] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[368.290902] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.005s)
[368.316292] [107/130] (647s left) gem_mmap_gtt (basic)
[368.346385] Starting subtest: basic
[368.347369] Subtest basic: SUCCESS (0.001s)
[368.429487] [108/130] (647s left) gem_exec_params (sol-reset-not-gen7)
[368.463642] Starting subtest: sol-reset-not-gen7
[368.466729] Subtest sol-reset-not-gen7: SUCCESS (0.002s)
[368.573183] [109/130] (647s left) kms_prop_blob (basic)
[368.601518] Starting subtest: basic
[368.602263] Subtest basic: SUCCESS (0.000s)
[368.673946] [110/130] (646s left) perf_pmu (semaphore-wait-idle)
[368.708331] Starting subtest: semaphore-wait-idle
[368.709235] Starting dynamic subtest: rcs0
[369.227965] Dynamic subtest rcs0: SUCCESS (0.510s)
[369.250382] Starting dynamic subtest: bcs0
[369.738956] Dynamic subtest bcs0: SUCCESS (0.509s)
[369.751749] Starting dynamic subtest: vcs0
[370.250372] Dynamic subtest vcs0: SUCCESS (0.509s)
[370.262318] Starting dynamic subtest: vecs0
[370.761170] Dynamic subtest vecs0: SUCCESS (0.509s)
[370.762927] Subtest semaphore-wait-idle: SUCCESS (2.045s)
[370.874165] [111/130] (644s left) gem_exec_fence (syncobj-timeline-signal)
[371.291562] Starting subtest: syncobj-timeline-signal
[371.299726] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[371.545898] [112/130] (644s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[371.610032] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[371.611718] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[371.698936] [113/130] (644s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[371.751857] Starting subtest: sprite-rotation-90-pos-100-0
[371.753699] Subtest sprite-rotation-90-pos-100-0: SKIP (0.002s)
[371.832105] [114/130] (643s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[374.393276] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[374.469975] [115/130] (641s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[374.525814] Starting subtest: multiplane-rotation-cropping-bottom
[385.096667] Subtest multiplane-rotation-cropping-bottom: SUCCESS (10.577s)
[385.229913] [116/130] (630s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[385.264276] Starting subtest: invalid-single-wait-unsubmitted
[385.265297] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[385.343386] [117/130] (630s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[385.402316] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[388.792716] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (3.391s)
[388.922596] [118/130] (626s left) syncobj_timeline (multi-wait-all-signaled)
[388.957871] Starting subtest: multi-wait-all-signaled
[388.958742] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[389.034052] [119/130] (626s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[389.146080] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[399.108154] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.966s)
[400.092647] [120/130] (615s left) kms_flip (basic-flip-vs-modeset)
[400.157145] Starting subtest: basic-flip-vs-modeset
[400.159435] Starting dynamic subtest: A-eDP1
[402.976471] Dynamic subtest A-eDP1: SUCCESS (2.817s)
[402.997963] Starting dynamic subtest: B-eDP1
[405.411740] Dynamic subtest B-eDP1: SUCCESS (2.427s)
[405.432223] Starting dynamic subtest: C-eDP1
[407.809403] Dynamic subtest C-eDP1: SUCCESS (2.388s)
[407.810753] Subtest basic-flip-vs-modeset: SUCCESS (7.638s)
[408.775165] [121/130] (607s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[408.878063] Starting subtest: flip-vs-cursor-crc-atomic
[409.777851] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.894s)
[410.709392] [122/130] (605s left) gem_exec_whisper (basic-queues-all)
[410.754774] Starting subtest: basic-queues-all
[416.415396] Subtest basic-queues-all: SUCCESS (5.650s)
[416.612810] [123/130] (599s left) prime_mmap (test_forked_cpu_write)
[416.646897] Starting subtest: test_forked_cpu_write
[416.647727] Starting dynamic subtest: test_forked_cpu_write-smem
[416.654170] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.008s)
[416.655116] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[416.745209] [124/130] (599s left) kms_color (invalid-ctm-matrix-sizes)
[416.801421] Starting subtest: invalid-ctm-matrix-sizes
[416.802448] Starting dynamic subtest: pipe-A
[416.806002] Dynamic subtest pipe-A: SUCCESS (0.004s)
[416.816130] Starting dynamic subtest: pipe-B
[416.816961] Dynamic subtest pipe-B: SUCCESS (0.005s)
[416.826516] Starting dynamic subtest: pipe-C
[416.827443] Dynamic subtest pipe-C: SUCCESS (0.006s)
[416.828315] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.016s)
[416.892773] [125/130] (599s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[416.955947] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[416.957554] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[417.042746] [126/130] (598s left) kms_pm_backlight (basic-brightness)
[417.100170] Starting subtest: basic-brightness
[417.151555] Starting dynamic subtest: eDP-1
[417.516956] Dynamic subtest eDP-1: SUCCESS (0.367s)
[417.517821] Subtest basic-brightness: SUCCESS (0.417s)
[418.434256] [127/130] (597s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[418.500863] Starting subtest: extended-pageflip-modeset-hang-oldfb
[418.501721] Starting dynamic subtest: pipe-A
[439.209516] Dynamic subtest pipe-A: SUCCESS (20.695s)
[439.232722] Starting dynamic subtest: pipe-C
[447.947780] Dynamic subtest pipe-C: SUCCESS (8.733s)
[447.948622] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (29.435s)
[448.901053] [128/130] (567s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[448.957133] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[452.587474] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (3.628s)
[452.715423] [129/130] (563s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[452.747769] Starting subtest: etime-single-wait-all-available-unsubmitted
[452.851826] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.103s)
[452.934895] [130/130] (563s left) gem_ctx_persistence (engines-hostile-preempt)
[452.966365] Starting subtest: engines-hostile-preempt
[452.967259] Starting dynamic subtest: rcs0
[453.003002] Dynamic subtest rcs0: SUCCESS (0.036s)
[453.021186] Starting dynamic subtest: bcs0
[453.051366] Dynamic subtest bcs0: SUCCESS (0.047s)
[453.071473] Starting dynamic subtest: vcs0
[453.096369] Dynamic subtest vcs0: SUCCESS (0.043s)
[453.114642] Starting dynamic subtest: vecs0
[453.138372] Dynamic subtest vecs0: SUCCESS (0.040s)
[453.139925] Subtest engines-hostile-preempt: SUCCESS (0.172s)
[453.225336] Closing watchdogs
Done.