WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T12:23:09+00:00
stage=PI_WRAPPER_FINISHED
ue, "sequence": true, "version": true}, "event": "OUTPUT_VALIDATION", "response": {"ack_state": "processed_once", "agent_elapsed_ms": 41044.581, "execution": {"elapsed_ms": 27157.63, "exit_code": 0, "output": "REMOTE_PROBE_RESULT {\"firewall_changed\": false, \"hostname\": \"10ff5251a9dc\", \"https_example\": {\"error\": \"ProbeTimeout\", \"ok\": false}, \"jupyter_8888_untouched\": true, \"network\": [{\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"raw.githubusercontent.com\", \"port\": 443}, {\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"api.kaggle.com\", \"port\": 443}, {\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"kkb-production.jupyter-proxy.kaggle.net\", \"port\": 443}, {\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"pste.us\", \"port\": 443}], \"probe\": \"webvm-rx-tx-remote-probe-v2\", \"time\": 1794135800.2410448, \"uid\": 0}\n", "sha256": "dbf9c23bc664229dba3021bdb7c0f82dd6078441eb55b64f4f66f3b28d3bed72", "timed_out": false}, "network_diagnostics": {"dev_net_tun": false, "iptables_available": false, "ipv4_forwarding": "1", "jupyter_proxy_kernel_id": null, "jupyter_proxy_route_found": false, "jupyter_proxy_token_present": false, "outbound_tcp_443": {"kkb-production.jupyter-proxy.kaggle.net": {"dns_or_tcp": false, "error": "ProbeTimeout"}, "pste.us": {"dns_or_tcp": false, "error": "ProbeTimeout"}}, "port_8889_listening": false, "tailscale_available": false, "tailscaled_available": false, "uid": 0}, "payload": "EXEC:dbf9c23bc664229dba3021bdb7c0f82dd6078441eb55b64f4f66f3b28d3bed72:0", "payload_length": 71, "received_at": "2026-11-08T11:03:59.323+00:00", "received_checksum": "d5d77bcde73654a1a760327be6c2485c6519e98870bc0725f5484da92a1b860b", "request_id": "io-loop-proof-20261108T105958599483Z", "sequence": 1, "type": "ack", "version": 1}, "time": "2026-11-08T11:05:09.312+00:00"}
[2026-11-08T11:05:14+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-io-loop-proof", "-p", "/home/user/kaggle-io-loop-run/output/notebook_diagnostics", "-o", "--file-pattern", "^io_loop_proof\\.ipynb$"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:05:09.320+00:00", "timeout_seconds": 180}
[2026-11-08T11:05:34+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-io-loop-proof", "-p", "/home/user/kaggle-io-loop-run/output/notebook_diagnostics", "-o", "--file-pattern", "^io_loop_proof\\.ipynb$"], "duration_ms": 25403.869, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel log downloaded to /home/user/kaggle-io-loop-run/output/notebook_diagnostics/webvm-io-loop-proof.log \n", "time": "2026-11-08T11:05:34.725+00:00"}
[2026-11-08T11:05:38+00:00] IO_LOOP_CONTROLLER {"event": "NOTEBOOK_DIAGNOSTIC_DOWNLOAD", "exit_code": 0, "files": [], "output_tail": "Kernel log downloaded to /home/user/kaggle-io-loop-run/output/notebook_diagnostics/webvm-io-loop-proof.log \n", "time": "2026-11-08T11:05:34.740+00:00"}
[2026-11-08T11:05:38+00:00] IO_LOOP_CONTROLLER {"event": "PROXY_DIAGNOSTIC_RESULTS", "found": false, "line_count": 0, "lines": [], "time": "2026-11-08T11:05:34.742+00:00"}
[2026-11-08T11:05:38+00:00] IO_LOOP_CONTROLLER {"event": "LOOP_PROOF_PASS", "output_bytes": 2007, "output_file": "/home/user/kaggle-io-loop-run/output/tx_response.json", "payload_bytes": 3644, "request_id": "io-loop-proof-20261108T105958599483Z", "round_trip_ms": 336358.099, "rx_checksum": "d5d77bcde73654a1a760327be6c2485c6519e98870bc0725f5484da92a1b860b", "sequence": 1, "time": "2026-11-08T11:05:34.816+00:00", "tx_checksum": "75f1bb5c5e739384c33c2f53746e8f17dbd357c1ea74d9083878c4d9691bcff9"}
[2026-11-08T11:05:42+00:00] LOOP_PROOF_PASS output=/home/user/kaggle-io-loop-run/output/tx_response.json log=/home/user/kaggle-io-loop-run/run.jsonl
[2026-11-08T11:05:49+00:00] IO_LOOP_CONTROLLER_EXIT returncode=0
[2026-11-08T11:06:04+00:00] RX_TX_CURRENT_DEPLOYMENT_PROOF_PASS transport=kaggle-api-dataset-and-kernel-output
[2026-11-08T11:06:04+00:00] TCP_LINK_CONTROLLER_START kernel_id=simonday/webvm-tcp-link
[2026-11-08T11:06:04+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RUN_BEGIN", "kernel_id": "simonday/webvm-tcp-link", "poll_seconds": 10, "time": "2026-11-08T11:05:38.450+00:00", "timeout_seconds": 900}
[2026-11-08T11:06:04+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:05:38.468+00:00", "timeout_seconds": 20}
[2026-11-08T11:06:04+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 21656.585, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:06:00.137+00:00"}
[2026-11-08T11:06:04+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:06:00.141+00:00", "timeout_seconds": 20}
[2026-11-08T11:06:22+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 22220.865, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:06:22.364+00:00"}
[2026-11-08T11:06:22+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 43906.11, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:06:22.372+00:00"}
[2026-11-08T11:06:22+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_PREDEPLOY_STATUS_UNKNOWN", "kernel_id": "simonday/webvm-tcp-link", "note": "Continuing with the explicit deployment request", "time": "2026-11-08T11:06:22.375+00:00"}
[2026-11-08T11:06:23+00:00] IO_LOOP_CONTROLLER {"event": "POC_FILES_WRITTEN", "notebook_bytes": 28039, "service": "tailscale-socks5-http-connect-bridge", "time": "2026-11-08T11:06:23.113+00:00"}
[2026-11-08T11:06:23+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:06:23.132+00:00", "timeout_seconds": 300}
[2026-11-08T11:06:47+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "duration_ms": 24132.84, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 20 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-tcp-link\n", "time": "2026-11-08T11:06:47.266+00:00"}
[2026-11-08T11:06:49+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T11:06:47.273+00:00"}
[2026-11-08T11:06:49+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "local_port": 18088, "service": "tailscale-socks5-http-connect-bridge", "time": "2026-11-08T11:06:47.275+00:00", "wait_for_completion": false}
[2026-11-08T11:06:49+00:00] TCP_LINK_KERNEL_SUBMITTED kernel_id=simonday/webvm-tcp-link local_port=18088 service=tailscale-socks5-http-connect-bridge
[2026-11-08T11:06:49+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:06:47.282+00:00", "timeout_seconds": 15}
[2026-11-08T11:07:02+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15314.709, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:07:02.599+00:00"}
[2026-11-08T11:07:02+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:07:02.604+00:00", "timeout_seconds": 15}
[2026-11-08T11:07:17+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15205.674, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:07:17.815+00:00"}
[2026-11-08T11:07:19+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30547.325, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:07:17.827+00:00"}
[2026-11-08T11:07:19+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:07:17.832+00:00", "waited_seconds": 30.6}
[2026-11-08T11:07:22+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:07:22.912+00:00", "timeout_seconds": 15}
[2026-11-08T11:07:38+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15272.25, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:07:38.195+00:00"}
[2026-11-08T11:07:38+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:07:38.221+00:00", "timeout_seconds": 15}
[2026-11-08T11:07:53+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15263.5, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:07:53.494+00:00"}
[2026-11-08T11:07:53+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30634.646, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:07:53.505+00:00"}
[2026-11-08T11:07:53+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:07:53.511+00:00", "waited_seconds": 66.2}
[2026-11-08T11:07:58+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:07:58.530+00:00", "timeout_seconds": 15}
[2026-11-08T11:08:14+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16310.37, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:08:14.844+00:00"}
[2026-11-08T11:08:14+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:08:14.851+00:00", "timeout_seconds": 15}
[2026-11-08T11:08:31+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16907.54, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:08:31.761+00:00"}
[2026-11-08T11:08:34+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 33245.23, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:08:31.773+00:00"}
[2026-11-08T11:08:34+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:08:31.775+00:00", "waited_seconds": 104.5}
[2026-11-08T11:08:36+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:08:36.788+00:00", "timeout_seconds": 15}
[2026-11-08T11:08:52+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15238.04, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:08:52.049+00:00"}
[2026-11-08T11:08:52+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:08:52.079+00:00", "timeout_seconds": 15}
[2026-11-08T11:09:07+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15259.64, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:09:07.346+00:00"}
[2026-11-08T11:09:07+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30577.35, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:09:07.361+00:00"}
[2026-11-08T11:09:07+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:09:07.425+00:00", "waited_seconds": 140.1}
[2026-11-08T11:09:12+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:09:12.453+00:00", "timeout_seconds": 15}
[2026-11-08T11:09:27+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15213.005, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:09:27.668+00:00"}
[2026-11-08T11:09:27+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:09:27.674+00:00", "timeout_seconds": 15}
[2026-11-08T11:09:44+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16532.375, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:09:44.235+00:00"}
[2026-11-08T11:09:46+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31800.005, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:09:44.250+00:00"}
[2026-11-08T11:09:46+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:09:44.255+00:00", "waited_seconds": 177.0}
[2026-11-08T11:09:49+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:09:49.274+00:00", "timeout_seconds": 15}
[2026-11-08T11:10:04+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15225.58, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:10:04.526+00:00"}
[2026-11-08T11:10:04+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:10:04.553+00:00", "timeout_seconds": 15}
[2026-11-08T11:10:22+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 17521.715, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:10:22.091+00:00"}
[2026-11-08T11:10:22+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 32856.98, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:10:22.123+00:00"}
[2026-11-08T11:10:22+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:10:22.136+00:00", "waited_seconds": 214.8}
[2026-11-08T11:10:27+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:10:27.154+00:00", "timeout_seconds": 15}
[2026-11-08T11:10:43+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15835.636, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:10:43.007+00:00"}
[2026-11-08T11:10:43+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:10:43.027+00:00", "timeout_seconds": 15}
[2026-11-08T11:10:59+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16788.295, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:10:59.816+00:00"}
[2026-11-08T11:11:02+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 32683.236, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:10:59.827+00:00"}
[2026-11-08T11:11:02+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:10:59.829+00:00", "waited_seconds": 252.5}
[2026-11-08T11:11:05+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:11:04.900+00:00", "timeout_seconds": 15}
[2026-11-08T11:11:20+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15265.73, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:11:20.164+00:00"}
[2026-11-08T11:11:20+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:11:20.174+00:00", "timeout_seconds": 15}
[2026-11-08T11:11:35+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15204.44, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:11:35.386+00:00"}
[2026-11-08T11:11:35+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30541.545, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:11:35.401+00:00"}
[2026-11-08T11:11:35+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:11:35.405+00:00", "waited_seconds": 288.1}
[2026-11-08T11:11:40+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:11:40.413+00:00", "timeout_seconds": 15}
[2026-11-08T11:11:55+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15230.305, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:11:55.645+00:00"}
[2026-11-08T11:11:55+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T11:11:55.649+00:00", "timeout_seconds": 15}
[2026-11-08T11:12:11+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15261.3, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T11:12:10.944+00:00"}
[2026-11-08T11:12:13+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30559.991, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T11:12:10.970+00:00"}
[2026-11-08T11:12:13+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T11:12:10.975+00:00", "waited_seconds": 323.7}
[2026-11-08T11:12:16+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_STARTUP_PENDING", "kernel_id": "simonday/webvm-tcp-link", "status_tail": "", "time": "2026-11-08T11:12:15.988+00:00", "waited_seconds": 328.7}
[2026-11-08T11:12:16+00:00] TCP_LINK_STARTUP_PENDING kernel_id=simonday/webvm-tcp-link
[2026-11-08T11:12:16+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-tcp-link", "-p", "/home/user/kaggle-tcp-link-run/output/tcp_link_status_pending", "-o", "--file-pattern", "^tcp_link_status[.]json$"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T11:12:16.035+00:00", "timeout_seconds": 45}
[2026-11-08T11:12:42+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-tcp-link", "-p", "/home/user/kaggle-tcp-link-run/output/tcp_link_status_pending", "-o", "--file-pattern", "^tcp_link_status[.]json$"], "duration_ms": 26290.885, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Downloading tcp_link_status.json to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending\n\n  0%|          | 0.00/160 [00:00<?, ?B/s]\n100%|██████████| 160/160 [00:00<00:00, 2.32kB/s]\n\nOutput file downloaded to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending/tcp_link_status.json\nKernel log downloaded to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending/webvm-tcp-link.log \n", "time": "2026-11-08T11:12:42.327+00:00"}
[2026-11-08T11:12:44+00:00] IO_LOOP_CONTROLLER {"details": {"error_type": "URLError", "message": "<urlopen error [Errno -3] Temporary failure in name resolution>", "stage": "FAILED", "time": "2026-11-08T11:08:04+00:00"}, "event": "TCP_LINK_STARTUP_STATUS", "kernel_id": "simonday/webvm-tcp-link", "source": "pending_state_artifact", "status": "FAILED", "time": "2026-11-08T11:12:42.344+00:00"}
[2026-11-08T11:12:44+00:00] TCP_LINK_STARTUP_STATUS {"error_type": "URLError", "message": "<urlopen error [Errno -3] Temporary failure in name resolution>", "stage": "FAILED", "time": "2026-11-08T11:08:04+00:00"}
[2026-11-08T11:12:44+00:00] TCP_LINK_CONTROLLER_EXIT returncode=0
[2026-11-08T11:12:44+00:00] TCP_LINK_BRIDGE_SUBMITTED transport=tailscale-socks5-http-connect tailnet_port=18088
[2026-11-08T11:12:44+00:00] RX_TX_REMOTE_COMMAND_POLL_STARTED interval_seconds=30 current_sha256=dbf9c23bc664229dba3021bdb7c0f82dd6078441eb55b64f4f66f3b28d3bed72
[2026-11-08T12:23:06+00:00] Traceback (most recent call last):
[2026-11-08T12:23:09+00:00]   File "/home/user/examples/python3/pi.py", line 65, in <module>
[2026-11-08T12:23:09+00:00]     main()
[2026-11-08T12:23:09+00:00]   File "https://raw.githubusercontent.com/scatterp2/webvm/main/examples/python3/pi_impl.py", line 990, in main
[2026-11-08T12:23:09+00:00]   File "https://raw.githubusercontent.com/scatterp2/webvm/main/examples/python3/pi_impl.py", line 949, in run_io_loop_proof
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 216, in urlopen
[2026-11-08T12:23:09+00:00]     return opener.open(url, data, timeout)
[2026-11-08T12:23:09+00:00]            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 519, in open
[2026-11-08T12:23:09+00:00]     response = self._open(req, data)
[2026-11-08T12:23:09+00:00]                ^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 536, in _open
[2026-11-08T12:23:09+00:00]     result = self._call_chain(self.handle_open, protocol, protocol +
[2026-11-08T12:23:09+00:00]              ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 496, in _call_chain
[2026-11-08T12:23:09+00:00]     result = func(*args)
[2026-11-08T12:23:09+00:00]              ^^^^^^^^^^^
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 1404, in https_open
[2026-11-08T12:23:09+00:00]     return self.do_open(http.client.HTTPSConnection, req,
[2026-11-08T12:23:09+00:00]            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 1361, in do_open
[2026-11-08T12:23:09+00:00]     h.request(req.get_method(), req.selector, req.data, headers,
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1311, in request
[2026-11-08T12:23:09+00:00]     self._send_request(method, url, body, headers, encode_chunked)
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1357, in _send_request
[2026-11-08T12:23:09+00:00]     self.endheaders(body, encode_chunked=encode_chunked)
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1306, in endheaders
[2026-11-08T12:23:09+00:00]     self._send_output(message_body, encode_chunked=encode_chunked)
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1066, in _send_output
[2026-11-08T12:23:09+00:00]     self.send(msg)
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1004, in send
[2026-11-08T12:23:09+00:00]     self.connect()
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1483, in connect
[2026-11-08T12:23:09+00:00]     self.sock = self._context.wrap_socket(self.sock,
[2026-11-08T12:23:09+00:00]                 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/ssl.py", line 517, in wrap_socket
[2026-11-08T12:23:09+00:00]     return self.sslsocket_class._create(
[2026-11-08T12:23:09+00:00]            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/ssl.py", line 1121, in _create
[2026-11-08T12:23:09+00:00]     self.do_handshake()
[2026-11-08T12:23:09+00:00]   File "/usr/lib/python3.11/ssl.py", line 1394, in do_handshake
[2026-11-08T12:23:09+00:00]     self.settimeout(timeout)
[2026-11-08T12:23:09+00:00] OverflowError: timestamp too large to convert to C _PyTime_t
[2026-11-08T12:23:09+00:00] PI_WRAPPER_FINISHED returncode=1