Skip to content

Instantly share code, notes, and snippets.

@scubbo
Created May 29, 2025 04:29
Show Gist options
  • Save scubbo/0f56e9b1b83a14963d6a09182be06ab2 to your computer and use it in GitHub Desktop.
Save scubbo/0f56e9b1b83a14963d6a09182be06ab2 to your computer and use it in GitHub Desktop.
Logs from local `proxy`, while attempting to unarchive a Function
metadata-1 | {"description":"by hostname(vercel-archivable-function-testbed-lilac.vercel.app)","hostnameOrID":"vercel-archivable-function-testbed-lilac.vercel.app","invokeState":0,"level":"debug","msg":"item count for query: 1","time":"2025-05-29T03:44:53Z","vercel_id":"5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
metadata-1 | {"description":"by hostname(vercel-archivable-function-testbed-lilac.vercel.app)","hostnameOrID":"vercel-archivable-function-testbed-lilac.vercel.app","invokeState":0,"level":"debug","msg":"item PK/SK for query: vercel-archivable-function-testbed-lilac.vercel.app/v1_a, replicated at 2025-05-28T23:13:05.712Z by d","time":"2025-05-29T03:44:53Z","vercel_id":"5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
metadata-1 | {"description":"project by id(prj_CVaIYAOMlM9zBuudvHK4Fgsalf5p)","hostnameOrID":"vercel-archivable-function-testbed-lilac.vercel.app","invokeState":0,"level":"debug","msg":"item count for query: 1","time":"2025-05-29T03:44:53Z","vercel_id":"5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
metadata-1 | {"description":"project by id(prj_CVaIYAOMlM9zBuudvHK4Fgsalf5p)","hostnameOrID":"vercel-archivable-function-testbed-lilac.vercel.app","invokeState":0,"level":"debug","msg":"item PK/SK for query: prj_CVaIYAOMlM9zBuudvHK4Fgsalf5p/v1_p_team_6EAi1th4tEa8FlwN9TzQot94, replicated at 2025-05-28T23:12:15.3574845Z by l","time":"2025-05-29T03:44:53Z","vercel_id":"5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
metadata-1 | {"description":"rolling by projectId(prj_CVaIYAOMlM9zBuudvHK4Fgsalf5p)","hostnameOrID":"vercel-archivable-function-testbed-lilac.vercel.app","invokeState":0,"level":"debug","msg":"item count for query: 0","time":"2025-05-29T03:44:54Z","vercel_id":"5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
metadata-1 | {"description":"deployment by id(dpl_CjP3jULDuoqCrkHM3LVaJYQybNkd)","hostnameOrID":"vercel-archivable-function-testbed-lilac.vercel.app","invokeState":0,"level":"debug","msg":"item count for query: 1","time":"2025-05-29T03:44:54Z","vercel_id":"5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
metadata-1 | {"description":"deployment by id(dpl_CjP3jULDuoqCrkHM3LVaJYQybNkd)","hostnameOrID":"vercel-archivable-function-testbed-lilac.vercel.app","invokeState":0,"level":"debug","msg":"item PK/SK for query: vercel-archivable-function-testbed-7ism1fu1r.vercel.app/v1_d_0_team_6EAi1th4tEa8FlwN9TzQot94, replicated at 2025-05-28T23:13:39.3534014Z by a","time":"2025-05-29T03:44:54Z","vercel_id":"5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
edge-function-router-1 | 2025/05/29 03:44:58 Datadog Tracer v1.66.0 ERROR: failure sending traces (attempt 1), will retry: Post "http://127.0.0.1:8126/v0.4/traces": dial tcp 127.0.0.1:8126: connect: connection refused, 6 additional messages skipped (first occurrence: 29 May 25 03:43 UTC)
edge-function-router-1 | 2025/05/29 03:44:58 Datadog Tracer v1.66.0 ERROR: lost 2 traces: Post "http://127.0.0.1:8126/v0.4/traces": dial tcp 127.0.0.1:8126: connect: connection refused, 6 additional messages skipped (first occurrence: 29 May 25 03:43 UTC)
port-forwarder-1 | Handling connection for 8083
now-proxy-1 | 2025/05/29 03:44:59 [warn] 35#35: *49663 [lua] log_by_lua.lua:344: High Internal TTFB 6435, vercel 3625.978, proxy 3625.978, rcv 1748490292.973, deployment_id=dpl_CjP3jULDuoqCrkHM3LVaJYQybNkd path_type=streaming_func report
now-proxy-1 | start delta
now-proxy-1 | ms ms
now-proxy-1 | 0.0 77.04
now-proxy-1 | 77.0 0.11 request_body-read_body
now-proxy-1 | 78.0 1294.74 cache-data_hostname-ORIGIN-x-HIT
now-proxy-1 | 78.0 1.74 metadata-api-request-fetch-ip:0
now-proxy-1 | 79.7 1288.89 metadata-api-request-duration:1
now-proxy-1 | 79.7 1288.69 regional_metadata-http-get
now-proxy-1 | 1368.9 0.20 c metadata-process-data_hostname-json-decode:29666
now-proxy-1 | 1372.4 0.25 cache-data_hostname-WORKER-x-SET
now-proxy-1 | 1372.7 1.50
now-proxy-1 | 1374.2 0.12 cache-path_keys-WORKER-x-MISS
now-proxy-1 | 1374.4 21.10 cache-path_keys-REGIONAL-x-MISS
now-proxy-1 | 1374.6 530.42 path_keys-http-get
now-proxy-1 | 1374.9 0.17 cache-encrypted_env_doc-WORKER-x-MISS
now-proxy-1 | 1375.2 15.27 cache-encrypted_env_doc-REGIONAL-x-MISS
now-proxy-1 | 1375.3 540.07 encrypted_env_doc-http-get
now-proxy-1 | 1375.4 0.19 cache-routes_doc-WORKER-x-MISS
now-proxy-1 | 1375.7 17.35 cache-routes_doc-REGIONAL-x-MISS
now-proxy-1 | 1376.0 531.23 routes_doc-http-get
now-proxy-1 | 1390.5 524.90 cache-encrypted_env_doc-ORIGIN-x-HIT
now-proxy-1 | 1393.0 514.21 cache-routes_doc-ORIGIN-x-HIT
now-proxy-1 | 1395.5 517.93 cache-path_keys-ORIGIN-x-HIT
now-proxy-1 | 1911.5 0.47 c routes_doc-decode:1935
now-proxy-1 | 1913.0 0.34 routes_doc-get_handle_routes
now-proxy-1 | 1914.0 0.05 c path_keys-decode:1076
now-proxy-1 | 1916.1 0.38 c encrypted_env_doc-decode:18182
now-proxy-1 | 1916.5 3.84
now-proxy-1 | 1920.5 1.07 cache-oidc_tokens-get
now-proxy-1 | 1921.6 2.04
now-proxy-1 | 1923.6 1361.70 cache-oidc_tokens-ORIGIN-x-HIT
now-proxy-1 | 1926.5 791.71 aws_web_identity_credentials-http-post
now-proxy-1 | 2719.5 556.33 aws_kms-http-post
now-proxy-1 | 3276.3 0.28 c metadata-process-oidc_tokens-json-decode:1315
now-proxy-1 | 3279.9 4.96 cache-oidc_tokens-REGIONAL-x-SET
now-proxy-1 | 3280.3 0.93 cache-oidc_tokens-set
now-proxy-1 | 3285.2 0.11 cache-oidc_tokens-WORKER-x-SET
now-proxy-1 | 3285.3 3.20
now-proxy-1 | 3288.5 3.43 c router-cpu:12
now-proxy-1 | 3288.5 332.77 router-route
now-proxy-1 | 3288.7 0.02 router-run_phase_hook_init_after
now-proxy-1 | 3291.6 0.13 cache-build_outputs-WORKER-x-MISS
now-proxy-1 | 3292.4 3.81 cache-build_outputs-REGIONAL-x-MISS
now-proxy-1 | 3292.9 326.07 build_outputs-http-head
now-proxy-1 | 3296.2 322.95 cache-build_outputs-ORIGIN-x-HIT
now-proxy-1 | 3621.2 0.00 router-run_phase_hook_finalize_after
now-proxy-1 | 3621.3 3.47
now-proxy-1 | 3624.8 2.26 cache-requests-ORIGIN-STREAMING_FUNC-ERROR
now-proxy-1 | 3625.1 1.52 cache-requests-REGIONAL-STREAMING_FUNC-MISS
now-proxy-1 | 3625.1 0.46 cache-requests-get
now-proxy-1 | 3627.2 2809.02 lambda-iad1--success
now-proxy-1 | 3629.5 2800.00 lambda-sfb-http-lambda
now-proxy-1 | 3629.5 3.00 lambda-sfb-compute-resolver-lambda
now-proxy-1 | 3640.5 2789.00 lambda-n1-http-lambda
now-proxy-1 | 3640.5 2127.00 lambda-n1-tunnel-lambda
now-proxy-1 | 3640.5 1654.00 lambda-aws-invoke-lambda
now-proxy-1 | 3640.5 7.00 lambda-n1-invoke-lambda
now-proxy-1 | 3647.5 133.00 lambda-n1-tcp-lambda
now-proxy-1 | 3780.5 151.00 lambda-n1-tls-lambda
now-proxy-1 | 5299.5 148.00 lambda-child-spawn-lambda
now-proxy-1 | 5447.5 874.00 lambda-ttfb-lambda
now-proxy-1 | 5447.5 160.00 lambda-total-latency-lambda
now-proxy-1 | 5447.5 159.00 lambda-child-init-lambda
now-proxy-1 | 5447.5 14.00 lambda-bytecode-lambda
now-proxy-1 | 5447.5 4.00 lambda-bootstrap-lambda
now-proxy-1 | 5452.5 142.00 lambda-import-fn-lambda
now-proxy-1 | 5608.5 8.00 lambda-post-init-delay-lambda
now-proxy-1 | 5611.5 719.00 lambda-child-request-ttfb-lambda
now-proxy-1 | 5611.5 75.00 lambda-tunnel-connect-lambda
now-proxy-1 | 5617.5 714.00 lambda-response-lambda
now-proxy-1 | 5689.5 616.00 lambda-fetch-lambda
now-proxy-1 | 6436.6 60.97 stream-from-reader:14533
now-proxy-1 | while logging request, client: 192.168.65.1, vercel_id: "5dc2a678904f-1748490292973-9fa3b3bd8e1f", server: , request: "GET /api/hello HTTP/1.1", host: "vercel-archivable-function-testbed-lilac.vercel.app"
serverless-function-router-1 | {"bridge_version":"1.19.10","client_ip":"192.168.65.1","deployment_id":"dpl_CjP3jULDuoqCrkHM3LVaJYQybNkd","deployment_plan":"hobby","deployment_target":"production","dispatcher":"n1.lambda","function_arn":"arn:aws:lambda:iad1:730531992728:function:team_6EAi1th4tEa8FlwN9TzQot94-20ebf12be691d40b45ae0570088e773462","invocation_type":"","invoked":true,"level":"info","msg":"debug log: tunnel:\tH3Qizo7245kbE4yg4HidqkiiNoueP3nvSbxb-ONXhAE=\n2025-05-29T03:44:56.621717212Z:\tcreated\n2025-05-29T03:44:56.622502421Z:\tinitial request: 00000001\n2025-05-29T03:44:58.750153838Z:\tconnected\n2025-05-29T03:44:58.754976422Z:\tmetric\n2025-05-29T03:44:58.915164255Z:\tmetric\n2025-05-29T03:44:59.116475172Z:\tmetric\n2025-05-29T03:44:59.317633547Z:\tmetric\n2025-05-29T03:44:59.745797255Z:\tbytecode: 398831 bytes\n2025-05-29T03:44:59.745847839Z:\tmetric\n2025-05-29T03:44:59.74671513Z:\tmetric\n2025-05-29T03:44:59.74693913Z:\tnack registered: 00000002\n2025-05-29T03:44:59.74765463Z:\trequest deleted: 00000001\n2025-05-29T03:44:59.748469255Z:\tmetric\n2025-05-29T03:44:59.748523464Z:\tdisconnected\n2025-05-29T03:44:59.876102214Z:\tinvocation done\n2025-05-29T03:44:59.876625714Z:\tclosed\n2025-05-29T03:44:59.876888172Z:\treport written\n\n\ndebug:\n[vc] 75 connection to tunnel established\u001e\n[vc] 75 sending RequestId([0, 0, 0, 0]) metric packet to SFR\u001e\n[vc] 244 sending RequestId([0, 0, 0, 0]) metric packet to SFR\u001e\n[vc] 444 sending RequestId([0, 0, 0, 0]) metric packet to SFR\u001e\n[vc] 644 sending RequestId([0, 0, 0, 0]) metric packet to SFR\u001e\n[vc] 694 sending RequestId([0, 0, 0, 1]) metric_log packet to SFR\u001e\n[vc] 719 sending RequestId([0, 0, 0, 1]) response_init packet to SFR\u001e\n[vc] 753 sending RequestId([0, 0, 0, 1]) response_chunk packet to SFR\u001e\n[vc] 772 sending RequestId([0, 0, 0, 1]) response_chunk packet to SFR\u001e\n[vc] 772 sending RequestId([0, 0, 0, 1]) response_chunk packet to SFR\u001e\n[vc] 772 sending RequestId([0, 0, 0, 1]) response_end packet to SFR\u001e\n[vc] 835 sending RequestId([0, 0, 0, 1]) byte_code_chunk packet to SFR\u001e\n[vc] 1055 sending RequestId([0, 0, 0, 0]) metric packet to SFR\u001e\n[vc] 1055 sending RequestId([0, 0, 0, 0]) metric packet to SFR\u001e\n[vc] 1055 all requests completed\u001e\n[vc] 1055 sent final nack: RequestId([0, 0, 0, 2])\u001e\n[vc] 1055 flushing remaining outbound packets\u001e\n[vc] 1055 sending RequestId([0, 0, 0, 1]) eof packet to SFR\u001e\n[vc] 1055 sending metrics packet to SFR\u001e\n[vc] 1055 shutting down write half\u001e\n[vc] 1055 waiting for inbound packet reader to complete\u001e\n[vc] 1202 mainloop completed, returning Ok(())\u001e\n[vc] 1203 invocation handle is complete\n","path_type":"streaming_func","request_host":"vercel-archivable-function-testbed-lilac.vercel.app","runtime":"nodejs22.x","time":"2025-05-29T03:44:59Z","x_vercel_id":"krun-cle1::iad1::5dc2a678904f-1748490292973-9fa3b3bd8e1f"}
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment