WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T09:39:56+00:00
stage=PI_HEARTBEAT
[2026-11-08T09:35:54+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-08T09:36:05+00:00] PI_IMPL_FETCHED source=main characters=37703
[2026-11-08T09:36:06+00:00] booting.
[2026-11-08T09:36:06+00:00] booting..
[2026-11-08T09:36:08+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=41202
[2026-11-08T09:36:09+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=10376
[2026-11-08T09:36:11+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=19424
[2026-11-08T09:36:11+00:00] RX_TX_PROOF_SKIPPED already_validated=1 override=WEBVM_RUN_RX_TX_PROOF=1
[2026-11-08T09:36:11+00:00] TCP_LINK_CONTROLLER_START kernel_id=simonday/webvm-tcp-link
[2026-11-08T09:36:14+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-08T09:36:14.346+00:00", "timeout_seconds": 900}
[2026-11-08T09:36:14+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-08T09:36:14.359+00:00", "timeout_seconds": 20}
[2026-11-08T09:36:35+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 21378.739, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:36:35.760+00:00"}
[2026-11-08T09:36:35+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-08T09:36:35.769+00:00", "timeout_seconds": 20}
[2026-11-08T09:36:59+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 23046.6, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:36:58.817+00:00"}
[2026-11-08T09:36:59+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 44470.101, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:36:58.824+00:00"}
[2026-11-08T09:36:59+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-08T09:36:58.827+00:00"}
[2026-11-08T09:36:59+00:00] IO_LOOP_CONTROLLER {"event": "POC_FILES_WRITTEN", "notebook_bytes": 8508, "service": "plain-python-tcp-echo-listener", "time": "2026-11-08T09:36:59.229+00:00"}
[2026-11-08T09:36:59+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-08T09:36:59.271+00:00", "timeout_seconds": 300}
[2026-11-08T09:37:37+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "duration_ms": 37427.875, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel push error: Maximum batch CPU session count of 5 reached.\n", "time": "2026-11-08T09:37:36.699+00:00"}
[2026-11-08T09:37:43+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T09:37:36.705+00:00"}
[2026-11-08T09:37:43+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "local_port": 8889, "service": "plain-python-tcp-echo-listener", "time": "2026-11-08T09:37:36.706+00:00", "wait_for_completion": false}
[2026-11-08T09:37:43+00:00] TCP_LINK_KERNEL_SUBMITTED kernel_id=simonday/webvm-tcp-link local_port=8889 service=plain-python-tcp-echo-listener
[2026-11-08T09:37:43+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-08T09:37:36.713+00:00", "timeout_seconds": 15}
[2026-11-08T09:37:52+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15322.859, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:37:52.038+00:00"}
[2026-11-08T09:37: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-08T09:37:52.042+00:00", "timeout_seconds": 15}
[2026-11-08T09:38:07+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15357.119, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:38:07.419+00:00"}
[2026-11-08T09:38:07+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30731.79, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:38:07.443+00:00"}
[2026-11-08T09:38: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-08T09:38:07.448+00:00", "waited_seconds": 30.7}
[2026-11-08T09:38: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-08T09:38:12.464+00:00", "timeout_seconds": 15}
[2026-11-08T09:38:28+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16086.125, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:38:28.551+00:00"}
[2026-11-08T09:38:28+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-08T09:38:28.556+00:00", "timeout_seconds": 15}
[2026-11-08T09:38:44+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15245.335, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:38:43.808+00:00"}
[2026-11-08T09:38:46+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31371.941, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:38:43.825+00:00"}
[2026-11-08T09:38: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-08T09:38:43.827+00:00", "waited_seconds": 67.1}
[2026-11-08T09:38:48+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-08T09:38:48.843+00:00", "timeout_seconds": 15}
[2026-11-08T09:39:04+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16009.899, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:39:04.855+00:00"}
[2026-11-08T09:39: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-08T09:39:04.859+00:00", "timeout_seconds": 15}
[2026-11-08T09:39:20+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15321.116, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:39:20.196+00:00"}
[2026-11-08T09:39:20+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31387.531, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:39:20.227+00:00"}
[2026-11-08T09:39:20+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-08T09:39:20.233+00:00", "waited_seconds": 103.5}
[2026-11-08T09:39:25+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-08T09:39:25.260+00:00", "timeout_seconds": 15}
[2026-11-08T09:39:40+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15224.36, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:39:40.487+00:00"}
[2026-11-08T09:39:40+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-08T09:39:40.497+00:00", "timeout_seconds": 15}
[2026-11-08T09:39:56+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15326.524, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:39:55.828+00:00"}