Run Test: slices-bi-singlenode-login 2026-03-12 23:00:43.708194 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-12 23:00:46.737155 CET - Run: slices --version 2026-03-12 23:00:46.917984 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-12 23:00:46.918254 CET - Run: slices pubkey list --format text 2026-03-12 23:00:47.624094 CET - Pubkey already registered 2026-03-12 23:00:47.624321 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-12 23:00:48.117073 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-12 23:00:48.117169 CET - Check List Flavors 2026-03-12 23:00:48.117339 CET - Run: slices bi --infra de-munich-bi-vm flavor list -f json 2026-03-12 23:01:49.236945 CET - Step 'List Flavors' took 61.12 seconds, which is longer than the warning threshold of 5 seconds 2026-03-12 23:01:49.237009 CET - Check List DiskImages 2026-03-12 23:01:49.237181 CET - Run: slices bi --infra de-munich-bi-vm diskimage list -f json 2026-03-12 23:02:49.884315 CET - Step 'List DiskImages' took 60.65 seconds, which is longer than the warning threshold of 5 seconds 2026-03-12 23:02:49.884364 CET - Requesting resources 2026-03-12 23:02:49.884805 CET - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-03-12 23:02:49.884892 CET - Run: slices bi --infra de-munich-bi-vm create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-6063b961 --user-data /tmp/tmplk9gfri2 2026-03-12 23:05:53.306073 CET - Resource ID: r_de-munich-bi-vm_01kkj1bcm0ezs89r0553fbdhqt 2026-03-12 23:05:53.306233 CET - Step 'Requesting resource' took 183.42 seconds, which is longer than the warning threshold of 30 seconds 2026-03-12 23:05:53.306261 CET - Waiting until resource ready 2026-03-12 23:05:55.306661 CET - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-6063b961 tst 2026-03-12 23:06:56.098031 CET - Status: UP 2026-03-12 23:06:56.098073 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kkj15tn5eex91veqtfzcppqb 2026-03-12 23:06:56.098107 CET - Validate resources 2026-03-12 23:07:56.933010 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/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-6063b961 tst 2026-03-12 23:07:56.933101 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-03-12T22:05:53.143271Z", "disk_image_id": "image_de-munich-bi-vm_01kjceghcff5gbwgxyt40nkj7a", "experiment_id": "exp_expauth.ilabt.imec.be_01kkj15tn5eex91veqtfzcppqb", "expires_at": "2026-03-13T00:02:00Z", "flavor_id": "flavor_de-munich-bi-vm_01kjceghd0fh1vh08ygdp4hrn9", "friendly_name": "tst", "id": "r_de-munich-bi-vm_01kkj1bcm0ezs89r0553fbdhqt", "network_interfaces": [], "private_ipv4": "131.159.14.96", "private_ipv6": null, "public_ipv4": null, "public_ipv6": null, "ssh_authorized_keys": [ "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAINNiZPtQkfCEsc1x2QSwNWekfhWwWZbOvpE8hE0dH253 Wim (ftester) Van de Meerssche" ], "ssh_logins": [ { "host": "131.159.14.96", "port": 22, "username": "ftester", "host_keys": [ "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBA9X/ghLSQycjetbOR7rvv3vibEi4GOVhiNPn64F6dU7XNEweKdlGVUAKIlpZc3JuqgkgA03A5ptq2KWz/KCCEM= root@tst", "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILMpSBmhMV9O4kBvCpOnjIyXGzKxEQtIavkxOI86m16t root@tst", "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDKN2OmuQU0wLHkDOdfPG5vCsrM65U8Fzy9OJ/Ki4URWv8yva9hPRfIcFAbPMmf+BA7rZ48Y+1AwPLl8/2U8ckVMxiujBgKqNudiBzfiUQFQGBsKk0654vQRaiVRsoPXOjStfG7I/b1MTMERaEpv7/pKzgQm+cY/YGhWWQq6iiyIvBYT4wP/1iQNy3fbU86mLNNApC+rJRV/qHCrWIJPmzxsIxtm3LM/WuuNV/TM7+Z6EGVkLBf8Lee61CMTLEwVJisImYNA6UUR1Ntl3+h/T3b2yZU8iXlXPw2lDMbnc/2K+PPvPz8PG2FIY2RBlf8e3zEyIR6P40dSlHGB2bN4SvFG0NNcx7pauKGxW/+rDTioZ3FF0Hy/pmscnQZfv5/yphC6qMe2fTof3SUw1ZRjYvgETTBm14U4qFSBh+DORtYjMVb7s8+NlDOWPDaWxn7eF4siTxqy6XQ5JseXtcb1fqGUR2p1jElX1BxS8dPrUYY+z9mkgnAYXBdsbK05ZqMzZc= 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-12 23:07:56.933120 CET - Error in test step 'Validate resources': The resource has a private IPv4 address, but it is NOT a private address: 131.159.14.96 2026-03-12 23:07:56.933136 CET - Step 'Validate resources' took 60.84 seconds, which is longer than the warning threshold of 5 seconds 2026-03-12 23:07:56.933153 CET - Check if resources are registered in experiment 2026-03-12 23:07:56.933279 CET - Run: slices experiment list-resources --format json tst-6063b961 2026-03-12 23:07:57.587312 CET - Status (on expauth): UP 2026-03-12 23:07:57.587434 CET - Testing extend expires_at (all resources in experiment) 2026-03-12 23:07:57.587565 CET - Run: slices bi --infra de-munich-bi-vm extend --duration 3h --experiment tst-6063b961 2026-03-12 23:12:59.541230 CET - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-6063b961 tst 2026-03-12 23:14:00.492299 CET - Testing extend expires_at (single resource in experiment) 2026-03-12 23:14:00.492534 CET - Run: slices bi --infra de-munich-bi-vm extend tst --duration 4h --experiment tst-6063b961 2026-03-12 23:19:02.730979 CET - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-6063b961 tst 2026-03-12 23:20:03.498486 CET - Step 'Check extend expire' took 725.91 seconds, which is longer than the warning threshold of 40 seconds 2026-03-12 23:20:03.498540 CET - Testing ssh login 2026-03-12 23:20:03.508221 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy on --show ssh_config --experiment tst-6063b961 tst 2026-03-12 23:21:04.340656 CET - Step 'Ssh info ssh_config' took 60.83 seconds, which is longer than the warning threshold of 5 seconds 2026-03-12 23:21:04.340895 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-6063b961 tst 2026-03-12 23:22:05.168353 CET - Step 'Check SSH pubkey (resource)' took 60.83 seconds, which is longer than the warning threshold of 5 seconds 2026-03-12 23:22:05.168637 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-6063b961 tst 2026-03-12 23:23:11.163091 CET - Step 'Check SSH pubkey (proxy)' took 65.99 seconds, which is longer than the warning threshold of 5 seconds 2026-03-12 23:23:11.163232 CET - Logging in using 'slices bi ssh' 2026-03-12 23:23:11.163257 CET - Forcing IPv4 only. 2026-03-12 23:23:11.163419 CET - Run: slices bi --infra de-munich-bi-vm ssh --show nothing --experiment tst-6063b961 tst -- -4 uname -a 2026-03-12 23:24:13.948673 CET - Forcing IPv4 only. 2026-03-12 23:24:13.948850 CET - Run: slices bi --infra de-munich-bi-vm ssh --show nothing --experiment tst-6063b961 tst -- -4 uptime 2026-03-12 23:25:16.437916 CET - CLI SSH Test passed. 2026-03-12 23:25:16.437973 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-12 23:25:16.437983 CET - Uptime: 22:25:16 up 19 min, 1 user, load average: 0.00, 0.00, 0.00 2026-03-12 23:25:16.438010 CET - Step 'Ssh login (via slices-cli, IPv4 only)' took 125.27 seconds, which is longer than the warning threshold of 20 seconds 2026-03-12 23:25:16.438032 CET - Logging in using SSH over SSH proxy 2026-03-12 23:25:16.438209 CET - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-6063b961 tst 2026-03-12 23:26:17.421101 CET - Step 'Ssh info proxy_pubkey_openssh' took 60.98 seconds, which is longer than the warning threshold of 5 seconds 2026-03-12 23:26:17.421567 CET - Added paramiko HostKeyEntry for 131.159.14.96 2026-03-12 23:26:17.421631 CET - Added paramiko HostKeyEntry for 131.159.14.96 2026-03-12 23:26:17.421764 CET - Added paramiko HostKeyEntry for 131.159.14.96 2026-03-12 23:26:17.422038 CET - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-03-12 23:26:17.422111 CET - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-03-12 23:26:17.422144 CET - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-03-12 23:26:17.422153 CET - Connecting to proxy tripwire.net.in.tum.de:10022 2026-03-12 23:26:18.148568 CET - Connecting to 131.159.14.96:22 over proxy 2026-03-12 23:26:19.446781 CET - SSH Test output: 2026-03-12 23:26:19.446824 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-12 23:26:19.446835 CET - Uptime: 22:26:19 up 20 min, 1 user, load average: 0.00, 0.00, 0.00 2026-03-12 23:26:19.599683 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-03-12 23:26:19.599739 CET - lsb_release matches expected value 2026-03-12 23:26:19.599750 CET - SSH Test passed. 2026-03-12 23:26:19.752505 CET - Cloud-init user-data: Hello World. The time is now Thu, 12 Mar 2026 22:06:50 +0000! 2026-03-12 23:26:19.758920 CET - Destroying tst-6063b961 tst 2026-03-12 23:26:19.759101 CET - Run: slices bi --infra de-munich-bi-vm destroy --experiment tst-6063b961 tst 2026-03-12 23:29:21.016066 CET - Step 'Destroy' took 181.26 seconds, which is longer than the warning threshold of 15 seconds 2026-03-12 23:29:21.016127 CET - Wait 2s before retry 2026-03-12 23:32:28.299242 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra de-munich-bi-vm destroy --experiment tst-6063b961 tst 2026-03-12 23:32:28.299353 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/destroy_retry1.txt': Deleting tst ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ ╭───────────────────── Traceback (most recent call last) ──────────────────────╮ │ /opt/slices-monitor-worker/slices-cli-venv/lib/python3.14/site-packages/slic │ │ es_clientlib_bi/bi_task.py:71 in get_tasks │ │ │ │ 68 │ │ │ params={"task_ids": task_ids}, │ │ 69 │ │ ) │ │ 70 │ │ try: │ │ ❱ 71 │ │ │ response.raise_for_status() │ │ 72 │ │ except HTTPError as err: │ │ 73 │ │ │ raise from_httpx_exception(err) from err │ │ 74 │ │ return _tasks_status_decoder.decode(response.content) │ │ │ │ /opt/slices-monitor-worker/slices-cli-venv/lib/python3.14/site-packages/http │ │ x/_models.py:829 in raise_for_status │ │ │ │ 826 │ │ } │ │ 827 │ │ error_type = error_types.get(status_class, "Invalid status co │ │ 828 │ │ message = message.format(self, error_type=error_type) │ │ ❱ 829 │ │ raise HTTPStatusError(message, request=request, response=self │ │ 830 │ │ │ 831 │ def json(self, **kwargs: typing.Any) -> typing.Any: │ │ 832 │ │ return jsonlib.loads(self.content, **kwargs) │ ╰──────────────────────────────────────────────────────────────────────────────╯ HTTPStatusError: Server error '500 Internal Server Error' for url 'https://slices-bi.slices-de.eu/de-munich-bi-vm/slices-bi/apis/bi.slices.eu/v1/t asks/?task_ids=task_de-munich-bi-vm_39v8wyytxk8dd8sfpxsqskf0q0' For more information check: https://developer.mozilla.org/en-US/docs/Web/HTTP/Status/500 The above exception was the direct cause of the following exception: ╭───────────────────── Traceback (most recent call last) ──────────────────────╮ │ /opt/slices-monitor-worker/slices-cli-venv/lib/python3.14/site-packages/slic │ │ es_cli_bi/commands/bi_helpers.py:83 in wrapper_handle_some_exceptions │ │ │ │ 80 │ def wrapper_handle_some_exceptions(*args, **kwargs): │ │ 81 │ │ """Function wrapper for handle_some_exceptions.""" # noqa: D4 │ │ 82 │ │ try: │ │ ❱ 83 │ │ │ return func(*args, **kwargs) │ │ 84 │ │ except ApiResponseError as e: │ │ 85 │ │ │ if e._httpx_status_error.response.status_code in ( │ │ 86 │ │ │ │ 502, │ │ │ │ /opt/slices-monitor-worker/slices-cli-venv/lib/python3.14/site-packages/slic │ │ es_cli_bi/commands/bi.py:1318 in destroy_resource │ │ │ │ 1315 │ │ except Exception: │ │ 1316 │ │ │ err_console.print(f"Could not remove ssh host key for {re │ │ 1317 │ │ │ ❱ 1318 │ task_results = follow_progress( │ │ 1319 │ │ ctx.obj.bi_tasks_api, │ │ 1320 │ │ [task_id for _, task_id, _ in delete_task_info], │ │ 1321 │ │ [task_name for task_name, _, _ in delete_task_info], │ │ │ │ /opt/slices-monitor-worker/slices-cli-venv/lib/python3.14/site-packages/slic │ │ es_cli_bi/core/progress.py:86 in follow_progress │ │ │ │ 83 │ │ │ bi_task_status: list[TaskStatus] = [] │ │ 84 │ │ │ while tasks_to_query: │ │ 85 │ │ │ │ bi_task_status.extend( │ │ ❱ 86 │ │ │ │ │ bi_tasks_api.get_tasks( │ │ 87 │ │ │ │ │ │ task_ids=tasks_to_query[0:MAX_PROGRESS_UPDATES │ │ 88 │ │ │ │ │ ) │ │ 89 │ │ │ │ ) │ │ │ │ /opt/slices-monitor-worker/slices-cli-venv/lib/python3.14/site-packages/slic │ │ es_clientlib_bi/bi_task.py:73 in get_tasks │ │ │ │ 70 │ │ try: │ │ 71 │ │ │ response.raise_for_status() │ │ 72 │ │ except HTTPError as err: │ │ ❱ 73 │ │ │ raise from_httpx_exception(err) from err │ │ 74 │ │ return _tasks_status_decoder.decode(response.content) │ │ 75 │ │ │ 76 │ def get_task_logs( │ ╰──────────────────────────────────────────────────────────────────────────────╯ ServiceException: ServiceException (for GET https://slices-bi.slices-de.eu/de-munich-bi-vm/slices-bi/apis/bi.slices.eu/v1/ta sks/?task_ids=task_de-munich-bi-vm_39v8wyytxk8dd8sfpxsqskf0q0): Internal Server Error 2026-03-12 23:32:28.299397 CET - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-03-12 23:32:28.299415 CET - Step 'Destroy' took 185.28 seconds, which is longer than the warning threshold of 15 seconds 2026-03-12 23:32:28.299431 CET - Wait 2s before retry 2026-03-12 23:35:31.429876 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra de-munich-bi-vm destroy --experiment tst-6063b961 tst 2026-03-12 23:35:31.429982 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/destroy_retry2.txt': Deleting tst: task starting # task_id='53be10756d9441f69f83f64351bb70e2' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_de-munich-bi-vm_01kkj1bcm0ezs89r0553fbdhqt Failure: INTERNAL_ERROR task_id='53be10756d9441f69f83f64351bb70e2' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019ce415-b280-77f2-84e0-0528deb6c6fa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-03-12T22:35:31+00:00.txt 2026-03-12 23:35:31.430005 CET - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-03-12 23:35:31.430023 CET - Step 'Destroy' took 181.13 seconds, which is longer than the warning threshold of 15 seconds 2026-03-12 23:35:31.430043 CET - Wait 2s before retry 2026-03-12 23:38:34.633889 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra de-munich-bi-vm destroy --experiment tst-6063b961 tst 2026-03-12 23:38:34.633979 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5fc565fb-e5e5-40f9-8b7d-e241944bdf94/destroy_retry3.txt': Deleting tst: task starting # task_id='9ab0edc9f8644b2795a4b1a5f0b4c7ad' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_de-munich-bi-vm_01kkj1bcm0ezs89r0553fbdhqt Failure: INTERNAL_ERROR task_id='9ab0edc9f8644b2795a4b1a5f0b4c7ad' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019ce415-b280-77f2-84e0-0528deb6c6fa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-03-12T22:38:34+00:00.txt 2026-03-12 23:38:34.634002 CET - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1) 2026-03-12 23:38:34.634021 CET - Step 'Destroy' took 181.20 seconds, which is longer than the warning threshold of 15 seconds