[41.269934] Initializing watchdogs [41.269972] /dev/watchdog0 [41.276772] [001/142] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt) [41.391306] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt [41.395879] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [41.474840] [002/142] (960s left) kms_flip (flip-vs-panning-vs-hang) [41.542526] Starting subtest: flip-vs-panning-vs-hang [41.545045] Starting dynamic subtest: A-eDP1 [79.960877] Dynamic subtest A-eDP1: SUCCESS (38.419s) [79.971379] Starting dynamic subtest: D-eDP1 [130.006625] Dynamic subtest D-eDP1: SUCCESS (50.045s) [130.011641] Subtest flip-vs-panning-vs-hang: SUCCESS (88.473s) [131.303251] [003/142] (870s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc) [131.699671] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc [131.704850] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [132.580926] [004/142] (869s left) gem_ctx_isolation (preservation-reset) [132.757943] Starting subtest: preservation-reset [132.760773] Starting dynamic subtest: rcs0 [132.964520] Dynamic subtest rcs0: SUCCESS (0.207s) [132.976689] Starting dynamic subtest: bcs0 [133.198136] Dynamic subtest bcs0: SUCCESS (0.234s) [133.205926] Starting dynamic subtest: vcs0 [133.452122] Dynamic subtest vcs0: SUCCESS (0.253s) [133.456465] Starting dynamic subtest: vcs1 [134.621313] Dynamic subtest vcs1: SUCCESS (1.170s) [134.631098] Starting dynamic subtest: vecs0 [136.606067] Dynamic subtest vecs0: SUCCESS (1.985s) [136.611094] Starting dynamic subtest: ccs0 [138.656938] Dynamic subtest ccs0: SUCCESS (2.050s) [138.659638] Subtest preservation-reset: SUCCESS (5.900s) [138.768424] [005/142] (863s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size) [138.874382] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size [138.879334] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s) [138.960243] [006/142] (862s left) gem_exec_capture (capture-invisible) [139.022624] Starting subtest: capture-invisible [139.025116] Starting dynamic subtest: smem0 [139.029686] Dynamic subtest smem0: SKIP (0.000s) [139.034264] Subtest capture-invisible: SKIP (0.000s) [139.104673] [007/142] (862s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt) [139.183228] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt [142.552200] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (3.374s) [142.676779] [008/142] (859s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt) [142.757439] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt [142.761229] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [142.833662] [009/142] (859s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt) [142.887031] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt [142.890393] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [142.972816] [010/142] (859s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_mc_ccs) [143.373129] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs [143.395119] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SUCCESS (0.022s) [144.194921] [011/142] (857s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [144.283282] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu [144.287213] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [144.364410] [012/142] (857s left) gem_userptr_blits (stress-mm-invalidate-close) [144.431505] Starting subtest: stress-mm-invalidate-close [146.672257] Subtest stress-mm-invalidate-close: SUCCESS (2.241s) [147.194833] [013/142] (854s left) vc4/vc4_purgeable_bo (access-purgeable-bo-mem) [147.306790] Subtest access-purgeable-bo-mem: SKIP (0.000s) [147.353701] [014/142] (854s left) kms_addfb_basic (basic-y-tiled-legacy) [147.422829] Starting subtest: basic-y-tiled-legacy [147.427446] Subtest basic-y-tiled-legacy: SKIP (0.000s) [147.540851] [015/142] (854s left) kms_pm_backlight (bad-brightness) [147.601136] Starting subtest: bad-brightness [147.648627] Starting dynamic subtest: eDP-1 [147.932948] Dynamic subtest eDP-1: SUCCESS (0.286s) [147.935607] Subtest bad-brightness: SUCCESS (0.334s) [148.833294] [016/142] (853s left) api_intel_bb (object-reloc-purge-cache) [148.892938] Starting subtest: object-reloc-purge-cache [148.897929] Subtest object-reloc-purge-cache: SKIP (0.001s) [148.966357] [017/142] (853s left) gem_lmem_swapping (heavy-multi) [149.032346] Subtest heavy-multi: SKIP (0.000s) [149.065593] [018/142] (853s left) gem_exec_capture (userptr) [149.104692] Starting subtest: userptr [149.923396] Subtest userptr: SUCCESS (0.821s) [150.013503] [019/142] (852s left) kms_color (invalid-ctm-matrix-sizes) [150.072852] Starting subtest: invalid-ctm-matrix-sizes [150.075396] Starting dynamic subtest: pipe-A [150.077886] Dynamic subtest pipe-A: SUCCESS (0.002s) [150.088808] Starting dynamic subtest: pipe-B [150.090642] Dynamic subtest pipe-B: SUCCESS (0.003s) [150.096553] Starting dynamic subtest: pipe-C [150.098721] Dynamic subtest pipe-C: SUCCESS (0.004s) [150.104394] Starting dynamic subtest: pipe-D [150.106554] Dynamic subtest pipe-D: SUCCESS (0.004s) [150.108741] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.014s) [150.141143] [020/142] (852s left) gem_userptr_blits (input-checking) [150.199166] Starting subtest: input-checking [150.201493] Subtest input-checking: SUCCESS (0.000s) [150.305884] [021/142] (851s left) kms_cursor_crc (cursor-offscreen-128x42) [150.415125] Starting subtest: cursor-offscreen-128x42 [150.417458] Starting dynamic subtest: pipe-A-eDP-1 [152.381424] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.965s) [152.407406] Starting dynamic subtest: pipe-D-eDP-1 [155.112427] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.706s) [155.114500] Subtest cursor-offscreen-128x42: SUCCESS (4.696s) [156.002609] [022/142] (846s left) gem_mmap_offset (ptrace) [156.050675] Starting subtest: ptrace [156.052858] Starting dynamic subtest: smem0-wb [156.078664] Dynamic subtest smem0-wb: SUCCESS (0.028s) [156.090684] Starting dynamic subtest: smem0-wc [156.113186] Dynamic subtest smem0-wc: SUCCESS (0.034s) [156.122991] Starting dynamic subtest: smem0-uc [156.141802] Dynamic subtest smem0-uc: SUCCESS (0.029s) [156.144348] Subtest ptrace: SUCCESS (0.094s) [156.229427] [023/142] (846s left) kms_cursor_crc (cursor-random-128x128) [156.347091] Starting subtest: cursor-random-128x128 [156.349538] Starting dynamic subtest: pipe-A-eDP-1 [159.724945] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.376s) [159.750157] Starting dynamic subtest: pipe-D-eDP-1 [163.796474] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.049s) [163.798950] Subtest cursor-random-128x128: SUCCESS (7.450s) [164.690703] [024/142] (837s left) i915_power (sanity) [164.738281] Starting subtest: sanity [168.761181] Subtest sanity: SUCCESS (4.017s) [170.518918] [025/142] (831s left) v3d/v3d_wait_bo (unused-bo-1ns) [170.610483] Subtest unused-bo-1ns: SKIP (0.000s) [170.659971] [026/142] (831s left) gem_create (create-ext-placement-all) [170.829808] Starting subtest: create-ext-placement-all [170.832887] Subtest create-ext-placement-all: SUCCESS (0.000s) [170.904603] [027/142] (831s left) kms_plane_alpha_blend (alpha-opaque-fb) [170.957973] Starting subtest: alpha-opaque-fb [171.190749] Starting dynamic subtest: pipe-A-eDP-1 [171.868724] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.677s) [171.882302] Starting dynamic subtest: pipe-D-eDP-1 [173.342689] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.473s) [173.345373] Subtest alpha-opaque-fb: SUCCESS (2.386s) [174.247790] [028/142] (828s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt) [174.319275] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt [174.324057] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [174.412653] [029/142] (828s left) kms_plane_lowres (tiling-4) [174.482960] Starting subtest: tiling-4 [174.485398] Starting dynamic subtest: pipe-A-eDP-1 [174.490193] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [174.780185] Starting dynamic subtest: pipe-B-eDP-1 [174.785389] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [174.797120] Starting dynamic subtest: pipe-C-eDP-1 [174.801839] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [174.812620] Starting dynamic subtest: pipe-D-eDP-1 [174.817289] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [174.821956] Subtest tiling-4: SKIP (0.296s) [175.645870] [030/142] (826s left) gem_exec_balancer (full-late) [175.711032] Starting subtest: full-late [179.988097] Subtest full-late: SUCCESS (4.277s) [180.232216] [031/142] (822s left) syncobj_timeline (signal) [180.285195] Starting subtest: signal [180.287516] Subtest signal: SUCCESS (0.001s) [180.349405] [032/142] (822s left) drm_fdinfo (busy-hang) [180.407872] Starting subtest: busy-hang [180.410825] Starting dynamic subtest: rcs0 [180.416447] Dynamic subtest rcs0: SKIP (0.001s) [180.424055] Starting dynamic subtest: bcs0 [180.429099] Dynamic subtest bcs0: SKIP (0.000s) [180.435746] Starting dynamic subtest: vcs0 [180.440221] Dynamic subtest vcs0: SKIP (0.000s) [180.447243] Starting dynamic subtest: vcs1 [180.452313] Dynamic subtest vcs1: SKIP (0.000s) [180.464517] Starting dynamic subtest: vecs0 [180.469459] Dynamic subtest vecs0: SKIP (0.000s) [180.482448] Starting dynamic subtest: ccs0 [180.488022] Dynamic subtest ccs0: SKIP (0.000s) [180.492835] Subtest busy-hang: SKIP (0.003s) [180.513299] [033/142] (822s left) gem_exec_schedule (implicit-write-read) [180.581682] Starting subtest: implicit-write-read [180.584042] Starting dynamic subtest: rcs0 [180.672667] Dynamic subtest rcs0: SUCCESS (0.109s) [180.687258] Starting dynamic subtest: vcs0 [180.736903] Dynamic subtest vcs0: SUCCESS (0.064s) [180.750154] Starting dynamic subtest: vcs1 [180.797431] Dynamic subtest vcs1: SUCCESS (0.060s) [180.810422] Starting dynamic subtest: vecs0 [180.858560] Dynamic subtest vecs0: SUCCESS (0.061s) [180.871930] Starting dynamic subtest: ccs0 [180.949567] Dynamic subtest ccs0: SUCCESS (0.091s) [180.952176] Subtest implicit-write-read: SUCCESS (0.386s) [182.511089] [034/142] (820s left) vc4/vc4_wait_bo (bad-bo) [182.582538] Subtest bad-bo: SKIP (0.000s) [182.632089] [035/142] (819s left) gem_mmap_offset (bad-object) [182.697438] Starting subtest: bad-object [182.699932] Subtest bad-object: SUCCESS (0.002s) [182.786143] [036/142] (819s left) i915_module_load (reload-no-display) [182.845124] Starting subtest: reload-no-display [186.132232] Subtest reload-no-display: SUCCESS (3.287s) [186.152126] [037/142] (816s left) gem_exec_reloc (basic-cpu-read-active) [187.093664] Subtest basic-cpu-read-active: SKIP (0.000s) [187.156606] [038/142] (815s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling) [187.212075] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling [187.214559] Starting dynamic subtest: pipe-A-default-mode [187.518756] Dynamic subtest pipe-A-default-mode: SKIP (0.304s) [187.523715] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.305s) [188.452836] [039/142] (814s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [188.536433] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [188.541222] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [188.620294] [040/142] (814s left) gem_eio (banned) [188.778831] Starting subtest: banned [189.082897] Subtest banned: SUCCESS (0.303s) [190.473119] [041/142] (812s left) kms_big_fb (yf-tiled-64bpp-rotate-90) [190.541070] Starting subtest: yf-tiled-64bpp-rotate-90 [190.545613] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s) [190.613448] [042/142] (812s left) gem_exec_whisper (basic-contexts-all) [190.678962] Starting subtest: basic-contexts-all [208.392378] Subtest basic-contexts-all: SUCCESS (17.713s) [208.567063] [043/142] (794s left) v3d/v3d_perfmon (create-perfmon-exceed) [208.654876] Subtest create-perfmon-exceed: SKIP (0.000s) [208.702532] [044/142] (794s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_mc_ccs) [209.122203] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_mc_ccs [209.129564] Subtest pipe-B-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s) [209.972220] [045/142] (792s left) kms_universal_plane (disable-primary-vs-flip-pipe-b) [210.043286] Starting subtest: disable-primary-vs-flip-pipe-B [212.854711] Subtest disable-primary-vs-flip-pipe-B: SUCCESS (2.814s) [213.801480] [046/142] (789s left) kms_cursor_edge_walk (128x128-right-edge) [213.890975] Starting subtest: 128x128-right-edge [213.893378] Starting dynamic subtest: pipe-A-eDP-1 [217.440883] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.549s) [217.452755] Starting dynamic subtest: pipe-D-eDP-1 [220.671732] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.228s) [220.674324] Subtest 128x128-right-edge: SUCCESS (6.781s) [221.596378] [047/142] (781s left) gem_exec_params (sol-reset-invalid) [221.656534] Starting subtest: sol-reset-invalid [221.659074] Subtest sol-reset-invalid: SUCCESS (0.001s) [223.512174] [048/142] (779s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled) [223.565634] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled [223.567620] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s) [223.625669] [049/142] (779s left) gem_ctx_persistence (smoketest) [223.673078] Starting subtest: smoketest [255.372402] Subtest smoketest: SUCCESS (31.700s) [255.492031] [050/142] (747s left) gem_softpin (evict-single-offset) [255.547887] Starting subtest: evict-single-offset [282.383318] Subtest evict-single-offset: SUCCESS (26.834s) [282.464145] [051/142] (720s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_mc_ccs) [282.972516] Starting subtest: pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs [282.980604] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s) [283.820770] [052/142] (719s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_dg2_mc_ccs) [284.190580] Starting subtest: pipe-A-bad-rotation-90-4_tiled_dg2_mc_ccs [284.195174] Subtest pipe-A-bad-rotation-90-4_tiled_dg2_mc_ccs: SKIP (0.000s) [285.086038] [053/142] (717s left) kms_vblank (pipe-c-wait-busy-hang) [285.168085] Starting subtest: pipe-C-wait-busy-hang [300.283321] Subtest pipe-C-wait-busy-hang: SUCCESS (15.113s) [301.227998] [054/142] (701s left) gem_ccs (block-copy-uncompressed) [301.280588] Starting subtest: block-copy-uncompressed [301.282911] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem [301.302078] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s) [301.308917] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem [301.331945] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.030s) [301.341644] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem [301.356709] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s) [301.368907] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem [301.373370] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s) [301.375724] Subtest block-copy-uncompressed: SUCCESS (0.094s) [301.467409] [055/142] (701s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move) [301.555051] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move [301.558998] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [301.646689] [056/142] (701s left) gem_render_copy (y-tiled-ccs-to-y-tiled) [301.714869] Starting subtest: y-tiled-ccs-to-y-tiled [301.719373] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s) [301.893171] [057/142] (701s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt) [301.978456] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt [301.983016] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [302.065669] [058/142] (701s left) gem_exec_fair (basic-none-rrul) [302.143079] Subtest basic-none-rrul: SKIP (0.000s) [302.189689] [059/142] (701s left) gen3_render_mixed_blits [302.279238] [060/142] (700s left) kms_ccs (pipe-a-bad-aux-stride-yf_tiled_ccs) [302.676305] Starting subtest: pipe-A-bad-aux-stride-yf_tiled_ccs [302.681545] Subtest pipe-A-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s) [303.583422] [061/142] (699s left) perf_pmu (idle) [303.637686] Starting subtest: idle [303.639870] Starting dynamic subtest: rcs0 [304.177761] Dynamic subtest rcs0: SUCCESS (0.534s) [304.192071] Starting dynamic subtest: bcs0 [304.685493] Dynamic subtest bcs0: SUCCESS (0.510s) [304.701038] Starting dynamic subtest: vcs0 [305.203459] Dynamic subtest vcs0: SUCCESS (0.516s) [305.217882] Starting dynamic subtest: vcs1 [305.731528] Dynamic subtest vcs1: SUCCESS (0.526s) [305.746072] Starting dynamic subtest: vecs0 [306.254455] Dynamic subtest vecs0: SUCCESS (0.517s) [306.268800] Starting dynamic subtest: ccs0 [306.767813] Dynamic subtest ccs0: SUCCESS (0.514s) [306.770905] Subtest idle: SUCCESS (3.129s) [306.840789] [062/142] (696s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_mc_ccs) [307.288358] Starting subtest: pipe-D-random-ccs-data-y_tiled_gen12_mc_ccs [307.297364] Subtest pipe-D-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s) [308.125303] [063/142] (695s left) kms_psr (sprite_mmap_cpu) [308.208587] Starting subtest: sprite_mmap_cpu [310.042384] Subtest sprite_mmap_cpu: SUCCESS (1.836s) [310.950417] [064/142] (692s left) kms_flip (2x-flip-vs-suspend) [311.031525] Starting subtest: 2x-flip-vs-suspend [311.048522] Subtest 2x-flip-vs-suspend: SKIP (0.018s) [311.118673] [065/142] (692s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt) [311.203249] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt [311.207186] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [311.296537] [066/142] (692s left) kms_flip (2x-plain-flip-fb-recreate-interruptible) [311.365837] Starting subtest: 2x-plain-flip-fb-recreate-interruptible [311.384239] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.016s) [311.455483] [067/142] (691s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_dg2_mc_ccs) [311.843243] Starting subtest: pipe-B-crc-primary-basic-4_tiled_dg2_mc_ccs [311.848812] Subtest pipe-B-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.000s) [312.734517] [068/142] (690s left) kms_lease (lease-get) [312.826882] Starting subtest: lease-get [312.829489] Starting dynamic subtest: pipe-A-eDP-1 [312.831780] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [312.837871] Starting dynamic subtest: pipe-B-eDP-1 [312.840573] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [312.846408] Starting dynamic subtest: pipe-C-eDP-1 [312.848749] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [312.854376] Starting dynamic subtest: pipe-D-eDP-1 [312.856722] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [312.858704] Subtest lease-get: SUCCESS (0.006s) [312.890060] [069/142] (690s left) kms_addfb_basic (bad-pitch-32) [312.935230] Starting subtest: bad-pitch-32 [312.937559] Subtest bad-pitch-32: SUCCESS (0.000s) [313.053141] [070/142] (690s left) kms_psr (psr2_primary_mmap_gtt) [313.112565] Starting subtest: psr2_primary_mmap_gtt [314.574903] Subtest psr2_primary_mmap_gtt: SKIP (1.459s) [314.716366] [071/142] (688s left) kms_chamelium_edid (dp-edid-read) [315.057471] Subtest dp-edid-read: SKIP (0.000s) [315.907893] [072/142] (687s left) gen9_exec_parse (bb-start-cmd) [315.980390] Subtest bb-start-cmd: SKIP (0.000s) [316.009712] [073/142] (687s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-render) [316.067726] Starting subtest: psr-1p-offscren-pri-indfb-draw-render [320.744161] Subtest psr-1p-offscren-pri-indfb-draw-render: SUCCESS (4.684s) [320.874988] [074/142] (682s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_mtl_rc_ccs_cc) [321.228033] Starting subtest: pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc [321.231225] Subtest pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.000s) [322.158803] [075/142] (681s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0) [322.244912] Starting subtest: linear-max-hw-stride-64bpp-rotate-0 [323.434066] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.191s) [323.559770] [076/142] (680s left) kms_getfb (getfb-handle-closed) [323.616648] Starting subtest: getfb-handle-closed [323.618670] Subtest getfb-handle-closed: SUCCESS (0.000s) [323.707508] [077/142] (680s left) debugfs_test (read_all_entries_display_on) [323.783199] Starting subtest: read_all_entries_display_on [324.103648] Subtest read_all_entries_display_on: SUCCESS (0.320s) [324.206600] [078/142] (679s left) kms_flip (2x-nonexisting-fb-interruptible) [324.273694] Starting subtest: 2x-nonexisting-fb-interruptible [324.284533] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.013s) [324.353360] [079/142] (679s left) kms_big_fb (4-tiled-addfb-size-offset-overflow) [324.440194] Starting subtest: 4-tiled-addfb-size-offset-overflow [324.442530] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s) [324.528339] [080/142] (679s left) i915_pm_rpm (gem-execbuf) [326.549920] Starting subtest: gem-execbuf [326.553084] Starting dynamic subtest: smem0 [328.209310] Dynamic subtest smem0: SUCCESS (1.656s) [328.211954] Subtest gem-execbuf: SUCCESS (1.658s) [328.283049] [081/142] (675s left) kms_addfb_basic (bad-pitch-65536) [328.350034] Starting subtest: bad-pitch-65536 [328.352053] Subtest bad-pitch-65536: SUCCESS (0.000s) [328.458342] [082/142] (675s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset) [328.525729] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset [328.531200] Starting dynamic subtest: A-eDP1 [332.394452] Dynamic subtest A-eDP1: SUCCESS (3.861s) [332.408597] Starting dynamic subtest: B-eDP1 [335.829669] Dynamic subtest B-eDP1: SUCCESS (3.433s) [335.843716] Starting dynamic subtest: C-eDP1 [339.285852] Dynamic subtest C-eDP1: SUCCESS (3.455s) [339.302830] Starting dynamic subtest: D-eDP1 [342.741453] Dynamic subtest D-eDP1: SUCCESS (3.456s) [342.746672] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (14.224s) [343.645951] [083/142] (660s left) perf (invalid-oa-exponent) [343.773875] Starting subtest: invalid-oa-exponent [343.782939] Subtest invalid-oa-exponent: SUCCESS (0.010s) [343.806587] [084/142] (660s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [343.870129] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [343.874703] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [343.950410] [085/142] (659s left) gem_pwrite_snooped [344.079622] [086/142] (659s left) gem_mmap_gtt (basic-write-gtt) [344.135630] Subtest basic-write-gtt: SKIP (0.000s) [344.164310] [087/142] (659s left) kms_ccs (pipe-a-crc-primary-rotation-180-yf_tiled_ccs) [344.543567] Starting subtest: pipe-A-crc-primary-rotation-180-yf_tiled_ccs [344.549046] Subtest pipe-A-crc-primary-rotation-180-yf_tiled_ccs: SKIP (0.000s) [345.453161] [088/142] (658s left) gem_exec_whisper (basic-fds-priority-all) [345.510732] Starting subtest: basic-fds-priority-all [377.416363] Subtest basic-fds-priority-all: SUCCESS (31.905s) [377.595445] [089/142] (626s left) kms_draw_crc (draw-method-mmap-wc) [377.695844] Starting subtest: draw-method-mmap-wc [377.697996] Starting dynamic subtest: xrgb8888-untiled [377.942879] Dynamic subtest xrgb8888-untiled: SUCCESS (0.248s) [377.959743] Starting dynamic subtest: rgb565-untiled [378.198314] Dynamic subtest rgb565-untiled: SUCCESS (0.254s) [378.213299] Starting dynamic subtest: xrgb2101010-untiled [378.412407] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s) [378.426316] Starting dynamic subtest: xrgb8888-xtiled [378.531533] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s) [378.547032] Starting dynamic subtest: rgb565-xtiled [378.675894] Dynamic subtest rgb565-xtiled: SUCCESS (0.143s) [378.689954] Starting dynamic subtest: xrgb2101010-xtiled [378.798538] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s) [378.814196] Starting dynamic subtest: xrgb8888-4tiled [378.975342] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.177s) [378.992000] Starting dynamic subtest: rgb565-4tiled [379.120173] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s) [379.136067] Starting dynamic subtest: xrgb2101010-4tiled [379.244714] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s) [379.246897] Subtest draw-method-mmap-wc: SUCCESS (1.549s) [379.318598] [090/142] (624s left) syncobj_wait (reset-during-wait-for-submit) [379.379117] Starting subtest: reset-during-wait-for-submit [379.479797] Subtest reset-during-wait-for-submit: SUCCESS (0.103s) [379.550706] [091/142] (624s left) gem_ctx_persistence (heartbeat-many) [379.600959] Starting subtest: heartbeat-many [379.605480] Subtest heartbeat-many: SKIP (0.000s) [379.671375] [092/142] (624s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move) [379.738294] Starting subtest: psr-1p-primscrn-cur-indfb-move [385.149951] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (5.411s) [385.261322] [093/142] (618s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-gtt) [385.345884] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-gtt [385.350487] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [385.423992] [094/142] (618s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_dg2_rc_ccs) [385.789996] Starting subtest: pipe-C-crc-primary-basic-4_tiled_dg2_rc_ccs [385.795264] Subtest pipe-C-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.000s) [386.656756] [095/142] (617s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled) [386.725918] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled [386.730697] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s) [386.907929] [096/142] (617s left) kms_vblank (pipe-d-ts-continuation-modeset-hang) [386.987765] Starting subtest: pipe-D-ts-continuation-modeset-hang [402.280644] Subtest pipe-D-ts-continuation-modeset-hang: SUCCESS (15.292s) [403.179825] [097/142] (601s left) kms_prime (basic-modeset-hybrid) [403.785669] Subtest basic-modeset-hybrid: SKIP (0.000s) [403.888285] [098/142] (600s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt) [403.961199] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt [403.965653] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [404.041531] [099/142] (600s left) v3d/v3d_perfmon (get-values-invalid-pointer) [404.144615] Subtest get-values-invalid-pointer: SKIP (0.000s) [404.173571] [100/142] (600s left) gem_exec_schedule (pi-userfault) [404.347856] Starting subtest: pi-userfault [404.350670] Starting dynamic subtest: rcs0 [404.356589] Dynamic subtest rcs0: SUCCESS (0.010s) [404.374731] Starting dynamic subtest: bcs0 [404.377306] Dynamic subtest bcs0: SUCCESS (0.006s) [404.392799] Starting dynamic subtest: vcs0 [404.394835] Dynamic subtest vcs0: SUCCESS (0.007s) [404.408730] Starting dynamic subtest: vcs1 [404.410803] Dynamic subtest vcs1: SUCCESS (0.004s) [404.420629] Starting dynamic subtest: vecs0 [404.422632] Dynamic subtest vecs0: SUCCESS (0.006s) [404.429880] Starting dynamic subtest: ccs0 [404.431876] Dynamic subtest ccs0: SUCCESS (0.006s) [404.433855] Subtest pi-userfault: SUCCESS (0.046s) [404.572689] [101/142] (599s left) api_intel_allocator (open-vm) [404.630047] Starting subtest: open-vm [404.632411] Subtest open-vm: SUCCESS (0.001s) [404.730331] [102/142] (599s left) kms_ccs (pipe-d-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc) [405.169640] Starting subtest: pipe-D-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc [405.172766] Subtest pipe-D-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.000s) [405.978561] [103/142] (598s left) kms_flip (flip-vs-dpms-off-vs-modeset) [406.043102] Starting subtest: flip-vs-dpms-off-vs-modeset [406.045521] Starting dynamic subtest: A-eDP1 [409.879110] Dynamic subtest A-eDP1: SUCCESS (3.832s) [409.893376] Starting dynamic subtest: B-eDP1 [413.332524] Dynamic subtest B-eDP1: SUCCESS (3.454s) [413.348201] Starting dynamic subtest: C-eDP1 [416.791364] Dynamic subtest C-eDP1: SUCCESS (3.456s) [416.809976] Starting dynamic subtest: D-eDP1 [420.245661] Dynamic subtest D-eDP1: SUCCESS (3.453s) [420.250455] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (14.212s) [421.146466] [104/142] (583s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt) [421.210539] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt [421.214635] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [421.308844] [105/142] (583s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite) [421.367538] Starting subtest: fbc-rgb101010-draw-pwrite [424.981114] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (3.618s) [425.103680] [106/142] (579s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu) [425.193195] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu [430.143287] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.954s) [430.269269] [107/142] (574s left) kms_chamelium_hpd (vga-hpd) [430.612623] Subtest vga-hpd: SKIP (0.000s) [431.495242] [108/142] (573s left) syncobj_wait (single-wait-for-submit-signaled) [431.546267] Starting subtest: single-wait-for-submit-signaled [431.549233] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s) [431.609527] [109/142] (572s left) kms_big_fb (yf-tiled-64bpp-rotate-180) [431.706675] Starting subtest: yf-tiled-64bpp-rotate-180 [431.711042] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s) [431.796897] [110/142] (572s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip) [431.884682] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip [433.113932] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: FAIL (1.070s) [433.138568] [111/142] (571s left) prime_self_import (basic-with_fd_dup) [433.190843] Starting subtest: basic-with_fd_dup [433.195078] Subtest basic-with_fd_dup: SUCCESS (0.002s) [433.279303] [112/142] (571s left) gem_wait (await) [433.358136] Starting subtest: await [433.360285] Starting dynamic subtest: all-engines [434.441746] Dynamic subtest all-engines: SUCCESS (1.083s) [434.466967] Starting dynamic subtest: rcs0 [435.500661] Dynamic subtest rcs0: SUCCESS (1.058s) [435.527203] Starting dynamic subtest: bcs0 [436.564400] Dynamic subtest bcs0: SUCCESS (1.062s) [436.589936] Starting dynamic subtest: vcs0 [437.597334] Dynamic subtest vcs0: SUCCESS (1.032s) [437.622706] Starting dynamic subtest: vcs1 [438.634561] Dynamic subtest vcs1: SUCCESS (1.035s) [438.659694] Starting dynamic subtest: vecs0 [439.674675] Dynamic subtest vecs0: SUCCESS (1.039s) [439.700354] Starting dynamic subtest: ccs0 [440.735278] Dynamic subtest ccs0: SUCCESS (1.059s) [440.738217] Subtest await: SUCCESS (7.377s) [440.953082] [113/142] (563s left) gem_exec_fence (syncobj-import) [441.392904] Starting subtest: syncobj-import [441.395591] Subtest syncobj-import: SUCCESS (0.003s) [442.474066] [114/142] (562s left) gem_exec_alignment (single) [442.552864] Starting subtest: single [442.559223] Subtest single: SUCCESS (0.005s) [442.655828] [115/142] (562s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_mtl_mc_ccs) [443.014554] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs [445.480796] Subtest pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (2.467s) [446.694882] [116/142] (558s left) kms_psr2_su (page_flip-xrgb8888) [446.753057] Subtest page_flip-XRGB8888: SKIP (0.000s) [446.774686] [117/142] (558s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_rc_ccs_cc) [447.209804] Starting subtest: pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs_cc [447.216089] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [448.094568] [118/142] (556s left) kms_invalid_mode (bad-hsync-end) [448.162014] Starting subtest: bad-hsync-end [448.164493] Starting dynamic subtest: eDP-1-pipe-A [448.443717] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.283s) [448.457439] Starting dynamic subtest: eDP-1-pipe-B [448.459945] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.001s) [448.468540] Starting dynamic subtest: eDP-1-pipe-C [448.471048] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.001s) [448.478337] Starting dynamic subtest: eDP-1-pipe-D [448.480770] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.001s) [448.483177] Subtest bad-hsync-end: SUCCESS (0.294s) [449.377899] [119/142] (555s left) kms_vblank (invalid) [449.468396] Starting subtest: invalid [449.878599] Subtest invalid: SUCCESS (0.410s) [450.800294] [120/142] (554s left) gem_sync (basic-store-each) [450.871949] Starting subtest: basic-store-each [453.046932] Subtest basic-store-each: SUCCESS (2.188s) [453.226830] [121/142] (551s left) prime_mmap (test_refcounting) [453.279793] Starting subtest: test_refcounting [453.282020] Starting dynamic subtest: test_refcounting-smem [453.307525] Dynamic subtest test_refcounting-smem: SUCCESS (0.032s) [453.309707] Subtest test_refcounting: SUCCESS (0.032s) [453.388847] [122/142] (551s left) perf (non-zero-reason) [453.491485] Starting subtest: non-zero-reason [453.494033] Starting dynamic subtest: 0-rcs0 [459.684422] Dynamic subtest 0-rcs0: SUCCESS (6.192s) [459.703134] Starting dynamic subtest: 1-vcs1 [463.958591] Dynamic subtest 1-vcs1: SUCCESS (4.274s) [463.962048] Subtest non-zero-reason: SUCCESS (10.467s) [463.995189] [123/142] (540s left) kms_plane_scaling (planes-downscale-factor-0-25) [464.057909] Starting subtest: planes-downscale-factor-0-25 [464.060452] Starting dynamic subtest: pipe-A-eDP-1 [464.065326] Dynamic subtest pipe-A-eDP-1: SKIP (0.007s) [464.081090] Starting dynamic subtest: pipe-B-eDP-1 [464.085923] Dynamic subtest pipe-B-eDP-1: SKIP (0.014s) [464.103439] Starting dynamic subtest: pipe-C-eDP-1 [464.108467] Dynamic subtest pipe-C-eDP-1: SKIP (0.014s) [464.120501] Starting dynamic subtest: pipe-D-eDP-1 [464.126184] Dynamic subtest pipe-D-eDP-1: SKIP (0.014s) [464.131352] Subtest planes-downscale-factor-0-25: SKIP (0.053s) [464.200962] [124/142] (540s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic) [464.280840] Starting subtest: 2x-cursor-vs-flip-atomic [464.285556] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s) [464.373520] [125/142] (540s left) syncobj_timeline (wait-any-complex) [464.431943] Starting subtest: wait-any-complex [464.494417] Subtest wait-any-complex: SUCCESS (0.064s) [464.564026] [126/142] (540s left) kms_flip (2x-absolute-wf_vblank-interruptible) [464.608687] Starting subtest: 2x-absolute-wf_vblank-interruptible [464.626763] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.014s) [464.690341] [127/142] (540s left) kms_ccs (pipe-d-bad-rotation-90-yf_tiled_ccs) [465.085274] Starting subtest: pipe-D-bad-rotation-90-yf_tiled_ccs [465.090981] Subtest pipe-D-bad-rotation-90-yf_tiled_ccs: SKIP (0.000s) [465.965529] [128/142] (539s left) kms_flip (2x-modeset-vs-vblank-race) [466.043726] Starting subtest: 2x-modeset-vs-vblank-race [466.077064] Subtest 2x-modeset-vs-vblank-race: SKIP (0.034s) [466.157256] [129/142] (538s left) gem_render_copy (linear-to-vebox-yf-tiled) [466.212869] Starting subtest: linear-to-vebox-yf-tiled [466.217581] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s) [466.393534] [130/142] (538s left) gen9_exec_parse (allowed-single) [466.464171] Subtest allowed-single: SKIP (0.000s) [466.484364] [131/142] (538s left) kms_cursor_legacy (flip-vs-cursor-atomic) [466.577456] Starting subtest: flip-vs-cursor-atomic [472.315407] Subtest flip-vs-cursor-atomic: SUCCESS (5.738s) [473.261189] [132/142] (531s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite) [473.347211] Starting subtest: psr-rgb565-draw-pwrite [478.392571] Subtest psr-rgb565-draw-pwrite: SUCCESS (5.049s) [478.495404] [133/142] (526s left) kms_atomic_interruptible (universal-setplane-cursor) [478.587132] Starting subtest: universal-setplane-cursor [478.589568] Starting dynamic subtest: eDP-1-pipe-A [486.075664] Dynamic subtest eDP-1-pipe-A: SUCCESS (7.487s) [486.078247] Subtest universal-setplane-cursor: SUCCESS (7.489s) [487.004555] [134/142] (518s left) kms_frontbuffer_tracking (fbcpsr-badstride) [487.095040] Starting subtest: fbcpsr-badstride [497.350631] Subtest fbcpsr-badstride: SUCCESS (10.260s) [497.449808] [135/142] (507s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa [497.516483] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled [497.620448] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.105s) [497.692198] [136/142] (507s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi [497.764075] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted [497.868398] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.102s) [497.933991] [137/142] (507s left) gem_tiled_swapping (non-threaded) [498.002516] Subtest non-threaded: SKIP (0.000s) [498.019704] [138/142] (507s left) kms_async_flips (test-cursor) [498.088386] Starting subtest: test-cursor [498.092863] Subtest test-cursor: SKIP (0.000s) [499.374509] [139/142] (506s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled) [499.431782] Starting subtest: multi-wait-for-submit-unsubmitted-signaled [499.434090] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s) [499.488488] [140/142] (505s left) syncobj_timeline (invalid-signal-one-illegal-handle) [499.523434] Starting subtest: invalid-signal-one-illegal-handle [499.525743] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s) [499.582451] [141/142] (505s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_mc_ccs) [499.983997] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs [499.989272] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s) [500.826155] [142/142] (504s left) perf_pmu (all-busy-check-all) [500.896388] Starting subtest: all-busy-check-all [502.405843] Subtest all-busy-check-all: SUCCESS (1.508s) [502.474484] Closing watchdogs Done.