WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-11T08:48:51+00:00
stage=PI_PROGRESS
[2026-11-11T08:46:01+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-11T08:46:09+00:00] PI_IMPL_FETCHED source=main characters=60694
[2026-11-11T08:46:10+00:00] booting.
[2026-11-11T08:46:10+00:00] booting..
[2026-11-11T08:46:10+00:00] GPU_DIAGNOSTIC_DEFERRED reason=prioritize_RX_TX_and_live_TCP_proof
[2026-11-11T08:46:11+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=58931
[2026-11-11T08:46:12+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=21992
[2026-11-11T08:46:13+00:00] TCP_LINK_MARKER_HANDLER_PATCHED source=tcp_socks5.py mode=runtime-injection
[2026-11-11T08:46:13+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=21980
[2026-11-11T08:46:14+00:00] IO_LOOP_SOURCE_FETCHED file=remote_probe.py attempt=1/10 characters=2493
[2026-11-11T08:46:14+00:00] IO_LOOP_SOURCE_FETCHED file=terminal_service.py attempt=1/10 characters=6373
[2026-11-11T08:46:15+00:00] IO_LOOP_SOURCE_FETCHED file=remote_shell.py attempt=1/10 characters=3115
[2026-11-11T08:46:15+00:00] IO_LOOP_CONTROLLER_START dataset_id=simonday/webvm-io-loop-rx kernel_id=simonday/webvm-io-loop-proof
[2026-11-11T08:46:18+00:00] RX_TX_INITIAL_PROOF_BACKGROUND_STARTED command_channel=available
[2026-11-11T08:46:18+00:00] RX_TX_TERMINAL_LISTENING host=127.0.0.1 port=2323 transport=Kaggle-RX-TX
[2026-11-11T08:46:18+00:00] WEBVM_TERMINAL_SERVER_DIAGNOSTIC_FAIL error=OSError: [Errno 22] Invalid argument
[2026-11-11T08:46:18+00:00] WEBVM_TERMINAL_SELFTEST_SKIPPED reason=loopback_socket_test_not_required
[2026-11-11T08:46:18+00:00] RX_TX_TOKENIZED_PROXY_MARKER_TEST_SCHEDULED_AFTER_ROUTE_DISCOVERY no_tailscale=true
[2026-11-11T08:46:18+00:00] RX_TX_REMOTE_COMMAND_POLL_STARTED interval_seconds=30 current_sha256=dd24e68864ed4a5cb3a220b702380b97a0bfa24737d6228fe5f70322e97048f8
[2026-11-11T08:46:19+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RUN_BEGIN", "kernel_id": "simonday/webvm-io-loop-proof", "poll_seconds": 10, "time": "2026-11-11T08:46:19.871+00:00", "timeout_seconds": 900}
[2026-11-11T08:46:20+00:00] IO_LOOP_CONTROLLER {"encoded_bytes": 3324, "event": "PYTHON_PAYLOAD_ENCODED", "path": "/home/user/kaggle-io-loop/remote_probe.py", "sha256": "dd24e68864ed4a5cb3a220b702380b97a0bfa24737d6228fe5f70322e97048f8", "source_bytes": 2493, "time": "2026-11-11T08:46:19.991+00:00"}
[2026-11-11T08:46:20+00:00] IO_LOOP_CONTROLLER {"checksum": "66493fa69bf7ee49311b381bf7ffc1fa92eab7409b6a8cdcc027ddee3f5c4ea4", "event": "POC_FILES_WRITTEN", "notebook_bytes": 34779, "payload": "IyEvdXNyL2Jpbi9lbnYgcHl0aG9uMwoiIiJGaW5kIHRoZSBLYWdnbGUgcHJveHkgdG9rZW4gdXNpbmcgdGhlIG5vdGVib29rJ3MgZnVsbCBwcm9jZXNzIGxpc3RpbmcuCgpUaGUgbGl2ZSBwcm9jZXNzIHRhYmxlIGlzIHNlYXJjaGVkIHdpdGggYHBzIGF1eHd3YCwgYXMgcmVxdWVzdGVkLiBUaGUKbWF0Y2hpbmcgdG9rZW4gaXMgd3JpdHRlbiBvbmx5IHRvIC9rYWdnbGUvd29ya2luZy93ZWJ2bV9wcm94eV9yb3V0ZS5qc29uCmZvciByZXR1cm4gdGhyb3VnaCB0aGUgZXhpc3RpbmcgUlgvVFggY2hhbm5lbC4gSXQgaXMgbmV2ZXIgcHJpbnRlZC4KIiIiCmltcG9ydCBqc29uCmltcG9ydCByZQppbXBvcnQgc3VicHJvY2Vzcwpmcm9tIHBhdGhsaWIgaW1wb3J0IFBhdGgKCkhPU1QgPSAia2tiLXByb2R1Y3Rpb24uanVweXRlci1wcm94eS5rYWdnbGUubmV0IgpSRVNVTFQgPSBQYXRoKCIva2FnZ2xlL3dvcmtpbmcvd2Vidm1fcHJveHlfcm91dGUuanNvbiIpClJPVVRFX1JFID0gcmUuY29tcGlsZShyIi9rLyhbMC05XSspLyhbXi9ccydcIj9dKykvcHJveHkoPzovfCQpIikKCnRyeToKICAgIHBzID0gc3VicHJvY2Vzcy5ydW4oCiAgICAgICAgWyJwcyIsICJhdXh3dyJdLAogICAgICAgIGNoZWNrPVRydWUsCiAgICAgICAgY2FwdHVyZV9vdXRwdXQ9VHJ1ZSwKICAgICAgICB0ZXh0PVRydWUsCiAgICAgICAgdGltZW91dD0xMCwKICAgICkKICAgIHByb2Nlc3NfbGluZXMgPSBwcy5zdGRvdXQuc3BsaXRsaW5lcygpCmV4Y2VwdCAoT1NFcnJvciwgc3VicHJvY2Vzcy5TdWJwcm9jZXNzRXJyb3IpIGFzIGV4YzoKICAgIHRyeToKICAgICAgICBSRVNVTFQudW5saW5rKCkKICAgIGV4Y2VwdCBGaWxlTm90Rm91bmRFcnJvcjoKICAgICAgICBwYXNzCiAgICBwcmludCgKICAgICAgICAiV0VCVk1fUFJPWFlfUk9VVEVfRElTQ09WRVJZIGZvdW5kPWZhbHNlIG1ldGhvZD1wc19hdXh3dyAiCiAgICAgICAgImVycm9yX3R5cGU9e30iLmZvcm1hdCh0eXBlKGV4YykuX19uYW1lX18pLAogICAgICAgIGZsdXNoPVRydWUsCiAgICApCiAgICByYWlzZSBTeXN0ZW1FeGl0KDApCgpqdXB5dGVyX2xpbmVzID0gWwogICAgbGluZSBmb3IgbGluZSBpbiBwcm9jZXNzX2xpbmVzCiAgICBpZiAianVweXRlciIgaW4gbGluZS5sb3dlcigpIG9yICJub3RlYm9vayIgaW4gbGluZS5sb3dlcigpCl0Kc2VhcmNoX2dyb3VwcyA9IChqdXB5dGVyX2xpbmVzLCBwcm9jZXNzX2xpbmVzKQpmb3VuZCA9IE5vbmUKc291cmNlX2dyb3VwID0gTm9uZQoKIyBTZWFyY2ggdGhlIGVudGlyZSBwcyBjb21tYW5kIGxpbmUsIHByaW9yaXRpc2luZyBKdXB5dGVyL05vdGVib29rIHByb2Nlc3Nlcy4KZm9yIGdyb3VwX25hbWUsIGxpbmVzIGluICgoImp1cHl0ZXJfcHJvY2VzcyIsIGp1cHl0ZXJfbGluZXMpLCAoImFsbF9wcm9jZXNzZXMiLCBwcm9jZXNzX2xpbmVzKSk6CiAgICBmb3IgbGluZSBpbiBsaW5lczoKICAgICAgICBtYXRjaCA9IFJPVVRFX1JFLnNlYXJjaChsaW5lKQogICAgICAgIGlmIG1hdGNoOgogICAgICAgICAgICBmb3VuZCA9IHsKICAgICAgICAgICAgICAgICJob3N0IjogSE9TVCwKICAgICAgICAgICAgICAgICJwYXRoIjogIi9rL3t9L3t9L3Byb3h5Ii5mb3JtYXQobWF0Y2guZ3JvdXAoMSksIG1hdGNoLmdyb3VwKDIpKSwKICAgICAgICAgICAgICAgICJrZXJuZWxfaWQiOiBtYXRjaC5ncm91cCgxKSwKICAgICAgICAgICAgICAgICJ0b2tlbiI6IG1hdGNoLmdyb3VwKDIpLAogICAgICAgICAgICB9CiAgICAgICAgICAgIHNvdXJjZV9ncm91cCA9IGdyb3VwX25hbWUKICAgICAgICAgICAgYnJlYWsKICAgIGlmIGZvdW5kOgogICAgICAgIGJyZWFrCgppZiBub3QgZm91bmQ6CiAgICB0cnk6CiAgICAgICAgUkVTVUxULnVubGluaygpCiAgICBleGNlcHQgRmlsZU5vdEZvdW5kRXJyb3I6CiAgICAgICAgcGFzcwogICAgcHJpbnQoCiAgICAgICAgIldFQlZNX1BST1hZX1JPVVRFX0RJU0NPVkVSWSBmb3VuZD1mYWxzZSBtZXRob2Q9cHNfYXV4d3cgIgogICAgICAgICJwcm9jZXNzX2NvdW50PXt9IGp1cHl0ZXJfcHJvY2Vzc19jb3VudD17fSIuZm9ybWF0KAogICAgICAgICAgICBtYXgoMCwgbGVuKHByb2Nlc3NfbGluZXMpIC0gMSksIGxlbihqdXB5dGVyX2xpbmVzKSksCiAgICAgICAgZmx1c2g9VHJ1ZSwKICAgICkKZWxzZToKICAgIFJFU1VMVC5wYXJlbnQubWtkaXIocGFyZW50cz1UcnVlLCBleGlzdF9vaz1UcnVlKQogICAgUkVTVUxULndyaXRlX3RleHQoanNvbi5kdW1wcyhmb3VuZCwgc29ydF9rZXlzPVRydWUpICsgIlxuIiwgZW5jb2Rpbmc9InV0Zi04IikKICAgIHByaW50KAogICAgICAgICJXRUJWTV9QUk9YWV9ST1VURV9ESVNDT1ZFUlkgZm91bmQ9dHJ1ZSBtZXRob2Q9cHNfYXV4d3cgIgogICAgICAgICJzb3VyY2U9e30ga2VybmVsX2lkPXt9IHRva2VuX3ByZXNlbnQ9dHJ1ZSIuZm9ybWF0KAogICAgICAgICAgICBzb3VyY2VfZ3JvdXAsIGZvdW5kWyJrZXJuZWxfaWQiXSksCiAgICAgICAgZmx1c2g9VHJ1ZSwKICAgICkK", "request_id": "io-loop-proof-20261111T084619978739Z", "rx_bytes": 3565, "sequence": 1, "time": "2026-11-11T08:46:20.488+00:00"}
[2026-11-11T08:46:20+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "version", "-p", "/home/user/kaggle-io-loop-run/dataset", "-m", "WebVM RX io-loop-proof-20261111T084619978739Z", "-r", "skip"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T08:46:20.492+00:00", "timeout_seconds": 300}
[2026-11-11T08:46:51+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "version", "-p", "/home/user/kaggle-io-loop-run/dataset", "-m", "WebVM RX io-loop-proof-20261111T084619978739Z", "-r", "skip"], "duration_ms": 30577.739, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/3.48k [00:00<?, ?B/s]\n100%|██████████| 3.48k/3.48k [00:01<00:00, 2.81kB/s]\n100%|██████████| 3.48k/3.48k [00:01<00:00, 1.80kB/s]\nUpload successful: rx_message.json (3KB)\nDataset version is being created. Please check progress at https://www.kaggle.com/datasets/simonday/webvm-io-loop-rx\n", "time": "2026-11-11T08:46:51.071+00:00"}
[2026-11-11T08:46:53+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "66493fa69bf7ee49311b381bf7ffc1fa92eab7409b6a8cdcc027ddee3f5c4ea4", "time": "2026-11-11T08:46:51.082+00:00"}
[2026-11-11T08:46:53+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "download", "simonday/webvm-io-loop-rx", "-f", "rx_message.json", "-p", "/home/user/kaggle-io-loop-run/output/published_rx_verify", "--unzip", "-o"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T08:46:51.184+00:00", "timeout_seconds": 90}
[2026-11-11T08:47:20+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "download", "simonday/webvm-io-loop-rx", "-f", "rx_message.json", "-p", "/home/user/kaggle-io-loop-run/output/published_rx_verify", "--unzip", "-o"], "duration_ms": 28407.34, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Dataset URL: https://www.kaggle.com/datasets/simonday/webvm-io-loop-rx\nLicense(s): CC0-1.0\nDownloading rx_message.json to /home/user/kaggle-io-loop-run/output/published_rx_verify\n\n  0%|          | 0.00/3.48k [00:00<?, ?B/s]\n100%|██████████| 3.48k/3.48k [00:00<00:00, 49.3kB/s]\n\n", "time": "2026-11-11T08:47:19.592+00:00"}
[2026-11-11T08:47:25+00:00] IO_LOOP_CONTROLLER {"checksum": "66493fa69bf7ee49311b381bf7ffc1fa92eab7409b6a8cdcc027ddee3f5c4ea4", "dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_PUBLICATION_VERIFIED", "file": "/home/user/kaggle-io-loop-run/output/published_rx_verify/rx_message.json", "request_id": "io-loop-proof-20261111T084619978739Z", "time": "2026-11-11T08:47:19.609+00:00"}
[2026-11-11T08:47:25+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T08:47:19.613+00:00", "timeout_seconds": 300}
[2026-11-11T08:47:48+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 28232.204, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 44 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-11T08:47:47.847+00:00"}
[2026-11-11T08:47:50+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-11T08:47:47.888+00:00"}
[2026-11-11T08:47:50+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T08:47:47.899+00:00", "timeout_seconds": 60}
[2026-11-11T08:48:12+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 24643.08, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-11T08:48:12.542+00:00"}
[2026-11-11T08:48:13+00:00] IO_LOOP_CONTROLLER {"event": "EXECUTION_STATUS", "exit_code": 0, "kernel_id": "simonday/webvm-io-loop-proof", "status": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"", "time": "2026-11-11T08:48:12.547+00:00"}
[2026-11-11T08:48:22+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T08:48:22.568+00:00", "timeout_seconds": 60}
[2026-11-11T08:48:46+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 24092.345, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.COMPLETE\"\n", "time": "2026-11-11T08:48:46.661+00:00"}
[2026-11-11T08:48:51+00:00] IO_LOOP_CONTROLLER {"event": "EXECUTION_STATUS", "exit_code": 0, "kernel_id": "simonday/webvm-io-loop-proof", "status": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.COMPLETE\"", "time": "2026-11-11T08:48:46.670+00:00"}