WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T10:13:54+00:00
stage=PI_HEARTBEAT
[2026-11-08T10:09:55+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-08T10:10:07+00:00] PI_IMPL_FETCHED source=main characters=40291
[2026-11-08T10:10:08+00:00] booting.
[2026-11-08T10:10:08+00:00] booting..
[2026-11-08T10:10:10+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=43230
[2026-11-08T10:10:12+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=12489
[2026-11-08T10:10:13+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=19424
[2026-11-08T10:10:14+00:00] IO_LOOP_SOURCE_FETCHED file=remote_probe.py attempt=1/10 characters=1855
[2026-11-08T10:10:14+00:00] IO_LOOP_CONTROLLER_START dataset_id=simonday/webvm-io-loop-rx kernel_id=simonday/webvm-io-loop-proof
[2026-11-08T10:10:18+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-08T10:10:18.278+00:00", "timeout_seconds": 900}
[2026-11-08T10:10:18+00:00] IO_LOOP_CONTROLLER {"encoded_bytes": 2476, "event": "PYTHON_PAYLOAD_ENCODED", "path": "/home/user/kaggle-io-loop/remote_probe.py", "sha256": "3b25cf5b0f58d1106820234a71145bf3d9261c97defc0f230e57780b3231afa8", "source_bytes": 1855, "time": "2026-11-08T10:10:18.416+00:00"}
[2026-11-08T10:10:19+00:00] IO_LOOP_CONTROLLER {"checksum": "465ea847d96c3911395be262ba93e10cb19b951bac55755fe80c4ec4b459611b", "event": "POC_FILES_WRITTEN", "notebook_bytes": 22510, "payload": "IyEvdXNyL2Jpbi9lbnYgcHl0aG9uMwoiIiJSZW1vdGUgcHJvYmUgZXhlY3V0ZWQgdGhyb3VnaCB0aGUgV2ViVk0gLT4gS2FnZ2xlIFJYL1RYIGNvbW1hbmQgZW52ZWxvcGUuCgpUaGlzIGludGVudGlvbmFsbHkgYXZvaWRzIGNoYW5naW5nIGZpcmV3YWxsIHJ1bGVzIG9yIHRvdWNoaW5nIEp1cHl0ZXIuIEl0IHJlcG9ydHMKd2hldGhlciBLYWdnbGUgY2FuIHJlYWNoIHRoZSBwdWJsaWMgbmV0d29yayBhbmQgdGhlIFdlYlZNLXNpZGUgcHJveHkgZW5kcG9pbnQuCiIiIgppbXBvcnQganNvbiwgb3MsIHNvY2tldCwgc2h1dGlsLCBzdWJwcm9jZXNzLCBzeXMsIHRpbWUKZnJvbSB1cmxsaWIucmVxdWVzdCBpbXBvcnQgdXJsb3BlbiwgUmVxdWVzdAoKZGVmIHByb2JlX2hvc3QoaG9zdCwgcG9ydD00NDMpOgogICAgcm93ID0geyJob3N0IjogaG9zdCwgInBvcnQiOiBwb3J0fQogICAgdHJ5OgogICAgICAgIGFkZHJzID0gc29ydGVkKHt4WzRdWzBdIGZvciB4IGluIHNvY2tldC5nZXRhZGRyaW5mbyhob3N0LCBwb3J0LCB0eXBlPXNvY2tldC5TT0NLX1NUUkVBTSl9KQogICAgICAgIHJvd1siZG5zIl0gPSBUcnVlCiAgICAgICAgcm93WyJhZGRyZXNzX2NvdW50Il0gPSBsZW4oYWRkcnMpCiAgICBleGNlcHQgRXhjZXB0aW9uIGFzIGV4YzoKICAgICAgICByb3cudXBkYXRlKGRucz1GYWxzZSwgZXJyb3I9dHlwZShleGMpLl9fbmFtZV9fICsgIjogIiArIHN0cihleGMpWzoxODBdKQogICAgICAgIHJldHVybiByb3cKICAgIHRyeToKICAgICAgICB3aXRoIHNvY2tldC5jcmVhdGVfY29ubmVjdGlvbigoaG9zdCwgcG9ydCksIHRpbWVvdXQ9NCk6CiAgICAgICAgICAgIHJvd1sidGNwIl0gPSBUcnVlCiAgICBleGNlcHQgRXhjZXB0aW9uIGFzIGV4YzoKICAgICAgICByb3cudXBkYXRlKHRjcD1GYWxzZSwgdGNwX2Vycm9yPXR5cGUoZXhjKS5fX25hbWVfXyArICI6ICIgKyBzdHIoZXhjKVs6MTgwXSkKICAgICAgICByZXR1cm4gcm93CiAgICByb3dbInRjcCJdID0gVHJ1ZQogICAgcmV0dXJuIHJvdwoKcmVwb3J0ID0gewogICAgInByb2JlIjogIndlYnZtLXJ4LXR4LXJlbW90ZS1wcm9iZS12MSIsCiAgICAidGltZSI6IHRpbWUudGltZSgpLAogICAgImhvc3RuYW1lIjogc29ja2V0LmdldGhvc3RuYW1lKCksCiAgICAidWlkIjogb3MuZ2V0ZXVpZCgpIGlmIGhhc2F0dHIob3MsICJnZXRldWlkIikgZWxzZSBOb25lLAogICAgIm5ldHdvcmsiOiBbcHJvYmVfaG9zdChoKSBmb3IgaCBpbiAoCiAgICAgICAgInJhdy5naXRodWJ1c2VyY29udGVudC5jb20iLCAiYXBpLmthZ2dsZS5jb20iLAogICAgICAgICJra2ItcHJvZHVjdGlvbi5qdXB5dGVyLXByb3h5LmthZ2dsZS5uZXQiLCAicHN0ZS51cyIKICAgICldLAogICAgImp1cHl0ZXJfODg4OF91bnRvdWNoZWQiOiBUcnVlLAogICAgImZpcmV3YWxsX2NoYW5nZWQiOiBGYWxzZSwKfQp0cnk6CiAgICByZXEgPSBSZXF1ZXN0KCJodHRwczovL2V4YW1wbGUuY29tLyIsIGhlYWRlcnM9eyJVc2VyLUFnZW50IjogIndlYnZtLXJ4LXR4LXByb2JlLzEifSkKICAgIHdpdGggdXJsb3BlbihyZXEsIHRpbWVvdXQ9NikgYXMgcjoKICAgICAgICByZXBvcnRbImh0dHBzX2V4YW1wbGUiXSA9IHsib2siOiBUcnVlLCAic3RhdHVzIjogci5zdGF0dXMsICJieXRlcyI6IGxlbihyLnJlYWQoMjU2KSl9CmV4Y2VwdCBFeGNlcHRpb24gYXMgZXhjOgogICAgcmVwb3J0WyJodHRwc19leGFtcGxlIl0gPSB7Im9rIjogRmFsc2UsICJlcnJvciI6IHR5cGUoZXhjKS5fX25hbWVfXyArICI6ICIgKyBzdHIoZXhjKVs6MjAwXX0KcHJpbnQoIlJFTU9URV9QUk9CRV9SRVNVTFQgIiArIGpzb24uZHVtcHMocmVwb3J0LCBzb3J0X2tleXM9VHJ1ZSksIGZsdXNoPVRydWUpCg==", "request_id": "io-loop-proof-20261108T101018402994Z", "rx_bytes": 2717, "sequence": 1, "time": "2026-11-08T10:10:18.773+00:00"}
[2026-11-08T10:10:19+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T10:10:18.823+00:00", "timeout_seconds": 60}
[2026-11-08T10:11:17+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "duration_ms": 57615.56, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "ready", "time": "2026-11-08T10:11:16.441+00:00"}
[2026-11-08T10:11:23+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_DATASET_EXISTENCE_PROBE", "exists": true, "time": "2026-11-08T10:11:16.447+00:00"}
[2026-11-08T10:11:23+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "version", "-p", "/home/user/kaggle-io-loop-run/dataset", "-m", "WebVM IO loop RX message sequence 1", "-r", "skip"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T10:11:16.453+00:00", "timeout_seconds": 300}
[2026-11-08T10:11:51+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "version", "-p", "/home/user/kaggle-io-loop-run/dataset", "-m", "WebVM IO loop RX message sequence 1", "-r", "skip"], "duration_ms": 35157.56, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/2.65k [00:00<?, ?B/s]\n100%|██████████| 2.65k/2.65k [00:01<00:00, 1.38kB/s]\n100%|██████████| 2.65k/2.65k [00:03<00:00, 876B/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-08T10:11:51.611+00:00"}
[2026-11-08T10:11:54+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "465ea847d96c3911395be262ba93e10cb19b951bac55755fe80c4ec4b459611b", "time": "2026-11-08T10:11:51.629+00:00"}
[2026-11-08T10:11:54+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-08T10:11:51.639+00:00", "timeout_seconds": 90}
[2026-11-08T10:12:24+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": 32873.785, "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/2.65k [00:00<?, ?B/s]\n100%|██████████| 2.65k/2.65k [00:00<00:00, 46.7kB/s]\n\n", "time": "2026-11-08T10:12:24.514+00:00"}
[2026-11-08T10:12:29+00:00] IO_LOOP_CONTROLLER {"checksum": "465ea847d96c3911395be262ba93e10cb19b951bac55755fe80c4ec4b459611b", "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-20261108T101018402994Z", "time": "2026-11-08T10:12:24.539+00:00"}
[2026-11-08T10:12:29+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-08T10:12:24.545+00:00", "timeout_seconds": 300}
[2026-11-08T10:12:53+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 28508.465, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 16 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-08T10:12:53.054+00:00"}
[2026-11-08T10:12:55+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-08T10:12:53.079+00:00"}
[2026-11-08T10:12:55+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-08T10:12:53.095+00:00", "timeout_seconds": 60}
[2026-11-08T10:13:18+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 25336.55, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-08T10:13:18.433+00:00"}
[2026-11-08T10:13:19+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-08T10:13:18.440+00:00"}
[2026-11-08T10:13:28+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-08T10:13:28.542+00:00", "timeout_seconds": 60}
[2026-11-08T10:13:52+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 23987.24, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-08T10:13:52.530+00:00"}