WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-11T09:44:22+00:00
stage=PI_PROGRESS
[2026-11-11T09:42:07+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-11T09:42:15+00:00] PI_IMPL_FETCHED source=main characters=60694
[2026-11-11T09:42:16+00:00] booting.
[2026-11-11T09:42:16+00:00] booting..
[2026-11-11T09:42:16+00:00] GPU_DIAGNOSTIC_DEFERRED reason=prioritize_RX_TX_and_live_TCP_proof
[2026-11-11T09:42:17+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=59370
[2026-11-11T09:42:18+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=21989
[2026-11-11T09:42:19+00:00] TCP_LINK_MARKER_HANDLER_PATCHED source=tcp_socks5.py mode=runtime-injection
[2026-11-11T09:42:19+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=21980
[2026-11-11T09:42:20+00:00] IO_LOOP_SOURCE_FETCHED file=remote_probe.py attempt=1/10 characters=2665
[2026-11-11T09:42:21+00:00] IO_LOOP_SOURCE_FETCHED file=terminal_service.py attempt=1/10 characters=6373
[2026-11-11T09:42:22+00:00] IO_LOOP_SOURCE_FETCHED file=remote_shell.py attempt=1/10 characters=3115
[2026-11-11T09:42:22+00:00] IO_LOOP_CONTROLLER_START dataset_id=simonday/webvm-io-loop-rx kernel_id=simonday/webvm-io-loop-proof
[2026-11-11T09:42:24+00:00] RX_TX_INITIAL_PROOF_BACKGROUND_STARTED command_channel=available
[2026-11-11T09:42:24+00:00] RX_TX_TERMINAL_LISTENING host=127.0.0.1 port=2323 transport=Kaggle-RX-TX
[2026-11-11T09:42:24+00:00] WEBVM_TERMINAL_SERVER_DIAGNOSTIC_FAIL error=OSError: [Errno 22] Invalid argument
[2026-11-11T09:42:24+00:00] WEBVM_TERMINAL_SELFTEST_SKIPPED reason=loopback_socket_test_not_required
[2026-11-11T09:42:24+00:00] RX_TX_TOKENIZED_PROXY_MARKER_TEST_SCHEDULED_AFTER_ROUTE_DISCOVERY no_tailscale=true
[2026-11-11T09:42:24+00:00] RX_TX_REMOTE_COMMAND_POLL_STARTED interval_seconds=30 current_sha256=a38733060dea6fd70ed2d54674f3ad5336a24c8b403d617b5678f267e3125e6c
[2026-11-11T09:42:25+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-11T09:42:25.861+00:00", "timeout_seconds": 900}
[2026-11-11T09:42:26+00:00] IO_LOOP_CONTROLLER {"encoded_bytes": 3556, "event": "PYTHON_PAYLOAD_ENCODED", "path": "/home/user/kaggle-io-loop/remote_probe.py", "sha256": "a38733060dea6fd70ed2d54674f3ad5336a24c8b403d617b5678f267e3125e6c", "source_bytes": 2665, "time": "2026-11-11T09:42:26.000+00:00"}
[2026-11-11T09:42:26+00:00] IO_LOOP_CONTROLLER {"checksum": "a5abb04d9663908b74ba0d3a97ad1d00fdcfbf541cde9795f77636dd18b1d74e", "event": "POC_FILES_WRITTEN", "notebook_bytes": 34776, "payload": "IyEvdXNyL2Jpbi9lbnYgcHl0aG9uMwoiIiJGaW5kIHRoZSBLYWdnbGUgcHJveHkgdG9rZW4gdXNpbmcgdGhlIG5vdGVib29rJ3MgZnVsbCBwcm9jZXNzIGxpc3RpbmcuCgpUaGUgbGl2ZSBwcm9jZXNzIHRhYmxlIGlzIHNlYXJjaGVkIHdpdGggYHBzIGF1eHd3YCwgYXMgcmVxdWVzdGVkLiBUaGUKbWF0Y2hpbmcgdG9rZW4gaXMgd3JpdHRlbiBvbmx5IHRvIC9rYWdnbGUvd29ya2luZy93ZWJ2bV9wcm94eV9yb3V0ZS5qc29uCmZvciByZXR1cm4gdGhyb3VnaCB0aGUgZXhpc3RpbmcgUlgvVFggY2hhbm5lbC4gSXQgaXMgbmV2ZXIgcHJpbnRlZC4KIiIiCmltcG9ydCBqc29uCmltcG9ydCByZQppbXBvcnQgc3VicHJvY2Vzcwpmcm9tIHBhdGhsaWIgaW1wb3J0IFBhdGgKCkhPU1QgPSAia2tiLXByb2R1Y3Rpb24uanVweXRlci1wcm94eS5rYWdnbGUubmV0IgpSRVNVTFQgPSBQYXRoKCIva2FnZ2xlL3dvcmtpbmcvd2Vidm1fcHJveHlfcm91dGUuanNvbiIpClJPVVRFX1JFID0gcmUuY29tcGlsZShyIi9rLyhbMC05XSspLyhbXi9ccydcIj9dKykvcHJveHkoPzovfCQpIikKCnRyeToKICAgIHBzID0gc3VicHJvY2Vzcy5ydW4oCiAgICAgICAgWyJwcyIsICItYXV4Il0sCiAgICAgICAgY2hlY2s9VHJ1ZSwKICAgICAgICBjYXB0dXJlX291dHB1dD1UcnVlLAogICAgICAgIHRleHQ9VHJ1ZSwKICAgICAgICB0aW1lb3V0PTEwLAogICAgKQogICAgcHJvY2Vzc19saW5lcyA9IHBzLnN0ZG91dC5zcGxpdGxpbmVzKCkKICAgIHByaW50KCJXRUJWTV9GVUxMX1BTX0FVWF9CRUdJTiIsIGZsdXNoPVRydWUpCiAgICBwcmludChwcy5zdGRvdXQsIGVuZD0iIiBpZiBwcy5zdGRvdXQuZW5kc3dpdGgoIlxuIikgZWxzZSAiXG4iLCBmbHVzaD1UcnVlKQogICAgcHJpbnQoIldFQlZNX0ZVTExfUFNfQVVYX0VORCIsIGZsdXNoPVRydWUpCmV4Y2VwdCAoT1NFcnJvciwgc3VicHJvY2Vzcy5TdWJwcm9jZXNzRXJyb3IpIGFzIGV4YzoKICAgIHRyeToKICAgICAgICBSRVNVTFQudW5saW5rKCkKICAgIGV4Y2VwdCBGaWxlTm90Rm91bmRFcnJvcjoKICAgICAgICBwYXNzCiAgICBwcmludCgKICAgICAgICAiV0VCVk1fUFJPWFlfUk9VVEVfRElTQ09WRVJZIGZvdW5kPWZhbHNlIG1ldGhvZD1wc19hdXggIgogICAgICAgICJlcnJvcl90eXBlPXt9Ii5mb3JtYXQodHlwZShleGMpLl9fbmFtZV9fKSwKICAgICAgICBmbHVzaD1UcnVlLAogICAgKQogICAgcmFpc2UgU3lzdGVtRXhpdCgwKQoKanVweXRlcl9saW5lcyA9IFsKICAgIGxpbmUgZm9yIGxpbmUgaW4gcHJvY2Vzc19saW5lcwogICAgaWYgImp1cHl0ZXIiIGluIGxpbmUubG93ZXIoKSBvciAibm90ZWJvb2siIGluIGxpbmUubG93ZXIoKQpdCnNlYXJjaF9ncm91cHMgPSAoanVweXRlcl9saW5lcywgcHJvY2Vzc19saW5lcykKZm91bmQgPSBOb25lCnNvdXJjZV9ncm91cCA9IE5vbmUKCiMgU2VhcmNoIHRoZSBlbnRpcmUgcHMgY29tbWFuZCBsaW5lLCBwcmlvcml0aXNpbmcgSnVweXRlci9Ob3RlYm9vayBwcm9jZXNzZXMuCmZvciBncm91cF9uYW1lLCBsaW5lcyBpbiAoKCJqdXB5dGVyX3Byb2Nlc3MiLCBqdXB5dGVyX2xpbmVzKSwgKCJhbGxfcHJvY2Vzc2VzIiwgcHJvY2Vzc19saW5lcykpOgogICAgZm9yIGxpbmUgaW4gbGluZXM6CiAgICAgICAgbWF0Y2ggPSBST1VURV9SRS5zZWFyY2gobGluZSkKICAgICAgICBpZiBtYXRjaDoKICAgICAgICAgICAgZm91bmQgPSB7CiAgICAgICAgICAgICAgICAiaG9zdCI6IEhPU1QsCiAgICAgICAgICAgICAgICAicGF0aCI6ICIvay97fS97fS9wcm94eSIuZm9ybWF0KG1hdGNoLmdyb3VwKDEpLCBtYXRjaC5ncm91cCgyKSksCiAgICAgICAgICAgICAgICAia2VybmVsX2lkIjogbWF0Y2guZ3JvdXAoMSksCiAgICAgICAgICAgICAgICAidG9rZW4iOiBtYXRjaC5ncm91cCgyKSwKICAgICAgICAgICAgfQogICAgICAgICAgICBzb3VyY2VfZ3JvdXAgPSBncm91cF9uYW1lCiAgICAgICAgICAgIGJyZWFrCiAgICBpZiBmb3VuZDoKICAgICAgICBicmVhawoKaWYgbm90IGZvdW5kOgogICAgdHJ5OgogICAgICAgIFJFU1VMVC51bmxpbmsoKQogICAgZXhjZXB0IEZpbGVOb3RGb3VuZEVycm9yOgogICAgICAgIHBhc3MKICAgIHByaW50KAogICAgICAgICJXRUJWTV9QUk9YWV9ST1VURV9ESVNDT1ZFUlkgZm91bmQ9ZmFsc2UgbWV0aG9kPXBzX2F1eHd3ICIKICAgICAgICAicHJvY2Vzc19jb3VudD17fSBqdXB5dGVyX3Byb2Nlc3NfY291bnQ9e30iLmZvcm1hdCgKICAgICAgICAgICAgbWF4KDAsIGxlbihwcm9jZXNzX2xpbmVzKSAtIDEpLCBsZW4oanVweXRlcl9saW5lcykpLAogICAgICAgIGZsdXNoPVRydWUsCiAgICApCmVsc2U6CiAgICBSRVNVTFQucGFyZW50Lm1rZGlyKHBhcmVudHM9VHJ1ZSwgZXhpc3Rfb2s9VHJ1ZSkKICAgIFJFU1VMVC53cml0ZV90ZXh0KGpzb24uZHVtcHMoZm91bmQsIHNvcnRfa2V5cz1UcnVlKSArICJcbiIsIGVuY29kaW5nPSJ1dGYtOCIpCiAgICBwcmludCgKICAgICAgICAiV0VCVk1fUFJPWFlfUk9VVEVfRElTQ09WRVJZIGZvdW5kPXRydWUgbWV0aG9kPXBzX2F1eHd3ICIKICAgICAgICAic291cmNlPXt9IGtlcm5lbF9pZD17fSB0b2tlbl9wcmVzZW50PXRydWUiLmZvcm1hdCgKICAgICAgICAgICAgc291cmNlX2dyb3VwLCBmb3VuZFsia2VybmVsX2lkIl0pLAogICAgICAgIGZsdXNoPVRydWUsCiAgICApCg==", "request_id": "io-loop-proof-20261111T094225991535Z", "rx_bytes": 3797, "sequence": 1, "time": "2026-11-11T09:42:26.671+00:00"}
[2026-11-11T09:42:26+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-20261111T094225991535Z", "-r", "skip"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T09:42:26.700+00:00", "timeout_seconds": 300}
[2026-11-11T09:43:00+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-20261111T094225991535Z", "-r", "skip"], "duration_ms": 33619.835, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/3.71k [00:00<?, ?B/s]\n100%|██████████| 3.71k/3.71k [00:01<00:00, 2.99kB/s]\n100%|██████████| 3.71k/3.71k [00:01<00:00, 2.00kB/s]\nUpload successful: rx_message.json (4KB)\nDataset version is being created. Please check progress at https://www.kaggle.com/datasets/simonday/webvm-io-loop-rx\n", "time": "2026-11-11T09:43:00.321+00:00"}
[2026-11-11T09:43:08+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "a5abb04d9663908b74ba0d3a97ad1d00fdcfbf541cde9795f77636dd18b1d74e", "time": "2026-11-11T09:43:00.332+00:00"}
[2026-11-11T09:43:08+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-11T09:43:00.438+00:00", "timeout_seconds": 90}
[2026-11-11T09:43:29+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": 28465.42, "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.71k [00:00<?, ?B/s]\n100%|██████████| 3.71k/3.71k [00:00<00:00, 59.8kB/s]\n\n", "time": "2026-11-11T09:43:28.904+00:00"}
[2026-11-11T09:43:34+00:00] IO_LOOP_CONTROLLER {"checksum": "a5abb04d9663908b74ba0d3a97ad1d00fdcfbf541cde9795f77636dd18b1d74e", "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-20261111T094225991535Z", "time": "2026-11-11T09:43:28.928+00:00"}
[2026-11-11T09:43:34+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-11T09:43:28.933+00:00", "timeout_seconds": 300}
[2026-11-11T09:43:55+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 26662.01, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 46 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-11T09:43:55.595+00:00"}
[2026-11-11T09:43:58+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-11T09:43:55.676+00:00"}
[2026-11-11T09:43:58+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-11T09:43:55.681+00:00", "timeout_seconds": 60}
[2026-11-11T09:44:20+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 24816.881, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-11T09:44:20.499+00:00"}
[2026-11-11T09:44:22+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-11T09:44:20.504+00:00"}