WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-11T08:02:06+00:00
stage=PI_PROGRESS
[2026-11-11T07:57:44+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-11T07:57:57+00:00] PI_IMPL_FETCHED source=main characters=59276
[2026-11-11T07:57:57+00:00] booting.
[2026-11-11T07:57:58+00:00] booting..
[2026-11-11T07:57:58+00:00] GPU_DIAGNOSTIC_DEFERRED reason=prioritize_RX_TX_and_live_TCP_proof
[2026-11-11T07:58:00+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=58931
[2026-11-11T07:58:01+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=22004
[2026-11-11T07:58:02+00:00] TCP_LINK_MARKER_HANDLER_PATCHED source=tcp_socks5.py mode=runtime-injection
[2026-11-11T07:58:02+00:00] IO_LOOP_SOURCE_FETCHED file=tcp_socks5.py attempt=1/10 characters=21980
[2026-11-11T07:58:03+00:00] IO_LOOP_SOURCE_FETCHED file=remote_probe.py attempt=1/10 characters=2130
[2026-11-11T07:58:04+00:00] IO_LOOP_SOURCE_FETCHED file=terminal_service.py attempt=1/10 characters=6373
[2026-11-11T07:58:05+00:00] IO_LOOP_SOURCE_FETCHED file=remote_shell.py attempt=1/10 characters=3115
[2026-11-11T07:58:05+00:00] IO_LOOP_CONTROLLER_START dataset_id=simonday/webvm-io-loop-rx kernel_id=simonday/webvm-io-loop-proof
[2026-11-11T07:58:08+00:00] RX_TX_INITIAL_PROOF_BACKGROUND_STARTED command_channel=available
[2026-11-11T07:58:08+00:00] RX_TX_TERMINAL_LISTENING host=127.0.0.1 port=2323 transport=Kaggle-RX-TX
[2026-11-11T07:58:08+00:00] WEBVM_TERMINAL_SERVER_DIAGNOSTIC_FAIL error=OSError: [Errno 22] Invalid argument
[2026-11-11T07:58:08+00:00] WEBVM_TERMINAL_SELFTEST_SKIPPED reason=loopback_socket_test_not_required
[2026-11-11T07:58:08+00:00] RX_TX_TOKENIZED_PROXY_MARKER_TEST_SCHEDULED_AFTER_ROUTE_DISCOVERY no_tailscale=true
[2026-11-11T07:58:08+00:00] RX_TX_REMOTE_COMMAND_POLL_STARTED interval_seconds=30 current_sha256=9d17e02958c1184e38af1c8b04aa4bda89b5b58b3008ddc4a3d1ab02dae193fe
[2026-11-11T07:58:10+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-11T07:58:10.600+00:00", "timeout_seconds": 900}
[2026-11-11T07:58:10+00:00] IO_LOOP_CONTROLLER {"encoded_bytes": 2840, "event": "PYTHON_PAYLOAD_ENCODED", "path": "/home/user/kaggle-io-loop/remote_probe.py", "sha256": "9d17e02958c1184e38af1c8b04aa4bda89b5b58b3008ddc4a3d1ab02dae193fe", "source_bytes": 2130, "time": "2026-11-11T07:58:10.732+00:00"}
[2026-11-11T07:58:11+00:00] IO_LOOP_CONTROLLER {"checksum": "61d3967a5c776e81c447c551de6894a82790caecb09d89eaf398be91f51fa2c8", "event": "POC_FILES_WRITTEN", "notebook_bytes": 34823, "payload": "IyEvdXNyL2Jpbi9lbnYgcHl0aG9uMwoiIiJEaXNjb3ZlciB0aGUgbGl2ZSBLYWdnbGUgSnVweXRlciBwcm94eSByb3V0ZSBmb3IgdGhlIFdlYlZNIHRva2VuaXplZCBtYXJrZXIgc2VuZC4KClRoZSB0b2tlbiBpcyB3cml0dGVuIG9ubHkgdG8gL2thZ2dsZS93b3JraW5nL3dlYnZtX3Byb3h5X3JvdXRlLmpzb24sIHdoaWNoIGlzCnJldHVybmVkIGFzIHBhcnQgb2YgdHhfcmVzcG9uc2UuanNvbiB0byB0aGUgV2ViVk0gY29udHJvbGxlci4gSXQgaXMgbmV2ZXIKcHJpbnRlZCBpbnRvIG5vdGVib29rIG91dHB1dCBvciBkaWFnbm9zdGljIGxvZ3MuCiIiIgppbXBvcnQgZ2xvYgppbXBvcnQganNvbgppbXBvcnQgcmUKZnJvbSBwYXRobGliIGltcG9ydCBQYXRoCgpIT1NUID0gImtrYi1wcm9kdWN0aW9uLmp1cHl0ZXItcHJveHkua2FnZ2xlLm5ldCIKUkVTVUxUID0gUGF0aCgiL2thZ2dsZS93b3JraW5nL3dlYnZtX3Byb3h5X3JvdXRlLmpzb24iKQoKZGVmIGZpbmRfYmFzZV91cmwoKToKICAgIHBpZHMgPSBbMTJdICsgWwogICAgICAgIGludChpdGVtLnNwbGl0KCIvIilbMl0pIGZvciBpdGVtIGluIGdsb2IuZ2xvYigiL3Byb2MvWzAtOV0qL2NtZGxpbmUiKQogICAgICAgIGlmIGl0ZW0uc3BsaXQoIi8iKVsyXS5pc2RpZ2l0KCkgYW5kIGludChpdGVtLnNwbGl0KCIvIilbMl0pICE9IDEyCiAgICBdCiAgICBmb3IgcGlkIGluIHBpZHM6CiAgICAgICAgdHJ5OgogICAgICAgICAgICBhcmdzID0gWwogICAgICAgICAgICAgICAgdmFsdWUuZGVjb2RlKCJ1dGYtOCIsICJyZXBsYWNlIikKICAgICAgICAgICAgICAgIGZvciB2YWx1ZSBpbiBQYXRoKCIvcHJvYy97fS9jbWRsaW5lIi5mb3JtYXQocGlkKSkucmVhZF9ieXRlcygpLnNwbGl0KGIiXDAiKQogICAgICAgICAgICAgICAgaWYgdmFsdWUKICAgICAgICAgICAgXQogICAgICAgIGV4Y2VwdCBPU0Vycm9yOgogICAgICAgICAgICBjb250aW51ZQogICAgICAgIGlmIG5vdCBhbnkoImp1cHl0ZXIiIGluIHZhbHVlLmxvd2VyKCkgb3IgIm5vdGVib29rIiBpbiB2YWx1ZS5sb3dlcigpIGZvciB2YWx1ZSBpbiBhcmdzKToKICAgICAgICAgICAgY29udGludWUKICAgICAgICBmb3IgaW5kZXgsIHZhbHVlIGluIGVudW1lcmF0ZShhcmdzKToKICAgICAgICAgICAgaWYgdmFsdWUuc3RhcnRzd2l0aCgoIi0tTm90ZWJvb2tBcHAuYmFzZV91cmw9IiwgIi0tU2VydmVyQXBwLmJhc2VfdXJsPSIpKToKICAgICAgICAgICAgICAgIHJldHVybiB2YWx1ZS5zcGxpdCgiPSIsIDEpWzFdCiAgICAgICAgICAgIGlmIHZhbHVlIGluICgiLS1Ob3RlYm9va0FwcC5iYXNlX3VybCIsICItLVNlcnZlckFwcC5iYXNlX3VybCIpIGFuZCBpbmRleCArIDEgPCBsZW4oYXJncyk6CiAgICAgICAgICAgICAgICByZXR1cm4gYXJnc1tpbmRleCArIDFdCiAgICByZXR1cm4gTm9uZQoKYmFzZV91cmwgPSBmaW5kX2Jhc2VfdXJsKCkKbWF0Y2ggPSByZS5mdWxsbWF0Y2gociIvay8oWzAtOV0rKS8oW0EtWmEtejAtOS5ffi1dKykvcHJveHkvPyIsIHN0cihiYXNlX3VybCBvciAiIikpCmlmIG5vdCBtYXRjaDoKICAgIHRyeToKICAgICAgICBSRVNVTFQudW5saW5rKCkKICAgIGV4Y2VwdCBGaWxlTm90Rm91bmRFcnJvcjoKICAgICAgICBwYXNzCiAgICBwcmludCgiV0VCVk1fUFJPWFlfUk9VVEVfRElTQ09WRVJZIGZvdW5kPWZhbHNlIGJhc2VfdXJsX3ByZXNlbnQ9e30iLmZvcm1hdChib29sKGJhc2VfdXJsKSksIGZsdXNoPVRydWUpCmVsc2U6CiAgICByb3V0ZSA9IHsKICAgICAgICAiaG9zdCI6IEhPU1QsCiAgICAgICAgInBhdGgiOiBiYXNlX3VybCwKICAgICAgICAia2VybmVsX2lkIjogbWF0Y2guZ3JvdXAoMSksCiAgICAgICAgInRva2VuIjogbWF0Y2guZ3JvdXAoMiksCiAgICB9CiAgICBSRVNVTFQucGFyZW50Lm1rZGlyKHBhcmVudHM9VHJ1ZSwgZXhpc3Rfb2s9VHJ1ZSkKICAgIFJFU1VMVC53cml0ZV90ZXh0KGpzb24uZHVtcHMocm91dGUsIHNvcnRfa2V5cz1UcnVlKSArICJcbiIsIGVuY29kaW5nPSJ1dGYtOCIpCiAgICBwcmludCgiV0VCVk1fUFJPWFlfUk9VVEVfRElTQ09WRVJZIGZvdW5kPXRydWUga2VybmVsX2lkPXt9IHRva2VuX3ByZXNlbnQ9dHJ1ZSIuZm9ybWF0KAogICAgICAgIG1hdGNoLmdyb3VwKDEpKSwgZmx1c2g9VHJ1ZSkK", "request_id": "io-loop-proof-20261111T075810722383Z", "rx_bytes": 3081, "sequence": 1, "time": "2026-11-11T07:58:11.241+00:00"}
[2026-11-11T07:58:11+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-20261111T075810722383Z", "-r", "skip"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T07:58:11.245+00:00", "timeout_seconds": 300}
[2026-11-11T07:59:15+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-20261111T075810722383Z", "-r", "skip"], "duration_ms": 63731.326, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/3.01k [00:00<?, ?B/s]\n100%|██████████| 3.01k/3.01k [00:01<00:00, 2.63kB/s]\n100%|██████████| 3.01k/3.01k [00:02<00:00, 1.52kB/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-11T07:59:14.983+00:00"}
[2026-11-11T07:59:21+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "61d3967a5c776e81c447c551de6894a82790caecb09d89eaf398be91f51fa2c8", "time": "2026-11-11T07:59:15.049+00:00"}
[2026-11-11T07:59:21+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-11T07:59:15.070+00:00", "timeout_seconds": 90}
[2026-11-11T07:59:45+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": 30749.389, "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.01k [00:00<?, ?B/s]\n100%|██████████| 3.01k/3.01k [00:00<00:00, 34.1kB/s]\n\n", "time": "2026-11-11T07:59:45.821+00:00"}
[2026-11-11T07:59:48+00:00] IO_LOOP_CONTROLLER {"checksum": "61d3967a5c776e81c447c551de6894a82790caecb09d89eaf398be91f51fa2c8", "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-20261111T075810722383Z", "time": "2026-11-11T07:59:45.837+00:00"}
[2026-11-11T07:59:48+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-11T07:59:45.844+00:00", "timeout_seconds": 300}
[2026-11-11T08:00:09+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 23987.549, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 40 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-11T08:00:09.832+00:00"}
[2026-11-11T08:00:12+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-11T08:00:09.848+00:00"}
[2026-11-11T08:00:12+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:00:10.009+00:00", "timeout_seconds": 60}
[2026-11-11T08:00:37+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 27382.201, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-11T08:00:37.392+00:00"}
[2026-11-11T08:00:42+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:00:37.402+00:00"}
[2026-11-11T08:00:47+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:00:47.479+00:00", "timeout_seconds": 60}
[2026-11-11T08:01:10+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 23248.866, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.COMPLETE\"\n", "time": "2026-11-11T08:01:10.728+00:00"}
[2026-11-11T08:01: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.COMPLETE\"", "time": "2026-11-11T08:01:10.746+00:00"}
[2026-11-11T08:01:15+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-11T08:01:10.759+00:00", "timeout_seconds": 300}
[2026-11-11T08:01:37+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": 26515.16, "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.93k [00:00<?, ?B/s]\n100%|██████████| 1.93k/1.93k [00:00<00:00, 102kB/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-11T08:01:37.276+00:00"}
[2026-11-11T08:01:39+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": 14053.296, "execution": {"elapsed_ms": 92.69, "exit_code": 0, "output": "WEBVM_PROXY_ROUTE_DISCOVERY found=false base_url_present=False\n", "sha256": "9d17e02958c1184e38af1c8b04aa4bda89b5b58b3008ddc4a3d1ab02dae193fe", "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"}}, "packet_capture": {"available": false, "capture_window_seconds": 12.027, "error_type": "TypeError", "filter": "IPv4 TCP/443 and UDP/53", "metadata_only": true, "method": "AF_PACKET", "payload_capture": false, "samples": [], "tcp_443_fin": 0, "tcp_443_inbound": 0, "tcp_443_outbound": 0, "tcp_443_packets": 0, "tcp_443_rst": 0, "tcp_443_syn": 0, "tcp_443_syn_ack": 0, "tcp_443_syn_retransmit_hints": 0, "udp_53_inbound": 0, "udp_53_outbound": 0, "udp_53_packets": 0}, "port_8889_listening": false, "tailscale_available": false, "tailscaled_available": false, "uid": 0}, "payload": "EXEC:9d17e02958c1184e38af1c8b04aa4bda89b5b58b3008ddc4a3d1ab02dae193fe:0", "payload_length": 71, "proxy_route": null, "received_at": "2026-11-11T08:00:36.621+00:00", "received_checksum": "61d3967a5c776e81c447c551de6894a82790caecb09d89eaf398be91f51fa2c8", "request_id": "io-loop-proof-20261111T075810722383Z", "sequence": 1, "type": "ack", "version": 1}, "time": "2026-11-11T08:01:37.302+00:00"}
[2026-11-11T08:01:42+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/notebook_diagnostics", "-o", "--file-pattern", "^io_loop_proof\\.ipynb$"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-11T08:01:37.318+00:00", "timeout_seconds": 180}
[2026-11-11T08:02:02+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/notebook_diagnostics", "-o", "--file-pattern", "^io_loop_proof\\.ipynb$"], "duration_ms": 25299.73, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel log downloaded to /home/user/kaggle-io-loop-run/output/notebook_diagnostics/webvm-io-loop-proof.log \n", "time": "2026-11-11T08:02:02.618+00:00"}
[2026-11-11T08:02:05+00:00] IO_LOOP_CONTROLLER {"event": "NOTEBOOK_DIAGNOSTIC_DOWNLOAD", "exit_code": 0, "files": [], "output_tail": "Kernel log downloaded to /home/user/kaggle-io-loop-run/output/notebook_diagnostics/webvm-io-loop-proof.log \n", "time": "2026-11-11T08:02:02.659+00:00"}
[2026-11-11T08:02:05+00:00] IO_LOOP_CONTROLLER {"event": "PROXY_DIAGNOSTIC_RESULTS", "found": false, "line_count": 0, "lines": [], "time": "2026-11-11T08:02:02.662+00:00"}
[2026-11-11T08:02:05+00:00] IO_LOOP_CONTROLLER {"event": "LOOP_PROOF_PASS", "output_bytes": 1974, "output_file": "/home/user/kaggle-io-loop-run/output/tx_response.json", "payload_bytes": 2840, "request_id": "io-loop-proof-20261111T075810722383Z", "round_trip_ms": 232063.674, "rx_checksum": "61d3967a5c776e81c447c551de6894a82790caecb09d89eaf398be91f51fa2c8", "sequence": 1, "time": "2026-11-11T08:02:02.665+00:00", "tx_checksum": "5aad93cff3e0714c0bfeb5a9e91fd6c710a40dbcfaf1c012c550538d43126137"}
[2026-11-11T08:02:06+00:00] IO_LOOP_CONTROLLER_SUCCESS_MARKER observed=LOOP_PROOF_PASS