2026-11-10T10:48:05.192+00:00 +0.028042 MANIFEST {"id": "P06", "case": "httpclient-slow-H", "side": "after", "sha": "965b08b3060575186415df240c898a50c8736c5d", "environment": "Ubuntu 24.04.4 LTS Linux x86_64; kernel 6.8.0-117-generic; dash 0.5.12-6ubuntu5 or bash 5.2.21 --posix; curl 8.5.0; GNU wget 1.21.4; BusyBox 1.36.1; Node v26.12.1; PowerShell 7.4.6 Core on Linux (helpers only)", "selected": {"surface": "P", "shell": null, "transport": null, "path": null, "mech": "H", "pty": true}, "selected_transport_version": "curl 8.5.0", "command": "/tmp/lead-32913-r1/evidence-capture/powershell-7.4.6/pwsh -NoLogo -NoProfile -File /tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/observe.ps1", "env": {"HOME": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/home", "T3CODE_HOME": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/home/t3", "T3CODE_INSTALL_BIN_DIR": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/bin", "TMPDIR": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/tmp", "TMP": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/tmp", "TEMP": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/tmp", "TERM": "xterm-256color", "LANG": "C.UTF-8", "LC_ALL": "C.UTF-8", "NO_COLOR": "1", "CAPTURE_TRANSPORT": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/transport.jsonl", "T3CODE_VERSION": "1.2.3", "T3CODE_RELEASE_BASE_URL": "http://127.0.0.1:38013", "PROCESSOR_ARCHITECTURE": "AMD64", "DOTNET_CLI_HOME": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/home", "POWERSHELL_TELEMETRY_OPTOUT": "1", "PATH": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/path", "CAPTURE_STAGING": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/helper-staging", "CAPTURE_URL": "http://127.0.0.1:38013/v1.2.3/t3-1.2.3-linux-x64.tar.gz", "CAPTURE_DEST": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/downloaded", "CAPTURE_SCRIPT": "/tmp/lead-32913-r1/evidence-capture/runs-20261110T104757.726561Z/P06-httpclient-slow-H/after/install.ps1"}, "original_source_sha256": "d24dd0357bd107d3643aeeb1e80eaebf2cb8d84928a5bbed3682a9a6b491f92c", "executed_source_sha256": "8f300517e31a124b5029345fb750556e6b4be3c877f113fa7300924f9fffd05e", "transforms": ["Set-PSDebug -Trace 1 observes execution of exact helper source, including disposal and request commands", "verbatim prologue and helper slice; real-HTTP synthetic caller; discovery origins only remapped"], "steps": "Start fixture; run command; deliver terminal Ctrl-C only when specified precondition is observed; capture exit, requests, install state, tracked PIDs."}
2026-11-10T10:48:05.195+00:00 +0.030574 SPAWN {"pid": 613477, "pty": true, "stdin": "PTY"}
2026-11-10T10:48:05.336+00:00 +0.172070 OUTPUT "\u001b[?1h\u001b="
2026-11-10T10:48:06.276+00:00 +1.112202 OUTPUT "DEBUG:    2+  >>>> & $env:CAPTURE_SCRIPT\r\n\r\n"
2026-11-10T10:48:06.371+00:00 +1.206501 OUTPUT "DEBUG:   16+  >>>> $ErrorActionPreference = \"Stop\"\r\n\r\nDEBUG:   17+  >>>> [Net.ServicePointManager]::SecurityProtocol = [Net.SecurityProtocolType]::Tls12\r\n\r\n"
2026-11-10T10:48:06.374+00:00 +1.209340 OUTPUT "DEBUG:   19+  >>>> $repo = \"pingdotgg/t3code\"\r\n\r\n"
2026-11-10T10:48:06.374+00:00 +1.209448 OUTPUT "DEBUG:   20+  >>>> $baseUrl = if ($env:T3CODE_RELEASE_BASE_URL) { $env:T3CODE_RELEASE_BASE_URL.TrimEnd(\"/\") } else { \"http://127.0.0.1:38013/$repo/releases/download\" }\r\n\r\n"
2026-11-10T10:48:06.374+00:00 +1.209531 OUTPUT "DEBUG:   20+ $baseUrl = if ( >>>> $env:T3CODE_RELEASE_BASE_URL) { $env:T3CODE_RELEASE_BASE_URL.TrimEnd(\"/\") } else { \"http://127.0.0.1:38013/$repo/releases/download\" }\r\n\r\n"
2026-11-10T10:48:06.377+00:00 +1.213248 OUTPUT "DEBUG:   20+ $baseUrl = if ($env:T3CODE_RELEASE_BASE_URL) {  >>>> $env:T3CODE_RELEASE_BASE_URL.TrimEnd(\"/\") } else { \"http://127.0.0.1:38013/$repo/releases/download\" }\r\n\r\n"
2026-11-10T10:48:06.388+00:00 +1.224100 OUTPUT "DEBUG:   21+  >>>> $t3Home = if ($env:T3CODE_HOME) { $env:T3CODE_HOME } else { Join-Path $HOME \".t3\" }\r\n\r\n"
2026-11-10T10:48:06.389+00:00 +1.224643 OUTPUT "DEBUG:   21+ $t3Home = if ( >>>> $env:T3CODE_HOME) { $env:T3CODE_HOME } else { Join-Path $HOME \".t3\" }\r\n\r\n"
2026-11-10T10:48:06.389+00:00 +1.224961 OUTPUT "DEBUG:   21+ $t3Home = if ($env:T3CODE_HOME) {  >>>> $env:T3CODE_HOME } else { Join-Path $HOME \".t3\" }\r\n\r\n"
2026-11-10T10:48:06.390+00:00 +1.226137 OUTPUT "DEBUG:   22+  >>>> $binDir = if ($env:T3CODE_INSTALL_BIN_DIR) { $env:T3CODE_INSTALL_BIN_DIR } else { Join-Path $HOME \".local\\bin\" }\r\n\r\n"
2026-11-10T10:48:06.399+00:00 +1.234731 OUTPUT "DEBUG:   22+ $binDir = if ( >>>> $env:T3CODE_INSTALL_BIN_DIR) { $env:T3CODE_INSTALL_BIN_DIR } else { Join-Path $HOME \".local\\bin\" }\r\n\r\nDEBUG:   22+ $binDir = if ($env:T3CODE_INSTALL_BIN_DIR) {  >>>> $env:T3CODE_INSTALL_BIN_DIR } else { Join-Path $HOME \".local\\bin\" }\r\n\r\nDEBUG:   24+  >>>> $binDir = $ExecutionContext.SessionState.Path.GetUnresolvedProviderPathFromPSPath($binDir)\r\n\r\n"
2026-11-10T10:48:06.412+00:00 +1.247809 OUTPUT "DEBUG:   31+  >>>> $terminal = -not [Console]::IsErrorRedirected -and $env:TERM -ne \"dumb\"\r\n\r\n"
2026-11-10T10:48:06.431+00:00 +1.266990 OUTPUT "DEBUG:   32+  >>>> $interactive = $terminal -and $Host.UI.SupportsVirtualTerminal\r\n\r\n"
2026-11-10T10:48:06.440+00:00 +1.275308 OUTPUT "DEBUG:   33+ if ( >>>> $interactive) {\r\n\r\nDEBUG:   35+    >>>> $glyphs = -join [char[]](0x2588, 0x2580, 0x2584, 0x25A0, 0x00B7)\r\n\r\nDEBUG:   36+    >>>> $encoding = [Console]::Error.Encoding\r\n\r\nDEBUG:   37+    >>>> $interactive = $encoding.GetString($encoding.GetBytes($glyphs)) -eq $glyphs\r\n\r\n"
2026-11-10T10:48:06.440+00:00 +1.275575 OUTPUT "DEBUG:   39+  >>>> $esc = [char]27\r\n\r\n"
2026-11-10T10:48:06.442+00:00 +1.277714 OUTPUT "DEBUG:   40+  >>>> $reset = $bold = $muted = $accent = $green = \"\"\r\n\r\nDEBUG:   40+ $reset =  >>>> $bold = $muted = $accent = $green = \"\"\r\n\r\nDEBUG:   40+ $reset = $bold =  >>>> $muted = $accent = $green = \"\"\r\n\r\nDEBUG:   40+ $reset = $bold = $muted =  >>>> $accent = $green = \"\"\r\n\r\nDEBUG:   40+ $reset = $bold = $muted = $accent =  >>>> $green = \"\"\r\n\r\nDEBUG:   41+ if ( >>>> $interactive -and -not $env:NO_COLOR) {\r\n\r\n"
2026-11-10T10:48:06.443+00:00 +1.278412 OUTPUT "DEBUG:  120+  >>>> [Console]::Error.WriteLine(\"CAPTURE_DISPATCH terminal=$terminal interactive=$interactive mechanism=H encoding=$([Console]::Error.Encoding.WebName)\")\r\n\r\n"
2026-11-10T10:48:06.443+00:00 +1.278606 OUTPUT "DEBUG:  120+ [Console]::Error.WriteLine(\"CAPTURE_DISPATCH terminal=$terminal interactive=$interactive mechanism=H encoding=$( >>>> [Console]::Error.Encoding.WebName)\")\r\n\r\n"
2026-11-10T10:48:06.454+00:00 +1.289946 OUTPUT "CAPTURE_DISPATCH terminal=True interactive=True mechanism=H encoding=utf-8\r\nDEBUG:  122+  >>>> Fetch $env:CAPTURE_URL $env:CAPTURE_DEST -progress\r\n\r\n"
2026-11-10T10:48:06.538+00:00 +1.373496 OUTPUT "DEBUG:   80+ function Fetch([string] $uri, [string] $destination, [switch] $progress)  >>>> {\r\n\r\n"
2026-11-10T10:48:06.542+00:00 +1.377751 OUTPUT "DEBUG:   81+   if ( >>>> -not $progress -or -not $interactive) {\r\n\r\n"
2026-11-10T10:48:06.549+00:00 +1.384766 OUTPUT "DEBUG:   86+    >>>> Add-Type -AssemblyName System.Net.Http\r\n\r\n"
2026-11-10T10:48:07.122+00:00 +1.957485 OUTPUT "DEBUG:   87+    >>>> $client = New-Object System.Net.Http.HttpClient\r\n\r\n"
2026-11-10T10:48:07.156+00:00 +1.991644 OUTPUT "DEBUG:   88+    >>>> $response = $source = $file = $null\r\n\r\nDEBUG:   88+   $response =  >>>> $source = $file = $null\r\n\r\nDEBUG:   88+   $response = $source =  >>>> $file = $null\r\n\r\nDEBUG:   90+     for ( >>>> $attempt = 1; ; $attempt++) {\r\n\r\n"
2026-11-10T10:48:07.158+00:00 +1.993571 OUTPUT "DEBUG:   91+       try {  >>>> $response = $client.GetAsync($uri, [System.Net.Http.HttpCompletionOption]::ResponseHeadersRead).GetAwaiter().GetResult() }\r\n\r\n"
2026-11-10T10:48:07.315+00:00 +2.150363 SERVER {"request_t": 2.143983, "event": "request", "path": "/v1.2.3/t3-1.2.3-linux-x64.tar.gz", "endpoint": "archive", "attempt": 1, "status": 504, "behavior": 504, "accept": null}
2026-11-10T10:48:07.316+00:00 +2.151943 SERVER {"event": "body_complete", "endpoint": "archive", "bytes": 19}
2026-11-10T10:48:07.316+00:00 +2.152045 SERVER {"event": "request_handler_end", "endpoint": "archive"}
2026-11-10T10:48:07.359+00:00 +2.194660 OUTPUT "DEBUG:   93+       if ( >>>> $response.IsSuccessStatusCode -or -not (Wait-Retry ([int]$response.StatusCode) $attempt)) { break }\r\n\r\n"
2026-11-10T10:48:07.382+00:00 +2.217653 OUTPUT "DEBUG:   69+ function Wait-Retry([int] $status, [int] $attempt)  >>>> {\r\n\r\nDEBUG:   70+   if ( >>>> $attempt -ge 4 -or ($status -ge 400 -and $status -lt 500 -and $status -ne 408 -and $status -ne 429)) { return $false }\r\n\r\n"
2026-11-10T10:48:07.389+00:00 +2.224336 OUTPUT "DEBUG:   71+    >>>> Start-Sleep -Seconds ([Math]::Pow(2, $attempt - 1))\r\n\r\n"
2026-11-10T10:48:08.700+00:00 +3.535980 OUTPUT "DEBUG:   72+   return  >>>> $true\r\n\r\nDEBUG:   73+  >>>> }"
2026-11-10T10:48:08.707+00:00 +3.542723 OUTPUT "\r\n\r\nDEBUG:   94+        >>>> $response.Dispose()\r\n\r\n"
2026-11-10T10:48:08.719+00:00 +3.554585 OUTPUT "DEBUG:   90+     for ($attempt = 1; ;  >>>> $attempt++) {\r\n\r\n"
2026-11-10T10:48:08.725+00:00 +3.560774 OUTPUT "DEBUG:   91+       try {  >>>> $response = $client.GetAsync($uri, [System.Net.Http.HttpCompletionOption]::ResponseHeadersRead).GetAwaiter().GetResult() }\r\n\r\n"
2026-11-10T10:48:08.728+00:00 +3.563866 SERVER {"request_t": 3.557493, "event": "request", "path": "/v1.2.3/t3-1.2.3-linux-x64.tar.gz", "endpoint": "archive", "attempt": 2, "status": 200, "behavior": "slow", "accept": null}
2026-11-10T10:48:08.730+00:00 +3.566096 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 0}
2026-11-10T10:48:08.734+00:00 +3.569509 OUTPUT "DEBUG:   93+       if ( >>>> $response.IsSuccessStatusCode -or -not (Wait-Retry ([int]$response.StatusCode) $attempt)) { break }\r\n\r\nDEBUG:   93+       if ($response.IsSuccessStatusCode -or -not (Wait-Retry ([int]$response.StatusCode) $attempt)) {  >>>> break }\r\n\r\nDEBUG:   96+      >>>> $response.EnsureSuccessStatusCode() | Out-Null\r\n\r\n"
2026-11-10T10:48:08.734+00:00 +3.570095 OUTPUT "DEBUG:   97+      >>>> $source = $response.Content.ReadAsStreamAsync().GetAwaiter().GetResult()\r\n\r\n"
2026-11-10T10:48:08.741+00:00 +3.576890 OUTPUT "DEBUG:   98+      >>>> $file = [IO.File]::Create($destination)\r\n"
2026-11-10T10:48:08.746+00:00 +3.581498 OUTPUT "\r\nDEBUG:   99+      >>>> $buffer = New-Object byte[] 65536\r\n\r\n"
2026-11-10T10:48:08.748+00:00 +3.583822 OUTPUT "DEBUG:  100+      >>>> $bytes = 0L\r\n"
2026-11-10T10:48:08.748+00:00 +3.583907 OUTPUT "\r\n"
2026-11-10T10:48:08.751+00:00 +3.586821 OUTPUT "DEBUG:  101+      >>>> $clock = [Diagnostics.Stopwatch]::StartNew()\r\n\r\n"
2026-11-10T10:48:08.752+00:00 +3.587940 OUTPUT "DEBUG:  102+      >>>> Draw-Download 0 $response.Content.Headers.ContentLength\r\n\r\n"
2026-11-10T10:48:08.760+00:00 +3.596002 OUTPUT "DEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {"
2026-11-10T10:48:08.764+00:00 +3.600012 OUTPUT "\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\n"
2026-11-10T10:48:08.772+00:00 +3.608187 OUTPUT "DEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)"
2026-11-10T10:48:08.776+00:00 +3.612096 OUTPUT "\r\n\r\n"
2026-11-10T10:48:08.780+00:00 +3.615958 OUTPUT "DEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)"
2026-11-10T10:48:08.782+00:00 +3.617521 OUTPUT "\r\n\r\n"
2026-11-10T10:48:08.786+00:00 +3.621938 OUTPUT "DEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\n"
2026-11-10T10:48:08.792+00:00 +3.628529 OUTPUT "DEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\n"
2026-11-10T10:48:08.793+00:00 +3.629016 OUTPUT "DEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\n"
2026-11-10T10:48:08.797+00:00 +3.632868 OUTPUT "DEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n"
2026-11-10T10:48:08.800+00:00 +3.636015 OUTPUT "\r\n"
2026-11-10T10:48:08.807+00:00 +3.643208 OUTPUT "\r\u001b[2K  ································   0%  0.0 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\n"
2026-11-10T10:48:08.809+00:00 +3.644284 OUTPUT "DEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:08.825+00:00 +3.660683 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\n"
2026-11-10T10:48:08.830+00:00 +3.665393 OUTPUT "DEBUG:  105+        >>>> $bytes += $count\r\n"
2026-11-10T10:48:08.830+00:00 +3.665593 OUTPUT "\r\n"
2026-11-10T10:48:08.831+00:00 +3.666804 OUTPUT "DEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n"
2026-11-10T10:48:08.831+00:00 +3.667126 OUTPUT "\r\n"
2026-11-10T10:48:08.833+00:00 +3.669187 OUTPUT "DEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n"
2026-11-10T10:48:08.838+00:00 +3.674237 OUTPUT "\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:08.854+00:00 +3.689658 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 16415}
2026-11-10T10:48:08.857+00:00 +3.692398 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\n"
2026-11-10T10:48:08.863+00:00 +3.698321 OUTPUT "DEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■·····························  12%  0.0 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\n"
2026-11-10T10:48:08.867+00:00 +3.703029 OUTPUT "DEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:08.978+00:00 +3.813487 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 32830}
2026-11-10T10:48:08.985+00:00 +3.820651 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\n"
2026-11-10T10:48:08.986+00:00 +3.821806 OUTPUT "DEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {"
2026-11-10T10:48:08.986+00:00 +3.821962 OUTPUT "\r\n"
2026-11-10T10:48:08.986+00:00 +3.822030 OUTPUT "\r\n"
2026-11-10T10:48:08.986+00:00 +3.822198 OUTPUT "DEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength"
2026-11-10T10:48:08.987+00:00 +3.822278 OUTPUT "\r\n"
2026-11-10T10:48:08.987+00:00 +3.822378 OUTPUT "\r\n"
2026-11-10T10:48:08.987+00:00 +3.823196 OUTPUT "DEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {"
2026-11-10T10:48:08.988+00:00 +3.823296 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823382 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823463 OUTPUT "DEBUG:   50+   if ( >>>> -not $interactive) { return }"
2026-11-10T10:48:08.988+00:00 +3.823523 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823594 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823666 OUTPUT "DEBUG:   51+    >>>> $columns = [Console]::WindowWidth"
2026-11-10T10:48:08.988+00:00 +3.823706 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823765 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823843 OUTPUT "DEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }"
2026-11-10T10:48:08.988+00:00 +3.823890 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823940 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.823999 OUTPUT "DEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {"
2026-11-10T10:48:08.988+00:00 +3.824096 OUTPUT "\r\n"
2026-11-10T10:48:08.988+00:00 +3.824171 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824252 OUTPUT "DEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))"
2026-11-10T10:48:08.989+00:00 +3.824290 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824343 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824496 OUTPUT "DEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)"
2026-11-10T10:48:08.989+00:00 +3.824550 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824603 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824676 OUTPUT "DEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)"
2026-11-10T10:48:08.989+00:00 +3.824724 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824781 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824873 OUTPUT "DEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled"
2026-11-10T10:48:08.989+00:00 +3.824913 OUTPUT "\r\n"
2026-11-10T10:48:08.989+00:00 +3.824973 OUTPUT "\r\n"
2026-11-10T10:48:08.990+00:00 +3.825705 OUTPUT "DEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■···························  18%  0.0 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.102+00:00 +3.937429 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\n"
2026-11-10T10:48:09.108+00:00 +3.943710 OUTPUT "DEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)"
2026-11-10T10:48:09.123+00:00 +3.958651 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 49245}
2026-11-10T10:48:09.124+00:00 +3.959392 OUTPUT "\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■························  25%  0.1 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.247+00:00 +4.082802 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 65660}
2026-11-10T10:48:09.250+00:00 +4.085598 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■·······················  31%  0.1 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.368+00:00 +4.204001 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 82075}
2026-11-10T10:48:09.369+00:00 +4.204627 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\n"
2026-11-10T10:48:09.370+00:00 +4.206179 OUTPUT "DEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\n"
2026-11-10T10:48:09.374+00:00 +4.209876 OUTPUT "DEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■·····················  37%  0.1 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.489+00:00 +4.325112 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 98490}
2026-11-10T10:48:09.496+00:00 +4.332244 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))"
2026-11-10T10:48:09.501+00:00 +4.336810 OUTPUT "\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■···················  43%  0.1 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.614+00:00 +4.449904 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 114905}
2026-11-10T10:48:09.621+00:00 +4.456514 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\n"
2026-11-10T10:48:09.633+00:00 +4.468660 OUTPUT "DEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■················  50%  0.1 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.740+00:00 +4.575302 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 131320}
2026-11-10T10:48:09.746+00:00 +4.582229 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\n"
2026-11-10T10:48:09.748+00:00 +4.584051 OUTPUT "DEBUG:  105+        >>>> $bytes += $count\r\n\r\n"
2026-11-10T10:48:09.753+00:00 +4.588855 OUTPUT "DEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\n"
2026-11-10T10:48:09.755+00:00 +4.590490 OUTPUT "DEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\n"
2026-11-10T10:48:09.755+00:00 +4.590550 OUTPUT "DEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\n"
2026-11-10T10:48:09.755+00:00 +4.590573 OUTPUT "DEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\n"
2026-11-10T10:48:09.756+00:00 +4.591591 OUTPUT "DEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■···············  56%  0.1 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\n"
2026-11-10T10:48:09.756+00:00 +4.591643 OUTPUT "DEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.860+00:00 +4.695874 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 147735}
2026-11-10T10:48:09.861+00:00 +4.696664 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\n"
2026-11-10T10:48:09.861+00:00 +4.697180 OUTPUT "DEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\n"
2026-11-10T10:48:09.862+00:00 +4.697300 OUTPUT "DEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\n"
2026-11-10T10:48:09.862+00:00 +4.697348 OUTPUT "DEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n"
2026-11-10T10:48:09.862+00:00 +4.697398 OUTPUT "\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■·············  62%  0.2 / 0.3 MB"
2026-11-10T10:48:09.862+00:00 +4.697427 OUTPUT "DEBUG:   66+  >>>> }\r\n\r\n"
2026-11-10T10:48:09.862+00:00 +4.697480 OUTPUT "DEBUG:  108+          >>>> $clock.Restart()\r\n\r\n"
2026-11-10T10:48:09.862+00:00 +4.697519 OUTPUT "DEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:09.981+00:00 +4.816455 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 164150}
2026-11-10T10:48:09.985+00:00 +4.820713 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\n"
2026-11-10T10:48:09.992+00:00 +4.827576 OUTPUT "DEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■■■···········  68%  0.2 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:10.105+00:00 +4.940690 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 180565}
2026-11-10T10:48:10.108+00:00 +4.943411 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\n"
2026-11-10T10:48:10.110+00:00 +4.946215 OUTPUT "DEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\n"
2026-11-10T10:48:10.111+00:00 +4.946677 OUTPUT "DEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\n"
2026-11-10T10:48:10.112+00:00 +4.948028 OUTPUT "DEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\n"
2026-11-10T10:48:10.113+00:00 +4.948544 OUTPUT "DEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■■■■■■········  75%  0.2 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:10.226+00:00 +5.061423 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 196980}
2026-11-10T10:48:10.230+00:00 +5.065418 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■■■■■■■·······  81%  0.2 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:10.346+00:00 +5.181947 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 213395}
2026-11-10T10:48:10.353+00:00 +5.188364 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■■■■■■■■■·····  87%  0.2 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:10.474+00:00 +5.309632 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 229810}
2026-11-10T10:48:10.478+00:00 +5.314239 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\n"
2026-11-10T10:48:10.481+00:00 +5.317233 OUTPUT "DEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■■■■■■■■■■■···  93%  0.2 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\n"
2026-11-10T10:48:10.596+00:00 +5.432240 SERVER {"event": "body_chunk", "endpoint": "archive", "bytes": 16415, "offset": 246225}
2026-11-10T10:48:10.597+00:00 +5.433096 OUTPUT "DEBUG:  104+        >>>> $file.Write($buffer, 0, $count)\r\n\r\nDEBUG:  105+        >>>> $bytes += $count\r\n\r\nDEBUG:  106+       if ( >>>> $clock.ElapsedMilliseconds -ge 100) {\r\n\r\nDEBUG:  107+          >>>> Draw-Download $bytes $response.Content.Headers.ContentLength\r\n\r\n"
2026-11-10T10:48:10.600+00:00 +5.435561 OUTPUT "DEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■■■■■■■■■■■■■■ 100%  0.3 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  108+          >>>> $clock.Restart()\r\n\r\nDEBUG:  103+     while ( >>>> ($count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  103+     while (( >>>> $count = $source.Read($buffer, 0, $buffer.Length)) -gt 0) {\r\n\r\nDEBUG:  111+      >>>> Draw-Download $bytes $bytes\r\n\r\nDEBUG:   49+ function Draw-Download([long] $bytes, [long] $total)  >>>> {\r\n\r\nDEBUG:   50+   if ( >>>> -not $interactive) { return }\r\n\r\nDEBUG:   51+    >>>> $columns = [Console]::WindowWidth\r\n\r\nDEBUG:   52+   if ( >>>> $columns -le 0) { $columns = 80 }\r\n\r\nDEBUG:   53+   if ( >>>> $total -gt 0 -and $columns -ge 9) {\r\n\r\nDEBUG:   54+      >>>> $percent = [Math]::Min(100, [Math]::Floor($bytes * 100.0 / $total))\r\n\r\nDEBUG:   55+      >>>> $width = [Math]::Min(32, $columns - 8)\r\n\r\nDEBUG:   56+      >>>> $filled = [int][Math]::Floor($percent * $width / 100)\r\n\r\nDEBUG:   57+      >>>> $bar = ([string][char]0x25A0) * $filled\r\n\r\nDEBUG:   58+      >>>> $rest = ([string][char]0x00B7) * ($width - $filled)\r\n\r\nDEBUG:   59+      >>>> $sizes = (\"  {0:F1} / {1:F1} MB\" -f ($bytes / 1MB), ($total / 1MB))\r\n\r\nDEBUG:   60+     if ( >>>> $width + 7 + $sizes.Length -ge $columns) { $sizes = \"\" }\r\n\r\nDEBUG:   61+      >>>> [Console]::Error.Write((\"`r$esc[2K  $accent$bar$reset$muted$rest$reset {0,3}%\" -f $percent) + \"$muted$sizes$reset\")\r\n\r\n\r\u001b[2K  ■■■■■■■■■■■■■■■■■■■■■■■■■■■■■■■■ 100%  0.3 / 0.3 MBDEBUG:   66+  >>>> }\r\n\r\nDEBUG:  112+      >>>> [Console]::Error.WriteLine()\r\n\r\n"
2026-11-10T10:48:10.601+00:00 +5.437054 OUTPUT "\r\nDEBUG:  114+     if ( >>>> $file) { $file.Dispose() }\r\n\r\n"
2026-11-10T10:48:10.602+00:00 +5.437583 OUTPUT "DEBUG:  114+     if ($file) {  >>>> $file.Dispose() }\r\n\r\n"
2026-11-10T10:48:10.603+00:00 +5.438702 OUTPUT "DEBUG:  115+     if ( >>>> $source) { $source.Dispose() }\r\n\r\n"
2026-11-10T10:48:10.605+00:00 +5.440561 OUTPUT "DEBUG:  115+     if ($source) {  >>>> $source.Dispose() }\r\n\r\n"
2026-11-10T10:48:10.609+00:00 +5.444702 OUTPUT "DEBUG:  116+     if ( >>>> $response) { $response.Dispose() }\r\n\r\nDEBUG:  116+     if ($response) {  >>>> $response.Dispose() }\r\n\r\nDEBUG:  117+      >>>> $client.Dispose()\r\n\r\nDEBUG:  119+  >>>> }\r\n\r\n"
2026-11-10T10:48:10.609+00:00 +5.444969 OUTPUT "DEBUG:  123+  >>>> Write-Output \"DEST_LENGTH=$((Get-Item $env:CAPTURE_DEST).Length)\"\r\n\r\nDEBUG:  123+ Write-Output \"DEST_LENGTH=$( >>>> (Get-Item $env:CAPTURE_DEST).Length)\"\r\n\r\n"
2026-11-10T10:48:10.652+00:00 +5.487448 OUTPUT "DEST_LENGTH=262640\r\nDEBUG:  124+  >>>> Write-Output \"DEST_SHA256=$((Get-FileHash $env:CAPTURE_DEST -Algorithm SHA256).Hash)\"\r\n\r\nDEBUG:  124+ Write-Output \"DEST_SHA256=$( >>>> (Get-FileHash $env:CAPTURE_DEST -Algorithm SHA256).Hash)\"\r\n\r\n"
2026-11-10T10:48:10.661+00:00 +5.497235 OUTPUT "DEST_SHA256=D43B9967918839C5A0C2430F323403E5E95EBC6C2C104CB17350194E6C3BCB8C\r\n"
2026-11-10T10:48:10.662+00:00 +5.497469 OUTPUT "DEBUG:  125+  >>>> Write-Output \"CAPTURE_SUCCESS\"\r\n\r\nCAPTURE_SUCCESS\r\n"
2026-11-10T10:48:10.662+00:00 +5.497849 OUTPUT "DEBUG:  132+ } finally {  >>>> [Console]::Error.WriteLine(\"CAPTURE_CALLER_FINALLY\") }\r\n\r\n"
2026-11-10T10:48:10.663+00:00 +5.498868 OUTPUT "CAPTURE_CALLER_FINALLY\r\n"
2026-11-10T10:48:10.717+00:00 +5.552647 SERVER {"event": "body_complete", "endpoint": "archive", "bytes": 262640}
2026-11-10T10:48:10.719+00:00 +5.554836 SERVER {"event": "request_handler_end", "endpoint": "archive"}
2026-11-10T10:48:10.854+00:00 +5.689841 SERVER_STATE_BEFORE_DRIVER_CLEANUP {"active_handlers": 0}
2026-11-10T10:48:10.862+00:00 +5.697976 RESULT {"exit_code": 0, "elapsed_seconds": 5.689772, "requests": 2, "interrupt_sent": false, "timeout": false, "state": {"marker": false, "files": [{"path": "home/.cache/powershell/StartupProfileData-NonInteractive", "bytes": 91404, "symlink": null}], "helper_outputs": [{"path": "downloaded", "bytes": 262640}], "staging": [], "sidecars": [], "tracked_downloader_pids": [], "remaining_tracked_downloaders": []}, "symlink_executable_check": null}
