Run Test: slices-bi-singlenode-login 2026-04-04 08:29:08.016556 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-04 08:29:11.133495 CEST - Run: slices --version 2026-04-04 08:29:11.340047 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.5.0 Slices clientlib bi v5.1.2 2026-04-04 08:29:11.340315 CEST - Run: slices pubkey list --format text 2026-04-04 08:29:11.913790 CEST - Pubkey already registered 2026-04-04 08:29:11.914025 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-04 08:29:12.451210 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-04 08:29:12.451324 CEST - Check List Flavors 2026-04-04 08:29:12.451526 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-04-04 08:29:13.149409 CEST - Check List DiskImages 2026-04-04 08:29:13.149626 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-04-04 08:29:13.858327 CEST - Requesting resources 2026-04-04 08:29:13.858785 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-04 08:29:13.858877 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-42408a75 --user-data /tmp/tmp0vy3yett 2026-04-04 08:29:15.785453 CEST - Resource ID: r_fr-sophia2-bi-vm1_01knbjwx60fvgazrz9y4typ7p5 2026-04-04 08:29:15.785591 CEST - Waiting until resource ready 2026-04-04 08:29:17.786020 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-42408a75 tst 2026-04-04 08:29:18.526218 CEST - Status: IMAGING 2026-04-04 08:29:21.264955 CEST - Status: IMAGING 2026-04-04 08:29:24.017710 CEST - Status: IMAGING 2026-04-04 08:29:26.792919 CEST - Status: BOOTING 2026-04-04 08:29:29.550413 CEST - Status: BOOTING 2026-04-04 08:29:32.277429 CEST - Status: BOOTING 2026-04-04 08:29:35.007388 CEST - Status: BOOTING 2026-04-04 08:29:37.733103 CEST - Status: BOOTING 2026-04-04 08:29:40.535911 CEST - Status: BOOTING 2026-04-04 08:29:43.323900 CEST - Status: BOOTING 2026-04-04 08:29:46.137299 CEST - Status: BOOTING 2026-04-04 08:29:48.880163 CEST - Status: BOOTING 2026-04-04 08:29:51.624539 CEST - Status: BOOTING 2026-04-04 08:29:54.360527 CEST - Status: BOOTING 2026-04-04 08:29:57.111221 CEST - Status: BOOTING 2026-04-04 08:29:59.860310 CEST - Status: BOOTING 2026-04-04 08:30:02.584233 CEST - Status: UP 2026-04-04 08:30:02.584281 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01knbjwwd7es0aep4b8682fxt4 2026-04-04 08:30:02.584316 CEST - Validate resources 2026-04-04 08:30:03.292394 CEST - The fields of the created resource were validated. 2026-04-04 08:30:03.292473 CEST - Check if resources are registered in experiment 2026-04-04 08:30:03.292640 CEST - Run: slices experiment list-resources --format json tst-42408a75 2026-04-04 08:30:03.929268 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:06.562951 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:09.200513 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:11.847366 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:14.466164 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:17.159970 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:19.836445 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:22.496780 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:25.181614 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:27.877694 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:30.559474 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:33.206373 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:35.844132 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:38.497018 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:41.187621 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:43.808935 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:46.422288 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:49.114234 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:51.804403 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:54.475406 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:57.155533 CEST - Status (on expauth): BOOTING 2026-04-04 08:30:59.779378 CEST - Status (on expauth): BOOTING 2026-04-04 08:31:02.403377 CEST - Status (on expauth): BOOTING 2026-04-04 08:31:04.403572 CEST - "slices experiment list-resources" was not ready immediately. 2026-04-04 08:31:04.403634 CEST - It was ready after: 61.111043 seconds. (Note: used wait steps of 2s) 2026-04-04 08:31:04.404968 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/c9131eb8-abf5-4e94-90f4-3b2c8d744869/step_Checkexperimentprint-resources_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices experiment list-resources --format json tst-42408a75 2026-04-04 08:31:04.405032 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/c9131eb8-abf5-4e94-90f4-3b2c8d744869/experiment-list-resources.txt': [ { "id": "r_fr-sophia2-bi-vm1_01knbjwx60fvgazrz9y4typ7p5", "created_at": "2026-04-04T06:29:15.577430Z", "created_by": "user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g", "experiment_id": "exp_expauth.ilabt.imec.be_01knbjwwd7es0aep4b8682fxt4", "friendly_name": "tst", "project_id": "proj_account.ilabt.imec.be_5pzabws7n79ydsagfg3vntg590", "service_id": "bi", "infra_id": "fr-sophia2-bi-vm1", "status": "booting", "type": "vm", "description": "Minimal VM", "expires_at": "2026-04-04T08:29:00Z", "terminated_at": null, "terminated_by": null } ] 2026-04-04 08:31:04.405049 CEST - Error in test step 'Check experiment print-resources': "slices experiment list-resources" returned resource in unexpected state: BOOTING 2026-04-04 08:31:04.405136 CEST - Testing extend expires_at (all resources in experiment) 2026-04-04 08:31:04.405234 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-42408a75 2026-04-04 08:31:06.224989 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-42408a75 tst 2026-04-04 08:31:06.938635 CEST - Testing extend expires_at (single resource in experiment) 2026-04-04 08:31:06.938816 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-42408a75 2026-04-04 08:31:08.649736 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-42408a75 tst 2026-04-04 08:31:09.390781 CEST - Testing ssh login 2026-04-04 08:31:09.405561 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-42408a75 tst 2026-04-04 08:31:10.281162 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-42408a75 tst 2026-04-04 08:31:11.162725 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-42408a75 tst 2026-04-04 08:31:11.988244 CEST - Logging in using 'slices bi ssh' 2026-04-04 08:31:11.988303 CEST - Forcing IPv4 only. 2026-04-04 08:31:11.988447 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-42408a75 tst -- -4 uname -a 2026-04-04 08:31:13.996084 CEST - Forcing IPv4 only. 2026-04-04 08:31:13.996239 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-42408a75 tst -- -4 uptime 2026-04-04 08:31:15.492419 CEST - CLI SSH Test passed. 2026-04-04 08:31:15.492464 CEST - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-04-04 08:31:15.492496 CEST - Uptime: 06:31:15 up 1 min, 1 user, load average: 0.08, 0.05, 0.01 2026-04-04 08:31:15.492527 CEST - Logging in using SSH over SSH proxy 2026-04-04 08:31:15.492670 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-42408a75 tst 2026-04-04 08:31:16.317723 CEST - Added paramiko HostKeyEntry for 10.123.246.167 2026-04-04 08:31:16.317820 CEST - Added paramiko HostKeyEntry for 10.123.246.167 2026-04-04 08:31:16.317966 CEST - Added paramiko HostKeyEntry for 10.123.246.167 2026-04-04 08:31:16.318240 CEST - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-04-04 08:31:16.318321 CEST - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-04-04 08:31:16.318356 CEST - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-04-04 08:31:16.318366 CEST - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-04-04 08:31:16.708635 CEST - Connecting to 10.123.246.167:22 over proxy 2026-04-04 08:31:17.381271 CEST - SSH Test output: 2026-04-04 08:31:17.381316 CEST - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-04-04 08:31:17.381327 CEST - Uptime: 06:31:17 up 1 min, 1 user, load average: 0.07, 0.05, 0.01 2026-04-04 08:31:17.519096 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-04-04 08:31:17.519146 CEST - lsb_release matches expected value 2026-04-04 08:31:17.519156 CEST - SSH Test passed. 2026-04-04 08:31:17.656459 CEST - Cloud-init user-data: Hello World. The time is now Sat, 04 Apr 2026 06:29:57 +0000! 2026-04-04 08:31:17.662916 CEST - Destroying tst-42408a75 tst 2026-04-04 08:31:17.663106 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-42408a75 tst