improvement: show verbose log output by default (#7799)

edvald-garden · edvald · web-flow · commit f5b042c0805d · 2025-10-31T20:38:10.000Z
* improvement: show verbose log output by default

* chore(ci): force color output in CI

* improvement(cli): tweak graph status output

* chore: minor logging cleanup

---------

Co-authored-by: Jon Edvald &lt;edvald@gmail.com&gt;
diff --git a/.circleci/continue-config.yml b/.circleci/continue-config.yml
@@ -20,6 +20,8 @@ parameters:
     GARDEN_DISABLE_VERSION_CHECK: "true"
     GARDEN_DISABLE_ANALYTICS: "true"
     GARDEN_K8S_BUILD_SYNC_MODE: "mutagen"
+    FORCE_COLOR: "1"
+    TERM: "xterm-256color"
 
   ubuntu-vm-runner: &ubuntu-vm-runner
     image: "ubuntu-2404:2024.08.1"
diff --git a/core/src/cli/cli.ts b/core/src/cli/cli.ts
@@ -433,7 +433,7 @@ ${renderCommands(commands)}
     }
 
     const log = logger.createLog()
-    log.verbose(`garden version: ${getPackageVersion()}`)
+    log.debug(`garden version: ${getPackageVersion()}`)
 
     // Load custom commands from current project (if applicable) and see if any match the arguments
     if (!command) {
diff --git a/core/src/cli/command-line.ts b/core/src/cli/command-line.ts
@@ -20,7 +20,7 @@ import { findProjectConfig } from "../config/base.js"
 import { GardenError, toGardenError } from "../exceptions.js"
 import type { Garden } from "../garden.js"
 import type { Log } from "../logger/log-entry.js"
-import { getTermWidth, renderDivider } from "../logger/util.js"
+import { getTerminalWidth, renderDivider } from "../logger/util.js"
 import type { GardenInstanceManager } from "../server/instance-manager.js"
 import { TypedEventEmitter } from "../util/events.js"
 import { uuidv4 } from "../util/random.js"
@@ -479,7 +479,7 @@ export class CommandLine extends TypedEventEmitter<CommandLineEvents> {
   private printWithDividers(text: string, title: string) {
     let width = max(text.split("\n").map((l) => stringWidth(l.trimEnd()))) || 0
     width += 2
-    const termWidth = getTermWidth()
+    const termWidth = getTerminalWidth()
     const minWidth = stringWidth(title) + 10
 
     if (width > termWidth) {
@@ -688,7 +688,7 @@ ${styles.accent.underline("Keys:")}
     opts: PrepareParams["opts"]
   }) {
     const id = uuidv4()
-    const width = getTermWidth() - 2
+    const width = getTerminalWidth() - 2
 
     const prepareParams: PrepareParams = {
       log: this.log,
diff --git a/core/src/cli/params.ts b/core/src/cli/params.ts
@@ -430,8 +430,8 @@ export const globalDisplayOptions = {
       ${styles.highlight("info: 2")}, ${styles.highlight("verbose: 3")}, ${styles.highlight("debug: 4")},
       ${styles.highlight("silly: 5")}.
       From the verbose log level onward action execution logs are also printed (e.g. test or run live log outputs).`,
-    hints: "[choice] [default: info] [error || 0, warn || 1, info || 2, verbose || 3, debug || 4, silly || 5]",
-    defaultValue: LogLevel[LogLevel.info],
+    hints: "[choice] [default: verbose] [error || 0, warn || 1, info || 2, verbose || 3, debug || 4, silly || 5]",
+    defaultValue: LogLevel[LogLevel.verbose],
   }),
   "output": new ChoicesParameter({
     aliases: ["o"],
diff --git a/core/src/graph/solver.ts b/core/src/graph/solver.ts
@@ -29,7 +29,7 @@ import chalk from "chalk"
 import { formatDuration } from "date-fns"
 
 const taskStyle = styles.highlight.bold
-const statusUpdateIntervalMsec = 10000
+const statusUpdateIntervalMsec = 20000
 
 export interface SolveOpts {
   statusOnly?: boolean
@@ -283,7 +283,7 @@ export class GraphSolver extends TypedEventEmitter<SolverEvents> {
     // TODO-0.13.1: currently a no-op, possibly not necessary
   }
 
-  renderStatus(batchId: string, startTime: Date) {
+  private renderStatus(batchId: string, startTime: Date) {
     const requested = Object.values(this.requestedTasks[batchId]).sort((a, b) => {
       // Sort by key in ascending order
       return a.getKey().localeCompare(b.getKey())
@@ -327,7 +327,7 @@ export class GraphSolver extends TypedEventEmitter<SolverEvents> {
       chalk.yellow(listActions(formatResultDescription("aborted", abortedDeps), aborted)),
       chalk.red(listActions(formatResultDescription("failed", failedDeps), failed)),
     ]
-    const duration = formatDuration({ seconds: (new Date().getTime() - startTime.getTime()) / 1000 })
+    const duration = formatDuration({ seconds: Math.round((new Date().getTime() - startTime.getTime()) / 1000) })
     const status = [
       renderDivider({
         color: chalk.magenta,
@@ -340,8 +340,12 @@ export class GraphSolver extends TypedEventEmitter<SolverEvents> {
     ]
 
     return {
-      status: status.filter((s) => stripAnsi(s) !== "").join("\n"),
-      content: stripAnsi(content.filter((s) => s !== "").join("\n")),
+      status: status.filter((s) => stripAnsi(s).trim() !== "").join("\n"),
+      content: content
+        .map((s) => stripAnsi(s).trim())
+        .filter((s) => s !== "")
+        .join("\n")
+        .trim(),
     }
   }
 
diff --git a/core/src/logger/log-entry.ts b/core/src/logger/log-entry.ts
@@ -19,6 +19,7 @@ import { omitUndefined } from "../util/objects.js"
 import { renderDuration } from "./util.js"
 import { styles } from "./styles.js"
 import { getStyle } from "./renderers.js"
+import hasAnsi from "has-ansi"
 
 export type LogSymbol = keyof typeof logSymbols | "empty"
 export type TaskLogStatus = "active" | "success" | "error"
@@ -410,7 +411,7 @@ export abstract class Log<C extends BaseContext = LogContext> implements LogConf
     const style = resolved.level === LogLevel.info ? styles.success : getStyle(resolved.level)
     return this.log({
       ...resolved,
-      msg: transformMsg(this.getMsgWithDuration(resolved) || "", (msg) => style(msg)),
+      msg: transformMsg(this.getMsgWithDuration(resolved) || "", (msg) => (hasAnsi(msg) ? msg : style(msg))),
     })
   }
 
diff --git a/core/src/logger/renderers.ts b/core/src/logger/renderers.ts
@@ -117,7 +117,7 @@ export function renderMsg(entry: LogEntry): string {
   const style = getStyle(level)
 
   // For log levels higher than "info" we print the log level name.
-  const logLevelName = entry.level > LogLevel.info ? `[${logLevelMap[entry.level]}] ` : ""
+  const logLevelName = entry.level > LogLevel.verbose ? `[${logLevelMap[entry.level]}] ` : ""
 
   const origin = context.origin ? `[${styles.italic(context.origin)}] ` : ""
 
diff --git a/core/src/logger/util.ts b/core/src/logger/util.ts
@@ -12,6 +12,7 @@ import stringWidth from "string-width"
 import { DEFAULT_BROWSER_DIVIDER_WIDTH } from "../constants.js"
 import { styles } from "./styles.js"
 import type { ChalkInstance } from "chalk"
+import ci from "ci-info"
 
 // Add platforms/terminals?
 export function envSupportsEmoji() {
@@ -20,6 +21,11 @@ export function envSupportsEmoji() {
   )
 }
 
+// Copied from https://github.com/sindresorhus/is-interactive
+export function isInteractive({ stream = process.stdout } = {}) {
+  return Boolean(stream && stream.isTTY && process.env.TERM !== "dumb" && !("CI" in process.env)) && !ci.isCI
+}
+
 let _overrideTerminalWidth: number | undefined
 
 export function overrideTerminalWidth(width: number | undefined) {
@@ -93,7 +99,7 @@ export function renderDivider({
 }: DividerOpts = {}) {
   const pad = " "
   if (!width) {
-    width = getTermWidth()
+    width = getTerminalWidth()
   }
 
   if (!color) {
@@ -112,11 +118,6 @@ export function renderDivider({
   return paddingString + dividerSideString + titleString + dividerSideString + paddingString
 }
 
-export const getTermWidth = () => {
-  // TODO: accept stdout as param
-  return process.stdout?.columns || 100
-}
-
 export function renderDuration(duration: number): string {
   return `(took ${duration} sec)`
 }
diff --git a/core/src/plugins/kubernetes/container/build/buildkit.ts b/core/src/plugins/kubernetes/container/build/buildkit.ts
@@ -256,8 +256,6 @@ export async function ensureBuildkit({
       timeoutSec: 600,
     })
 
-    deployLog.info("Done!")
-
     return { authSecret, updated: true }
   })
 }
diff --git a/core/src/tasks/delete-deploy.ts b/core/src/tasks/delete-deploy.ts
@@ -95,7 +95,6 @@ export class DeleteDeployTask extends BaseActionTask<DeployAction, DeployStatus>
     try {
       const output = await router.deploy.delete({ log: this.log, action, graph: this.graph })
       status = output.result
-      this.log.info("Done!")
     } catch (err) {
       this.log.error(`Failed deleting ${action.name}`)
       throw err
diff --git a/core/test/setup.ts b/core/test/setup.ts
@@ -17,9 +17,13 @@ import mocha from "mocha"
 import sourceMapSupport from "source-map-support"
 import { UnresolvedTemplateValue } from "../src/template/types.js"
 import { setGloablProjectApiVersion } from "../src/project-api-version.js"
+import { config as chaiConfig } from "chai"
 
 sourceMapSupport.install()
 
+// Disable truncation of strings in chai assertion error messages for better debugging
+chaiConfig.truncateThreshold = 0
+
 let lastTime = performance.now()
 const mainBlockCheckup = setInterval(() => {
   const now = performance.now()
diff --git a/core/test/unit/src/cli/cli.ts b/core/test/unit/src/cli/cli.ts
@@ -778,7 +778,7 @@ describe("cli", () => {
         },
         opts: {
           "silent": false,
-          "log-level": "info",
+          "log-level": "verbose",
           "emoji": envSupportsEmoji(),
           "show-timestamps": false,
           "yes": false,
@@ -853,7 +853,7 @@ describe("cli", () => {
         },
         opts: {
           "silent": false,
-          "log-level": "info",
+          "log-level": "verbose",
           "emoji": envSupportsEmoji(),
           "show-timestamps": false,
           "yes": false,
diff --git a/core/test/unit/src/cli/helpers.ts b/core/test/unit/src/cli/helpers.ts
@@ -147,7 +147,7 @@ describe("parseCliArgs", () => {
     const argv = parseCliArgs({ stringArgs: [], command: cmd, cli: true })
 
     expect(argv.silent).to.be.false
-    expect(argv["log-level"]).to.equal(LogLevel[LogLevel.info])
+    expect(argv["log-level"]).to.equal(LogLevel[LogLevel.verbose])
   })
 
   it("sets default command option values", () => {
@@ -313,7 +313,7 @@ describe("processCliArgs", () => {
     const cmd = new BuildCommand()
     const { opts } = parseAndProcess([], cmd)
     expect(opts.silent).to.be.false
-    expect(opts["log-level"]).to.equal(LogLevel[LogLevel.info])
+    expect(opts["log-level"]).to.equal(LogLevel[LogLevel.verbose])
   })
 
   it("prefers defaultValue value over cliDefault when cli=false", () => {
diff --git a/core/test/unit/src/commands/logs.ts b/core/test/unit/src/commands/logs.ts
@@ -351,7 +351,7 @@ describe("LogsCommand", () => {
               name: "c-by-far-the-longest-of-the-bunch",
               msg: logMsgWithColor,
               timestamp: new Date("2021-05-13T20:04:00.000Z"), // <--- 4
-              level: LogLevel.verbose,
+              level: LogLevel.debug,
             })
           } else if (action.name === "d-very-very-long") {
             onLogEntry({
diff --git a/core/test/unit/src/logger/log-entry.ts b/core/test/unit/src/logger/log-entry.ts
@@ -12,7 +12,7 @@ import type { Logger } from "../../../../src/logger/logger.js"
 import { getRootLogger, LogLevel } from "../../../../src/logger/logger.js"
 import { freezeTime } from "../../../helpers.js"
 import type { CoreLog, Log, LogMetadata } from "../../../../src/logger/log-entry.js"
-import { createActionLog } from "../../../../src/logger/log-entry.js"
+import { createActionLog, resolveMsg } from "../../../../src/logger/log-entry.js"
 import { omit } from "lodash-es"
 import { styles } from "../../../../src/logger/styles.js"
 
@@ -84,8 +84,9 @@ describe("Log", () => {
       expect(entry.msg).to.eql(styles.success("success"))
     })
     it("should log success message in original color if it has ansi", () => {
-      const entry = log.success(`hello ${styles.highlight("cyan")}`).getLatestEntry()
-      expect(entry.msg).to.eql(`hello ${styles.highlight("cyan")}`)
+      const msg = `hello ${styles.highlight("cyan")}`
+      const entry = log.success(msg).getLatestEntry()
+      expect(resolveMsg(entry)?.trim()).to.eql(msg.trim())
     })
     it("should set the symbol to success", () => {
       const entry = log.success("success").getLatestEntry()

Original file line number	Diff line number	Diff line change
`@@ -433,7 +433,7 @@ ${renderCommands(commands)}`
`433`	`433`	`}`
`434`	`434`
`435`	`435`	`const log = logger.createLog()`
`436`		- log.verbose(`garden version: ${getPackageVersion()}`)
	`436`	+ log.debug(`garden version: ${getPackageVersion()}`)
`437`	`437`
`438`	`438`	`// Load custom commands from current project (if applicable) and see if any match the arguments`
`439`	`439`	`if (!command) {`
Original file line number	Diff line number	Diff line change
`@@ -256,8 +256,6 @@ export async function ensureBuildkit({`
`256`	`256`	`timeoutSec: 600,`
`257`	`257`	`})`
`258`	`258`
`259`		`- deployLog.info("Done!")`
`260`		`-`
`261`	`259`	`return { authSecret, updated: true }`
`262`	`260`	`})`
`263`	`261`	`}`