WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-08T20:15:09+00:00
stage=PI_WRAPPER_FINISHED
V9uYW1lcywgc3RyKToKICAgICAgICBtb2R1bGVfbWF0Y2hlcyA9IFtsaW5lLnNwbGl0KClbMF0gZm9yIGxpbmUgaW4gbW9kdWxlX25hbWVzLnNwbGl0bGluZXMoKQogICAgICAgICAgICAgICAgICAgICAgICAgIGlmIGFueShrIGluIGxpbmUubG93ZXIoKSBmb3IgayBpbgogICAgICAgICAgICAgICAgICAgICAgICAgICAgICAgICAoImlwX3RhYmxlcyIsICJpcHRhYmxlX25hdCIsICJuZl9uYXQiLCAibmZfdGFibGVzIiwgIm5mdF9jaGFpbiIpKV0KICAgIGFwdF9jYWNoZSA9IGJvdW5kZWRfY29tbWFuZChbImFwdC1jYWNoZSIsICJwb2xpY3kiLCAiaXB0YWJsZXMiXSwgMykgaWYgY29tbWFuZHMuZ2V0KCJhcHQtZ2V0IikgZWxzZSB7Im5vdGUiOiAiYXB0LWdldCBub3QgZm91bmQifQogICAgZHBrZ19hcmNoID0gYm91bmRlZF9jb21tYW5kKFsiZHBrZyIsICItLXByaW50LWFyY2hpdGVjdHVyZSJdLCAzKSBpZiBjb21tYW5kcy5nZXQoImRwa2ciKSBlbHNlIHsibm90ZSI6ICJkcGtnIG5vdCBmb3VuZCJ9CiAgICB1bmFtZSA9IGJvdW5kZWRfY29tbWFuZChbInVuYW1lIiwgIi1hIl0sIDMpCiAgICByZXR1cm4gewogICAgICAgICJhcmNoaXRlY3R1cmUiOiBwbGF0Zm9ybS5tYWNoaW5lKCksCiAgICAgICAgImtlcm5lbCI6IHVuYW1lLAogICAgICAgICJjb21tYW5kcyI6IGNvbW1hbmRzLAogICAgICAgICJkcGtnX2FyY2hpdGVjdHVyZSI6IGRwa2dfYXJjaCwKICAgICAgICAiYXB0X3BvbGljeV9pcHRhYmxlcyI6IGFwdF9jYWNoZSwKICAgICAgICAicHJvY19pcF90YWJsZXNfbmFtZXMiOiBwcm9jX25hbWVzLAogICAgICAgICJuZXRmaWx0ZXJfbW9kdWxlc19sb2FkZWQiOiBtb2R1bGVfbWF0Y2hlcywKICAgICAgICAiY29uZmlnX25ldGZpbHRlciI6IHsKICAgICAgICAgICAgInByb2NfY29uZmlnX2d6IjogInByZXNlbnQiIGlmIG9zLnBhdGguZXhpc3RzKCIvcHJvYy9jb25maWcuZ3oiKSBlbHNlICJhYnNlbnQiLAogICAgICAgICAgICAiYm9vdF9jb25maWciOiAicHJlc2VudCIgaWYgb3MucGF0aC5leGlzdHMoIi9ib290L2NvbmZpZy0iICsgb3MudW5hbWUoKS5yZWxlYXNlKSBlbHNlICJhYnNlbnQiLAogICAgICAgIH0sCiAgICB9CgpyZXBvcnQgPSB7CiAgICAicHJvYmUiOiAid2Vidm0tcngtdHgtcmVtb3RlLXByb2JlLXYzIiwKICAgICJ0aW1lIjogdGltZS50aW1lKCksCiAgICAiaG9zdG5hbWUiOiBvcy51bmFtZSgpLm5vZGVuYW1lIGlmIGhhc2F0dHIob3MsICJ1bmFtZSIpIGVsc2UgTm9uZSwKICAgICJ1aWQiOiBvcy5nZXRldWlkKCkgaWYgaGFzYXR0cihvcywgImdldGV1aWQiKSBlbHNlIE5vbmUsCiAgICAibmV0d29yayI6IFtwcm9iZV9ob3N0KGhvc3QpIGZvciBob3N0IGluICgKICAgICAgICAicmF3LmdpdGh1YnVzZXJjb250ZW50LmNvbSIsICJhcGkua2FnZ2xlLmNvbSIsCiAgICAgICAgImtrYi1wcm9kdWN0aW9uLmp1cHl0ZXItcHJveHkua2FnZ2xlLm5ldCIsICJwc3RlLnVzIgogICAgKV0sCiAgICAianVweXRlcl84ODg4X3VudG91Y2hlZCI6IFRydWUsCiAgICAiZmlyZXdhbGxfY2hhbmdlZCI6IEZhbHNlLAp9CnJlcG9ydFsiaHR0cHNfZXhhbXBsZSJdID0gcnVuX2JvdW5kZWQoSFRUUFNfQ09ERSwgW10sIDcpCnJlcG9ydFsibmV0ZmlsdGVyX2RpYWdub3N0aWNzIl0gPSBuZXRmaWx0ZXJfZGlhZ25vc3RpY3MoKQpwcmludCgiUkVNT1RFX1BST0JFX1JFU1VMVCAiICsganNvbi5kdW1wcyhyZXBvcnQsIHNvcnRfa2V5cz1UcnVlKSwgZmx1c2g9VHJ1ZSkK", "request_id": "io-loop-proof-20261108T190819622083Z", "rx_bytes": 7097, "sequence": 1, "time": "2026-11-08T19:08:20.172+00:00"}
[2026-11-08T19:08:20+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-08T19:08:20.275+00:00", "timeout_seconds": 60}
[2026-11-08T19:08:41+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 21342.935, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:08:41.274+00:00"}
[2026-11-08T19:08:41+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-08T19:08:41.334+00:00", "timeout_seconds": 20}
[2026-11-08T19:09:01+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 20310.306, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:09:01.647+00:00"}
[2026-11-08T19:09:02+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 41741.7, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T19:09:01.655+00:00"}
[2026-11-08T19:09:02+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-08T19:09:01.657+00:00"}
[2026-11-08T19:09:03+00:00] IO_LOOP_CONTROLLER {"event": "POC_FILES_WRITTEN", "notebook_bytes": 32694, "service": "tailscale-socks5-http-connect-bridge", "time": "2026-11-08T19:09:02.936+00:00"}
[2026-11-08T19:09:06+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-08T19:09:02.939+00:00", "timeout_seconds": 300}
[2026-11-08T19:09:09+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "duration_ms": 49608.175, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "ready", "time": "2026-11-08T19:09:09.885+00:00"}
[2026-11-08T19:09:13+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_DATASET_EXISTENCE_PROBE", "exists": true, "time": "2026-11-08T19:09:09.895+00:00"}
[2026-11-08T19:09:13+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-20261108T190819622083Z", "-r", "skip"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T19:09:09.905+00:00", "timeout_seconds": 300}
[2026-11-08T19:09:57+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-tcp-link-run/kernel"], "duration_ms": 54787.97, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 26 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-tcp-link\n", "time": "2026-11-08T19:09:57.730+00:00"}
[2026-11-08T19:10:01+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T19:09:57.816+00:00"}
[2026-11-08T19:10:01+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-tcp-link", "local_port": 18088, "service": "tailscale-socks5-http-connect-bridge", "time": "2026-11-08T19:09:57.819+00:00", "wait_for_completion": false}
[2026-11-08T19:10:01+00:00] TCP_LINK_KERNEL_SUBMITTED kernel_id=simonday/webvm-tcp-link local_port=18088 service=tailscale-socks5-http-connect-bridge
[2026-11-08T19:10:01+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-08T19:09:57.842+00:00", "timeout_seconds": 15}
[2026-11-08T19:10:13+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15758.011, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:10:13.632+00:00"}
[2026-11-08T19:10:17+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-08T19:10:13.652+00:00", "timeout_seconds": 15}
[2026-11-08T19:10:17+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-20261108T190819622083Z", "-r", "skip"], "duration_ms": 67769.765, "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:07<00:00, 941B/s]\n100%|██████████| 6.93k/6.93k [00:08<00:00, 862B/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-08T19:10:17.681+00:00"}
[2026-11-08T19:10:20+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "b9b47bf785e877217919d057c4df0e2c6aaaee33fe38679b90b735f93eb7b085", "time": "2026-11-08T19:10:17.802+00:00"}
[2026-11-08T19:10:20+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-08T19:10:18.040+00:00", "timeout_seconds": 90}
[2026-11-08T19:10:29+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15358.645, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:10:29.014+00:00"}
[2026-11-08T19:10:29+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31201.085, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T19:10:29.023+00:00"}
[2026-11-08T19:10:29+00:00] IO_LOOP_CONTROLLER {"consecutive_timeouts": 1, "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": "Will switch to artifact diagnostics after three consecutive status timeouts", "time": "2026-11-08T19:10:29.026+00:00", "waited_seconds": 31.2}
[2026-11-08T19:10:34+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-08T19:10:34.059+00:00", "timeout_seconds": 15}
[2026-11-08T19:10:49+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15756.695, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:10:49.818+00:00"}
[2026-11-08T19:10:49+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-08T19:10:49.824+00:00", "timeout_seconds": 15}
[2026-11-08T19:11:05+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": 46916.625, "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, 63.2kB/s]\n100%|██████████| 6.93k/6.93k [00:00<00:00, 38.8kB/s]\n\n", "time": "2026-11-08T19:11:04.958+00:00"}
[2026-11-08T19:11:07+00:00] IO_LOOP_CONTROLLER {"checksum": "b9b47bf785e877217919d057c4df0e2c6aaaee33fe38679b90b735f93eb7b085", "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-20261108T190819622083Z", "time": "2026-11-08T19:11:04.974+00:00"}
[2026-11-08T19:11:07+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-08T19:11:04.980+00:00", "timeout_seconds": 300}
[2026-11-08T19:11:07+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15575.15, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:11:05.402+00:00"}
[2026-11-08T19:11:07+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31362.156, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T19:11:05.418+00:00"}
[2026-11-08T19:11:07+00:00] IO_LOOP_CONTROLLER {"consecutive_timeouts": 2, "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": "Will switch to artifact diagnostics after three consecutive status timeouts", "time": "2026-11-08T19:11:05.421+00:00", "waited_seconds": 67.6}
[2026-11-08T19:11:10+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-08T19:11:10.466+00:00", "timeout_seconds": 15}
[2026-11-08T19:11:26+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15759.121, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:11:26.245+00:00"}
[2026-11-08T19:11:29+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-08T19:11:26.321+00:00", "timeout_seconds": 15}
[2026-11-08T19:11:41+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "duration_ms": 15428.205, "event": "API_REQUEST_TIMEOUT", "output_tail": "", "time": "2026-11-08T19:11:41.753+00:00"}
[2026-11-08T19:11:41+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "status", "simonday/webvm-tcp-link"], "elapsed_ms": 31300.285, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T19:11:41.764+00:00"}
[2026-11-08T19:11:41+00:00] IO_LOOP_CONTROLLER {"consecutive_timeouts": 3, "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": "Will switch to artifact diagnostics after three consecutive status timeouts", "time": "2026-11-08T19:11:41.864+00:00", "waited_seconds": 104.0}
[2026-11-08T19:11:41+00:00] IO_LOOP_CONTROLLER {"action": "try output artifact and notebook log diagnostics", "event": "TCP_LINK_STATUS_UNAVAILABLE_FALLBACK", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T19:11:41.867+00:00", "waited_seconds": 104.0}
[2026-11-08T19:11:41+00:00] IO_LOOP_CONTROLLER {"event": "TCP_LINK_STARTUP_PENDING", "kernel_id": "simonday/webvm-tcp-link", "status_tail": "", "time": "2026-11-08T19:11:41.868+00:00", "waited_seconds": 104.0}
[2026-11-08T19:11:41+00:00] TCP_LINK_STARTUP_PENDING kernel_id=simonday/webvm-tcp-link
[2026-11-08T19:11:41+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-tcp-link", "-p", "/home/user/kaggle-tcp-link-run/output/tcp_link_status_pending", "-o", "--file-pattern", "^tcp_link_status[.]json$"], "event": "API_REQUEST_START", "proxy_vars_removed": false, "time": "2026-11-08T19:11:41.893+00:00", "timeout_seconds": 45}
[2026-11-08T19:11:45+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 40212.71, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel push error: Maximum batch CPU session count of 5 reached.\n", "time": "2026-11-08T19:11:45.194+00:00"}
[2026-11-08T19:11:48+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-08T19:11:45.253+00:00"}
[2026-11-08T19:11:48+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-08T19:11:45.264+00:00", "timeout_seconds": 60}
[2026-11-08T19:12:30+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 43378.35, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.COMPLETE\"\n", "time": "2026-11-08T19:12:28.645+00:00"}
[2026-11-08T19:12:37+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-08T19:12:28.663+00:00"}
[2026-11-08T19:12:40+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-08T19:12:30.200+00:00", "timeout_seconds": 300}
[2026-11-08T19:12:40+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "output", "simonday/webvm-tcp-link", "-p", "/home/user/kaggle-tcp-link-run/output/tcp_link_status_pending", "-o", "--file-pattern", "^tcp_link_status[.]json$"], "duration_ms": 48299.954, "event": "API_REQUEST_TIMEOUT", "output_tail": "b'Downloading tcp_link_status.json to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending\\n\\r  0%|          | 0.00/160 [00:00<?, ?B/s]\\r100%|\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88| 160/160 [00:00<00:00, 1.37kB/s]\\r100%|\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88| 160/160 [00:00<00:00, 1.20kB/s]\\n\\nOutput file downloaded to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending/tcp_link_status.json\\nKernel log downloaded to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending/webvm-tcp-link.log \\n'", "time": "2026-11-08T19:12:30.370+00:00"}
[2026-11-08T19:12:40+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "output", "simonday/webvm-tcp-link", "-p", "/home/user/kaggle-tcp-link-run/output/tcp_link_status_pending", "-o", "--file-pattern", "^tcp_link_status[.]json$"], "event": "API_REQUEST_START", "proxy_vars_removed": true, "time": "2026-11-08T19:12:30.396+00:00", "timeout_seconds": 45}
[2026-11-08T19:13:16+00:00] IO_LOOP_CONTROLLER {"attempt": "proxy_bypass", "command": ["kaggle", "kernels", "output", "simonday/webvm-tcp-link", "-p", "/home/user/kaggle-tcp-link-run/output/tcp_link_status_pending", "-o", "--file-pattern", "^tcp_link_status[.]json$"], "duration_ms": 46270.476, "event": "API_REQUEST_TIMEOUT", "output_tail": "b'Downloading tcp_link_status.json to /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending\\n\\r  0%|          | 0.00/160 [00:00<?, ?B/s]\\r100%|\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88| 160/160 [00:00<00:00, 794B/s]\\r100%|\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88\\xe2\\x96\\x88| 160/160 [00:00<00:00, 739B/s]\\n'", "time": "2026-11-08T19:13:16.678+00:00"}
[2026-11-08T19:13:16+00:00] IO_LOOP_CONTROLLER {"attempts": 2, "command": ["kaggle", "kernels", "output", "simonday/webvm-tcp-link", "-p", "/home/user/kaggle-tcp-link-run/output/tcp_link_status_pending", "-o", "--file-pattern", "^tcp_link_status[.]json$"], "elapsed_ms": 94875.719, "event": "API_REQUEST_EXHAUSTED", "read_only": true, "reason": "all permitted attempts timed out", "time": "2026-11-08T19:13:16.764+00:00"}
[2026-11-08T19:13:16+00:00] IO_LOOP_CONTROLLER {"error": "Kaggle read-only API command timed out in configured and proxy-bypass attempts: kaggle kernels output simonday/webvm-tcp-link -p /home/user/kaggle-tcp-link-run/output/tcp_link_status_pending -o --file-pattern ^tcp_link_status[.]json$", "event": "TCP_LINK_PENDING_ARTIFACT_TIMEOUT", "kernel_id": "simonday/webvm-tcp-link", "time": "2026-11-08T19:13:16.770+00:00"}
[2026-11-08T19:13:18+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": 48227.005, "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, 21.6kB/s]\n100%|██████████| 2.90k/2.90k [00:00<00:00, 14.4kB/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-08T19:13:18.433+00:00"}
[2026-11-08T19:13:20+00:00] TCP_LINK_CONTROLLER_EXIT returncode=0
[2026-11-08T19:13:20+00:00] TCP_LINK_BRIDGE_SUBMITTED transport=tailscale-socks5-http-connect tailnet_port=18088
[2026-11-08T19:13:20+00:00] IO_LOOP_CONTROLLER {"checks": {"ack_state": true, "execution_not_timed_out": true, "execution_succeeded": true, "payload": true, "payload_length": true, "received_checksum": false, "request_id": false, "sequence": true, "version": true}, "event": "OUTPUT_VALIDATION", "response": {"ack_state": "processed_once", "agent_elapsed_ms": 42871.72, "execution": {"elapsed_ms": 29169.15, "exit_code": 0, "output": "REMOTE_PROBE_RESULT {\"firewall_changed\": false, \"hostname\": \"449bcc10e45d\", \"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 449bcc10e45d 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\": 1794164882.030115, \"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-08T19:08:43.119+00:00", "received_checksum": "ee733de50f3a9abc16e69963f148ea45e7a497e33d96eb573cfa55a79987a08f", "request_id": "io-loop-proof-20261108T190330615014Z", "sequence": 1, "type": "ack", "version": 1}, "time": "2026-11-08T19:13:18.612+00:00"}
[2026-11-08T19:13:21+00:00] Traceback (most recent call last):
[2026-11-08T19:13:21+00:00]   File "/home/user/kaggle-io-loop/controller.py", line 864, in <module>
[2026-11-08T19:13:21+00:00]     main()
[2026-11-08T19:13:21+00:00]   File "/home/user/kaggle-io-loop/controller.py", line 819, in main
[2026-11-08T19:13:21+00:00]     response = validate_output(output_dir / "tx_response.json", message, log)
[2026-11-08T19:13:21+00:00]                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T19:13:21+00:00]   File "/home/user/kaggle-io-loop/controller.py", line 237, in validate_output
[2026-11-08T19:13:21+00:00]     raise RuntimeError("TX response validation failed: " + repr(checks))
[2026-11-08T19:13:21+00:00] RuntimeError: TX response validation failed: {'version': True, 'sequence': True, 'request_id': False, 'ack_state': True, 'received_checksum': False, 'payload': True, 'payload_length': True, 'execution_succeeded': True, 'execution_not_timed_out': True}
[2026-11-08T19:13:21+00:00] IO_LOOP_CONTROLLER_EXIT returncode=1
[2026-11-08T19:13:23+00:00] RX_TX_INITIAL_PROOF_FAILED error=RuntimeError: IO_LOOP_CONTROLLER failed with exit code 1
[2026-11-08T20:15:06+00:00] Traceback (most recent call last):
[2026-11-08T20:15:09+00:00]   File "/home/user/examples/python3/pi.py", line 65, in <module>
[2026-11-08T20:15:09+00:00]     main()
[2026-11-08T20:15:09+00:00]   File "https://raw.githubusercontent.com/scatterp2/webvm/main/examples/python3/pi_impl.py", line 1083, in main
[2026-11-08T20:15:09+00:00]   File "https://raw.githubusercontent.com/scatterp2/webvm/main/examples/python3/pi_impl.py", line 1037, in run_io_loop_proof
[2026-11-08T20:15:09+00:00]     temporary_path = probe_path.with_suffix(".py.new")
[2026-11-08T20:15:09+00:00]          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 216, in urlopen
[2026-11-08T20:15:09+00:00]     return opener.open(url, data, timeout)
[2026-11-08T20:15:09+00:00]            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 519, in open
[2026-11-08T20:15:09+00:00]     response = self._open(req, data)
[2026-11-08T20:15:09+00:00]                ^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 536, in _open
[2026-11-08T20:15:09+00:00]     result = self._call_chain(self.handle_open, protocol, protocol +
[2026-11-08T20:15:09+00:00]              ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 496, in _call_chain
[2026-11-08T20:15:09+00:00]     result = func(*args)
[2026-11-08T20:15:09+00:00]              ^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 1404, in https_open
[2026-11-08T20:15:09+00:00]     return self.do_open(http.client.HTTPSConnection, req,
[2026-11-08T20:15:09+00:00]            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/urllib/request.py", line 1361, in do_open
[2026-11-08T20:15:09+00:00]     h.request(req.get_method(), req.selector, req.data, headers,
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1311, in request
[2026-11-08T20:15:09+00:00]     self._send_request(method, url, body, headers, encode_chunked)
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1357, in _send_request
[2026-11-08T20:15:09+00:00]     self.endheaders(body, encode_chunked=encode_chunked)
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1306, in endheaders
[2026-11-08T20:15:09+00:00]     self._send_output(message_body, encode_chunked=encode_chunked)
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1066, in _send_output
[2026-11-08T20:15:09+00:00]     self.send(msg)
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1004, in send
[2026-11-08T20:15:09+00:00]     self.connect()
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/http/client.py", line 1483, in connect
[2026-11-08T20:15:09+00:00]     self.sock = self._context.wrap_socket(self.sock,
[2026-11-08T20:15:09+00:00]                 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/ssl.py", line 517, in wrap_socket
[2026-11-08T20:15:09+00:00]     return self.sslsocket_class._create(
[2026-11-08T20:15:09+00:00]            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/ssl.py", line 1121, in _create
[2026-11-08T20:15:09+00:00]     self.do_handshake()
[2026-11-08T20:15:09+00:00]   File "/usr/lib/python3.11/ssl.py", line 1394, in do_handshake
[2026-11-08T20:15:09+00:00]     self.settimeout(timeout)
[2026-11-08T20:15:09+00:00] OverflowError: timestamp too large to convert to C _PyTime_t
[2026-11-08T20:15:09+00:00] PI_WRAPPER_FINISHED returncode=1