[65.208387] Initializing watchdogs
[65.208437]   /dev/watchdog0
[65.213989] [001/130] (960s left) kms_hdmi_inject (inject-audio)
[65.554554] Starting subtest: inject-audio
[65.556776] Subtest inject-audio: SKIP (0.000s)
[66.475585] [002/130] (959s left) syncobj_wait (wait-all-for-submit-snapshot)
[66.506341] Starting subtest: wait-all-for-submit-snapshot
[66.590629] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[66.640700] [003/130] (959s left) syncobj_wait (wait-all-for-submit-complex)
[66.676161] Starting subtest: wait-all-for-submit-complex
[66.903652] Subtest wait-all-for-submit-complex: SUCCESS (0.218s)
[66.944251] [004/130] (958s left) kms_plane (plane-position-hole-dpms)
[66.997711] Starting subtest: plane-position-hole-dpms
[67.507173] Starting dynamic subtest: pipe-A-plane-1
[70.113291] Dynamic subtest pipe-A-plane-1: SUCCESS (2.607s)
[70.126265] Starting dynamic subtest: pipe-A-plane-2
[72.730981] Dynamic subtest pipe-A-plane-2: SUCCESS (2.617s)
[72.745027] Starting dynamic subtest: pipe-A-plane-3
[75.293647] Dynamic subtest pipe-A-plane-3: SUCCESS (2.563s)
[75.303538] Starting dynamic subtest: pipe-A-plane-4
[77.858727] Dynamic subtest pipe-A-plane-4: SUCCESS (2.565s)
[77.870521] Starting dynamic subtest: pipe-A-plane-5
[77.871345] Dynamic subtest pipe-A-plane-5: SUCCESS (0.006s)
[79.162330] Starting dynamic subtest: pipe-B-plane-1
[81.702046] Dynamic subtest pipe-B-plane-1: SUCCESS (2.548s)
[81.713349] Starting dynamic subtest: pipe-B-plane-2
[84.254570] Dynamic subtest pipe-B-plane-2: SUCCESS (2.552s)
[84.260284] Starting dynamic subtest: pipe-B-plane-3
[86.868403] Dynamic subtest pipe-B-plane-3: SUCCESS (2.615s)
[86.880217] Starting dynamic subtest: pipe-B-plane-4
[89.441118] Dynamic subtest pipe-B-plane-4: SUCCESS (2.572s)
[89.453723] Starting dynamic subtest: pipe-B-plane-5
[89.454813] Dynamic subtest pipe-B-plane-5: SUCCESS (0.007s)
[89.455816] Subtest plane-position-hole-dpms: SUCCESS (22.450s)
[90.349626] [005/130] (935s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[90.418911] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-cpu
[96.814047] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (6.394s)
[96.912815] [006/130] (928s left) kms_rotation_crc (sprite-rotation-180)
[96.975302] Starting subtest: sprite-rotation-180
[100.922689] Subtest sprite-rotation-180: SUCCESS (3.947s)
[102.189136] [007/130] (923s left) kms_psr (fbc-psr-sprite-blt)
[102.250769] Starting subtest: fbc-psr-sprite-blt
[102.251681] Starting dynamic subtest: eDP-1
[102.253397] Dynamic subtest eDP-1: SKIP (0.000s)
[102.254767] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[102.298970] [008/130] (923s left) gem_exec_capture (capture)
[102.337369] Starting subtest: capture
[102.338391] Starting dynamic subtest: rcs0-smem
[103.152846] Dynamic subtest rcs0-smem: SUCCESS (0.815s)
[103.158726] Starting dynamic subtest: bcs0-smem
[103.973450] Dynamic subtest bcs0-smem: SUCCESS (0.820s)
[103.979904] Starting dynamic subtest: vcs0-smem
[104.795963] Dynamic subtest vcs0-smem: SUCCESS (0.822s)
[104.801904] Starting dynamic subtest: vecs0-smem
[105.662947] Dynamic subtest vecs0-smem: FAIL (0.845s)
[105.663666] Subtest capture: FAIL (3.303s)
[105.735676] [009/130] (920s left) gem_ctx_persistence (hostile)
[105.770468] Starting subtest: hostile
[107.257320] Subtest hostile: FAIL (1.455s)
[107.319899] [010/130] (918s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[107.386434] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[114.344410] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (6.958s)
[114.428057] [011/130] (911s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[114.489167] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[117.886894] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.397s)
[119.170058] [012/130] (906s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[119.203596] Starting subtest: cpuset-medium-copy-odd
[125.867543] Subtest cpuset-medium-copy-odd: SUCCESS (6.664s)
[125.921519] [013/130] (900s left) prime_vgem (fence-flip-hang)
[126.055230] Subtest fence-flip-hang: SKIP (0.000s)
[126.116936] [014/130] (899s left) i915_pm_rps (thresholds)
[126.157396] Starting subtest: thresholds
[126.160031] Subtest thresholds: SKIP (0.001s)
[126.246362] [015/130] (899s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[126.317629] Starting subtest: y-tiled-64bpp-rotate-0
[127.835538] Subtest y-tiled-64bpp-rotate-0: SUCCESS (1.518s)
[127.990180] [016/130] (897s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[128.044850] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[128.046642] Starting dynamic subtest: pipe-A-default-mode
[130.943686] Dynamic subtest pipe-A-default-mode: SUCCESS (2.896s)
[130.944968] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (2.898s)
[131.830028] [017/130] (894s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[131.892903] Starting subtest: fbc-pr-sprite-mmap-cpu
[131.894632] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[131.943102] [018/130] (894s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[132.029275] Starting subtest: cursorB-vs-flipA-toggle
[132.032237] Subtest cursorB-vs-flipA-toggle: SKIP (0.002s)
[132.123362] [019/130] (893s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[132.186413] Starting subtest: yf-tiled-32bpp-rotate-90
[132.187830] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[132.266697] [020/130] (893s left) gem_ccs (suspend-resume)
[132.301813] Starting subtest: suspend-resume
[132.303382] Subtest suspend-resume: SKIP (0.000s)
[132.347120] [021/130] (893s left) syncobj_timeline (32bits-limit)
[132.380987] Starting subtest: 32bits-limit
[132.381829] Subtest 32bits-limit: SUCCESS (0.001s)
[132.426881] [022/130] (893s left) kms_psr (psr-cursor-blt)
[132.484761] Starting subtest: psr-cursor-blt
[132.485957] Starting dynamic subtest: eDP-1
[134.251138] Dynamic subtest eDP-1: SUCCESS (1.766s)
[134.252413] Subtest psr-cursor-blt: SUCCESS (1.767s)
[135.152183] [023/130] (890s left) kms_cursor_crc (cursor-onscreen-128x128)
[135.216348] Starting subtest: cursor-onscreen-128x128
[135.223353] Starting dynamic subtest: pipe-A-eDP-1
[136.656305] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.434s)
[136.710817] Starting dynamic subtest: pipe-C-eDP-1
[138.890349] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.185s)
[138.891198] Subtest cursor-onscreen-128x128: SUCCESS (3.673s)
[139.825308] [024/130] (886s left) syncobj_wait (multi-wait-submitted-signaled)
[139.862789] Starting subtest: multi-wait-submitted-signaled
[139.863582] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[139.908287] [025/130] (886s left) gem_tiled_blits (normal)
[140.019159] Starting subtest: normal
[165.436559] Subtest normal: SUCCESS (25.408s)
[165.484202] [026/130] (860s left) api_intel_bb (intel-bb-blit-none)
[165.561403] Starting subtest: intel-bb-blit-none
[169.811638] Subtest intel-bb-blit-none: SUCCESS (4.242s)
[169.912938] [027/130] (856s left) syncobj_wait (signal)
[169.955283] Starting subtest: signal
[169.956535] Subtest signal: SUCCESS (0.000s)
[170.010618] [028/130] (856s left) prime_mmap (test_reprime)
[170.042350] Starting subtest: test_reprime
[170.043063] Starting dynamic subtest: test_reprime-smem
[170.113525] Dynamic subtest test_reprime-smem: SUCCESS (0.072s)
[170.114330] Subtest test_reprime: SUCCESS (0.072s)
[170.189089] [029/130] (856s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[170.256023] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[170.257583] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[170.332326] [030/130] (855s left) kms_chamelium_hpd (vga-hpd-fast)
[170.678890] Subtest vga-hpd-fast: SKIP (0.000s)
[171.546901] [031/130] (854s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[171.631681] Starting subtest: basic-flip-before-cursor-atomic
[172.384370] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.752s)
[173.296111] [032/130] (852s left) prime_mmap_kms (buffer-sharing)
[173.352972] Starting subtest: buffer-sharing
[173.744610] Subtest buffer-sharing: SUCCESS (0.388s)
[174.643722] [033/130] (851s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[178.122943] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[178.131587] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.006s)
[179.057064] [034/130] (847s left) kms_vblank (query-forked-hang)
[179.117029] Starting subtest: query-forked-hang
[179.119019] Starting dynamic subtest: pipe-A-eDP-1
[205.849863] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.730s)
[205.863696] Starting dynamic subtest: pipe-C-eDP-1
[230.877898] Dynamic subtest pipe-C-eDP-1: SUCCESS (25.023s)
[230.879225] Subtest query-forked-hang: SUCCESS (51.758s)
[231.791300] [035/130] (794s left) gem_bad_reloc (negative-reloc-bltcopy)
[231.830593] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[231.880024] [036/130] (794s left) gem_userptr_blits (create-destroy-unsync)
[231.915876] Subtest create-destroy-unsync: SKIP (0.000s)
[232.015775] [037/130] (794s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[235.471791] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[235.480360] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.004s)
[236.402467] [038/130] (789s left) fbdev (nullptr)
[236.447769] Starting subtest: nullptr
[236.449028] Subtest nullptr: SUCCESS (0.000s)
[236.495655] [039/130] (789s left) kms_psr (fbc-psr2-cursor-blt)
[236.552054] Starting subtest: fbc-psr2-cursor-blt
[236.553229] Starting dynamic subtest: eDP-1
[236.555407] Dynamic subtest eDP-1: SKIP (0.000s)
[236.556881] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[236.605021] [040/130] (789s left) core_setmaster (master-drop-set-root)
[237.363982] Starting subtest: master-drop-set-root
[237.378396] Subtest master-drop-set-root: SUCCESS (0.010s)
[237.400282] [041/130] (789s left) i915_pm_rpm (system-suspend-devices)
[239.058244] Starting subtest: system-suspend-devices
[246.667072] Subtest system-suspend-devices: SUCCESS (7.608s)
[247.086758] [042/130] (779s left) kms_feature_discovery (display-1x)
[247.146078] Starting subtest: display-1x
[247.146937] Subtest display-1x: SUCCESS (0.000s)
[247.205783] [043/130] (779s left) dmabuf_sync_file (export-multiwait)
[247.243538] Starting subtest: export-multiwait
[247.244897] Subtest export-multiwait: SUCCESS (0.001s)
[247.278265] [044/130] (779s left) vgem_basic (create)
[247.318612] Starting subtest: create
[247.319428] Subtest create: SUCCESS (0.000s)
[247.359822] [045/130] (779s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[247.415509] Starting subtest: psr-2p-primscrn-cur-indfb-move
[247.417440] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[247.493210] [046/130] (779s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[247.555054] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[247.557206] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[247.620978] [047/130] (778s left) gem_sync (basic-all)
[247.660503] Starting subtest: basic-all
[249.790469] Subtest basic-all: SUCCESS (2.129s)
[249.959445] [048/130] (776s left) kms_joiner (basic-big-joiner)
[250.015497] Starting subtest: basic-big-joiner
[250.017042] Subtest basic-big-joiner: SKIP (0.000s)
[250.067222] [049/130] (776s left) kms_cursor_crc (cursor-random-32x10)
[250.125151] Starting subtest: cursor-random-32x10
[250.185486] Subtest cursor-random-32x10: SKIP (0.059s)
[250.250333] [050/130] (776s left) syncobj_timeline (wait-all-interrupted)
[250.285489] Starting subtest: wait-all-interrupted
[250.401037] Subtest wait-all-interrupted: SUCCESS (0.107s)
[250.449377] [051/130] (776s left) syncobj_timeline (wait-for-submit-complex)
[250.482216] Starting subtest: wait-for-submit-complex
[250.546961] Subtest wait-for-submit-complex: SUCCESS (0.064s)
[250.600966] [052/130] (776s left) kms_plane_scaling (planes-downscale-factor-0-75)
[250.655833] Starting subtest: planes-downscale-factor-0-75
[250.656582] Starting dynamic subtest: pipe-A
[251.886048] Dynamic subtest pipe-A: SUCCESS (1.229s)
[251.906467] Starting dynamic subtest: pipe-B
[253.164982] Dynamic subtest pipe-B: SUCCESS (1.278s)
[253.184862] Starting dynamic subtest: pipe-C
[254.436934] Dynamic subtest pipe-C: SUCCESS (1.271s)
[254.438409] Subtest planes-downscale-factor-0-75: SUCCESS (3.781s)
[255.727810] [053/130] (770s left) kms_async_flips (alternate-sync-async-flip)
[255.790607] Starting subtest: alternate-sync-async-flip
[256.154493] Starting dynamic subtest: pipe-A-eDP-1
[259.033294] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.879s)
[259.429565] Starting dynamic subtest: pipe-B-eDP-1
[262.298773] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.886s)
[262.693362] Starting dynamic subtest: pipe-C-eDP-1
[265.562571] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.887s)
[265.563584] Subtest alternate-sync-async-flip: SUCCESS (9.773s)
[266.863040] [054/130] (759s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[266.931067] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[266.932810] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[267.002758] [055/130] (759s left) kms_hdr (static-swap)
[267.052499] Starting subtest: static-swap
[267.055365] Subtest static-swap: SKIP (0.001s)
[267.105612] [056/130] (759s left) gem_ppgtt (shrink-vs-evict-any)
[267.138724] Starting subtest: shrink-vs-evict-any
[274.991391] Subtest shrink-vs-evict-any: SUCCESS (7.843s)
[275.041637] [057/130] (751s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[275.116435] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[276.137238] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.020s)
[276.250719] [058/130] (750s left) gem_mmap_gtt (bad-object)
[276.284231] Starting subtest: bad-object
[276.285257] Subtest bad-object: SUCCESS (0.000s)
[276.345953] [059/130] (750s left) kms_cursor_edge_walk (128x128-right-edge)
[276.403144] Starting subtest: 128x128-right-edge
[276.405799] Starting dynamic subtest: pipe-A-eDP-1
[279.466909] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.061s)
[279.480381] Starting dynamic subtest: pipe-C-eDP-1
[282.200148] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.731s)
[282.201424] Subtest 128x128-right-edge: SUCCESS (5.796s)
[283.124111] [060/130] (743s left) gen3_render_tiledx_blits
[283.190539] [061/130] (743s left) gem_spin_batch (user-each)
[283.227150] Starting subtest: user-each
[286.489460] Subtest user-each: SUCCESS (3.256s)
[286.694858] [062/130] (740s left) gem_exec_schedule (implicit-write-read)
[286.736375] Starting subtest: implicit-write-read
[286.737430] Starting dynamic subtest: rcs0
[286.863520] Dynamic subtest rcs0: SUCCESS (0.126s)
[286.878805] Starting dynamic subtest: bcs0
[286.956222] Dynamic subtest bcs0: SUCCESS (0.091s)
[286.971334] Starting dynamic subtest: vcs0
[287.048203] Dynamic subtest vcs0: SUCCESS (0.091s)
[287.062507] Starting dynamic subtest: vecs0
[287.139807] Dynamic subtest vecs0: SUCCESS (0.090s)
[287.141206] Subtest implicit-write-read: SUCCESS (0.404s)
[287.481825] [063/130] (739s left) gem_ctx_isolation (dirty-create)
[287.523195] Starting subtest: dirty-create
[287.524281] Starting dynamic subtest: rcs0
[287.693796] Dynamic subtest rcs0: SUCCESS (0.171s)
[287.706310] Starting dynamic subtest: bcs0
[287.912390] Dynamic subtest bcs0: SUCCESS (0.214s)
[287.921694] Starting dynamic subtest: vcs0
[288.113324] Dynamic subtest vcs0: SUCCESS (0.204s)
[288.122693] Starting dynamic subtest: vecs0
[288.322662] Dynamic subtest vecs0: SUCCESS (0.209s)
[288.323458] Subtest dirty-create: SUCCESS (0.800s)
[288.537717] [064/130] (738s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[288.602809] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[288.604369] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[288.678948] [065/130] (738s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[288.733100] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[288.733959] Starting dynamic subtest: pipe-A-default-mode
[294.003484] Dynamic subtest pipe-A-default-mode: SUCCESS (5.266s)
[294.004842] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SUCCESS (5.268s)
[294.882021] [066/130] (732s left) kms_chamelium_edid (dp-edid-resolution-list)
[295.255817] Subtest dp-edid-resolution-list: SKIP (0.000s)
[296.148913] [067/130] (730s left) kms_psr (pr-sprite-render)
[296.211376] Starting subtest: pr-sprite-render
[296.213297] Subtest pr-sprite-render: SKIP (0.000s)
[296.263620] [068/130] (730s left) syncobj_wait (invalid-reset-bad-pad)
[296.296288] Starting subtest: invalid-reset-bad-pad
[296.297087] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[296.347578] [069/130] (730s left) i915_hangman (error-state-basic)
[296.381231] Starting subtest: error-state-basic
[296.507777] Subtest error-state-basic: SUCCESS (0.128s)
[296.563026] [070/130] (730s left) perf_pmu (render-node-busy)
[296.599443] Starting subtest: render-node-busy
[296.600427] Starting dynamic subtest: rcs0
[297.173341] Dynamic subtest rcs0: SUCCESS (0.573s)
[297.190524] Starting dynamic subtest: bcs0
[297.730306] Dynamic subtest bcs0: SUCCESS (0.555s)
[297.748899] Starting dynamic subtest: vcs0
[298.284291] Dynamic subtest vcs0: SUCCESS (0.552s)
[298.294586] Starting dynamic subtest: vecs0
[298.840369] Dynamic subtest vecs0: SUCCESS (0.554s)
[298.842266] Subtest render-node-busy: SUCCESS (2.242s)
[298.898014] [071/130] (728s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[298.962973] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[298.964518] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[299.032195] [072/130] (728s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[299.092594] Starting subtest: 4-tiled-32bpp-rotate-180
[299.094242] Subtest 4-tiled-32bpp-rotate-180: SKIP (0.000s)
[299.166372] [073/130] (727s left) kms_flip (2x-plain-flip)
[299.224679] Starting subtest: 2x-plain-flip
[299.228775] Subtest 2x-plain-flip: SKIP (0.005s)
[299.288906] [074/130] (727s left) gem_mmap_gtt (basic-copy)
[299.319509] Starting subtest: basic-copy
[300.214641] Subtest basic-copy: SUCCESS (0.888s)
[300.271396] [075/130] (726s left) gem_exec_schedule (preempt-user)
[300.313595] Starting subtest: preempt-user
[300.314391] Starting dynamic subtest: rcs0
[300.332049] Dynamic subtest rcs0: SUCCESS (0.019s)
[300.342793] Starting dynamic subtest: bcs0
[300.349406] Dynamic subtest bcs0: SUCCESS (0.017s)
[300.358688] Starting dynamic subtest: vcs0
[300.366764] Dynamic subtest vcs0: SUCCESS (0.017s)
[300.376625] Starting dynamic subtest: vecs0
[300.384144] Dynamic subtest vecs0: SUCCESS (0.017s)
[300.384890] Subtest preempt-user: SUCCESS (0.073s)
[300.708525] [076/130] (726s left) gem_exec_balancer (full-late)
[300.743326] Starting subtest: full-late
[301.859312] Subtest full-late: SUCCESS (1.116s)
[302.869963] [077/130] (724s left) gem_exec_reloc (basic-gtt-cpu-active)
[302.907251] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[302.973153] [078/130] (724s left) core_auth (basic-auth)
[303.000353] Starting subtest: basic-auth
[303.002307] Subtest basic-auth: SUCCESS (0.002s)
[303.051613] [079/130] (724s left) gem_workarounds (suspend-resume-fd)
[303.094754] Starting subtest: suspend-resume-fd
[318.949883] Subtest suspend-resume-fd: SUCCESS (1.876s)
[319.021124] [080/130] (722s left) gem_render_copy_redux (normal)
[319.056561] Starting subtest: normal
[319.470969] Subtest normal: SUCCESS (0.414s)
[319.547078] [081/130] (721s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[319.600242] Starting subtest: compare-crc-sanitycheck-xr24
[319.601266] Starting dynamic subtest: pipe-A-eDP-1
[320.174846] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.574s)
[320.191914] Starting dynamic subtest: pipe-B-eDP-1
[321.585512] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.410s)
[321.596270] Starting dynamic subtest: pipe-C-eDP-1
[322.967183] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.380s)
[322.968385] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.368s)
[323.913267] [082/130] (717s left) gem_tiled_partial_pwrite_pread (reads)
[323.957082] Subtest reads: SKIP (0.000s)
[324.028283] [083/130] (717s left) gem_exec_params (dr1-dirt)
[324.064524] Starting subtest: DR1-dirt
[324.065403] Subtest DR1-dirt: SUCCESS (0.000s)
[324.132082] [084/130] (717s left) gen7_exec_parse (cmd-crossing-page)
[324.166704] Subtest cmd-crossing-page: SKIP (0.000s)
[324.187500] [085/130] (717s left) kms_cdclk (mode-transition)
[324.240753] Starting subtest: mode-transition
[324.241727] Starting dynamic subtest: pipe-A-eDP-1
[324.538100] Dynamic subtest pipe-A-eDP-1: SKIP (0.294s)
[324.556706] Starting dynamic subtest: pipe-B-eDP-1
[324.558593] Dynamic subtest pipe-B-eDP-1: SKIP (0.004s)
[324.577323] Starting dynamic subtest: pipe-C-eDP-1
[324.579102] Dynamic subtest pipe-C-eDP-1: SKIP (0.003s)
[324.582800] Subtest mode-transition: SKIP (0.306s)
[325.451156] [086/130] (715s left) gem_exec_whisper (basic-fds-forked)
[325.492933] Starting subtest: basic-fds-forked
[333.009481] Subtest basic-fds-forked: SUCCESS (7.506s)
[333.171014] [087/130] (708s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[333.239013] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[333.240525] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[333.307639] [088/130] (708s left) gem_exec_balancer (bonded-false-hang)
[334.307027] Starting subtest: bonded-false-hang
[334.308568] Subtest bonded-false-hang: SKIP (0.000s)
[334.365590] [089/130] (707s left) gem_exec_fence (basic-busy)
[334.522862] Starting subtest: basic-busy
[334.524097] Starting dynamic subtest: rcs0
[334.567286] Dynamic subtest rcs0: SUCCESS (0.048s)
[334.587205] Starting dynamic subtest: bcs0
[334.613174] Dynamic subtest bcs0: SUCCESS (0.044s)
[334.630469] Starting dynamic subtest: vcs0
[334.663344] Dynamic subtest vcs0: SUCCESS (0.048s)
[334.684625] Starting dynamic subtest: vecs0
[334.711025] Dynamic subtest vecs0: SUCCESS (0.046s)
[334.712703] Subtest basic-busy: SUCCESS (0.193s)
[335.172876] [090/130] (706s left) gem_reg_read (bad-register)
[335.203894] Starting subtest: bad-register
[335.204739] Subtest bad-register: SUCCESS (0.000s)
[335.256434] [091/130] (706s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[335.312939] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[335.314691] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[335.391697] [092/130] (706s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[335.446538] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[335.447584] Starting dynamic subtest: pipe-A-default-mode
[335.753192] Dynamic subtest pipe-A-default-mode: SKIP (0.305s)
[335.755295] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.307s)
[336.651333] [093/130] (704s left) perf (create-destroy-userspace-config)
[336.744975] Starting subtest: create-destroy-userspace-config
[336.763515] Subtest create-destroy-userspace-config: SUCCESS (0.018s)
[336.782329] [094/130] (704s left) kms_pm_backlight (fade-with-dpms)
[336.835990] Starting subtest: fade-with-dpms
[338.992387] Starting dynamic subtest: eDP-1
[341.700639] Dynamic subtest eDP-1: SUCCESS (2.708s)
[341.701667] Subtest fade-with-dpms: SUCCESS (4.865s)
[342.587148] [095/130] (698s left) gem_spin_batch (engines)
[342.629582] Starting subtest: engines
[342.630678] Starting dynamic subtest: rcs0
[345.874863] Dynamic subtest rcs0: SUCCESS (3.234s)
[345.886677] Starting dynamic subtest: bcs0
[349.136865] Dynamic subtest bcs0: SUCCESS (3.235s)
[349.149684] Starting dynamic subtest: vcs0
[352.374873] Dynamic subtest vcs0: SUCCESS (3.236s)
[352.386867] Starting dynamic subtest: vecs0
[355.613845] Dynamic subtest vecs0: SUCCESS (3.237s)
[355.615542] Subtest engines: SUCCESS (12.949s)
[355.782291] [096/130] (685s left) kms_vrr (flip-basic)
[355.854592] Starting subtest: flip-basic
[355.857330] Starting dynamic subtest: pipe-A-eDP-1
[368.566581] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.700s)
[368.869982] Subtest flip-basic: SUCCESS (13.016s)
[369.801363] [097/130] (671s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[369.892530] Starting subtest: short-busy-flip-before-cursor-toggle
[369.894579] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[369.980936] [098/130] (671s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[370.072103] Starting subtest: cursorA-vs-flipA-legacy
[381.163980] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.090s)
[382.078321] [099/130] (659s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[382.119066] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[382.120023] Starting dynamic subtest: smem
[382.213931] Dynamic subtest smem: SUCCESS (0.095s)
[382.214712] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SUCCESS (0.095s)
[382.409229] [100/130] (659s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[382.465926] Starting subtest: crc-primary-basic-y-tiled-ccs
[382.466686] Starting dynamic subtest: pipe-A-eDP-1
[382.474348] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[382.485293] Starting dynamic subtest: pipe-B-eDP-1
[382.492713] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[382.502832] Starting dynamic subtest: pipe-C-eDP-1
[382.510086] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[382.511458] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.007s)
[382.524039] [101/130] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[382.580197] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[382.582027] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[382.652056] [102/130] (659s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[382.714730] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[383.644288] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.929s)
[383.749050] [103/130] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[383.807548] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[383.809052] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[383.873818] [104/130] (657s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[383.929486] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[383.930544] Starting dynamic subtest: pipe-A-eDP-1
[383.938802] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[383.949066] Starting dynamic subtest: pipe-B-eDP-1
[383.956094] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[383.965221] Starting dynamic subtest: pipe-C-eDP-1
[383.972327] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[383.973713] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.006s)
[383.986756] [105/130] (657s left) perf_pmu (busy-double-start)
[384.016776] Starting subtest: busy-double-start
[384.017580] Starting dynamic subtest: rcs0
[387.620966] Dynamic subtest rcs0: FAIL (3.585s)
[387.638690] Starting dynamic subtest: bcs0
[391.168976] Dynamic subtest bcs0: SUCCESS (3.564s)
[391.189328] Starting dynamic subtest: vcs0
[394.770155] Dynamic subtest vcs0: FAIL (3.560s)
[394.786984] Starting dynamic subtest: vecs0
[398.325034] Dynamic subtest vecs0: FAIL (3.557s)
[398.326275] Subtest busy-double-start: FAIL (14.270s)
[398.368378] [106/130] (643s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[398.431401] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[398.432256] Starting dynamic subtest: pipe-A-eDP-1
[398.439684] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[398.449520] Starting dynamic subtest: pipe-B-eDP-1
[398.456907] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[398.467553] Starting dynamic subtest: pipe-C-eDP-1
[398.474946] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[398.476365] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.005s)
[398.489866] [107/130] (643s left) gem_mmap_gtt (basic)
[398.517804] Starting subtest: basic
[398.518931] Subtest basic: SUCCESS (0.001s)
[398.574393] [108/130] (643s left) gem_exec_params (sol-reset-not-gen7)
[398.606026] Starting subtest: sol-reset-not-gen7
[398.608022] Subtest sol-reset-not-gen7: SUCCESS (0.001s)
[398.695368] [109/130] (643s left) kms_prop_blob (basic)
[398.724422] Starting subtest: basic
[398.725232] Subtest basic: SUCCESS (0.000s)
[398.770230] [110/130] (643s left) perf_pmu (semaphore-wait-idle)
[398.801267] Starting subtest: semaphore-wait-idle
[398.802062] Starting dynamic subtest: rcs0
[399.320766] Dynamic subtest rcs0: SUCCESS (0.509s)
[399.343942] Starting dynamic subtest: bcs0
[399.832039] Dynamic subtest bcs0: SUCCESS (0.509s)
[399.855502] Starting dynamic subtest: vcs0
[400.343554] Dynamic subtest vcs0: SUCCESS (0.510s)
[400.367123] Starting dynamic subtest: vecs0
[400.854774] Dynamic subtest vecs0: SUCCESS (0.509s)
[400.856449] Subtest semaphore-wait-idle: SUCCESS (2.045s)
[400.943017] [111/130] (640s left) gem_exec_fence (syncobj-timeline-signal)
[401.355148] Starting subtest: syncobj-timeline-signal
[401.356739] Subtest syncobj-timeline-signal: SUCCESS (0.003s)
[401.552144] [112/130] (640s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[401.623927] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[401.625645] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[401.697018] [113/130] (640s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[401.750954] Starting subtest: sprite-rotation-90-pos-100-0
[401.754730] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[401.806051] [114/130] (640s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[404.369084] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[404.414823] [115/130] (637s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[404.470408] Starting subtest: multiplane-rotation-cropping-bottom
[410.801978] Subtest multiplane-rotation-cropping-bottom: SUCCESS (6.333s)
[410.912650] [116/130] (631s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[410.944634] Starting subtest: invalid-single-wait-unsubmitted
[410.945469] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[410.998464] [117/130] (630s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[411.055688] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[416.369277] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (5.312s)
[416.455027] [118/130] (625s left) syncobj_timeline (multi-wait-all-signaled)
[416.486373] Starting subtest: multi-wait-all-signaled
[416.487208] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[416.533273] [119/130] (625s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[416.616902] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[424.104814] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (7.487s)
[425.024736] [120/130] (617s left) kms_flip (basic-flip-vs-modeset)
[425.077165] Starting subtest: basic-flip-vs-modeset
[425.079668] Starting dynamic subtest: A-eDP1
[427.866500] Dynamic subtest A-eDP1: SUCCESS (2.786s)
[427.884001] Starting dynamic subtest: B-eDP1
[430.233838] Dynamic subtest B-eDP1: SUCCESS (2.366s)
[430.250163] Starting dynamic subtest: C-eDP1
[432.587301] Dynamic subtest C-eDP1: SUCCESS (2.352s)
[432.588748] Subtest basic-flip-vs-modeset: SUCCESS (7.510s)
[433.483651] [121/130] (608s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[433.580062] Starting subtest: flip-vs-cursor-crc-atomic
[434.275972] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.696s)
[435.209426] [122/130] (606s left) gem_exec_whisper (basic-queues-all)
[435.253405] Starting subtest: basic-queues-all
[442.017195] Subtest basic-queues-all: SUCCESS (6.762s)
[442.185395] [123/130] (599s left) prime_mmap (test_forked_cpu_write)
[442.216184] Starting subtest: test_forked_cpu_write
[442.216974] Starting dynamic subtest: test_forked_cpu_write-smem
[442.223474] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.008s)
[442.224366] Subtest test_forked_cpu_write: SUCCESS (0.008s)
[442.298003] [124/130] (599s left) kms_color (invalid-ctm-matrix-sizes)
[442.362056] Starting subtest: invalid-ctm-matrix-sizes
[442.363347] Starting dynamic subtest: pipe-A
[442.365898] Dynamic subtest pipe-A: SUCCESS (0.004s)
[442.375915] Starting dynamic subtest: pipe-B
[442.376898] Dynamic subtest pipe-B: SUCCESS (0.005s)
[442.386141] Starting dynamic subtest: pipe-C
[442.387084] Dynamic subtest pipe-C: SUCCESS (0.005s)
[442.387853] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.016s)
[442.427757] [125/130] (599s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[442.484982] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[442.487051] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[442.549534] [126/130] (599s left) kms_pm_backlight (basic-brightness)
[442.603311] Starting subtest: basic-brightness
[442.631670] Starting dynamic subtest: eDP-1
[443.130879] Dynamic subtest eDP-1: SUCCESS (0.500s)
[443.131683] Subtest basic-brightness: SUCCESS (0.529s)
[444.043673] [127/130] (598s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[444.111029] Starting subtest: extended-pageflip-modeset-hang-oldfb
[444.111906] Starting dynamic subtest: pipe-A
[465.031948] Dynamic subtest pipe-A: SUCCESS (20.917s)
[465.058490] Starting dynamic subtest: pipe-C
[473.750721] Dynamic subtest pipe-C: SUCCESS (8.710s)
[473.752095] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (29.640s)
[474.633538] [128/130] (567s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[474.697887] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[480.108000] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (5.409s)
[480.214917] [129/130] (562s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[480.248111] Starting subtest: etime-single-wait-all-available-unsubmitted
[480.352939] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.104s)
[480.409865] [130/130] (561s left) gem_ctx_persistence (engines-hostile-preempt)
[480.444261] Starting subtest: engines-hostile-preempt
[480.445377] Starting dynamic subtest: rcs0
[480.460528] Dynamic subtest rcs0: SUCCESS (0.015s)
[480.471155] Starting dynamic subtest: bcs0
[480.514116] Dynamic subtest bcs0: SUCCESS (0.051s)
[480.533613] Starting dynamic subtest: vcs0
[480.542545] Dynamic subtest vcs0: SUCCESS (0.027s)
[480.558735] Starting dynamic subtest: vecs0
[480.581955] Dynamic subtest vecs0: SUCCESS (0.038s)
[480.583537] Subtest engines-hostile-preempt: SUCCESS (0.138s)
[480.677723] Closing watchdogs
Done.