Run Test: slices-bi-singlenode-login 2026-03-15 16:03:29.878234 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-15 16:03:32.972945 CET - Run: slices --version 2026-03-15 16:03:33.149903 CET - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.3.8 Slices clientlib bi v5.1.2 2026-03-15 16:03:33.150176 CET - Run: slices pubkey list --format text 2026-03-15 16:03:33.859118 CET - Pubkey already registered 2026-03-15 16:03:33.859342 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-15 16:03:34.366420 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-15 16:03:34.366545 CET - Check List Flavors 2026-03-15 16:03:34.366720 CET - Run: slices bi --infra de-munich-bi-vm flavor list -f json 2026-03-15 16:04:35.123378 CET - Step 'List Flavors' took 60.76 seconds, which is longer than the warning threshold of 5 seconds 2026-03-15 16:04:35.123428 CET - Check List DiskImages 2026-03-15 16:04:35.123589 CET - Run: slices bi --infra de-munich-bi-vm diskimage list -f json 2026-03-15 16:05:35.705281 CET - Step 'List DiskImages' took 60.58 seconds, which is longer than the warning threshold of 5 seconds 2026-03-15 16:05:35.705328 CET - Requesting resources 2026-03-15 16:05:35.705738 CET - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-03-15 16:05:35.705820 CET - Run: slices bi --infra de-munich-bi-vm create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e8a8bcea --user-data /tmp/tmp2vx0v0dn 2026-03-15 16:08:43.166197 CET - Resource ID: r_de-munich-bi-vm_01kks0np4zewsrscq9jcwpt8n8 2026-03-15 16:08:43.166315 CET - Step 'Requesting resource' took 187.46 seconds, which is longer than the warning threshold of 30 seconds 2026-03-15 16:08:43.166336 CET - Waiting until resource ready 2026-03-15 16:08:45.166693 CET - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-e8a8bcea tst 2026-03-15 16:09:45.897438 CET - Status: BOOTING 2026-03-15 16:10:48.603875 CET - Status: UP 2026-03-15 16:10:48.603915 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kks0g08tferspfmjyzjx6dja 2026-03-15 16:10:48.603945 CET - Validate resources 2026-03-15 16:11:49.327175 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/step_Validateresources_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-e8a8bcea tst 2026-03-15 16:11:49.327254 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-03-15T15:08:43.030124Z", "disk_image_id": "image_de-munich-bi-vm_01kjceghcff5gbwgxyt40nkj7a", "experiment_id": "exp_expauth.ilabt.imec.be_01kks0g08tferspfmjyzjx6dja", "expires_at": "2026-03-15T17:05:00Z", "flavor_id": "flavor_de-munich-bi-vm_01kjceghd0fh1vh08ygdp4hrn9", "friendly_name": "tst", "id": "r_de-munich-bi-vm_01kks0np4zewsrscq9jcwpt8n8", "network_interfaces": [], "private_ipv4": "172.30.255.3", "private_ipv6": null, "public_ipv4": null, "public_ipv6": null, "ssh_authorized_keys": [ "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAINNiZPtQkfCEsc1x2QSwNWekfhWwWZbOvpE8hE0dH253 Wim (ftester) Van de Meerssche" ], "ssh_logins": [ { "host": "172.30.255.3", "port": 22, "username": "ftester", "host_keys": [ "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBJfcqHKmgwT4lZ/AXneX/B6nqoKUTDokOJzOw0wJVqzHxQJCIkpkfQYon10Nv7WYRH8i94Pd/77ayerIaaiTLc0= root@tst", "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIJKhEPjPLNzBXXpDWllXF3GbxkUNeHHAhPFDrBPgK4kN root@tst", "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDvdegWt6fBl56DU23xjP4uccMn72icoUMqZpvbsXsBuOiOoZU2hNJ5B/L0tsEloa2vpDrBJ/H0Dury3eljwX79Wggdaog6mHz3r1Wznk6D/Di7g9cCXkco6J2kQ0EBPiURKvb3Hf1d0bsM0dEx+if60ry7WePSN95zyJ5yDpDH8mHAVxAtHoEbFPoCKuiUqBWkCrJQGmRi7vA6UVoqyCVwPseOJ0n2eWcQaOe7IigwVsxejNVCP5FSE+g+kdztB1M19w3vNui9Sr5wZvBhffKXu5+Qu3vuhUS5JLDmcgCxR4TqkTtshyToOwXqXGv9HjbtEFlW+y8YWbYrPzZ5F9sfBLeBUbkYfgE4r6Umf4I7pKqMcbzeOeMbjU5tQyKAp3oczLG8aROS43xycjM2Eq/+IrMHdzz3U8cHZBrAr2k786bfVQHCGpMo0yc1pJ2CfOFnohlPQHWzfDiNclzNyFHzruX/GhbEdUCdG/eX9gKASKmr9SvjhqaNvDhrG6gr19s= root@tst" ], "description": "login for the VM", "jump_proxy": { "host": "tripwire.net.in.tum.de", "port": 10022, "username": "proxy", "host_keys": [ "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDG0Lq9tQhNNac9cAakdBSPzZ4/jYgRWIJ+0liG9c+c59LJltwOwnPYdOWvLv5HsN391E/fUXojP61vipOrMyuIipiEhOLhjAzP5b5R1WW3xWXWn+BWoXfBocPqPk2V/86aruTACox5CN4etwHWtuxkREyHfteVUhbcFHzNm0qYBQnLXDQxkuZOFbVTnYqRXh3pX0PuncJmwBB4IGE15JKs+A3WBFp1vz1Q2UvtH34rY8rmfKW80fFlzqnJqQun8gAHDfIl0Qa3QTxnecaocLolds5NGwHY7QRzW3yA7FirauZEaMho9Mbm9TRNNlEIR043amlrlukbMRLf/KqoyHJH7NZDXSwkcLrjOn5QsuliCbGbhrYlWux/y61719QKyn2XnuDpuOx1eM/7Kk+oHZhgPK6WgdMWmCBU3LaGfzjaCLMvED4lfBgT1nALTsE3KE2cvzbntlb/fHydevrKMmNrc1Em7WXYtGBjXuVs1G/fYAX9IrE8q6jXrSwO9FSZaZU=", "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBCKHIhTPdwDCtXQPH2/CR3MUzZUl3W6WNCrEBxka53WgjdfFtLko21enKbGYxI+POwDdE5sE6eIvjKDsZtFeA2Q=", "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIA8fyPxzW+ozU5ooygHANHFMVUA2xwnFU1TA5eoF3Xef" ], "description": "login for the JumpHost", "jump_proxy": null } } ], "status": "up", "terminated_at": null, "userdata": "#!/bin/sh\necho \"Hello World. The time is now $(date -R)!\" | tee '/user_data_output.txt'", "console_url": null, "machine_id": null, "failure_reason": null, "infra_id": "de-munich-bi-vm", "machine_name": null } ] 2026-03-15 16:11:49.327271 CET - Error in test step 'Validate resources': "slices bi list-resources" returned resource with expires_at not around the time the test expected the resource to expire: expires_at=2026-03-15 17:05:00+00:00 test expected expiration around 2026-03-15 17:08:43.166074+00:00 2026-03-15 16:11:49.327285 CET - Step 'Validate resources' took 60.72 seconds, which is longer than the warning threshold of 5 seconds 2026-03-15 16:11:49.327301 CET - Check if resources are registered in experiment 2026-03-15 16:11:49.327435 CET - Run: slices experiment list-resources --format json tst-e8a8bcea 2026-03-15 16:11:49.927497 CET - Status (on expauth): UP 2026-03-15 16:11:49.927639 CET - Testing extend expires_at (all resources in experiment) 2026-03-15 16:11:49.927774 CET - Run: slices bi --infra de-munich-bi-vm extend --duration 3h --experiment tst-e8a8bcea 2026-03-15 16:16:52.048592 CET - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-e8a8bcea tst 2026-03-15 16:17:52.823354 CET - Testing extend expires_at (single resource in experiment) 2026-03-15 16:17:52.823535 CET - Run: slices bi --infra de-munich-bi-vm extend tst --duration 4h --experiment tst-e8a8bcea 2026-03-15 16:22:54.900630 CET - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-e8a8bcea tst 2026-03-15 16:23:56.935128 CET - Step 'Check extend expire' took 727.01 seconds, which is longer than the warning threshold of 40 seconds 2026-03-15 16:23:56.935184 CET - Testing ssh login 2026-03-15 16:23:56.945062 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy on --show ssh_config --experiment tst-e8a8bcea tst 2026-03-15 16:24:57.798614 CET - Step 'Ssh info ssh_config' took 60.85 seconds, which is longer than the warning threshold of 5 seconds 2026-03-15 16:24:57.798833 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e8a8bcea tst 2026-03-15 16:25:58.626394 CET - Step 'Check SSH pubkey (resource)' took 60.83 seconds, which is longer than the warning threshold of 5 seconds 2026-03-15 16:25:58.626603 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-e8a8bcea tst 2026-03-15 16:26:59.543489 CET - Step 'Check SSH pubkey (proxy)' took 60.92 seconds, which is longer than the warning threshold of 5 seconds 2026-03-15 16:26:59.543588 CET - Logging in using 'slices bi ssh' 2026-03-15 16:26:59.543611 CET - Forcing IPv4 only. 2026-03-15 16:26:59.543745 CET - Run: slices bi --infra de-munich-bi-vm ssh --show nothing --experiment tst-e8a8bcea tst -- -4 uname -a 2026-03-15 16:28:02.426066 CET - Forcing IPv4 only. 2026-03-15 16:28:02.426213 CET - Run: slices bi --infra de-munich-bi-vm ssh --show nothing --experiment tst-e8a8bcea tst -- -4 uptime 2026-03-15 16:29:05.076339 CET - CLI SSH Test passed. 2026-03-15 16:29:05.076380 CET - 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-03-15 16:29:05.076390 CET - Uptime: 15:29:05 up 19 min, 1 user, load average: 0.00, 0.00, 0.00 2026-03-15 16:29:05.076410 CET - Step 'Ssh login (via slices-cli, IPv4 only)' took 125.53 seconds, which is longer than the warning threshold of 20 seconds 2026-03-15 16:29:05.076428 CET - Logging in using SSH over SSH proxy 2026-03-15 16:29:05.076564 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-e8a8bcea tst 2026-03-15 16:30:05.933188 CET - Step 'Ssh info proxy_pubkey_openssh' took 60.86 seconds, which is longer than the warning threshold of 5 seconds 2026-03-15 16:30:05.933632 CET - Added paramiko HostKeyEntry for 172.30.255.3 2026-03-15 16:30:05.933699 CET - Added paramiko HostKeyEntry for 172.30.255.3 2026-03-15 16:30:05.933829 CET - Added paramiko HostKeyEntry for 172.30.255.3 2026-03-15 16:30:05.934082 CET - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-03-15 16:30:05.934154 CET - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-03-15 16:30:05.934186 CET - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-03-15 16:30:05.934195 CET - Connecting to proxy tripwire.net.in.tum.de:10022 2026-03-15 16:30:06.708195 CET - Connecting to 172.30.255.3:22 over proxy 2026-03-15 16:30:08.093407 CET - SSH Test output: 2026-03-15 16:30:08.093451 CET - 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-03-15 16:30:08.093460 CET - Uptime: 15:30:07 up 21 min, 1 user, load average: 0.00, 0.00, 0.00 2026-03-15 16:30:08.261335 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-03-15 16:30:08.261385 CET - lsb_release matches expected value 2026-03-15 16:30:08.261396 CET - SSH Test passed. 2026-03-15 16:30:08.428743 CET - Cloud-init user-data: Hello World. The time is now Sun, 15 Mar 2026 15:09:47 +0000! 2026-03-15 16:30:08.434906 CET - Destroying tst-e8a8bcea tst 2026-03-15 16:30:08.435087 CET - Run: slices bi --infra de-munich-bi-vm destroy --experiment tst-e8a8bcea tst 2026-03-15 16:33:09.766860 CET - Step 'Destroy' took 181.33 seconds, which is longer than the warning threshold of 15 seconds 2026-03-15 16:33:09.766918 CET - Wait 2s before retry 2026-03-15 16:36:12.872985 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra de-munich-bi-vm destroy --experiment tst-e8a8bcea tst 2026-03-15 16:36:12.873078 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/destroy_retry1.txt': Deleting tst: task starting # task_id='c4b45c32873c4b8ebb3f5e1a465e21bb' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_de-munich-bi-vm_01kks0np4zewsrscq9jcwpt8n8 Failure: INTERNAL_ERROR task_id='c4b45c32873c4b8ebb3f5e1a465e21bb' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019cf20a-d89f-7733-8cb2-e993396d22a8'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-03-15T15:36:12+00:00.txt 2026-03-15 16:36:12.873100 CET - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-03-15 16:36:12.873119 CET - Step 'Destroy' took 181.11 seconds, which is longer than the warning threshold of 15 seconds 2026-03-15 16:36:12.873138 CET - Wait 2s before retry 2026-03-15 16:39:16.034183 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra de-munich-bi-vm destroy --experiment tst-e8a8bcea tst 2026-03-15 16:39:16.034261 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/destroy_retry2.txt': Deleting tst: task starting # task_id='32c18dfdf26845ceae287c43f294346a' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_de-munich-bi-vm_01kks0np4zewsrscq9jcwpt8n8 Failure: INTERNAL_ERROR task_id='32c18dfdf26845ceae287c43f294346a' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019cf20a-d89f-7733-8cb2-e993396d22a8'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-03-15T15:39:15+00:00.txt 2026-03-15 16:39:16.034279 CET - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-03-15 16:39:16.034294 CET - Step 'Destroy' took 181.16 seconds, which is longer than the warning threshold of 15 seconds 2026-03-15 16:39:16.034309 CET - Wait 2s before retry 2026-03-15 16:42:19.144334 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra de-munich-bi-vm destroy --experiment tst-e8a8bcea tst 2026-03-15 16:42:19.144409 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6e436220-215a-40fa-a4fd-190ef1336d74/destroy_retry3.txt': Deleting tst: task starting # task_id='3285e19fd1a849bb93bcce7acb8f8166' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_de-munich-bi-vm_01kks0np4zewsrscq9jcwpt8n8 Failure: INTERNAL_ERROR task_id='3285e19fd1a849bb93bcce7acb8f8166' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019cf20a-d89f-7733-8cb2-e993396d22a8'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-03-15T15:42:19+00:00.txt 2026-03-15 16:42:19.144445 CET - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1) 2026-03-15 16:42:19.144461 CET - Step 'Destroy' took 181.11 seconds, which is longer than the warning threshold of 15 seconds