[42.028391] Initializing watchdogs
[42.028435]   /dev/watchdog0
[42.034270] [001/130] (960s left) kms_hdmi_inject (inject-audio)
[42.369263] Starting subtest: inject-audio
[42.371592] Subtest inject-audio: SKIP (0.000s)
[43.270218] [002/130] (959s left) syncobj_wait (wait-all-for-submit-snapshot)
[43.309444] Starting subtest: wait-all-for-submit-snapshot
[43.393797] Subtest wait-all-for-submit-snapshot: SUCCESS (0.084s)
[43.472777] [003/130] (959s left) syncobj_wait (wait-all-for-submit-complex)
[43.505299] Starting subtest: wait-all-for-submit-complex
[43.732561] Subtest wait-all-for-submit-complex: SUCCESS (0.218s)
[43.801612] [004/130] (958s left) kms_plane (plane-position-hole-dpms)
[43.856606] Starting subtest: plane-position-hole-dpms
[44.459788] Starting dynamic subtest: pipe-A-plane-1
[47.195369] Dynamic subtest pipe-A-plane-1: SUCCESS (2.735s)
[47.207166] Starting dynamic subtest: pipe-A-plane-2
[50.752357] Dynamic subtest pipe-A-plane-2: SUCCESS (3.552s)
[50.757481] Starting dynamic subtest: pipe-A-plane-3
[52.860238] Dynamic subtest pipe-A-plane-3: SUCCESS (2.113s)
[52.873827] Starting dynamic subtest: pipe-A-plane-4
[55.550042] Dynamic subtest pipe-A-plane-4: SUCCESS (2.690s)
[55.561829] Starting dynamic subtest: pipe-A-plane-5
[55.566257] Dynamic subtest pipe-A-plane-5: SUCCESS (0.017s)
[56.907702] Starting dynamic subtest: pipe-B-plane-1
[59.578184] Dynamic subtest pipe-B-plane-1: SUCCESS (2.683s)
[59.588247] Starting dynamic subtest: pipe-B-plane-2
[62.247884] Dynamic subtest pipe-B-plane-2: SUCCESS (2.669s)
[62.255571] Starting dynamic subtest: pipe-B-plane-3
[64.937107] Dynamic subtest pipe-B-plane-3: SUCCESS (2.692s)
[64.948327] Starting dynamic subtest: pipe-B-plane-4
[67.620395] Dynamic subtest pipe-B-plane-4: SUCCESS (2.688s)
[67.632958] Starting dynamic subtest: pipe-B-plane-5
[67.634200] Dynamic subtest pipe-B-plane-5: SUCCESS (0.014s)
[67.635728] Subtest plane-position-hole-dpms: SUCCESS (23.784s)
[68.548076] [005/130] (934s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[68.623178] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-cpu
[74.404764] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (5.778s)
[74.532764] [006/130] (928s left) kms_rotation_crc (sprite-rotation-180)
[74.591916] Starting subtest: sprite-rotation-180
[80.993084] Subtest sprite-rotation-180: SUCCESS (6.406s)
[82.309327] [007/130] (920s left) kms_psr (fbc-psr-sprite-blt)
[82.373024] Starting subtest: fbc-psr-sprite-blt
[82.374051] Starting dynamic subtest: eDP-1
[82.375865] Dynamic subtest eDP-1: SKIP (0.000s)
[82.377373] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[82.443417] [008/130] (920s left) gem_exec_capture (capture)
[82.484751] Starting subtest: capture
[82.485645] Starting dynamic subtest: rcs0-smem
[83.301041] Dynamic subtest rcs0-smem: SUCCESS (0.816s)
[83.307212] Starting dynamic subtest: bcs0-smem
[84.299130] Dynamic subtest bcs0-smem: SUCCESS (0.998s)
[84.305128] Starting dynamic subtest: vcs0-smem
[85.119805] Dynamic subtest vcs0-smem: SUCCESS (0.821s)
[85.126140] Starting dynamic subtest: vecs0-smem
[85.981365] Dynamic subtest vecs0-smem: FAIL (0.845s)
[85.982121] Subtest capture: FAIL (3.481s)
[86.078951] [009/130] (916s left) gem_ctx_persistence (hostile)
[86.118958] Starting subtest: hostile
[87.610278] Subtest hostile: FAIL (1.461s)
[87.701980] [010/130] (914s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[87.765919] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[95.129183] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.365s)
[95.247704] [011/130] (907s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[95.306096] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[98.519904] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.213s)
[98.678096] [012/130] (903s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[98.711353] Starting subtest: cpuset-medium-copy-odd
[105.401122] Subtest cpuset-medium-copy-odd: SUCCESS (6.690s)
[105.522173] [013/130] (897s left) prime_vgem (fence-flip-hang)
[105.645296] Subtest fence-flip-hang: SKIP (0.000s)
[105.734342] [014/130] (896s left) i915_pm_rps (thresholds)
[105.770328] Starting subtest: thresholds
[105.772355] Subtest thresholds: SKIP (0.000s)
[105.851851] [015/130] (896s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[105.923126] Starting subtest: y-tiled-64bpp-rotate-0
[107.925998] Subtest y-tiled-64bpp-rotate-0: SUCCESS (2.003s)
[108.077283] [016/130] (894s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[108.132889] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[108.134769] Starting dynamic subtest: pipe-A-default-mode
[111.262458] Dynamic subtest pipe-A-default-mode: SUCCESS (3.128s)
[111.263288] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (3.129s)
[112.199138] [017/130] (890s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[112.257846] Starting subtest: fbc-pr-sprite-mmap-cpu
[112.259456] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[112.330104] [018/130] (890s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[112.441152] Starting subtest: cursorB-vs-flipA-toggle
[112.445762] Subtest cursorB-vs-flipA-toggle: SKIP (0.004s)
[112.579930] [019/130] (890s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[112.648301] Starting subtest: yf-tiled-32bpp-rotate-90
[112.649833] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[112.730179] [020/130] (890s left) gem_ccs (suspend-resume)
[112.768587] Starting subtest: suspend-resume
[112.770192] Subtest suspend-resume: SKIP (0.000s)
[112.842626] [021/130] (889s left) syncobj_timeline (32bits-limit)
[112.872656] Starting subtest: 32bits-limit
[112.873425] Subtest 32bits-limit: SUCCESS (0.001s)
[112.946047] [022/130] (889s left) kms_psr (psr-cursor-blt)
[112.999810] Starting subtest: psr-cursor-blt
[113.000639] Starting dynamic subtest: eDP-1
[114.945386] Dynamic subtest eDP-1: SUCCESS (1.945s)
[114.946360] Subtest psr-cursor-blt: SUCCESS (1.946s)
[115.872745] [023/130] (886s left) kms_cursor_crc (cursor-onscreen-128x128)
[115.936459] Starting subtest: cursor-onscreen-128x128
[115.942441] Starting dynamic subtest: pipe-A-eDP-1
[118.460052] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.518s)
[118.517281] Starting dynamic subtest: pipe-C-eDP-1
[121.690347] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.180s)
[121.691527] Subtest cursor-onscreen-128x128: SUCCESS (5.754s)
[122.635112] [024/130] (880s left) syncobj_wait (multi-wait-submitted-signaled)
[122.676897] Starting subtest: multi-wait-submitted-signaled
[122.677689] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[122.750960] [025/130] (880s left) gem_tiled_blits (normal)
[122.866470] Starting subtest: normal
[146.281663] Subtest normal: SUCCESS (23.404s)
[146.388845] [026/130] (856s left) api_intel_bb (intel-bb-blit-none)
[146.472359] Starting subtest: intel-bb-blit-none
[150.857303] Subtest intel-bb-blit-none: SUCCESS (4.376s)
[150.974523] [027/130] (851s left) syncobj_wait (signal)
[151.010729] Starting subtest: signal
[151.011613] Subtest signal: SUCCESS (0.000s)
[151.088445] [028/130] (851s left) prime_mmap (test_reprime)
[151.126431] Starting subtest: test_reprime
[151.127240] Starting dynamic subtest: test_reprime-smem
[151.164321] Dynamic subtest test_reprime-smem: SUCCESS (0.040s)
[151.165150] Subtest test_reprime: SUCCESS (0.040s)
[151.259136] [029/130] (851s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[151.332715] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[151.334283] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[151.433830] [030/130] (851s left) kms_chamelium_hpd (vga-hpd-fast)
[151.793261] Subtest vga-hpd-fast: SKIP (0.000s)
[152.673994] [031/130] (850s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[152.790653] Starting subtest: basic-flip-before-cursor-atomic
[154.005778] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.215s)
[154.953242] [032/130] (848s left) prime_mmap_kms (buffer-sharing)
[155.017129] Starting subtest: buffer-sharing
[155.507424] Subtest buffer-sharing: SUCCESS (0.490s)
[156.424042] [033/130] (846s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[160.031334] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[160.033085] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.003s)
[160.966391] [034/130] (842s left) kms_vblank (query-forked-hang)
[161.031170] Starting subtest: query-forked-hang
[161.033693] Starting dynamic subtest: pipe-A-eDP-1
[187.869647] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.835s)
[187.878140] Starting dynamic subtest: pipe-C-eDP-1
[213.859183] Dynamic subtest pipe-C-eDP-1: SUCCESS (25.986s)
[213.860568] Subtest query-forked-hang: SUCCESS (52.825s)
[214.768554] [035/130] (788s left) gem_bad_reloc (negative-reloc-bltcopy)
[214.805924] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[214.872791] [036/130] (788s left) gem_userptr_blits (create-destroy-unsync)
[214.912686] Subtest create-destroy-unsync: SKIP (0.000s)
[215.018357] [037/130] (788s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[218.590846] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[218.598390] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.005s)
[219.514045] [038/130] (783s left) fbdev (nullptr)
[219.550854] Starting subtest: nullptr
[219.551780] Subtest nullptr: SUCCESS (0.000s)
[219.606524] [039/130] (783s left) kms_psr (fbc-psr2-cursor-blt)
[219.665205] Starting subtest: fbc-psr2-cursor-blt
[219.666187] Starting dynamic subtest: eDP-1
[219.667686] Dynamic subtest eDP-1: SKIP (0.000s)
[219.669022] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[219.745144] [040/130] (783s left) core_setmaster (master-drop-set-root)
[220.434870] Starting subtest: master-drop-set-root
[220.451918] Subtest master-drop-set-root: SUCCESS (0.017s)
[220.494221] [041/130] (782s left) i915_pm_rpm (system-suspend-devices)
[222.130996] Starting subtest: system-suspend-devices
[229.718301] Subtest system-suspend-devices: SUCCESS (7.587s)
[230.139566] [042/130] (773s left) kms_feature_discovery (display-1x)
[230.194764] Starting subtest: display-1x
[230.196023] Subtest display-1x: SUCCESS (0.000s)
[230.265728] [043/130] (772s left) dmabuf_sync_file (export-multiwait)
[230.310890] Starting subtest: export-multiwait
[230.312221] Subtest export-multiwait: SUCCESS (0.001s)
[230.372984] [044/130] (772s left) vgem_basic (create)
[230.409847] Starting subtest: create
[230.410654] Subtest create: SUCCESS (0.000s)
[230.470801] [045/130] (772s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[230.527100] Starting subtest: psr-2p-primscrn-cur-indfb-move
[230.528793] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[230.618649] [046/130] (772s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[230.674737] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[230.676175] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[230.772775] [047/130] (772s left) gem_sync (basic-all)
[230.814515] Starting subtest: basic-all
[232.933322] Subtest basic-all: SUCCESS (2.119s)
[233.125746] [048/130] (770s left) kms_joiner (basic-big-joiner)
[233.182585] Starting subtest: basic-big-joiner
[233.184377] Subtest basic-big-joiner: SKIP (0.000s)
[233.257312] [049/130] (770s left) kms_cursor_crc (cursor-random-32x10)
[233.313299] Starting subtest: cursor-random-32x10
[233.363485] Subtest cursor-random-32x10: SKIP (0.049s)
[233.470289] [050/130] (769s left) syncobj_timeline (wait-all-interrupted)
[233.500371] Starting subtest: wait-all-interrupted
[233.602965] Subtest wait-all-interrupted: SUCCESS (0.102s)
[233.687494] [051/130] (769s left) syncobj_timeline (wait-for-submit-complex)
[233.717016] Starting subtest: wait-for-submit-complex
[233.780042] Subtest wait-for-submit-complex: SUCCESS (0.063s)
[233.860295] [052/130] (769s left) kms_plane_scaling (planes-downscale-factor-0-75)
[233.918758] Starting subtest: planes-downscale-factor-0-75
[233.919628] Starting dynamic subtest: pipe-A
[234.034927] Dynamic subtest pipe-A: SUCCESS (0.115s)
[234.054982] Starting dynamic subtest: pipe-B
[235.358276] Dynamic subtest pipe-B: SUCCESS (1.322s)
[235.379560] Starting dynamic subtest: pipe-C
[236.686140] Dynamic subtest pipe-C: SUCCESS (1.327s)
[236.687403] Subtest planes-downscale-factor-0-75: SUCCESS (2.767s)
[237.962206] [053/130] (765s left) kms_async_flips (alternate-sync-async-flip)
[238.026343] Starting subtest: alternate-sync-async-flip
[238.427125] Starting dynamic subtest: pipe-A-eDP-1
[241.290180] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.862s)
[241.717585] Starting dynamic subtest: pipe-B-eDP-1
[244.601552] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.898s)
[245.016856] Starting dynamic subtest: pipe-C-eDP-1
[247.930089] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.921s)
[247.932031] Subtest alternate-sync-async-flip: SUCCESS (9.905s)
[249.224712] [054/130] (754s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[249.289654] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[249.291346] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[249.374302] [055/130] (754s left) kms_hdr (static-swap)
[249.434890] Starting subtest: static-swap
[250.640453] Starting dynamic subtest: pipe-A-eDP-1
[257.004253] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.365s)
[257.006201] Subtest static-swap: SUCCESS (7.569s)
[257.118109] [056/130] (746s left) gem_ppgtt (shrink-vs-evict-any)
[257.157386] Starting subtest: shrink-vs-evict-any
[265.038335] Subtest shrink-vs-evict-any: SUCCESS (7.846s)
[265.097795] [057/130] (738s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[265.169789] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[266.730630] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.560s)
[266.858202] [058/130] (736s left) gem_mmap_gtt (bad-object)
[266.888649] Starting subtest: bad-object
[266.889446] Subtest bad-object: SUCCESS (0.000s)
[266.975119] [059/130] (736s left) kms_cursor_edge_walk (128x128-right-edge)
[267.034155] Starting subtest: 128x128-right-edge
[267.036867] Starting dynamic subtest: pipe-A-eDP-1
[271.649357] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.611s)
[271.661680] Starting dynamic subtest: pipe-C-eDP-1
[275.954503] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.304s)
[275.955703] Subtest 128x128-right-edge: SUCCESS (8.919s)
[276.878151] [060/130] (726s left) gen3_render_tiledx_blits
[276.959103] [061/130] (726s left) gem_spin_batch (user-each)
[277.002253] Starting subtest: user-each
[280.259738] Subtest user-each: SUCCESS (3.252s)
[280.490400] [062/130] (723s left) gem_exec_schedule (implicit-write-read)
[280.531531] Starting subtest: implicit-write-read
[280.532323] Starting dynamic subtest: rcs0
[280.595443] Dynamic subtest rcs0: SUCCESS (0.064s)
[280.614541] Starting dynamic subtest: bcs0
[280.684046] Dynamic subtest bcs0: SUCCESS (0.087s)
[280.697969] Starting dynamic subtest: vcs0
[280.763480] Dynamic subtest vcs0: SUCCESS (0.078s)
[280.778937] Starting dynamic subtest: vecs0
[280.843422] Dynamic subtest vecs0: SUCCESS (0.078s)
[280.844719] Subtest implicit-write-read: SUCCESS (0.313s)
[281.204552] [063/130] (722s left) gem_ctx_isolation (dirty-create)
[281.244902] Starting subtest: dirty-create
[281.245990] Starting dynamic subtest: rcs0
[281.395922] Dynamic subtest rcs0: SUCCESS (0.151s)
[281.404747] Starting dynamic subtest: bcs0
[281.621590] Dynamic subtest bcs0: SUCCESS (0.225s)
[281.632848] Starting dynamic subtest: vcs0
[281.810606] Dynamic subtest vcs0: SUCCESS (0.186s)
[281.819792] Starting dynamic subtest: vecs0
[282.030683] Dynamic subtest vecs0: SUCCESS (0.222s)
[282.031552] Subtest dirty-create: SUCCESS (0.786s)
[282.267758] [064/130] (721s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[282.329028] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[282.331069] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[282.443822] [065/130] (721s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[282.502223] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[282.503144] Starting dynamic subtest: pipe-A-default-mode
[283.801769] Dynamic subtest pipe-A-default-mode: SKIP (1.298s)
[283.803842] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (1.300s)
[283.973753] [066/130] (719s left) kms_chamelium_edid (dp-edid-resolution-list)
[284.342334] Subtest dp-edid-resolution-list: SKIP (0.000s)
[285.220222] [067/130] (718s left) kms_psr (pr-sprite-render)
[285.280854] Starting subtest: pr-sprite-render
[285.282481] Subtest pr-sprite-render: SKIP (0.000s)
[285.359306] [068/130] (718s left) syncobj_wait (invalid-reset-bad-pad)
[285.389820] Starting subtest: invalid-reset-bad-pad
[285.390793] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[285.459574] [069/130] (718s left) i915_hangman (error-state-basic)
[285.499438] Starting subtest: error-state-basic
[285.625381] Subtest error-state-basic: SUCCESS (0.126s)
[285.712420] [070/130] (718s left) perf_pmu (render-node-busy)
[285.751140] Starting subtest: render-node-busy
[285.752053] Starting dynamic subtest: rcs0
[286.324460] Dynamic subtest rcs0: SUCCESS (0.572s)
[286.344459] Starting dynamic subtest: bcs0
[286.878321] Dynamic subtest bcs0: SUCCESS (0.552s)
[286.897241] Starting dynamic subtest: vcs0
[287.434245] Dynamic subtest vcs0: SUCCESS (0.554s)
[287.445858] Starting dynamic subtest: vecs0
[287.993357] Dynamic subtest vecs0: SUCCESS (0.557s)
[287.995281] Subtest render-node-busy: SUCCESS (2.243s)
[288.084128] [071/130] (715s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[288.151282] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[288.153198] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[288.244377] [072/130] (715s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[288.307382] Starting subtest: 4-tiled-32bpp-rotate-180
[288.308931] Subtest 4-tiled-32bpp-rotate-180: SKIP (0.000s)
[288.395810] [073/130] (715s left) kms_flip (2x-plain-flip)
[288.455926] Starting subtest: 2x-plain-flip
[288.459295] Subtest 2x-plain-flip: SKIP (0.004s)
[288.545580] [074/130] (715s left) gem_mmap_gtt (basic-copy)
[288.577206] Starting subtest: basic-copy
[289.474506] Subtest basic-copy: SUCCESS (0.892s)
[289.578942] [075/130] (714s left) gem_exec_schedule (preempt-user)
[289.622256] Starting subtest: preempt-user
[289.623140] Starting dynamic subtest: rcs0
[289.642870] Dynamic subtest rcs0: SUCCESS (0.020s)
[289.653519] Starting dynamic subtest: bcs0
[289.660441] Dynamic subtest bcs0: SUCCESS (0.017s)
[289.670692] Starting dynamic subtest: vcs0
[289.678008] Dynamic subtest vcs0: SUCCESS (0.017s)
[289.688258] Starting dynamic subtest: vecs0
[289.695541] Dynamic subtest vecs0: SUCCESS (0.017s)
[289.696322] Subtest preempt-user: SUCCESS (0.074s)
[290.033884] [076/130] (713s left) gem_exec_balancer (full-late)
[290.070895] Starting subtest: full-late
[291.151579] Subtest full-late: SUCCESS (1.081s)
[292.199585] [077/130] (711s left) gem_exec_reloc (basic-gtt-cpu-active)
[292.240288] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[292.306145] [078/130] (711s left) core_auth (basic-auth)
[292.340691] Starting subtest: basic-auth
[292.341583] Subtest basic-auth: SUCCESS (0.001s)
[292.420328] [079/130] (711s left) gem_workarounds (suspend-resume-fd)
[292.461986] Starting subtest: suspend-resume-fd
[308.624865] Subtest suspend-resume-fd: SUCCESS (1.482s)
[308.709309] [080/130] (709s left) gem_render_copy_redux (normal)
[308.746406] Starting subtest: normal
[309.166558] Subtest normal: SUCCESS (0.420s)
[309.286395] [081/130] (709s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[309.345626] Starting subtest: compare-crc-sanitycheck-xr24
[309.346790] Starting dynamic subtest: pipe-A-eDP-1
[310.069893] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.725s)
[310.094282] Starting dynamic subtest: pipe-B-eDP-1
[311.559054] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.492s)
[311.570664] Starting dynamic subtest: pipe-C-eDP-1
[313.007862] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.447s)
[313.009162] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.670s)
[313.939785] [082/130] (704s left) gem_tiled_partial_pwrite_pread (reads)
[313.980187] Subtest reads: SKIP (0.000s)
[314.044861] [083/130] (704s left) gem_exec_params (dr1-dirt)
[314.080755] Starting subtest: DR1-dirt
[314.081600] Subtest DR1-dirt: SUCCESS (0.000s)
[314.172758] [084/130] (704s left) gen7_exec_parse (cmd-crossing-page)
[314.205717] Subtest cmd-crossing-page: SKIP (0.000s)
[314.236734] [085/130] (704s left) kms_cdclk (mode-transition)
[314.294669] Starting subtest: mode-transition
[314.295737] Starting dynamic subtest: pipe-A-eDP-1
[314.606646] Dynamic subtest pipe-A-eDP-1: SKIP (0.313s)
[314.616930] Starting dynamic subtest: pipe-B-eDP-1
[314.618488] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[314.629775] Starting dynamic subtest: pipe-C-eDP-1
[314.631284] Dynamic subtest pipe-C-eDP-1: SKIP (0.002s)
[314.633516] Subtest mode-transition: SKIP (0.320s)
[315.541261] [086/130] (703s left) gem_exec_whisper (basic-fds-forked)
[315.581108] Starting subtest: basic-fds-forked
[321.723375] Subtest basic-fds-forked: SUCCESS (6.141s)
[321.907368] [087/130] (696s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[321.974904] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[321.976625] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[322.092565] [088/130] (696s left) gem_exec_balancer (bonded-false-hang)
[323.088418] Starting subtest: bonded-false-hang
[323.090027] Subtest bonded-false-hang: SKIP (0.000s)
[323.172550] [089/130] (695s left) gem_exec_fence (basic-busy)
[323.332723] Starting subtest: basic-busy
[323.334103] Starting dynamic subtest: rcs0
[323.382282] Dynamic subtest rcs0: SUCCESS (0.052s)
[323.402633] Starting dynamic subtest: bcs0
[323.430073] Dynamic subtest bcs0: SUCCESS (0.046s)
[323.447801] Starting dynamic subtest: vcs0
[323.478146] Dynamic subtest vcs0: SUCCESS (0.046s)
[323.496584] Starting dynamic subtest: vecs0
[323.526106] Dynamic subtest vecs0: SUCCESS (0.046s)
[323.527716] Subtest basic-busy: SUCCESS (0.198s)
[324.009140] [090/130] (694s left) gem_reg_read (bad-register)
[324.040773] Starting subtest: bad-register
[324.041566] Subtest bad-register: SUCCESS (0.000s)
[324.110329] [091/130] (694s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[324.177269] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[324.179148] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[324.295209] [092/130] (694s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[324.352612] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[324.355114] Starting dynamic subtest: pipe-A-default-mode
[324.664103] Dynamic subtest pipe-A-default-mode: SKIP (0.309s)
[324.666425] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.311s)
[325.588647] [093/130] (693s left) perf (create-destroy-userspace-config)
[325.693846] Starting subtest: create-destroy-userspace-config
[325.712287] Subtest create-destroy-userspace-config: SUCCESS (0.018s)
[325.740432] [094/130] (693s left) kms_pm_backlight (fade-with-dpms)
[325.795422] Starting subtest: fade-with-dpms
[328.016887] Starting dynamic subtest: eDP-1
[330.646888] Dynamic subtest eDP-1: SUCCESS (2.633s)
[330.649804] Subtest fade-with-dpms: SUCCESS (4.856s)
[331.577889] [095/130] (687s left) gem_spin_batch (engines)
[331.616866] Starting subtest: engines
[331.617693] Starting dynamic subtest: rcs0
[334.861078] Dynamic subtest rcs0: SUCCESS (3.236s)
[334.884577] Starting dynamic subtest: bcs0
[338.097616] Dynamic subtest bcs0: SUCCESS (3.236s)
[338.120603] Starting dynamic subtest: vcs0
[341.323260] Dynamic subtest vcs0: SUCCESS (3.235s)
[341.333510] Starting dynamic subtest: vecs0
[344.595126] Dynamic subtest vecs0: SUCCESS (3.235s)
[344.596603] Subtest engines: SUCCESS (12.947s)
[344.807421] [096/130] (674s left) kms_vrr (flip-basic)
[344.869260] Starting subtest: flip-basic
[344.871597] Subtest flip-basic: SKIP (0.000s)
[344.935101] [097/130] (673s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[345.045873] Starting subtest: short-busy-flip-before-cursor-toggle
[345.048198] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[345.187147] [098/130] (673s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[345.293238] Starting subtest: cursorA-vs-flipA-legacy
[356.404164] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.109s)
[357.333971] [099/130] (661s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[357.369525] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[357.370534] Starting dynamic subtest: smem
[357.464551] Dynamic subtest smem: SUCCESS (0.095s)
[357.465368] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SUCCESS (0.095s)
[357.692221] [100/130] (661s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[357.746318] Starting subtest: crc-primary-basic-y-tiled-ccs
[357.747149] Starting dynamic subtest: pipe-A-eDP-1
[357.755375] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[357.765285] Starting dynamic subtest: pipe-B-eDP-1
[357.772702] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[357.784653] Starting dynamic subtest: pipe-C-eDP-1
[357.791840] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[357.793284] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.008s)
[357.827172] [101/130] (661s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[357.885011] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[357.886815] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[357.958758] [102/130] (661s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[358.019244] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[359.451350] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.432s)
[359.580168] [103/130] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[359.636703] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[359.638864] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[359.729878] [104/130] (659s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[359.785876] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[359.788701] Starting dynamic subtest: pipe-A-eDP-1
[359.797925] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[359.812654] Starting dynamic subtest: pipe-B-eDP-1
[359.820666] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[359.835246] Starting dynamic subtest: pipe-C-eDP-1
[359.843079] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[359.844545] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.006s)
[359.858212] [105/130] (659s left) perf_pmu (busy-double-start)
[359.890380] Starting subtest: busy-double-start
[359.891207] Starting dynamic subtest: rcs0
[363.497055] Dynamic subtest rcs0: FAIL (3.584s)
[363.514068] Starting dynamic subtest: bcs0
[367.033894] Dynamic subtest bcs0: SUCCESS (3.559s)
[367.053475] Starting dynamic subtest: vcs0
[370.634226] Dynamic subtest vcs0: FAIL (3.558s)
[370.650588] Starting dynamic subtest: vecs0
[374.188914] Dynamic subtest vecs0: FAIL (3.556s)
[374.190488] Subtest busy-double-start: FAIL (14.260s)
[374.256290] [106/130] (644s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[374.310123] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[374.311021] Starting dynamic subtest: pipe-A-eDP-1
[374.318245] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[374.328190] Starting dynamic subtest: pipe-B-eDP-1
[374.336557] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[374.347541] Starting dynamic subtest: pipe-C-eDP-1
[374.354515] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[374.355879] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.004s)
[374.383647] [107/130] (644s left) gem_mmap_gtt (basic)
[374.415136] Starting subtest: basic
[374.415900] Subtest basic: SUCCESS (0.001s)
[374.475002] [108/130] (644s left) gem_exec_params (sol-reset-not-gen7)
[374.506231] Starting subtest: sol-reset-not-gen7
[374.508303] Subtest sol-reset-not-gen7: SUCCESS (0.001s)
[374.623366] [109/130] (644s left) kms_prop_blob (basic)
[374.656958] Starting subtest: basic
[374.657761] Subtest basic: SUCCESS (0.000s)
[374.738669] [110/130] (644s left) perf_pmu (semaphore-wait-idle)
[374.770037] Starting subtest: semaphore-wait-idle
[374.770870] Starting dynamic subtest: rcs0
[375.289754] Dynamic subtest rcs0: SUCCESS (0.510s)
[375.312184] Starting dynamic subtest: bcs0
[375.801242] Dynamic subtest bcs0: SUCCESS (0.510s)
[375.823386] Starting dynamic subtest: vcs0
[376.312508] Dynamic subtest vcs0: SUCCESS (0.509s)
[376.335697] Starting dynamic subtest: vecs0
[376.823140] Dynamic subtest vecs0: SUCCESS (0.509s)
[376.824882] Subtest semaphore-wait-idle: SUCCESS (2.045s)
[376.942608] [111/130] (642s left) gem_exec_fence (syncobj-timeline-signal)
[377.346362] Starting subtest: syncobj-timeline-signal
[377.354662] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[377.591570] [112/130] (641s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[377.653557] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[377.655098] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[377.738164] [113/130] (641s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[377.796640] Starting subtest: sprite-rotation-90-pos-100-0
[377.798847] Subtest sprite-rotation-90-pos-100-0: SKIP (0.002s)
[377.870290] [114/130] (641s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[380.431016] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[380.508146] [115/130] (638s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[380.566992] Starting subtest: multiplane-rotation-cropping-bottom
[391.104759] Subtest multiplane-rotation-cropping-bottom: SUCCESS (10.540s)
[391.201729] [116/130] (628s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[391.237222] Starting subtest: invalid-single-wait-unsubmitted
[391.238104] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[391.313842] [117/130] (628s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[391.374709] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[394.758448] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (3.383s)
[394.889283] [118/130] (624s left) syncobj_timeline (multi-wait-all-signaled)
[394.924753] Starting subtest: multi-wait-all-signaled
[394.925644] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[395.000462] [119/130] (624s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[395.110140] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[405.086969] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.980s)
[406.034366] [120/130] (613s left) kms_flip (basic-flip-vs-modeset)
[406.095274] Starting subtest: basic-flip-vs-modeset
[406.097939] Starting dynamic subtest: A-eDP1
[408.879916] Dynamic subtest A-eDP1: SUCCESS (2.780s)
[408.901601] Starting dynamic subtest: B-eDP1
[411.312230] Dynamic subtest B-eDP1: SUCCESS (2.429s)
[411.333501] Starting dynamic subtest: C-eDP1
[413.711789] Dynamic subtest C-eDP1: SUCCESS (2.399s)
[413.715456] Subtest basic-flip-vs-modeset: SUCCESS (7.616s)
[414.676714] [121/130] (604s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[414.783212] Starting subtest: flip-vs-cursor-crc-atomic
[415.695816] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.913s)
[416.663648] [122/130] (602s left) gem_exec_whisper (basic-queues-all)
[416.706033] Starting subtest: basic-queues-all
[422.299749] Subtest basic-queues-all: SUCCESS (5.591s)
[422.497294] [123/130] (596s left) prime_mmap (test_forked_cpu_write)
[422.525779] Starting subtest: test_forked_cpu_write
[422.526613] Starting dynamic subtest: test_forked_cpu_write-smem
[422.533951] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.009s)
[422.534941] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[422.628602] [124/130] (596s left) kms_color (invalid-ctm-matrix-sizes)
[422.685012] Starting subtest: invalid-ctm-matrix-sizes
[422.685772] Starting dynamic subtest: pipe-A
[422.687265] Dynamic subtest pipe-A: SUCCESS (0.003s)
[422.697639] Starting dynamic subtest: pipe-B
[422.698440] Dynamic subtest pipe-B: SUCCESS (0.005s)
[422.708691] Starting dynamic subtest: pipe-C
[422.709433] Dynamic subtest pipe-C: SUCCESS (0.006s)
[422.710168] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.016s)
[422.776992] [125/130] (596s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[422.831101] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[422.832640] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[422.910443] [126/130] (596s left) kms_pm_backlight (basic-brightness)
[422.965738] Starting subtest: basic-brightness
[423.017547] Starting dynamic subtest: eDP-1
[423.344514] Dynamic subtest eDP-1: SUCCESS (0.330s)
[423.345355] Subtest basic-brightness: SUCCESS (0.380s)
[424.276807] [127/130] (595s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[424.337651] Starting subtest: extended-pageflip-modeset-hang-oldfb
[424.340146] Starting dynamic subtest: pipe-A
[445.245203] Dynamic subtest pipe-A: SUCCESS (20.902s)
[445.255580] Starting dynamic subtest: pipe-C
[453.979379] Dynamic subtest pipe-C: SUCCESS (8.733s)
[453.980279] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (29.640s)
[454.932694] [128/130] (564s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[454.992446] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[458.683279] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (3.690s)
[458.813879] [129/130] (560s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[458.846948] Starting subtest: etime-single-wait-all-available-unsubmitted
[458.948511] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[459.030981] [130/130] (560s left) gem_ctx_persistence (engines-hostile-preempt)
[459.061849] Starting subtest: engines-hostile-preempt
[459.062704] Starting dynamic subtest: rcs0
[459.112144] Dynamic subtest rcs0: SUCCESS (0.049s)
[459.132477] Starting dynamic subtest: bcs0
[459.147891] Dynamic subtest bcs0: SUCCESS (0.034s)
[459.167260] Starting dynamic subtest: vcs0
[459.168062] Dynamic subtest vcs0: SUCCESS (0.016s)
[459.177165] Starting dynamic subtest: vecs0
[459.234900] Dynamic subtest vecs0: SUCCESS (0.068s)
[459.236450] Subtest engines-hostile-preempt: SUCCESS (0.173s)
[459.322309] Closing watchdogs
Done.