Add debug prints

Author: yosuke-wolfssl

apps/wolfsshd/test/sshd_login_grace_test.ps1

@@ -47,11 +47,18 @@ AuthorizedKeysFile $authFile
 "" | Out-File -FilePath $authFile -Encoding ASCII
 if (Test-Path $logFile) { Remove-Item $logFile -Force }
 
+# Capture the daemon's own stdout/stderr (where the [SSHD-DBG] diagnostics go)
+# so we can inspect them even if the -E log file mechanism is not writing.
+$stdoutFile = Join-Path $scriptDir "grace_stdout.txt"
+$stderrFile = Join-Path $scriptDir "grace_stderr.txt"
+
 # Run wolfsshd in the foreground (-D) with debug logging to a file (-E). On
 # Windows, -D selects the non-service foreground path, which lets us read the
 # log just like the Unix test does.
 $sshd = Start-Process -FilePath $SshdExe `
     -ArgumentList "-D", "-d", "-E", "`"$logFile`"", "-f", "`"$confFile`"", "-p", "$Port" `
+    -RedirectStandardOutput $stdoutFile `
+    -RedirectStandardError $stderrFile `
     -NoNewWindow -PassThru
 
 try {
@@ -104,6 +111,18 @@ try {
     }
     Write-Host "=== end of wolfsshd log ==="
 
+    # Dump the daemon's captured stdout/stderr (carries the [SSHD-DBG] lines).
+    foreach ($f in @($stdoutFile, $stderrFile)) {
+        Write-Host "=== daemon $(Split-Path -Leaf $f) ==="
+        if ((Test-Path $f) -and ((Get-Item $f).Length -gt 0)) {
+            Get-Content $f
+        }
+        else {
+            Write-Host "(empty)"
+        }
+        Write-Host "=== end ==="
+    }
+
     if ((Test-Path $logFile) -and
         (Select-String -Path $logFile -Pattern "Failed login within grace period" -Quiet)) {
         Write-Host "PASS: unauthenticated connection dropped at login grace deadline"

apps/wolfsshd/wolfsshd.c

@@ -1924,6 +1924,10 @@ static VOID CALLBACK GraceTimeoutCb(PTP_CALLBACK_INSTANCE instance, PVOID ctx,
 {
     WOLFSSHD_CONNECTION* conn = (WOLFSSHD_CONNECTION*)ctx;
 
+    /* TEMP DEBUG: confirm the threadpool timer actually fires */
+    fprintf(stderr, "[SSHD-DBG] grace timeout callback fired\n");
+    fflush(stderr);
+
     if (conn != NULL) {
         conn->timeOut = 1;
     }
@@ -2030,6 +2034,10 @@ static void* HandleConnection(void* arg)
             else {
                 SetThreadpoolTimer(conn->loginTimer, &due, 0, 0);
             }
+            /* TEMP DEBUG: confirm the grace timer was armed */
+            fprintf(stderr, "[SSHD-DBG] grace timer armed graceTime=%ld timer=%p\n",
+                    graceTime, (void*)conn->loginTimer);
+            fflush(stderr);
     #else
             signal(SIGALRM, alarmCatch);
             loginGraceTimedOut = 0; /* clear any stale state before arming */
@@ -2064,6 +2072,11 @@ static void* HandleConnection(void* arg)
             conn->timeOut = 1;
         }
     #endif
+        /* TEMP DEBUG: report how the accept loop ended */
+        fprintf(stderr,
+                "[SSHD-DBG] accept loop done ret=%d error=%d timeOut=%d "
+                "graceTime=%ld\n", ret, error, conn->timeOut, graceTime);
+        fflush(stderr);
         wolfSSH_Log(WS_LOG_INFO,
                     "[SSHD] grace time = %ld timeout = %d", graceTime,
                     conn->timeOut);