WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T10:02:53+00:00
stage=PI_HEARTBEAT
[2026-11-08T09:58:55+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-08T09:59:08+00:00] PI_IMPL_FETCHED source=main characters=37586
[2026-11-08T09:59:09+00:00] booting.
[2026-11-08T09:59:09+00:00] booting..
[2026-11-08T09:59:11+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=41060
[2026-11-08T09:59:12+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=10376
[2026-11-08T09:59:14+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=19424
[2026-11-08T09:59:14+00:00] RX_TX_PROOF_SKIPPED already_validated=1 override=WEBVM_RUN_RX_TX_PROOF=1
[2026-11-08T09:59:14+00:00] TCP_LINK_CONTROLLER_START kernel_id=simonday/webvm-tcp-link
[2026-11-08T09:59:17+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:59:17.545+00:00", "timeout_seconds": 900}
[2026-11-08T09:59:18+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:59:17.560+00:00", "timeout_seconds": 20}
[2026-11-08T09:59:38+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 20954.786, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:59:38.536+00:00"}
[2026-11-08T09:59: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-08T09:59:38.546+00:00", "timeout_seconds": 20}
[2026-11-08T09:59:59+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 20443.274, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:59:58.990+00:00"}
[2026-11-08T09:59:59+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 41444.895, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:59:58.997+00:00"}
[2026-11-08T09:59: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:59:58.999+00:00"}
[2026-11-08T09:59:59+00:00] IO_LOOP_CONTROLLER {"event": "POC_FILES_WRITTEN", "notebook_bytes": 27457, "service": "plain-python-tcp-echo-listener", "time": "2026-11-08T09:59:59.783+00:00"}
[2026-11-08T09:59: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:59:59.787+00:00", "timeout_seconds": 300}
[2026-11-08T10:00:35+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "duration_ms": 35355.671, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 18 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-tcp-link\n", "time": "2026-11-08T10:00:35.143+00:00"}
[2026-11-08T10:00:42+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T10:00:35.150+00:00"}
[2026-11-08T10:00:42+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-08T10:00:35.152+00:00", "wait_for_completion": false}
[2026-11-08T10:00:42+00:00] TCP_LINK_KERNEL_SUBMITTED kernel_id=simonday/webvm-tcp-link local_port=8889 service=plain-python-tcp-echo-listener
[2026-11-08T10:00:42+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-08T10:00:35.155+00:00", "timeout_seconds": 15}
[2026-11-08T10:00:51+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15832.379, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:00:50.990+00:00"}
[2026-11-08T10:00:51+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-08T10:00:50.996+00:00", "timeout_seconds": 15}
[2026-11-08T10:01:06+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15379.994, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:01:06.404+00:00"}
[2026-11-08T10:01:06+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31283.129, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T10:01:06.437+00:00"}
[2026-11-08T10:01:06+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-08T10:01:06.444+00:00", "waited_seconds": 31.3}
[2026-11-08T10:01:11+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-08T10:01:11.468+00:00", "timeout_seconds": 15}
[2026-11-08T10:01:26+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15206.846, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:01:26.678+00:00"}
[2026-11-08T10:01:26+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-08T10:01:26.683+00:00", "timeout_seconds": 15}
[2026-11-08T10:01:42+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15378.884, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:01:42.068+00:00"}
[2026-11-08T10:01:44+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30621.661, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T10:01:42.079+00:00"}
[2026-11-08T10:01:44+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-08T10:01:42.083+00:00", "waited_seconds": 66.9}
[2026-11-08T10:01:47+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-08T10:01:47.092+00:00", "timeout_seconds": 15}
[2026-11-08T10:02:02+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15242.065, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:02:02.336+00:00"}
[2026-11-08T10:02: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-08T10:02:02.341+00:00", "timeout_seconds": 15}
[2026-11-08T10:02:17+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15304.555, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:02:17.660+00:00"}
[2026-11-08T10:02:17+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30604.42, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T10:02:17.693+00:00"}
[2026-11-08T10:02:17+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-08T10:02:17.701+00:00", "waited_seconds": 102.5}
[2026-11-08T10:02: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-08T10:02:22.715+00:00", "timeout_seconds": 15}
[2026-11-08T10:02:38+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15266.299, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:02:37.984+00:00"}
[2026-11-08T10:02: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-08T10:02:37.994+00:00", "timeout_seconds": 15}
[2026-11-08T10:02:53+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15202.58, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T10:02:53.205+00:00"}