WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T06:21:02+00:00
stage=PI_PROGRESS
[2026-11-08T06:17:20+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-08T06:17:28+00:00] PI_IMPL_FETCHED source=main characters=37335
[2026-11-08T06:17:29+00:00] booting.
[2026-11-08T06:17:29+00:00] booting..
[2026-11-08T06:17:30+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=35203
[2026-11-08T06:17:31+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=10376
[2026-11-08T06:17:33+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=19593
[2026-11-08T06:17:33+00:00] RX_TX_PROOF_SKIPPED already_validated=1 override=WEBVM_RUN_RX_TX_PROOF=1
[2026-11-08T06:17:33+00:00] TCP_LINK_CONTROLLER_START kernel_id=simonday/webvm-tcp-link
[2026-11-08T06:17:36+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-08T06:17:36.375+00:00", "timeout_seconds": 900}
[2026-11-08T06:17: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-08T06:17:36.393+00:00", "timeout_seconds": 20}
[2026-11-08T06:17:58+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 21718.436, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:17:58.126+00:00"}
[2026-11-08T06:17:58+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-08T06:17:58.133+00:00", "timeout_seconds": 20}
[2026-11-08T06:18:19+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 21475.525, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:18:19.610+00:00"}
[2026-11-08T06:18:19+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 43227.911, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T06:18:19.618+00:00"}
[2026-11-08T06:18: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_PREDEPLOY_STATUS_UNKNOWN", "kernel_id": "simonday/webvm-tcp-link", "note": "Continuing with the explicit deployment request", "time": "2026-11-08T06:18:19.621+00:00"}
[2026-11-08T06:18:20+00:00] IO_LOOP_CONTROLLER {"event": "POC_FILES_WRITTEN", "notebook_bytes": 33344, "service": "tailscale-serve-tcp-to-http-connect-socks5", "time": "2026-11-08T06:18:20.474+00:00"}
[2026-11-08T06:18:20+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-08T06:18:20.482+00:00", "timeout_seconds": 300}
[2026-11-08T06:18:48+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "duration_ms": 26954.93, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 7 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-tcp-link\n", "time": "2026-11-08T06:18:47.438+00:00"}
[2026-11-08T06:18:54+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T06:18:47.445+00:00"}
[2026-11-08T06:18:54+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "local_port": 8889, "service": "tailscale-serve-tcp-to-http-connect-socks5", "tailnet_port": 18088, "time": "2026-11-08T06:18:47.447+00:00", "wait_for_completion": false}
[2026-11-08T06:18:54+00:00] TCP_LINK_KERNEL_SUBMITTED kernel_id=simonday/webvm-tcp-link local_port=8889 tailnet_port=18088
[2026-11-08T06:18:54+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-08T06:18:47.451+00:00", "timeout_seconds": 15}
[2026-11-08T06:19:02+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15310.476, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:19:02.763+00:00"}
[2026-11-08T06:19: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-08T06:19:02.768+00:00", "timeout_seconds": 15}
[2026-11-08T06:19:18+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15243.351, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:19:18.013+00:00"}
[2026-11-08T06:19:18+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30569.214, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T06:19:18.018+00:00"}
[2026-11-08T06:19:18+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-08T06:19:18.021+00:00", "waited_seconds": 30.6}
[2026-11-08T06:19:23+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-08T06:19:23.129+00:00", "timeout_seconds": 15}
[2026-11-08T06:19:38+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15306.895, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:19:38.437+00:00"}
[2026-11-08T06:19: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-08T06:19:38.449+00:00", "timeout_seconds": 15}
[2026-11-08T06:19:55+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16430.15, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:19:54.886+00:00"}
[2026-11-08T06:20:02+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31833.23, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T06:19:54.897+00:00"}
[2026-11-08T06:20: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-08T06:19:54.900+00:00", "waited_seconds": 67.5}
[2026-11-08T06:20:02+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-08T06:19:59.917+00:00", "timeout_seconds": 15}
[2026-11-08T06:20:16+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16831.384, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:20:16.752+00:00"}
[2026-11-08T06:20:16+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-08T06:20:16.763+00:00", "timeout_seconds": 15}
[2026-11-08T06:20:32+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15317.975, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T06:20:32.098+00:00"}
[2026-11-08T06:20:32+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 32223.27, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T06:20:32.137+00:00"}
[2026-11-08T06:20:32+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-08T06:20:32.143+00:00", "waited_seconds": 104.7}
[2026-11-08T06:20:37+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_STARTUP_PENDING", "kernel_id": "simonday/webvm-tcp-link", "status_tail": "", "time": "2026-11-08T06:20:37.207+00:00", "waited_seconds": 109.8}
[2026-11-08T06:20:37+00:00] TCP_LINK_STARTUP_PENDING kernel_id=simonday/webvm-tcp-link
[2026-11-08T06:20:37+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-08T06:20:37.231+00:00", "timeout_seconds": 45}
[2026-11-08T06:21:02+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": 24806.439, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel log downloaded to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending/webvm-tcp-link.log \n", "time": "2026-11-08T06:21:02.039+00:00"}