WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T09:56:59+00:00
stage=PI_HEARTBEAT
[2026-11-08T09:52:06+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-08T09:52:14+00:00] PI_IMPL_FETCHED source=main characters=37703
[2026-11-08T09:52:14+00:00] booting.
[2026-11-08T09:52:15+00:00] booting..
[2026-11-08T09:52:16+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=40372
[2026-11-08T09:52:17+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=10376
[2026-11-08T09:52:18+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=19424
[2026-11-08T09:52:18+00:00] RX_TX_PROOF_SKIPPED already_validated=1 override=WEBVM_RUN_RX_TX_PROOF=1
[2026-11-08T09:52:18+00:00] TCP_LINK_CONTROLLER_START kernel_id=simonday/webvm-tcp-link
[2026-11-08T09:52:21+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:52:20.994+00:00", "timeout_seconds": 900}
[2026-11-08T09:52:21+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:52:21.012+00:00", "timeout_seconds": 20}
[2026-11-08T09:52:43+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 22527.33, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:52:43.552+00:00"}
[2026-11-08T09:52: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-08T09:52:43.557+00:00", "timeout_seconds": 20}
[2026-11-08T09:53:04+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 21316.275, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:53:04.875+00:00"}
[2026-11-08T09:53:04+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 43875.27, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:53:04.882+00:00"}
[2026-11-08T09:53:04+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:53:04.885+00:00"}
[2026-11-08T09:53:05+00:00] IO_LOOP_CONTROLLER {"event": "POC_FILES_WRITTEN", "notebook_bytes": 7294, "service": "plain-python-tcp-echo-listener", "time": "2026-11-08T09:53:05.125+00:00"}
[2026-11-08T09:53:05+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:53:05.132+00:00", "timeout_seconds": 300}
[2026-11-08T09:53:30+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "duration_ms": 24488.245, "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:53:29.621+00:00"}
[2026-11-08T09:53:36+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T09:53:29.625+00:00"}
[2026-11-08T09:53:36+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:53:29.707+00:00", "wait_for_completion": false}
[2026-11-08T09:53:36+00:00] TCP_LINK_KERNEL_SUBMITTED kernel_id=simonday/webvm-tcp-link local_port=8889 service=plain-python-tcp-echo-listener
[2026-11-08T09:53: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-08T09:53:29.711+00:00", "timeout_seconds": 15}
[2026-11-08T09:53:45+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15342.57, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:53:45.055+00:00"}
[2026-11-08T09:53:45+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:53:45.060+00:00", "timeout_seconds": 15}
[2026-11-08T09:54:00+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15271.155, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:54:00.333+00:00"}
[2026-11-08T09:54:00+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30628.055, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:54:00.337+00:00"}
[2026-11-08T09:54:00+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:54:00.339+00:00", "waited_seconds": 30.6}
[2026-11-08T09:54: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-08T09:54:05.438+00:00", "timeout_seconds": 15}
[2026-11-08T09:54:20+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15246.845, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:54:20.682+00:00"}
[2026-11-08T09:54: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-08T09:54:20.688+00:00", "timeout_seconds": 15}
[2026-11-08T09:54:36+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15288.54, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:54:35.983+00:00"}
[2026-11-08T09:54:36+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30610.39, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:54:36.001+00:00"}
[2026-11-08T09:54:36+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:54:36.005+00:00", "waited_seconds": 66.3}
[2026-11-08T09:54:41+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:54:41.016+00:00", "timeout_seconds": 15}
[2026-11-08T09:54:56+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15325.67, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:54:56.344+00:00"}
[2026-11-08T09:54:56+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:54:56.349+00:00", "timeout_seconds": 15}
[2026-11-08T09:55:11+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15399.025, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:55:11.754+00:00"}
[2026-11-08T09:55:11+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30770.15, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:55:11.783+00:00"}
[2026-11-08T09:55:11+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:55:11.789+00:00", "waited_seconds": 102.1}
[2026-11-08T09:55:16+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:55:16.824+00:00", "timeout_seconds": 15}
[2026-11-08T09:55:32+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15329.85, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:55:32.154+00:00"}
[2026-11-08T09:55:32+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:55:32.162+00:00", "timeout_seconds": 15}
[2026-11-08T09:55:48+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15874.48, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:55:48.043+00:00"}
[2026-11-08T09:55:50+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31263.84, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:55:48.066+00:00"}
[2026-11-08T09:55:50+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:55:48.197+00:00", "waited_seconds": 138.5}
[2026-11-08T09:55:53+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:55:53.213+00:00", "timeout_seconds": 15}
[2026-11-08T09:56:08+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15213.33, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:56:08.428+00:00"}
[2026-11-08T09:56:08+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:56:08.433+00:00", "timeout_seconds": 15}
[2026-11-08T09:56:23+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15307.28, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:56:23.754+00:00"}
[2026-11-08T09:56:23+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30575.425, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T09:56:23.786+00:00"}
[2026-11-08T09:56:23+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:56:23.793+00:00", "waited_seconds": 174.1}
[2026-11-08T09:56:28+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:56:28.817+00:00", "timeout_seconds": 15}
[2026-11-08T09:56:44+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15229.0, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:56:44.048+00:00"}
[2026-11-08T09:56:44+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:56:44.055+00:00", "timeout_seconds": 15}
[2026-11-08T09:56:59+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15319.4, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T09:56:59.391+00:00"}