WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T16:30:41+00:00
stage=PI_HEARTBEAT
ZGVmIG5ldGZpbHRlcl9kaWFnbm9zdGljcygpOgogICAgY29tbWFuZHMgPSB7fQogICAgZm9yIG5hbWUgaW4gKCJpcHRhYmxlcyIsICJpcHRhYmxlcy1uZnQiLCAiaXB0YWJsZXMtbGVnYWN5IiwgIm5mdCIsICJhcHQtZ2V0IiwgImRwa2ciLCAiYnVzeWJveCIpOgogICAgICAgIGNvbW1hbmRzW25hbWVdID0gc2h1dGlsLndoaWNoKG5hbWUpCiAgICBmb3IgcGF0aCBpbiAoIi91c3Ivc2Jpbi9pcHRhYmxlcyIsICIvc2Jpbi9pcHRhYmxlcyIsICIvdXNyL2Jpbi9pcHRhYmxlcyIsCiAgICAgICAgICAgICAgICAgIi91c3Ivc2Jpbi9pcHRhYmxlcy1uZnQiLCAiL3Vzci9zYmluL2lwdGFibGVzLWxlZ2FjeSIsCiAgICAgICAgICAgICAgICAgIi91c3Ivc2Jpbi9uZnQiLCAiL3NiaW4vbmZ0Iik6CiAgICAgICAgaWYgb3MucGF0aC5leGlzdHMocGF0aCk6CiAgICAgICAgICAgIGNvbW1hbmRzWyJleGlzdHM6IiArIHBhdGhdID0gVHJ1ZQogICAgcHJvY19uYW1lcyA9IHJlYWRfc21hbGwoIi9wcm9jL25ldC9pcF90YWJsZXNfbmFtZXMiLCAxMDAwKQogICAgbW9kdWxlX25hbWVzID0gcmVhZF9zbWFsbCgiL3Byb2MvbW9kdWxlcyIsIDgwMDApCiAgICBtb2R1bGVfbWF0Y2hlcyA9IFtdCiAgICBpZiBpc2luc3RhbmNlKG1vZHVsZV9uYW1lcywgc3RyKToKICAgICAgICBtb2R1bGVfbWF0Y2hlcyA9IFtsaW5lLnNwbGl0KClbMF0gZm9yIGxpbmUgaW4gbW9kdWxlX25hbWVzLnNwbGl0bGluZXMoKQogICAgICAgICAgICAgICAgICAgICAgICAgIGlmIGFueShrIGluIGxpbmUubG93ZXIoKSBmb3IgayBpbgogICAgICAgICAgICAgICAgICAgICAgICAgICAgICAgICAoImlwX3RhYmxlcyIsICJpcHRhYmxlX25hdCIsICJuZl9uYXQiLCAibmZfdGFibGVzIiwgIm5mdF9jaGFpbiIpKV0KICAgIGFwdF9jYWNoZSA9IGJvdW5kZWRfY29tbWFuZChbImFwdC1jYWNoZSIsICJwb2xpY3kiLCAiaXB0YWJsZXMiXSwgMykgaWYgY29tbWFuZHMuZ2V0KCJhcHQtZ2V0IikgZWxzZSB7Im5vdGUiOiAiYXB0LWdldCBub3QgZm91bmQifQogICAgZHBrZ19hcmNoID0gYm91bmRlZF9jb21tYW5kKFsiZHBrZyIsICItLXByaW50LWFyY2hpdGVjdHVyZSJdLCAzKSBpZiBjb21tYW5kcy5nZXQoImRwa2ciKSBlbHNlIHsibm90ZSI6ICJkcGtnIG5vdCBmb3VuZCJ9CiAgICB1bmFtZSA9IGJvdW5kZWRfY29tbWFuZChbInVuYW1lIiwgIi1hIl0sIDMpCiAgICByZXR1cm4gewogICAgICAgICJhcmNoaXRlY3R1cmUiOiBwbGF0Zm9ybS5tYWNoaW5lKCksCiAgICAgICAgImtlcm5lbCI6IHVuYW1lLAogICAgICAgICJjb21tYW5kcyI6IGNvbW1hbmRzLAogICAgICAgICJkcGtnX2FyY2hpdGVjdHVyZSI6IGRwa2dfYXJjaCwKICAgICAgICAiYXB0X3BvbGljeV9pcHRhYmxlcyI6IGFwdF9jYWNoZSwKICAgICAgICAicHJvY19pcF90YWJsZXNfbmFtZXMiOiBwcm9jX25hbWVzLAogICAgICAgICJuZXRmaWx0ZXJfbW9kdWxlc19sb2FkZWQiOiBtb2R1bGVfbWF0Y2hlcywKICAgICAgICAiY29uZmlnX25ldGZpbHRlciI6IHsKICAgICAgICAgICAgInByb2NfY29uZmlnX2d6IjogInByZXNlbnQiIGlmIG9zLnBhdGguZXhpc3RzKCIvcHJvYy9jb25maWcuZ3oiKSBlbHNlICJhYnNlbnQiLAogICAgICAgICAgICAiYm9vdF9jb25maWciOiAicHJlc2VudCIgaWYgb3MucGF0aC5leGlzdHMoIi9ib290L2NvbmZpZy0iICsgb3MudW5hbWUoKS5yZWxlYXNlKSBlbHNlICJhYnNlbnQiLAogICAgICAgIH0sCiAgICB9CgpyZXBvcnQgPSB7CiAgICAicHJvYmUiOiAid2Vidm0tcngtdHgtcmVtb3RlLXByb2JlLXYzIiwKICAgICJ0aW1lIjogdGltZS50aW1lKCksCiAgICAiaG9zdG5hbWUiOiBvcy51bmFtZSgpLm5vZGVuYW1lIGlmIGhhc2F0dHIob3MsICJ1bmFtZSIpIGVsc2UgTm9uZSwKICAgICJ1aWQiOiBvcy5nZXRldWlkKCkgaWYgaGFzYXR0cihvcywgImdldGV1aWQiKSBlbHNlIE5vbmUsCiAgICAibmV0d29yayI6IFtwcm9iZV9ob3N0KGhvc3QpIGZvciBob3N0IGluICgKICAgICAgICAicmF3LmdpdGh1YnVzZXJjb250ZW50LmNvbSIsICJhcGkua2FnZ2xlLmNvbSIsCiAgICAgICAgImtrYi1wcm9kdWN0aW9uLmp1cHl0ZXItcHJveHkua2FnZ2xlLm5ldCIsICJwc3RlLnVzIgogICAgKV0sCiAgICAianVweXRlcl84ODg4X3VudG91Y2hlZCI6IFRydWUsCiAgICAiZmlyZXdhbGxfY2hhbmdlZCI6IEZhbHNlLAp9CnJlcG9ydFsiaHR0cHNfZXhhbXBsZSJdID0gcnVuX2JvdW5kZWQoSFRUUFNfQ09ERSwgW10sIDcpCnJlcG9ydFsibmV0ZmlsdGVyX2RpYWdub3N0aWNzIl0gPSBuZXRmaWx0ZXJfZGlhZ25vc3RpY3MoKQpwcmludCgiUkVNT1RFX1BST0JFX1JFU1VMVCAiICsganNvbi5kdW1wcyhyZXBvcnQsIHNvcnRfa2V5cz1UcnVlKSwgZmx1c2g9VHJ1ZSkK", "request_id": "io-loop-proof-20261108T162119342938Z", "rx_bytes": 7097, "sequence": 1, "time": "2026-11-08T16:21:19.876+00:00"}
[2026-11-08T16:21: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-08T16:21:19.910+00:00", "timeout_seconds": 60}
[2026-11-08T16:22:15+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "duration_ms": 55317.174, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "ready", "time": "2026-11-08T16:22:15.229+00:00"}
[2026-11-08T16:22:20+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_DATASET_EXISTENCE_PROBE", "exists": true, "time": "2026-11-08T16:22:15.238+00:00"}
[2026-11-08T16:22: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-20261108T162119342938Z", "-r", "skip"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:22:15.247+00:00", "timeout_seconds": 300}
[2026-11-08T16:22:50+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-20261108T162119342938Z", "-r", "skip"], "duration_ms": 35352.254, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/6.93k [00:00<?, ?B/s]\n100%|██████████| 6.93k/6.93k [00:01<00:00, 5.63kB/s]\n100%|██████████| 6.93k/6.93k [00:02<00:00, 2.88kB/s]\nUpload successful: rx_message.json (7KB)\nDataset version is being created. Please check progress at https://www.kaggle.com/datasets/simonday/webvm-io-loop-rx\n", "time": "2026-11-08T16:22:50.600+00:00"}
[2026-11-08T16:22:53+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "3cf2ba76aa517cf3a7fe3984c2c08909904dc59c0d6919525a344ab82e66171a", "time": "2026-11-08T16:22:50.618+00:00"}
[2026-11-08T16:22: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-08T16:22:50.628+00:00", "timeout_seconds": 90}
[2026-11-08T16:23: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"], "duration_ms": 30759.674, "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/6.93k [00:00<?, ?B/s]\n100%|██████████| 6.93k/6.93k [00:00<00:00, 109kB/s]\n\n", "time": "2026-11-08T16:23:21.388+00:00"}
[2026-11-08T16:23:27+00:00] IO_LOOP_CONTROLLER {"checksum": "3cf2ba76aa517cf3a7fe3984c2c08909904dc59c0d6919525a344ab82e66171a", "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-20261108T162119342938Z", "time": "2026-11-08T16:23:21.407+00:00"}
[2026-11-08T16:23:27+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-08T16:23:21.411+00:00", "timeout_seconds": 300}
[2026-11-08T16:23:48+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 27212.766, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 22 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-08T16:23:48.625+00:00"}
[2026-11-08T16:23:51+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-08T16:23:48.639+00:00"}
[2026-11-08T16:23: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-08T16:23:48.652+00:00", "timeout_seconds": 60}
[2026-11-08T16:24:11+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 23220.75, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-08T16:24:11.873+00:00"}
[2026-11-08T16:24: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-08T16:24:11.886+00:00"}
[2026-11-08T16:24: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-08T16:24:21.915+00:00", "timeout_seconds": 60}
[2026-11-08T16:24:44+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 22465.52, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-08T16:24:44.381+00:00"}
[2026-11-08T16:24:46+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:24:44.386+00:00"}
[2026-11-08T16:24:54+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:24:54.456+00:00", "timeout_seconds": 60}
[2026-11-08T16:25:20+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 25829.509, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.COMPLETE\"\n", "time": "2026-11-08T16:25:20.290+00:00"}
[2026-11-08T16:25: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.COMPLETE\"", "time": "2026-11-08T16:25:20.311+00:00"}
[2026-11-08T16:25:25+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:25:20.545+00:00", "timeout_seconds": 300}
[2026-11-08T16:25:46+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": 25483.6, "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/2.90k [00:00<?, ?B/s]\n100%|██████████| 2.90k/2.90k [00:00<00:00, 39.3kB/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:25:45.967+00:00"}
[2026-11-08T16:25:50+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": 44137.415, "execution": {"elapsed_ms": 29748.89, "exit_code": 0, "output": "REMOTE_PROBE_RESULT {\"firewall_changed\": false, \"hostname\": \"1dcbda6eac47\", \"https_example\": {\"error\": \"ProbeTimeout\", \"ok\": false}, \"jupyter_8888_untouched\": true, \"netfilter_diagnostics\": {\"apt_policy_iptables\": {\"returncode\": 0, \"stderr\": \"\", \"stdout\": \"iptables:\\n  Installed: (none)\\n  Candidate: 1.8.10-3ubuntu2\\n  Version table:\\n     1.8.10-3ubuntu2 500\\n        500 http://archive.ubuntu.com/ubuntu noble/main amd64 Packages\\n\"}, \"architecture\": \"x86_64\", \"commands\": {\"apt-get\": \"/usr/bin/apt-get\", \"busybox\": null, \"dpkg\": \"/usr/bin/dpkg\", \"iptables\": null, \"iptables-legacy\": null, \"iptables-nft\": null, \"nft\": null}, \"config_netfilter\": {\"boot_config\": \"absent\", \"proc_config_gz\": \"present\"}, \"dpkg_architecture\": {\"returncode\": 0, \"stderr\": \"\", \"stdout\": \"amd64\\n\"}, \"kernel\": {\"returncode\": 0, \"stderr\": \"\", \"stdout\": \"Linux 1dcbda6eac47 6.18.55+ #1 SMP Thu Oct 22 16:37:21 UTC 2026 x86_64 x86_64 x86_64 GNU/Linux\\n\"}, \"netfilter_modules_loaded\": [\"nft_chain_nat\", \"nf_nat\", \"nf_tables\"], \"proc_ip_tables_names\": \"\"}, \"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-v3\", \"time\": 1794155046.0357993, \"uid\": 0}\n", "sha256": "623504b7c22661357f43a1f04cba452b0062aec4fe446b1f4a9524f63738ba16", "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:623504b7c22661357f43a1f04cba452b0062aec4fe446b1f4a9524f63738ba16:0", "payload_length": 71, "received_at": "2026-11-08T16:24:47.695+00:00", "received_checksum": "3cf2ba76aa517cf3a7fe3984c2c08909904dc59c0d6919525a344ab82e66171a", "request_id": "io-loop-proof-20261108T162119342938Z", "sequence": 1, "type": "ack", "version": 1}, "time": "2026-11-08T16:25:45.999+00:00"}
[2026-11-08T16:25:53+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-08T16:25:46.211+00:00", "timeout_seconds": 180}
[2026-11-08T16:26:11+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": 25038.125, "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-08T16:26:11.250+00:00"}
[2026-11-08T16:26:14+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-08T16:26:11.340+00:00"}
[2026-11-08T16:26:14+00:00] IO_LOOP_CONTROLLER {"event": "PROXY_DIAGNOSTIC_RESULTS", "found": false, "line_count": 0, "lines": [], "time": "2026-11-08T16:26:11.341+00:00"}
[2026-11-08T16:26:14+00:00] IO_LOOP_CONTROLLER {"event": "LOOP_PROOF_PASS", "output_bytes": 2967, "output_file": "/home/user/kaggle-io-loop-run/output/tx_response.json", "payload_bytes": 6856, "request_id": "io-loop-proof-20261108T162119342938Z", "round_trip_ms": 292166.119, "rx_checksum": "3cf2ba76aa517cf3a7fe3984c2c08909904dc59c0d6919525a344ab82e66171a", "sequence": 1, "time": "2026-11-08T16:26:11.344+00:00", "tx_checksum": "0422049604da1f1bb6419ccc450aa7f032e44a9440eca9249aad49b69a6bf9aa"}
[2026-11-08T16:26:17+00:00] LOOP_PROOF_PASS output=/home/user/kaggle-io-loop-run/output/tx_response.json log=/home/user/kaggle-io-loop-run/run.jsonl
[2026-11-08T16:26:19+00:00] IO_LOOP_CONTROLLER_EXIT returncode=0
[2026-11-08T16:26:26+00:00] RX_TX_CURRENT_DEPLOYMENT_PROOF_PASS transport=kaggle-api-dataset-and-kernel-output
[2026-11-08T16:26:26+00:00] TCP_LINK_CONTROLLER_START kernel_id=simonday/webvm-tcp-link
[2026-11-08T16:26:26+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RUN_BEGIN", "kernel_id": "simonday/webvm-tcp-link", "poll_seconds": 10, "time": "2026-11-08T16:26:14.482+00:00", "timeout_seconds": 900}
[2026-11-08T16:26:26+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:26:14.493+00:00", "timeout_seconds": 20}
[2026-11-08T16:26:36+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 21899.225, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:26:36.404+00:00"}
[2026-11-08T16:26:36+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T16:26:36.409+00:00", "timeout_seconds": 20}
[2026-11-08T16:26:58+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 22158.18, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:26:58.569+00:00"}
[2026-11-08T16:27:01+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 44085.906, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T16:26:58.577+00:00"}
[2026-11-08T16:27:01+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_PREDEPLOY_STATUS_UNKNOWN", "kernel_id": "simonday/webvm-tcp-link", "note": "Continuing with the explicit deployment request", "time": "2026-11-08T16:26:58.581+00:00"}
[2026-11-08T16:27:01+00:00] IO_LOOP_CONTROLLER {"event": "POC_FILES_WRITTEN", "notebook_bytes": 7290, "service": "plain-python-tcp-echo-listener", "time": "2026-11-08T16:26:59.204+00:00"}
[2026-11-08T16:27:01+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:26:59.211+00:00", "timeout_seconds": 300}
[2026-11-08T16:27:23+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "duration_ms": 24472.11, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 22 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-tcp-link\n", "time": "2026-11-08T16:27:23.686+00:00"}
[2026-11-08T16:27:26+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T16:27:23.692+00:00"}
[2026-11-08T16:27:26+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "local_port": 8889, "service": "plain-python-tcp-echo-listener", "time": "2026-11-08T16:27:23.693+00:00", "wait_for_completion": false}
[2026-11-08T16:27:26+00:00] TCP_LINK_KERNEL_SUBMITTED kernel_id=simonday/webvm-tcp-link local_port=8889 service=plain-python-tcp-echo-listener
[2026-11-08T16:27:26+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:27:23.699+00:00", "timeout_seconds": 15}
[2026-11-08T16:27:39+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 16121.529, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:27:39.823+00:00"}
[2026-11-08T16:27:39+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T16:27:39.827+00:00", "timeout_seconds": 15}
[2026-11-08T16:27:55+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15325.05, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:27:55.153+00:00"}
[2026-11-08T16:27:55+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31460.969, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T16:27:55.158+00:00"}
[2026-11-08T16:27:55+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T16:27:55.160+00:00", "waited_seconds": 31.5}
[2026-11-08T16:28:00+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:28:00.262+00:00", "timeout_seconds": 15}
[2026-11-08T16:28:15+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15376.105, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:28:15.634+00:00"}
[2026-11-08T16:28:18+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T16:28:15.641+00:00", "timeout_seconds": 15}
[2026-11-08T16:28:31+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15276.355, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:28:30.925+00:00"}
[2026-11-08T16:28:31+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30738.651, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T16:28:30.938+00:00"}
[2026-11-08T16:28:31+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T16:28:30.942+00:00", "waited_seconds": 67.2}
[2026-11-08T16:28:36+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:28:35.957+00:00", "timeout_seconds": 15}
[2026-11-08T16:28:52+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15891.424, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:28:51.851+00:00"}
[2026-11-08T16:28:52+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T16:28:51.858+00:00", "timeout_seconds": 15}
[2026-11-08T16:29:07+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15436.321, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:29:07.305+00:00"}
[2026-11-08T16:29:07+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31385.63, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T16:29:07.340+00:00"}
[2026-11-08T16:29:07+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T16:29:07.346+00:00", "waited_seconds": 103.6}
[2026-11-08T16:29:12+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:29:12.371+00:00", "timeout_seconds": 15}
[2026-11-08T16:29:27+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15233.611, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:29:27.607+00:00"}
[2026-11-08T16:29:32+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T16:29:27.619+00:00", "timeout_seconds": 15}
[2026-11-08T16:29:43+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15297.614, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:29:42.924+00:00"}
[2026-11-08T16:29:43+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 30573.551, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T16:29:42.935+00:00"}
[2026-11-08T16:29:43+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T16:29:42.938+00:00", "waited_seconds": 139.2}
[2026-11-08T16:29:48+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:29:48.027+00:00", "timeout_seconds": 15}
[2026-11-08T16:30:05+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 17079.634, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:30:05.108+00:00"}
[2026-11-08T16:30:05+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T16:30:05.117+00:00", "timeout_seconds": 15}
[2026-11-08T16:30:20+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15414.759, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:30:20.545+00:00"}
[2026-11-08T16:30:20+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 32546.56, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T16:30:20.571+00:00"}
[2026-11-08T16:30:20+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels status simonday/webvm-tcp-link", "event": "TCP_LINK_STATUS_POLL_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "note": "Retrying until the startup deadline instead of aborting the controller", "time": "2026-11-08T16:30:20.576+00:00", "waited_seconds": 176.9}
[2026-11-08T16:30:25+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T16:30:25.615+00:00", "timeout_seconds": 15}
[2026-11-08T16:30:41+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15236.1, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T16:30:40.853+00:00"}