test(cli): log warm process stages on windows

Author: miguel-heygen

.github/workflows/checkfast-measure.yml

@@ -46,7 +46,7 @@ jobs:
         run: bun run build
       - name: Measure cold vs warm, then the warm process tests
         shell: bash
-        run: cd packages/cli && npx vitest run src/warmCheck.process.test.ts --maxWorkers=1 -t "prints what a cold" 2>&1 | tail -150
+        run: cd packages/cli && npx vitest run src/warmCheck.process.test.ts --maxWorkers=1 -t "prints what a cold" 2>&1 | tail -150 || true; echo ===DBG; cat warm-dbg.log
       - uses: actions/upload-artifact@ea165f8d65b6e75b540449e92b4886f43607fa02 # v4
         if: always()
         with:

packages/cli/src/warmCheck.process.test.ts

@@ -31,6 +31,7 @@ function privateEnv(): Record<string, string> {
     HYPERFRAMES_NO_TELEMETRY: "1",
     DO_NOT_TRACK: "1",
     ...(process.platform === "win32" ? { LOCALAPPDATA: base } : { XDG_RUNTIME_DIR: base }),
+    HF_WARM_DBG: join(process.cwd(), "warm-dbg.log"),
   };
 }
 
@@ -61,7 +62,7 @@ function run(args: string[], env: Record<string, string>) {
     stdio: ["ignore", "pipe", "pipe"],
   });
   let stdout = "";
-  child.stdout.on("data", (data: Buffer) => (stdout += data.toString()));
+  child.stdout.on("data", (data: Buffer) => { stdout += data.toString(); process.stderr.write(`[out ${child.pid}] ${data}`); });
   const tag = args[2] ?? args[0]!.split(/[\\/]/).pop();
   child.stderr.on("data", (d: Buffer) => process.stderr.write(`[${Date.now()} ${tag} pid=${child.pid}] ${d}`));
   child.once("close", (code) => process.stderr.write(`[${Date.now()} ${tag}] closed ${code}\n`));

packages/cli/src/warmCheck.ts

@@ -16,6 +16,11 @@ import {
   type WarmRequest,
 } from "./warmCheckProtocol.js";
 
+import { appendFileSync as __dbgAppend } from "node:fs";
+const __dbg = (m: string) => __dbgAppend(process.env.HF_WARM_DBG ?? "warm-dbg.log", `${Date.now()} ${process.pid} ${m}\n`);
+process.on("uncaughtException", (e) => __dbg(`uncaught ${e?.stack ?? e}`));
+process.on("unhandledRejection", (e) => __dbg(`unhandled ${(e as Error)?.stack ?? e}`));
+__dbg(`start argv=${JSON.stringify(process.argv)}`);
 const [idleMs = "0", ...checkArgs] = process.argv.slice(2);
 const endpoint = warmCheckEndpoint();
 if (!endpoint) process.exit(0);
@@ -42,6 +47,7 @@ async function serve(socket: Socket, request: WarmRequest): Promise<void> {
   const finish = (code: number) => {
     if (finished) return;
     finished = true;
+    __dbg(`finish ${code} ${new Error().stack}`);
     socket.end(encodeFrame(Frame.Exit, String(code)), () => exit(code));
   };
   socket.on("close", () => {
@@ -53,7 +59,9 @@ async function serve(socket: Socket, request: WarmRequest): Promise<void> {
     finish(Number(code ?? process.exitCode ?? 0))) as typeof process.exit;
   process.chdir(request.cwd);
   process.argv = [process.execPath, request.script, ...request.argv];
+  __dbg(`serving ${JSON.stringify(request.argv)}`);
   await import(new URL("./cli.js", import.meta.url).href);
+  __dbg(`imported exitCode=${process.exitCode}`);
   setImmediate(() => finish(Number(process.exitCode ?? 0)));
 }
 
@@ -132,4 +140,4 @@ server.on("error", (error: NodeJS.ErrnoException) => {
 });
 
 listen();
-void prelaunchBrowserForCheck(checkArgs).catch(() => undefined);
+void prelaunchBrowserForCheck(checkArgs).then(() => __dbg('prelaunched'), (e) => __dbg(`prelaunch failed ${e?.stack ?? e}`));