[49.071282] Initializing watchdogs
[49.071328]   /dev/watchdog0
[49.077895] [001/130] (960s left) kms_hdmi_inject (inject-audio)
[49.412467] Starting subtest: inject-audio
[49.414835] Subtest inject-audio: SKIP (0.000s)
[50.372668] [002/130] (959s left) syncobj_wait (wait-all-for-submit-snapshot)
[50.406513] Starting subtest: wait-all-for-submit-snapshot
[50.490769] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[50.569536] [003/130] (959s left) syncobj_wait (wait-all-for-submit-complex)
[50.608350] Starting subtest: wait-all-for-submit-complex
[50.835328] Subtest wait-all-for-submit-complex: SUCCESS (0.218s)
[50.909752] [004/130] (958s left) kms_plane (plane-position-hole-dpms)
[50.969642] Starting subtest: plane-position-hole-dpms
[51.582217] Starting dynamic subtest: pipe-A-plane-1
[54.303638] Dynamic subtest pipe-A-plane-1: SUCCESS (2.721s)
[54.315989] Starting dynamic subtest: pipe-A-plane-2
[57.057228] Dynamic subtest pipe-A-plane-2: SUCCESS (2.750s)
[57.069555] Starting dynamic subtest: pipe-A-plane-3
[59.739111] Dynamic subtest pipe-A-plane-3: SUCCESS (2.685s)
[59.750993] Starting dynamic subtest: pipe-A-plane-4
[62.460381] Dynamic subtest pipe-A-plane-4: SUCCESS (2.721s)
[62.470881] Starting dynamic subtest: pipe-A-plane-5
[62.475930] Dynamic subtest pipe-A-plane-5: SUCCESS (0.016s)
[63.832769] Starting dynamic subtest: pipe-B-plane-1
[66.605527] Dynamic subtest pipe-B-plane-1: SUCCESS (2.711s)
[66.619608] Starting dynamic subtest: pipe-B-plane-2
[69.318930] Dynamic subtest pipe-B-plane-2: SUCCESS (2.658s)
[69.328492] Starting dynamic subtest: pipe-B-plane-3
[72.063753] Dynamic subtest pipe-B-plane-3: SUCCESS (2.706s)
[72.076540] Starting dynamic subtest: pipe-B-plane-4
[74.761081] Dynamic subtest pipe-B-plane-4: SUCCESS (2.667s)
[74.773725] Starting dynamic subtest: pipe-B-plane-5
[74.776444] Dynamic subtest pipe-B-plane-5: SUCCESS (0.017s)
[74.777482] Subtest plane-position-hole-dpms: SUCCESS (23.612s)
[75.658178] [005/130] (934s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[75.726969] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-cpu
[81.661230] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (5.895s)
[81.773124] [006/130] (928s left) kms_rotation_crc (sprite-rotation-180)
[81.837582] Starting subtest: sprite-rotation-180
[88.234893] Subtest sprite-rotation-180: SUCCESS (6.383s)
[89.541963] [007/130] (920s left) kms_psr (fbc-psr-sprite-blt)
[89.609101] Starting subtest: fbc-psr-sprite-blt
[89.610147] Starting dynamic subtest: eDP-1
[89.614622] Dynamic subtest eDP-1: SKIP (0.000s)
[89.616332] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[89.674912] [008/130] (920s left) gem_exec_capture (capture)
[89.714578] Starting subtest: capture
[89.715541] Starting dynamic subtest: rcs0-smem
[90.532773] Dynamic subtest rcs0-smem: SUCCESS (0.817s)
[90.539011] Starting dynamic subtest: bcs0-smem
[93.170999] Dynamic subtest bcs0-smem: SUCCESS (2.635s)
[93.177961] Starting dynamic subtest: vcs0-smem
[93.988208] Dynamic subtest vcs0-smem: SUCCESS (0.817s)
[93.994620] Starting dynamic subtest: vecs0-smem
[94.839271] Dynamic subtest vecs0-smem: FAIL (0.833s)
[94.840014] Subtest capture: FAIL (5.104s)
[94.939665] [009/130] (915s left) gem_ctx_persistence (hostile)
[94.976247] Starting subtest: hostile
[96.288233] Subtest hostile: SUCCESS (1.300s)
[96.389169] [010/130] (913s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[96.459635] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[103.761226] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.297s)
[103.885783] [011/130] (906s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[103.950498] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[107.143871] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.190s)
[107.302792] [012/130] (902s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[107.342121] Starting subtest: cpuset-medium-copy-odd
[114.032194] Subtest cpuset-medium-copy-odd: SUCCESS (6.688s)
[114.136752] [013/130] (895s left) prime_vgem (fence-flip-hang)
[114.261725] Subtest fence-flip-hang: SKIP (0.000s)
[114.351801] [014/130] (895s left) i915_pm_rps (thresholds)
[114.393703] Starting subtest: thresholds
[114.395575] Subtest thresholds: SKIP (0.001s)
[114.466343] [015/130] (895s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[114.541655] Starting subtest: y-tiled-64bpp-rotate-0
[116.748810] Subtest y-tiled-64bpp-rotate-0: SUCCESS (2.206s)
[116.958352] [016/130] (893s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[117.020074] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[117.022532] Starting dynamic subtest: pipe-A-default-mode
[120.173964] Dynamic subtest pipe-A-default-mode: SUCCESS (3.151s)
[120.174815] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (3.153s)
[121.096439] [017/130] (889s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[121.159294] Starting subtest: fbc-pr-sprite-mmap-cpu
[121.161529] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[121.229511] [018/130] (888s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[121.338499] Starting subtest: cursorB-vs-flipA-toggle
[121.343315] Subtest cursorB-vs-flipA-toggle: SKIP (0.004s)
[121.478065] [019/130] (888s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[121.543718] Starting subtest: yf-tiled-32bpp-rotate-90
[121.545253] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[121.628043] [020/130] (888s left) gem_ccs (suspend-resume)
[121.661307] Starting subtest: suspend-resume
[121.662868] Subtest suspend-resume: SKIP (0.000s)
[121.728350] [021/130] (888s left) syncobj_timeline (32bits-limit)
[121.764740] Starting subtest: 32bits-limit
[121.766533] Subtest 32bits-limit: SUCCESS (0.001s)
[121.844060] [022/130] (888s left) kms_psr (psr-cursor-blt)
[121.899894] Starting subtest: psr-cursor-blt
[121.900815] Starting dynamic subtest: eDP-1
[123.860320] Dynamic subtest eDP-1: SUCCESS (1.958s)
[123.861487] Subtest psr-cursor-blt: SUCCESS (1.959s)
[124.808242] [023/130] (885s left) kms_cursor_crc (cursor-onscreen-128x128)
[124.872360] Starting subtest: cursor-onscreen-128x128
[124.876337] Starting dynamic subtest: pipe-A-eDP-1
[127.347136] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.470s)
[127.402747] Starting dynamic subtest: pipe-C-eDP-1
[130.562249] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.166s)
[130.563766] Subtest cursor-onscreen-128x128: SUCCESS (5.689s)
[131.536189] [024/130] (878s left) syncobj_wait (multi-wait-submitted-signaled)
[131.576323] Starting subtest: multi-wait-submitted-signaled
[131.577201] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[131.646698] [025/130] (878s left) gem_tiled_blits (normal)
[131.750068] Starting subtest: normal
[155.236301] Subtest normal: SUCCESS (23.475s)
[155.350250] [026/130] (854s left) api_intel_bb (intel-bb-blit-none)
[155.432767] Starting subtest: intel-bb-blit-none
[160.507624] Subtest intel-bb-blit-none: SUCCESS (5.066s)
[160.640759] [027/130] (849s left) syncobj_wait (signal)
[160.683493] Starting subtest: signal
[160.684497] Subtest signal: SUCCESS (0.000s)
[160.750333] [028/130] (849s left) prime_mmap (test_reprime)
[160.787556] Starting subtest: test_reprime
[160.788320] Starting dynamic subtest: test_reprime-smem
[160.827147] Dynamic subtest test_reprime-smem: SUCCESS (0.041s)
[160.827977] Subtest test_reprime: SUCCESS (0.042s)
[160.919524] [029/130] (849s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[160.988271] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[160.989811] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[161.068246] [030/130] (849s left) kms_chamelium_hpd (vga-hpd-fast)
[161.443409] Subtest vga-hpd-fast: SKIP (0.000s)
[162.338421] [031/130] (848s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[162.453599] Starting subtest: basic-flip-before-cursor-atomic
[163.665382] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.212s)
[164.615498] [032/130] (845s left) prime_mmap_kms (buffer-sharing)
[164.674759] Starting subtest: buffer-sharing
[165.169832] Subtest buffer-sharing: SUCCESS (0.495s)
[166.089580] [033/130] (844s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[169.695131] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[169.702714] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.006s)
[170.617199] [034/130] (839s left) kms_vblank (query-forked-hang)
[170.680750] Starting subtest: query-forked-hang
[170.683477] Starting dynamic subtest: pipe-A-eDP-1
[196.826418] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.142s)
[196.840983] Starting dynamic subtest: pipe-C-eDP-1
[221.855290] Dynamic subtest pipe-C-eDP-1: SUCCESS (25.026s)
[221.856125] Subtest query-forked-hang: SUCCESS (51.173s)
[222.790346] [035/130] (787s left) gem_bad_reloc (negative-reloc-bltcopy)
[222.830018] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[222.877616] [036/130] (787s left) gem_userptr_blits (create-destroy-unsync)
[222.916971] Subtest create-destroy-unsync: SKIP (0.000s)
[223.038515] [037/130] (787s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[226.655425] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[226.665719] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.006s)
[227.594360] [038/130] (782s left) fbdev (nullptr)
[227.631562] Starting subtest: nullptr
[227.632569] Subtest nullptr: SUCCESS (0.000s)
[227.688460] [039/130] (782s left) kms_psr (fbc-psr2-cursor-blt)
[227.741840] Starting subtest: fbc-psr2-cursor-blt
[227.742649] Starting dynamic subtest: eDP-1
[227.744050] Dynamic subtest eDP-1: SKIP (0.000s)
[227.745398] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[227.807554] [040/130] (782s left) core_setmaster (master-drop-set-root)
[228.500423] Starting subtest: master-drop-set-root
[228.514062] Subtest master-drop-set-root: SUCCESS (0.009s)
[228.539460] [041/130] (781s left) i915_pm_rpm (system-suspend-devices)
[230.199372] Starting subtest: system-suspend-devices
[237.811351] Subtest system-suspend-devices: SUCCESS (7.612s)
[238.254657] [042/130] (772s left) kms_feature_discovery (display-1x)
[238.318140] Starting subtest: display-1x
[238.319222] Subtest display-1x: SUCCESS (0.000s)
[238.387334] [043/130] (772s left) dmabuf_sync_file (export-multiwait)
[238.424517] Starting subtest: export-multiwait
[238.425734] Subtest export-multiwait: SUCCESS (0.001s)
[238.487781] [044/130] (772s left) vgem_basic (create)
[238.524492] Starting subtest: create
[238.525655] Subtest create: SUCCESS (0.000s)
[238.587874] [045/130] (772s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[238.644760] Starting subtest: psr-2p-primscrn-cur-indfb-move
[238.646564] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[238.764683] [046/130] (771s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[238.825022] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[238.826631] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[238.938261] [047/130] (771s left) gem_sync (basic-all)
[238.974076] Starting subtest: basic-all
[241.294163] Subtest basic-all: SUCCESS (2.319s)
[241.487922] [048/130] (769s left) kms_joiner (basic-big-joiner)
[241.541342] Starting subtest: basic-big-joiner
[241.542962] Subtest basic-big-joiner: SKIP (0.000s)
[241.617037] [049/130] (769s left) kms_cursor_crc (cursor-random-32x10)
[241.678017] Starting subtest: cursor-random-32x10
[241.738247] Subtest cursor-random-32x10: SKIP (0.058s)
[241.840271] [050/130] (768s left) syncobj_timeline (wait-all-interrupted)
[241.877824] Starting subtest: wait-all-interrupted
[241.995935] Subtest wait-all-interrupted: SUCCESS (0.109s)
[242.071430] [051/130] (768s left) syncobj_timeline (wait-for-submit-complex)
[242.107462] Starting subtest: wait-for-submit-complex
[242.170201] Subtest wait-for-submit-complex: SUCCESS (0.063s)
[242.250730] [052/130] (768s left) kms_plane_scaling (planes-downscale-factor-0-75)
[242.306833] Starting subtest: planes-downscale-factor-0-75
[242.307791] Starting dynamic subtest: pipe-A
[242.425180] Dynamic subtest pipe-A: SUCCESS (0.117s)
[242.445468] Starting dynamic subtest: pipe-B
[243.742186] Dynamic subtest pipe-B: SUCCESS (1.313s)
[243.762967] Starting dynamic subtest: pipe-C
[245.065890] Dynamic subtest pipe-C: SUCCESS (1.325s)
[245.067542] Subtest planes-downscale-factor-0-75: SUCCESS (2.759s)
[246.357647] [053/130] (764s left) kms_async_flips (alternate-sync-async-flip)
[246.414468] Starting subtest: alternate-sync-async-flip
[246.824636] Starting dynamic subtest: pipe-A-eDP-1
[249.681427] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.856s)
[250.109246] Starting dynamic subtest: pipe-B-eDP-1
[252.988180] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.892s)
[253.417212] Starting dynamic subtest: pipe-C-eDP-1
[256.318083] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.919s)
[256.319481] Subtest alternate-sync-async-flip: SUCCESS (9.904s)
[257.607687] [054/130] (753s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[257.671237] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[257.672726] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[257.756687] [055/130] (753s left) kms_hdr (static-swap)
[257.813959] Starting subtest: static-swap
[259.023493] Starting dynamic subtest: pipe-A-eDP-1
[265.386203] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.364s)
[265.387590] Subtest static-swap: SUCCESS (7.572s)
[265.501512] [056/130] (745s left) gem_ppgtt (shrink-vs-evict-any)
[265.541811] Starting subtest: shrink-vs-evict-any
[271.093365] Subtest shrink-vs-evict-any: SUCCESS (5.518s)
[271.108455] [057/130] (739s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[271.176099] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[272.690768] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.514s)
[272.852599] [058/130] (737s left) gem_mmap_gtt (bad-object)
[272.882028] Starting subtest: bad-object
[272.882903] Subtest bad-object: SUCCESS (0.000s)
[272.964040] [059/130] (737s left) kms_cursor_edge_walk (128x128-right-edge)
[273.021695] Starting subtest: 128x128-right-edge
[273.022647] Starting dynamic subtest: pipe-A-eDP-1
[277.609433] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.585s)
[277.623673] Starting dynamic subtest: pipe-C-eDP-1
[281.889347] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.277s)
[281.890391] Subtest 128x128-right-edge: SUCCESS (8.868s)
[282.826484] [060/130] (728s left) gen3_render_tiledx_blits
[282.889577] [061/130] (728s left) gem_spin_batch (user-each)
[282.926701] Starting subtest: user-each
[286.188291] Subtest user-each: SUCCESS (3.255s)
[286.420434] [062/130] (724s left) gem_exec_schedule (implicit-write-read)
[286.475469] Starting subtest: implicit-write-read
[286.476218] Starting dynamic subtest: rcs0
[286.530533] Dynamic subtest rcs0: SUCCESS (0.064s)
[286.546942] Starting dynamic subtest: bcs0
[286.614275] Dynamic subtest bcs0: SUCCESS (0.082s)
[286.629592] Starting dynamic subtest: vcs0
[286.695383] Dynamic subtest vcs0: SUCCESS (0.080s)
[286.711131] Starting dynamic subtest: vecs0
[286.778157] Dynamic subtest vecs0: SUCCESS (0.081s)
[286.779444] Subtest implicit-write-read: SUCCESS (0.313s)
[287.168305] [063/130] (723s left) gem_ctx_isolation (dirty-create)
[287.207877] Starting subtest: dirty-create
[287.208842] Starting dynamic subtest: rcs0
[287.360731] Dynamic subtest rcs0: SUCCESS (0.153s)
[287.370215] Starting dynamic subtest: bcs0
[287.552238] Dynamic subtest bcs0: SUCCESS (0.191s)
[287.562956] Starting dynamic subtest: vcs0
[287.732738] Dynamic subtest vcs0: SUCCESS (0.179s)
[287.742910] Starting dynamic subtest: vecs0
[287.916927] Dynamic subtest vecs0: SUCCESS (0.184s)
[287.917787] Subtest dirty-create: SUCCESS (0.710s)
[288.172432] [064/130] (722s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[288.234421] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[288.236126] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[288.320200] [065/130] (722s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[288.374417] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[288.375360] Starting dynamic subtest: pipe-A-default-mode
[289.701744] Dynamic subtest pipe-A-default-mode: SKIP (1.324s)
[289.704026] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (1.327s)
[289.867976] [066/130] (721s left) kms_chamelium_edid (dp-edid-resolution-list)
[290.223479] Subtest dp-edid-resolution-list: SKIP (0.000s)
[291.106659] [067/130] (719s left) kms_psr (pr-sprite-render)
[291.166985] Starting subtest: pr-sprite-render
[291.169084] Subtest pr-sprite-render: SKIP (0.000s)
[291.247352] [068/130] (719s left) syncobj_wait (invalid-reset-bad-pad)
[291.281223] Starting subtest: invalid-reset-bad-pad
[291.282198] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[291.363085] [069/130] (719s left) i915_hangman (error-state-basic)
[291.403179] Starting subtest: error-state-basic
[291.529625] Subtest error-state-basic: SUCCESS (0.127s)
[291.612451] [070/130] (719s left) perf_pmu (render-node-busy)
[291.652664] Starting subtest: render-node-busy
[291.653654] Starting dynamic subtest: rcs0
[292.247218] Dynamic subtest rcs0: SUCCESS (0.594s)
[292.265146] Starting dynamic subtest: bcs0
[292.806432] Dynamic subtest bcs0: SUCCESS (0.557s)
[292.825164] Starting dynamic subtest: vcs0
[293.358340] Dynamic subtest vcs0: SUCCESS (0.550s)
[293.368657] Starting dynamic subtest: vecs0
[293.908533] Dynamic subtest vecs0: SUCCESS (0.548s)
[293.910318] Subtest render-node-busy: SUCCESS (2.256s)
[293.996750] [071/130] (717s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[294.060583] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[294.062099] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[294.171083] [072/130] (716s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[294.233336] Starting subtest: 4-tiled-32bpp-rotate-180
[294.234907] Subtest 4-tiled-32bpp-rotate-180: SKIP (0.000s)
[294.320961] [073/130] (716s left) kms_flip (2x-plain-flip)
[294.373657] Starting subtest: 2x-plain-flip
[294.378213] Subtest 2x-plain-flip: SKIP (0.004s)
[294.453611] [074/130] (716s left) gem_mmap_gtt (basic-copy)
[294.483085] Starting subtest: basic-copy
[295.382075] Subtest basic-copy: SUCCESS (0.892s)
[295.475021] [075/130] (715s left) gem_exec_schedule (preempt-user)
[295.514703] Starting subtest: preempt-user
[295.515848] Starting dynamic subtest: rcs0
[295.535420] Dynamic subtest rcs0: SUCCESS (0.020s)
[295.546415] Starting dynamic subtest: bcs0
[295.553827] Dynamic subtest bcs0: SUCCESS (0.018s)
[295.567820] Starting dynamic subtest: vcs0
[295.575590] Dynamic subtest vcs0: SUCCESS (0.021s)
[295.585492] Starting dynamic subtest: vecs0
[295.593381] Dynamic subtest vecs0: SUCCESS (0.017s)
[295.594154] Subtest preempt-user: SUCCESS (0.079s)
[295.946576] [076/130] (715s left) gem_exec_balancer (full-late)
[295.983071] Starting subtest: full-late
[297.072207] Subtest full-late: SUCCESS (1.089s)
[298.107473] [077/130] (713s left) gem_exec_reloc (basic-gtt-cpu-active)
[298.140490] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[298.192790] [078/130] (713s left) core_auth (basic-auth)
[298.225431] Starting subtest: basic-auth
[298.226215] Subtest basic-auth: SUCCESS (0.001s)
[298.302795] [079/130] (712s left) gem_workarounds (suspend-resume-fd)
[298.347194] Starting subtest: suspend-resume-fd
[314.922494] Subtest suspend-resume-fd: SUCCESS (2.339s)
[316.014082] [080/130] (710s left) gem_render_copy_redux (normal)
[316.051713] Starting subtest: normal
[316.478115] Subtest normal: SUCCESS (0.426s)
[316.569706] [081/130] (709s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[316.629789] Starting subtest: compare-crc-sanitycheck-xr24
[316.630711] Starting dynamic subtest: pipe-A-eDP-1
[317.355332] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.724s)
[317.375395] Starting dynamic subtest: pipe-B-eDP-1
[318.880946] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.525s)
[318.903204] Starting dynamic subtest: pipe-C-eDP-1
[320.378469] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.455s)
[320.379426] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.710s)
[321.313353] [082/130] (705s left) gem_tiled_partial_pwrite_pread (reads)
[321.354391] Subtest reads: SKIP (0.000s)
[321.393608] [083/130] (705s left) gem_exec_params (dr1-dirt)
[321.428266] Starting subtest: DR1-dirt
[321.429409] Subtest DR1-dirt: SUCCESS (0.000s)
[321.515723] [084/130] (705s left) gen7_exec_parse (cmd-crossing-page)
[321.552453] Subtest cmd-crossing-page: SKIP (0.000s)
[321.581062] [085/130] (705s left) kms_cdclk (mode-transition)
[321.641813] Starting subtest: mode-transition
[321.642601] Starting dynamic subtest: pipe-A-eDP-1
[321.965734] Dynamic subtest pipe-A-eDP-1: SKIP (0.314s)
[321.983578] Starting dynamic subtest: pipe-B-eDP-1
[321.986199] Dynamic subtest pipe-B-eDP-1: SKIP (0.003s)
[322.005342] Starting dynamic subtest: pipe-C-eDP-1
[322.007120] Dynamic subtest pipe-C-eDP-1: SKIP (0.003s)
[322.008785] Subtest mode-transition: SKIP (0.324s)
[322.910553] [086/130] (703s left) gem_exec_whisper (basic-fds-forked)
[322.954652] Starting subtest: basic-fds-forked
[329.167082] Subtest basic-fds-forked: SUCCESS (6.119s)
[329.350866] [087/130] (697s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[329.416388] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[329.418437] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[329.504128] [088/130] (697s left) gem_exec_balancer (bonded-false-hang)
[330.498498] Starting subtest: bonded-false-hang
[330.500184] Subtest bonded-false-hang: SKIP (0.000s)
[330.581640] [089/130] (696s left) gem_exec_fence (basic-busy)
[330.737702] Starting subtest: basic-busy
[330.739050] Starting dynamic subtest: rcs0
[330.820909] Dynamic subtest rcs0: SUCCESS (0.085s)
[330.838825] Starting dynamic subtest: bcs0
[330.869699] Dynamic subtest bcs0: SUCCESS (0.046s)
[330.887664] Starting dynamic subtest: vcs0
[330.923264] Dynamic subtest vcs0: SUCCESS (0.051s)
[330.943539] Starting dynamic subtest: vecs0
[330.967154] Dynamic subtest vecs0: SUCCESS (0.042s)
[330.968444] Subtest basic-busy: SUCCESS (0.232s)
[331.458413] [090/130] (695s left) gem_reg_read (bad-register)
[331.493651] Starting subtest: bad-register
[331.494579] Subtest bad-register: SUCCESS (0.000s)
[331.576873] [091/130] (695s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[331.631137] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[331.632630] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[331.723428] [092/130] (695s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[331.778412] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[331.779458] Starting dynamic subtest: pipe-A-default-mode
[332.100404] Dynamic subtest pipe-A-default-mode: SKIP (0.317s)
[332.101877] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.320s)
[333.015594] [093/130] (693s left) perf (create-destroy-userspace-config)
[333.115802] Starting subtest: create-destroy-userspace-config
[333.132903] Subtest create-destroy-userspace-config: SUCCESS (0.017s)
[333.175501] [094/130] (693s left) kms_pm_backlight (fade-with-dpms)
[333.236406] Starting subtest: fade-with-dpms
[335.448438] Starting dynamic subtest: eDP-1
[338.075308] Dynamic subtest eDP-1: SUCCESS (2.618s)
[338.076260] Subtest fade-with-dpms: SUCCESS (4.820s)
[338.987823] [095/130] (688s left) gem_spin_batch (engines)
[339.028725] Starting subtest: engines
[339.029760] Starting dynamic subtest: rcs0
[342.279265] Dynamic subtest rcs0: SUCCESS (3.234s)
[342.302920] Starting dynamic subtest: bcs0
[345.546263] Dynamic subtest bcs0: SUCCESS (3.232s)
[345.558770] Starting dynamic subtest: vcs0
[348.785840] Dynamic subtest vcs0: SUCCESS (3.233s)
[348.808975] Starting dynamic subtest: vecs0
[352.023445] Dynamic subtest vecs0: SUCCESS (3.233s)
[352.025069] Subtest engines: SUCCESS (12.940s)
[352.217603] [096/130] (674s left) kms_vrr (flip-basic)
[352.277597] Starting subtest: flip-basic
[352.280219] Subtest flip-basic: SKIP (0.000s)
[352.346964] [097/130] (674s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[352.452868] Starting subtest: short-busy-flip-before-cursor-toggle
[352.454986] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[352.597692] [098/130] (674s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[352.684852] Starting subtest: cursorA-vs-flipA-legacy
[363.786094] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.097s)
[364.700759] [099/130] (662s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[364.733743] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[364.734515] Starting dynamic subtest: smem
[364.828115] Dynamic subtest smem: SUCCESS (0.094s)
[364.828946] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SUCCESS (0.095s)
[365.087365] [100/130] (662s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[365.147265] Starting subtest: crc-primary-basic-y-tiled-ccs
[365.148392] Starting dynamic subtest: pipe-A-eDP-1
[365.156080] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[365.166024] Starting dynamic subtest: pipe-B-eDP-1
[365.173315] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[365.182129] Starting dynamic subtest: pipe-C-eDP-1
[365.189678] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[365.191146] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.006s)
[365.231121] [101/130] (661s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[365.288567] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[365.290207] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[365.385795] [102/130] (661s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[365.447483] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[366.891438] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.443s)
[367.037056] [103/130] (660s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[367.090791] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[367.092357] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[367.186102] [104/130] (660s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[367.241765] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[367.242968] Starting dynamic subtest: pipe-A-eDP-1
[367.251897] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[367.261889] Starting dynamic subtest: pipe-B-eDP-1
[367.269186] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[367.278344] Starting dynamic subtest: pipe-C-eDP-1
[367.285185] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[367.286516] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[367.314245] [105/130] (659s left) perf_pmu (busy-double-start)
[367.344326] Starting subtest: busy-double-start
[367.345224] Starting dynamic subtest: rcs0
[370.950142] Dynamic subtest rcs0: FAIL (3.583s)
[370.966495] Starting dynamic subtest: bcs0
[374.487591] Dynamic subtest bcs0: SUCCESS (3.554s)
[374.498870] Starting dynamic subtest: vcs0
[378.083008] Dynamic subtest vcs0: FAIL (3.556s)
[378.099071] Starting dynamic subtest: vecs0
[381.637098] Dynamic subtest vecs0: FAIL (3.555s)
[381.638400] Subtest busy-double-start: FAIL (14.251s)
[381.714783] [106/130] (645s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[381.772760] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[381.773631] Starting dynamic subtest: pipe-A-eDP-1
[381.781213] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[381.791873] Starting dynamic subtest: pipe-B-eDP-1
[381.798942] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[381.808766] Starting dynamic subtest: pipe-C-eDP-1
[381.815725] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[381.817165] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.004s)
[381.840749] [107/130] (645s left) gem_mmap_gtt (basic)
[381.871388] Starting subtest: basic
[381.872434] Subtest basic: SUCCESS (0.001s)
[381.955524] [108/130] (645s left) gem_exec_params (sol-reset-not-gen7)
[381.988417] Starting subtest: sol-reset-not-gen7
[381.991313] Subtest sol-reset-not-gen7: SUCCESS (0.002s)
[382.094212] [109/130] (645s left) kms_prop_blob (basic)
[382.124300] Starting subtest: basic
[382.125087] Subtest basic: SUCCESS (0.000s)
[382.198684] [110/130] (645s left) perf_pmu (semaphore-wait-idle)
[382.232659] Starting subtest: semaphore-wait-idle
[382.233449] Starting dynamic subtest: rcs0
[382.751954] Dynamic subtest rcs0: SUCCESS (0.509s)
[382.764608] Starting dynamic subtest: bcs0
[383.263621] Dynamic subtest bcs0: SUCCESS (0.509s)
[383.276059] Starting dynamic subtest: vcs0
[383.775376] Dynamic subtest vcs0: SUCCESS (0.509s)
[383.788653] Starting dynamic subtest: vecs0
[384.286327] Dynamic subtest vecs0: SUCCESS (0.509s)
[384.288135] Subtest semaphore-wait-idle: SUCCESS (2.044s)
[384.398757] [111/130] (643s left) gem_exec_fence (syncobj-timeline-signal)
[384.811908] Starting subtest: syncobj-timeline-signal
[384.812721] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[385.058859] [112/130] (642s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[385.124833] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[385.126406] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[385.225037] [113/130] (642s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[385.285875] Starting subtest: sprite-rotation-90-pos-100-0
[385.287619] Subtest sprite-rotation-90-pos-100-0: SKIP (0.002s)
[385.357400] [114/130] (642s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[387.923658] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[388.001007] [115/130] (639s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[388.056986] Starting subtest: multiplane-rotation-cropping-bottom
[398.586081] Subtest multiplane-rotation-cropping-bottom: SUCCESS (10.526s)
[398.682837] [116/130] (628s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[398.713509] Starting subtest: invalid-single-wait-unsubmitted
[398.714460] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[398.784732] [117/130] (628s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[398.840759] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[402.251977] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (3.409s)
[402.381239] [118/130] (625s left) syncobj_timeline (multi-wait-all-signaled)
[402.416871] Starting subtest: multi-wait-all-signaled
[402.417805] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[402.493165] [119/130] (625s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[402.586098] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[412.570056] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.977s)
[413.487369] [120/130] (614s left) kms_flip (basic-flip-vs-modeset)
[413.548278] Starting subtest: basic-flip-vs-modeset
[413.551288] Starting dynamic subtest: A-eDP1
[416.372986] Dynamic subtest A-eDP1: SUCCESS (2.822s)
[416.394075] Starting dynamic subtest: B-eDP1
[418.804976] Dynamic subtest B-eDP1: SUCCESS (2.433s)
[418.826777] Starting dynamic subtest: C-eDP1
[421.208448] Dynamic subtest C-eDP1: SUCCESS (2.398s)
[421.209811] Subtest basic-flip-vs-modeset: SUCCESS (7.660s)
[422.172081] [121/130] (605s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[422.278047] Starting subtest: flip-vs-cursor-crc-atomic
[423.173482] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.896s)
[424.099210] [122/130] (603s left) gem_exec_whisper (basic-queues-all)
[424.138370] Starting subtest: basic-queues-all
[429.783927] Subtest basic-queues-all: SUCCESS (5.646s)
[429.978732] [123/130] (597s left) prime_mmap (test_forked_cpu_write)
[430.014741] Starting subtest: test_forked_cpu_write
[430.015586] Starting dynamic subtest: test_forked_cpu_write-smem
[430.021581] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.007s)
[430.022462] Subtest test_forked_cpu_write: SUCCESS (0.008s)
[430.112340] [124/130] (597s left) kms_color (invalid-ctm-matrix-sizes)
[430.169627] Starting subtest: invalid-ctm-matrix-sizes
[430.170694] Starting dynamic subtest: pipe-A
[430.172932] Dynamic subtest pipe-A: SUCCESS (0.003s)
[430.182324] Starting dynamic subtest: pipe-B
[430.183146] Dynamic subtest pipe-B: SUCCESS (0.005s)
[430.192644] Starting dynamic subtest: pipe-C
[430.193391] Dynamic subtest pipe-C: SUCCESS (0.006s)
[430.194125] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.016s)
[430.260090] [125/130] (597s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[430.315339] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[430.317048] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[430.411930] [126/130] (597s left) kms_pm_backlight (basic-brightness)
[430.468338] Starting subtest: basic-brightness
[430.517115] Starting dynamic subtest: eDP-1
[430.848881] Dynamic subtest eDP-1: SUCCESS (0.330s)
[430.849784] Subtest basic-brightness: SUCCESS (0.381s)
[431.770011] [127/130] (595s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[431.831356] Starting subtest: extended-pageflip-modeset-hang-oldfb
[431.834023] Starting dynamic subtest: pipe-A
[452.477844] Dynamic subtest pipe-A: SUCCESS (20.643s)
[452.488608] Starting dynamic subtest: pipe-C
[461.217407] Dynamic subtest pipe-C: SUCCESS (8.737s)
[461.218451] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (29.386s)
[462.168224] [128/130] (565s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[462.225308] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[465.919679] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (3.692s)
[466.048743] [129/130] (561s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[466.084125] Starting subtest: etime-single-wait-all-available-unsubmitted
[466.193362] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.108s)
[466.280771] [130/130] (561s left) gem_ctx_persistence (engines-hostile-preempt)
[466.317287] Starting subtest: engines-hostile-preempt
[466.318158] Starting dynamic subtest: rcs0
[466.370625] Dynamic subtest rcs0: SUCCESS (0.053s)
[466.391323] Starting dynamic subtest: bcs0
[466.404413] Dynamic subtest bcs0: SUCCESS (0.032s)
[466.423067] Starting dynamic subtest: vcs0
[466.424010] Dynamic subtest vcs0: SUCCESS (0.016s)
[466.432624] Starting dynamic subtest: vecs0
[466.463586] Dynamic subtest vecs0: SUCCESS (0.039s)
[466.465051] Subtest engines-hostile-preempt: SUCCESS (0.147s)
[466.572591] Closing watchdogs
Done.