WVMQWENWS_6D91C42F
source=WEBVM
time=2026-11-07T09:28:31+00:00
stage=PI_WRAPPER_FINISHED
[2026-11-07T09:25:24+00:00] PI_WRAPPER_STARTED tag=WVMQWENWS_6D91C42F script=pi.py
[2026-11-07T09:25:34+00:00] PI_IMPL_FETCHED source=main characters=28735
[2026-11-07T09:25:34+00:00] booting.
[2026-11-07T09:25:35+00:00] booting..
[2026-11-07T09:25:36+00:00] IO_LOOP_SOURCE_FETCHED file=controller.py attempt=1/10 characters=11619
[2026-11-07T09:25:37+00:00] IO_LOOP_SOURCE_FETCHED file=agent.py attempt=1/10 characters=4423
[2026-11-07T09:25:37+00:00] IO_LOOP_CONTROLLER_START dataset_id=simonday/webvm-io-loop-rx kernel_id=simonday/webvm-io-loop-proof
[2026-11-07T09:28:29+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-07T09:25:40.218+00:00", "timeout_seconds": 900}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"checksum": "b7ef52d4fa791d943cf73fed5cb5eac1600acbb3eae57929bdfbc9c6d7f7c87a", "event": "POC_FILES_WRITTEN", "notebook_bytes": 6301, "payload": "WEBVM_IO_LOOP_HELLO_V1", "request_id": "io-loop-proof-0001", "rx_bytes": 237, "sequence": 1, "time": "2026-11-07T09:25:40.782+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "event": "API_REQUEST_START", "time": "2026-11-07T09:25:40.784+00:00", "timeout_seconds": 60}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "datasets", "status", "simonday/webvm-io-loop-rx"], "duration_ms": 53442.385, "event": "API_REQUEST_RESULT", "exit_code": 1, "output_tail": "403 Client Error: Forbidden for url: https://api.kaggle.com/v1/datasets.DatasetApiService/GetDatasetStatus\n\nYou don't have access to this resource.\nIf this is a competition, you may need to accept its rules first at\n  https://www.kaggle.com/competitions/<competition>/rules\n", "time": "2026-11-07T09:26:34.228+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_DATASET_EXISTENCE_PROBE", "exists": false, "time": "2026-11-07T09:26:34.233+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "datasets", "create", "-p", "/home/user/kaggle-io-loop-run/dataset", "-r", "skip"], "event": "API_REQUEST_START", "time": "2026-11-07T09:26:34.237+00:00", "timeout_seconds": 300}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "datasets", "create", "-p", "/home/user/kaggle-io-loop-run/dataset", "-r", "skip"], "duration_ms": 37614.08, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Starting upload for file rx_message.json\n\n  0%|          | 0.00/237 [00:00<?, ?B/s]\n100%|██████████| 237/237 [00:00<00:00, 241B/s]\n100%|██████████| 237/237 [00:02<00:00, 114B/s]\nUpload successful: rx_message.json (237B)\nYour private Dataset is being created. Please check progress at https://www.kaggle.com/datasets/simonday/webvm-io-loop-rx\n", "time": "2026-11-07T09:27:11.851+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"dataset_id": "simonday/webvm-io-loop-rx", "event": "RX_RESOURCE_PUBLISHED", "message_checksum": "b7ef52d4fa791d943cf73fed5cb5eac1600acbb3eae57929bdfbc9c6d7f7c87a", "time": "2026-11-07T09:27:11.867+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "event": "API_REQUEST_START", "time": "2026-11-07T09:27:11.872+00:00", "timeout_seconds": 300}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "kernels", "push", "-p", "/home/user/kaggle-io-loop-run/kernel"], "duration_ms": 22834.89, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "Kernel version 1 successfully pushed.  Please check progress at https://www.kaggle.com/code/simonday/webvm-io-loop-proof\n", "time": "2026-11-07T09:27:34.707+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"event": "KERNEL_SUBMITTED", "kernel_id": "simonday/webvm-io-loop-proof", "time": "2026-11-07T09:27:34.719+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "event": "API_REQUEST_START", "time": "2026-11-07T09:27:34.722+00:00", "timeout_seconds": 60}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 22938.99, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.RUNNING\"\n", "time": "2026-11-07T09:27:57.661+00:00"}
[2026-11-07T09:28:31+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-07T09:27:57.665+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "event": "API_REQUEST_START", "time": "2026-11-07T09:28:07.680+00:00", "timeout_seconds": 60}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"command": ["kaggle", "kernels", "status", "simonday/webvm-io-loop-proof"], "duration_ms": 20904.325, "event": "API_REQUEST_RESULT", "exit_code": 0, "output_tail": "simonday/webvm-io-loop-proof has status \"KernelWorkerStatus.ERROR\"\n", "time": "2026-11-07T09:28:28.585+00:00"}
[2026-11-07T09:28:31+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.ERROR\"", "time": "2026-11-07T09:28:28.596+00:00"}
[2026-11-07T09:28:31+00:00] IO_LOOP_CONTROLLER {"event": "EXECUTION_NOT_COMPLETE", "final_status": "ERROR", "time": "2026-11-07T09:28:28.598+00:00"}
[2026-11-07T09:28:31+00:00] Traceback (most recent call last):
[2026-11-07T09:28:31+00:00]   File "/home/user/kaggle-io-loop/controller.py", line 204, in <module>
[2026-11-07T09:28:31+00:00]     main()
[2026-11-07T09:28:31+00:00]   File "/home/user/kaggle-io-loop/controller.py", line 191, in main
[2026-11-07T09:28:31+00:00]     raise RuntimeError("Kaggle kernel did not complete successfully: " + str(final_status or "timeout"))
[2026-11-07T09:28:31+00:00] RuntimeError: Kaggle kernel did not complete successfully: ERROR
[2026-11-07T09:28:31+00:00] \nIO_LOOP_CONTROLLER_EXIT returncode=1
[2026-11-07T09:28:31+00:00] Traceback (most recent call last):
[2026-11-07T09:28:31+00:00]   File "/home/user/examples/python3/pi.py", line 65, in <module>
[2026-11-07T09:28:31+00:00]     main()
[2026-11-07T09:28:31+00:00]   File "https://raw.githubusercontent.com/scatterp2/webvm/main/examples/python3/pi_impl.py", line 729, in main
[2026-11-07T09:28:31+00:00]   File "https://raw.githubusercontent.com/scatterp2/webvm/main/examples/python3/pi_impl.py", line 721, in run_io_loop_proof
[2026-11-07T09:28:31+00:00] RuntimeError: Kaggle API-mediated IO loop proof failed with exit code 1
[2026-11-07T09:28:31+00:00] PI_WRAPPER_FINISHED returncode=1