WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-07T17:46:00+00:00
stage=PI_WRAPPER_FINISHED
[2026-11-07T17:41:56+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-07T17:42:07+00:00] PI_IMPL_FETCHED source=main characters=34655
[2026-11-07T17:42:08+00:00] booting.
[2026-11-07T17:42:08+00:00] booting..
[2026-11-07T17:42:09+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=22065
[2026-11-07T17:42:11+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=6697
[2026-11-07T17:42:11+00:00] IO_LOOP_CONTROLLER_START dataset_id=simonday/webvm-io-loop-rx kernel_id=simonday/webvm-io-loop-proof
[2026-11-07T17:42:13+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-07T17:42:13.757+00:00", "timeout_seconds": 900}
[2026-11-07T17:42:14+00:00] IO_LOOP_CONTROLLER {"checksum": "3c428f50423e35f99aca63e9b7e999cedf3b23682364c9bcd16b5ab87b855235", "event": "POC_FILES_WRITTEN", "notebook_bytes": 14175, "payload": "WEBVM_IO_LOOP_HELLO_V1", "request_id": "io-loop-proof-20261107T174213808525Z", "rx_bytes": 255, "sequence": 1, "time": "2026-11-07T17:42:14.634+00:00"}
[2026-11-07T17:43:15+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-07T17:42:14.638+00:00", "timeout_seconds": 60}
[2026-11-07T17:43:18+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "duration_ms": 60234.07, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "ready", "time": "2026-11-07T17:43:14.874+00:00"}
[2026-11-07T17:43:47+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_DATASET_EXISTENCE_PROBE", "exists": true, "time": "2026-11-07T17:43:14.896+00:00"}
[2026-11-07T17:43:47+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-07T17:43:14.905+00:00", "timeout_seconds": 300}
[2026-11-07T17:43:47+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": 32027.636, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/255 [00:00<?, ?B/s]\n100%|██████████| 255/255 [00:01<00:00, 246B/s]\n100%|██████████| 255/255 [00:01<00:00, 136B/s]\nUpload successful: rx_message.json (255B)\nDataset version is being created. Please check progress at https://www.kaggle.com/datasets/simonday/webvm-io-loop-rx\n", "time": "2026-11-07T17:43:46.934+00:00"}
[2026-11-07T17:44:13+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "3c428f50423e35f99aca63e9b7e999cedf3b23682364c9bcd16b5ab87b855235", "time": "2026-11-07T17:43:46.946+00:00"}
[2026-11-07T17:44:13+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-07T17:43:46.958+00:00", "timeout_seconds": 90}
[2026-11-07T17:44:13+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": 26421.895, "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/255 [00:00<?, ?B/s]\n100%|██████████| 255/255 [00:00<00:00, 3.87kB/s]\n\n", "time": "2026-11-07T17:44:13.380+00:00"}
[2026-11-07T17:44:36+00:00] IO_LOOP_CONTROLLER {"checksum": "3c428f50423e35f99aca63e9b7e999cedf3b23682364c9bcd16b5ab87b855235", "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-20261107T174213808525Z", "time": "2026-11-07T17:44:13.393+00:00"}
[2026-11-07T17:44:40+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-07T17:44:13.477+00:00", "timeout_seconds": 300}
[2026-11-07T17:44:40+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 23359.2, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 11 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-07T17:44:36.837+00:00"}
[2026-11-07T17:44:40+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-07T17:44:36.857+00:00"}
[2026-11-07T17:44:40+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-07T17:44:36.872+00:00", "timeout_seconds": 60}
[2026-11-07T17:45:00+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 23756.875, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-07T17:45:00.630+00:00"}
[2026-11-07T17:45:10+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-07T17:45:00.635+00:00"}
[2026-11-07T17:45:10+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-07T17:45:10.674+00:00", "timeout_seconds": 60}
[2026-11-07T17:45:33+00:00] IO_LOOP_CONTROLLER {"attempt": "configured_environment", "command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 22583.016, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.COMPLETE\"\n", "time": "2026-11-07T17:45:33.258+00:00"}
[2026-11-07T17:45:33+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-07T17:45:33.263+00:00"}
[2026-11-07T17:45:33+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-07T17:45:33.269+00:00", "timeout_seconds": 300}
[2026-11-07T17:45:58+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": 24815.169, "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/464 [00:00<?, ?B/s]\n100%|██████████| 464/464 [00:00<00:00, 13.6kB/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-07T17:45:58.086+00:00"}
[2026-11-07T17:46:00+00:00] IO_LOOP_CONTROLLER {"checks": {"ack_state": 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": 2455.764, "payload": "ACK:WEBVM_IO_LOOP_HELLO_V1", "payload_length": 26, "received_at": "2026-11-07T17:44:56.334+00:00", "received_checksum": "3c428f50423e35f99aca63e9b7e999cedf3b23682364c9bcd16b5ab87b855235", "request_id": "io-loop-proof-20261107T174213808525Z", "sequence": 1, "type": "ack", "version": 1}, "time": "2026-11-07T17:45:58.242+00:00"}
[2026-11-07T17:46:00+00:00] IO_LOOP_CONTROLLER {"event": "LOOP_PROOF_PASS", "output_bytes": 464, "output_file": "/home/user/kaggle-io-loop-run/output/tx_response.json", "payload_bytes": 22, "request_id": "io-loop-proof-20261107T174213808525Z", "round_trip_ms": 224488.77, "rx_checksum": "3c428f50423e35f99aca63e9b7e999cedf3b23682364c9bcd16b5ab87b855235", "sequence": 1, "time": "2026-11-07T17:45:58.248+00:00", "tx_checksum": "c2d4d1b41ecb756ad92d44e78afe55abdc7cf12f586499fdf5fa551454834b6f"}
[2026-11-07T17:46:00+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-07T17:46:00+00:00] IO_LOOP_CONTROLLER_EXIT returncode=0
[2026-11-07T17:46:00+00:00] PI_WRAPPER_FINISHED returncode=0