WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T16:01:53+00:00
stage=PI_PROGRESS
[2026-11-08T15:56:57+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-08T15:57:08+00:00] PI_IMPL_FETCHED source=main characters=40248
[2026-11-08T15:57:09+00:00] booting.
[2026-11-08T15:57:09+00:00] booting..
[2026-11-08T15:57:11+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=45063
[2026-11-08T15:57:13+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=13623
[2026-11-08T15:57:15+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=19424
[2026-11-08T15:57:16+00:00] IO_LOOP_SOURCE_FETCHED file=remote_probe.py attempt=1/10 characters=2732
[2026-11-08T15:57:16+00:00] IO_LOOP_CONTROLLER_START dataset_id=simonday/webvm-io-loop-rx kernel_id=simonday/webvm-io-loop-proof
[2026-11-08T15:57:20+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-08T15:57:20.142+00:00", "timeout_seconds": 900}
[2026-11-08T15:57:20+00:00] IO_LOOP_CONTROLLER {"encoded_bytes": 3644, "event": "PYTHON_PAYLOAD_ENCODED", "path": "/home/user/kaggle-io-loop/remote_probe.py", "sha256": "dbf9c23bc664229dba3021bdb7c0f82dd6078441eb55b64f4f66f3b28d3bed72", "source_bytes": 2732, "time": "2026-11-08T15:57:20.262+00:00"}
[2026-11-08T15:57:20+00:00] IO_LOOP_CONTROLLER {"checksum": "99074b5761f46db05ad53640dba7f035739f428df18578c850dd0bc04b8e690c", "event": "POC_FILES_WRITTEN", "notebook_bytes": 23979, "payload": "IyEvdXNyL2Jpbi9lbnYgcHl0aG9uMwoiIiJCb3VuZGVkIG5ldHdvcmsgcHJvYmUgZXhlY3V0ZWQgdGhyb3VnaCB0aGUgV2ViVk0gLT4gS2FnZ2xlIFJYL1RYIGVudmVsb3BlLgoKRXZlcnkgRE5TL2Nvbm5lY3Rpb24gY2hlY2sgcnVucyBpbiBhIGNoaWxkIHByb2Nlc3Mgd2l0aCBhIGhhcmQgd2FsbC1jbG9jayBsaW1pdCwKc28gYSByZXNvbHZlciBzdGFsbCBjYW5ub3QgYmxvY2sgdGhlIFJYL1RYIGFja25vd2xlZGdlbWVudC4gVGhpcyBkb2VzIG5vdCBjaGFuZ2UKZmlyZXdhbGwgcnVsZXMgb3IgdG91Y2ggSnVweXRlci4KIiIiCmltcG9ydCBqc29uLCBvcywgc3VicHJvY2Vzcywgc3lzLCB0aW1lCgpQUk9CRV9DT0RFID0gciIiIgppbXBvcnQganNvbiwgc29ja2V0LCBzeXMKaG9zdCwgcG9ydCA9IHN5cy5hcmd2WzFdLCBpbnQoc3lzLmFyZ3ZbMl0pCmFkZHJlc3NlcyA9IHNvcnRlZCh7aXRlbVs0XVswXSBmb3IgaXRlbSBpbiBzb2NrZXQuZ2V0YWRkcmluZm8oCiAgICBob3N0LCBwb3J0LCB0eXBlPXNvY2tldC5TT0NLX1NUUkVBTQopfSkKd2l0aCBzb2NrZXQuY3JlYXRlX2Nvbm5lY3Rpb24oKGhvc3QsIHBvcnQpLCB0aW1lb3V0PTIpOgogICAgcGFzcwpwcmludChqc29uLmR1bXBzKHsiZG5zIjogVHJ1ZSwgImFkZHJlc3NfY291bnQiOiBsZW4oYWRkcmVzc2VzKSwgInRjcCI6IFRydWV9KSkKIiIiCgpIVFRQU19DT0RFID0gciIiIgppbXBvcnQganNvbgpmcm9tIHVybGxpYi5yZXF1ZXN0IGltcG9ydCB1cmxvcGVuLCBSZXF1ZXN0CnJlcSA9IFJlcXVlc3QoImh0dHBzOi8vZXhhbXBsZS5jb20vIiwgaGVhZGVycz17IlVzZXItQWdlbnQiOiAid2Vidm0tcngtdHgtcHJvYmUvMSJ9KQp3aXRoIHVybG9wZW4ocmVxLCB0aW1lb3V0PTMpIGFzIHJlc3BvbnNlOgogICAgcHJpbnQoanNvbi5kdW1wcyh7Im9rIjogVHJ1ZSwgInN0YXR1cyI6IHJlc3BvbnNlLnN0YXR1cywKICAgICAgICAgICAgICAgICAgICAgICJieXRlcyI6IGxlbihyZXNwb25zZS5yZWFkKDI1NikpfSkpCiIiIgoKZGVmIHJ1bl9ib3VuZGVkKGNvZGUsIGFyZ3MsIHRpbWVvdXQpOgogICAgdHJ5OgogICAgICAgIHJlc3VsdCA9IHN1YnByb2Nlc3MucnVuKAogICAgICAgICAgICBbc3lzLmV4ZWN1dGFibGUsICItSSIsICItYyIsIGNvZGUsICphcmdzXSwKICAgICAgICAgICAgY2FwdHVyZV9vdXRwdXQ9VHJ1ZSwgdGV4dD1UcnVlLCB0aW1lb3V0PXRpbWVvdXQsIGNoZWNrPUZhbHNlCiAgICAgICAgKQogICAgICAgIGlmIHJlc3VsdC5yZXR1cm5jb2RlICE9IDA6CiAgICAgICAgICAgIHJldHVybiB7Im9rIjogRmFsc2UsICJlcnJvciI6ICJQcm9iZUZhaWxlZCIsCiAgICAgICAgICAgICAgICAgICAgImRldGFpbCI6IChyZXN1bHQuc3RkZXJyIG9yICIiKVstMTgwOl19CiAgICAgICAgdHJ5OgogICAgICAgICAgICByZXR1cm4ganNvbi5sb2FkcyhyZXN1bHQuc3Rkb3V0LnN0cmlwKCkuc3BsaXRsaW5lcygpWy0xXSkKICAgICAgICBleGNlcHQgKFZhbHVlRXJyb3IsIEluZGV4RXJyb3IpOgogICAgICAgICAgICByZXR1cm4geyJvayI6IEZhbHNlLCAiZXJyb3IiOiAiSW52YWxpZFByb2JlT3V0cHV0In0KICAgIGV4Y2VwdCBzdWJwcm9jZXNzLlRpbWVvdXRFeHBpcmVkOgogICAgICAgIHJldHVybiB7Im9rIjogRmFsc2UsICJlcnJvciI6ICJQcm9iZVRpbWVvdXQifQogICAgZXhjZXB0IEV4Y2VwdGlvbiBhcyBleGM6CiAgICAgICAgcmV0dXJuIHsib2siOiBGYWxzZSwgImVycm9yIjogdHlwZShleGMpLl9fbmFtZV9fICsgIjogIiArIHN0cihleGMpWzoxNjBdfQoKZGVmIHByb2JlX2hvc3QoaG9zdCwgcG9ydD00NDMpOgogICAgcmVzdWx0ID0gcnVuX2JvdW5kZWQoUFJPQkVfQ09ERSwgW2hvc3QsIHN0cihwb3J0KV0sIDUpCiAgICByb3cgPSB7Imhvc3QiOiBob3N0LCAicG9ydCI6IHBvcnR9CiAgICBpZiByZXN1bHQuZ2V0KCJvayIpIGlzIEZhbHNlOgogICAgICAgIHJvdy51cGRhdGUoZG5zX29yX3RjcD1GYWxzZSwgZXJyb3I9cmVzdWx0LmdldCgiZXJyb3IiLCAiUHJvYmVGYWlsZWQiKSkKICAgICAgICBpZiByZXN1bHQuZ2V0KCJkZXRhaWwiKToKICAgICAgICAgICAgcm93WyJkZXRhaWwiXSA9IHJlc3VsdFsiZGV0YWlsIl0KICAgIGVsc2U6CiAgICAgICAgcm93LnVwZGF0ZShyZXN1bHQpCiAgICByZXR1cm4gcm93CgpyZXBvcnQgPSB7CiAgICAicHJvYmUiOiAid2Vidm0tcngtdHgtcmVtb3RlLXByb2JlLXYyIiwKICAgICJ0aW1lIjogdGltZS50aW1lKCksCiAgICAiaG9zdG5hbWUiOiBvcy51bmFtZSgpLm5vZGVuYW1lIGlmIGhhc2F0dHIob3MsICJ1bmFtZSIpIGVsc2UgTm9uZSwKICAgICJ1aWQiOiBvcy5nZXRldWlkKCkgaWYgaGFzYXR0cihvcywgImdldGV1aWQiKSBlbHNlIE5vbmUsCiAgICAibmV0d29yayI6IFtwcm9iZV9ob3N0KGhvc3QpIGZvciBob3N0IGluICgKICAgICAgICAicmF3LmdpdGh1YnVzZXJjb250ZW50LmNvbSIsICJhcGkua2FnZ2xlLmNvbSIsCiAgICAgICAgImtrYi1wcm9kdWN0aW9uLmp1cHl0ZXItcHJveHkua2FnZ2xlLm5ldCIsICJwc3RlLnVzIgogICAgKV0sCiAgICAianVweXRlcl84ODg4X3VudG91Y2hlZCI6IFRydWUsCiAgICAiZmlyZXdhbGxfY2hhbmdlZCI6IEZhbHNlLAp9CnJlcG9ydFsiaHR0cHNfZXhhbXBsZSJdID0gcnVuX2JvdW5kZWQoSFRUUFNfQ09ERSwgW10sIDcpCnByaW50KCJSRU1PVEVfUFJPQkVfUkVTVUxUICIgKyBqc29uLmR1bXBzKHJlcG9ydCwgc29ydF9rZXlzPVRydWUpLCBmbHVzaD1UcnVlKQo=", "request_id": "io-loop-proof-20261108T155720250929Z", "rx_bytes": 3885, "sequence": 1, "time": "2026-11-08T15:57:20.752+00:00"}
[2026-11-08T15:57:21+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-08T15:57:20.796+00:00", "timeout_seconds": 60}
[2026-11-08T15:58:19+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "duration_ms": 57718.45, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "ready", "time": "2026-11-08T15:58:18.517+00:00"}
[2026-11-08T15:58:25+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_DATASET_EXISTENCE_PROBE", "exists": true, "time": "2026-11-08T15:58:18.524+00:00"}
[2026-11-08T15:58:25+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-20261108T155720250929Z", "-r", "skip"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T15:58:18.532+00:00", "timeout_seconds": 300}
[2026-11-08T15:58:52+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-20261108T155720250929Z", "-r", "skip"], "duration_ms": 34147.661, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/3.79k [00:00<?, ?B/s]\n100%|██████████| 3.79k/3.79k [00:01<00:00, 3.83kB/s]\n100%|██████████| 3.79k/3.79k [00:02<00:00, 1.85kB/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-08T15:58:52.681+00:00"}
[2026-11-08T15:58:56+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "99074b5761f46db05ad53640dba7f035739f428df18578c850dd0bc04b8e690c", "time": "2026-11-08T15:58:52.697+00:00"}
[2026-11-08T15:58:56+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-08T15:58:52.708+00:00", "timeout_seconds": 90}
[2026-11-08T15:59:23+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": 30886.326, "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.79k [00:00<?, ?B/s]\n100%|██████████| 3.79k/3.79k [00:00<00:00, 62.8kB/s]\n\n", "time": "2026-11-08T15:59:23.595+00:00"}
[2026-11-08T15:59:28+00:00] IO_LOOP_CONTROLLER {"checksum": "99074b5761f46db05ad53640dba7f035739f428df18578c850dd0bc04b8e690c", "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-20261108T155720250929Z", "time": "2026-11-08T15:59:23.618+00:00"}
[2026-11-08T15:59:28+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-08T15:59:23.624+00:00", "timeout_seconds": 300}
[2026-11-08T15:59:49+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 25676.364, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 21 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-08T15:59:49.304+00:00"}
[2026-11-08T15:59:51+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-08T15:59:49.325+00:00"}
[2026-11-08T15:59:51+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-08T15:59:49.418+00:00", "timeout_seconds": 60}
[2026-11-08T16:00:14+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 24783.891, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-08T16:00:14.203+00:00"}
[2026-11-08T16:00:15+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-08T16:00:14.216+00:00"}
[2026-11-08T16:00:24+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-08T16:00:24.243+00:00", "timeout_seconds": 60}
[2026-11-08T16:00:48+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 24036.485, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-08T16:00:48.280+00:00"}
[2026-11-08T16:00:50+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-08T16:00:48.286+00:00"}
[2026-11-08T16:00: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-08T16:00:58.355+00:00", "timeout_seconds": 60}
[2026-11-08T16:01:21+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 23555.3, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.COMPLETE\"\n", "time": "2026-11-08T16:01:21.914+00:00"}
[2026-11-08T16:01:24+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-08T16:01:21.933+00:00"}
[2026-11-08T16:01:26+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-io-loop-proof", "-p", "/home/user/kaggle-io-loop-run/output", "-o", "--file-pattern", "^tx_response\\.json$"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:01:22.089+00:00", "timeout_seconds": 300}
[2026-11-08T16:01:49+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-io-loop-proof", "-p", "/home/user/kaggle-io-loop-run/output", "-o", "--file-pattern", "^tx_response\\.json$"], "duration_ms": 26961.165, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Downloading tx_response.json to /home/user/kaggle-io-loop-run/output\n\n  0%|          | 0.00/1.96k [00:00<?, ?B/s]\n100%|██████████| 1.96k/1.96k [00:00<00:00, 26.5kB/s]\n\nOutput file downloaded to /home/user/kaggle-io-loop-run/output/tx_response.json\nKernel log downloaded to /home/user/kaggle-io-loop-run/output/webvm-io-loop-proof.log \n", "time": "2026-11-08T16:01:49.045+00:00"}
[2026-11-08T16:01:53+00:00] IO_LOOP_CONTROLLER {"checks": {"ack_state": true, "execution_not_timed_out": true, "execution_succeeded": true, "payload": true, "payload_length": true, "received_checksum": true, "request_id": true, "sequence": true, "version": true}, "event": "OUTPUT_VALIDATION", "response": {"ack_state": "processed_once", "agent_elapsed_ms": 41827.452, "execution": {"elapsed_ms": 27159.17, "exit_code": 0, "output": "REMOTE_PROBE_RESULT {\"firewall_changed\": false, \"hostname\": \"f57a065da2ed\", \"https_example\": {\"error\": \"ProbeTimeout\", \"ok\": false}, \"jupyter_8888_untouched\": true, \"network\": [{\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"raw.githubusercontent.com\", \"port\": 443}, {\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"api.kaggle.com\", \"port\": 443}, {\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"kkb-production.jupyter-proxy.kaggle.net\", \"port\": 443}, {\"dns_or_tcp\": false, \"error\": \"ProbeTimeout\", \"host\": \"pste.us\", \"port\": 443}], \"probe\": \"webvm-rx-tx-remote-probe-v2\", \"time\": 1794153607.158412, \"uid\": 0}\n", "sha256": "dbf9c23bc664229dba3021bdb7c0f82dd6078441eb55b64f4f66f3b28d3bed72", "timed_out": false}, "network_diagnostics": {"dev_net_tun": false, "iptables_available": false, "ipv4_forwarding": "1", "jupyter_proxy_kernel_id": null, "jupyter_proxy_route_found": false, "jupyter_proxy_token_present": false, "outbound_tcp_443": {"kkb-production.jupyter-proxy.kaggle.net": {"dns_or_tcp": false, "error": "ProbeTimeout"}, "pste.us": {"dns_or_tcp": false, "error": "ProbeTimeout"}}, "port_8889_listening": false, "tailscale_available": false, "tailscaled_available": false, "uid": 0}, "payload": "EXEC:dbf9c23bc664229dba3021bdb7c0f82dd6078441eb55b64f4f66f3b28d3bed72:0", "payload_length": 71, "received_at": "2026-11-08T16:00:46.234+00:00", "received_checksum": "99074b5761f46db05ad53640dba7f035739f428df18578c850dd0bc04b8e690c", "request_id": "io-loop-proof-20261108T155720250929Z", "sequence": 1, "type": "ack", "version": 1}, "time": "2026-11-08T16:01:49.079+00:00"}