Run Test: slices-bi-singlenode-login 2026-03-02 23:02:08.418334 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-02 23:02:11.442926 CET - Run: slices --version 2026-03-02 23:02:11.618436 CET - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib bi v5.1.1 Slices clientlib core v5.3.8 2026-03-02 23:02:11.618677 CET - Run: slices pubkey list --format text 2026-03-02 23:02:12.154082 CET - Pubkey already registered 2026-03-02 23:02:12.154297 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-02 23:02:12.676949 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-02 23:02:12.677052 CET - Check List Flavors 2026-03-02 23:02:12.677239 CET - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-03-02 23:02:16.520436 CET - Check List DiskImages 2026-03-02 23:02:16.520710 CET - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-03-02 23:02:17.232013 CET - Requesting resources 2026-03-02 23:02:17.232619 CET - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-82b02018 --user-data /tmp/tmp5akrl868 2026-03-02 23:02:21.037970 CET - Resource ID: r_gr-volos-bi-vm1_01kjr95q8deq9r4aap3wsd4et3 2026-03-02 23:02:21.038176 CET - Waiting until resource ready 2026-03-02 23:02:23.038663 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-82b02018 tst 2026-03-02 23:02:23.831690 CET - Status: IMAGING 2026-03-02 23:02:26.615909 CET - Status: IMAGING 2026-03-02 23:02:29.434852 CET - Status: IMAGING 2026-03-02 23:02:32.224423 CET - Status: IMAGING 2026-03-02 23:02:35.055906 CET - Status: BOOTING 2026-03-02 23:02:37.848237 CET - Status: BOOTING 2026-03-02 23:02:40.658619 CET - Status: BOOTING 2026-03-02 23:02:43.506755 CET - Status: BOOTING 2026-03-02 23:02:46.352035 CET - Status: BOOTING 2026-03-02 23:02:49.200571 CET - Status: BOOTING 2026-03-02 23:02:52.059394 CET - Status: BOOTING 2026-03-02 23:02:54.874625 CET - Status: BOOTING 2026-03-02 23:02:57.657811 CET - Status: BOOTING 2026-03-02 23:03:00.436256 CET - Status: BOOTING 2026-03-02 23:03:03.222471 CET - Status: BOOTING 2026-03-02 23:03:05.999665 CET - Status: BOOTING 2026-03-02 23:03:08.791429 CET - Status: BOOTING 2026-03-02 23:03:11.635135 CET - Status: BOOTING 2026-03-02 23:03:14.514920 CET - Status: BOOTING 2026-03-02 23:03:17.310841 CET - Status: BOOTING 2026-03-02 23:03:20.093764 CET - Status: BOOTING 2026-03-02 23:03:22.885801 CET - Status: BOOTING 2026-03-02 23:03:25.729797 CET - Status: BOOTING 2026-03-02 23:03:28.576583 CET - Status: BOOTING 2026-03-02 23:03:31.409058 CET - Status: BOOTING 2026-03-02 23:03:34.194240 CET - Status: BOOTING 2026-03-02 23:03:36.967048 CET - Status: BOOTING 2026-03-02 23:03:39.747722 CET - Status: BOOTING 2026-03-02 23:03:42.531514 CET - Status: BOOTING 2026-03-02 23:03:45.323430 CET - Status: BOOTING 2026-03-02 23:03:48.132138 CET - Status: BOOTING 2026-03-02 23:03:50.931057 CET - Status: BOOTING 2026-03-02 23:03:53.721374 CET - Status: BOOTING 2026-03-02 23:03:56.517676 CET - Status: BOOTING 2026-03-02 23:03:59.299719 CET - Status: BOOTING 2026-03-02 23:04:02.157373 CET - Status: BOOTING 2026-03-02 23:04:04.949950 CET - Status: BOOTING 2026-03-02 23:04:07.730514 CET - Status: BOOTING 2026-03-02 23:04:10.521897 CET - Status: BOOTING 2026-03-02 23:04:13.317334 CET - Status: BOOTING 2026-03-02 23:04:16.104899 CET - Status: BOOTING 2026-03-02 23:04:18.899943 CET - Status: BOOTING 2026-03-02 23:04:21.747332 CET - Status: UP 2026-03-02 23:04:21.747398 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kjr95mpheykvpsk1y6j3pd5q 2026-03-02 23:04:21.747449 CET - Check if resources are registered in experiment 2026-03-02 23:04:21.747650 CET - Run: slices experiment list-resources --format json tst-82b02018 2026-03-02 23:04:22.385035 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:24.974311 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:27.612067 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:30.267975 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:32.925724 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:35.518980 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:38.101825 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:40.690852 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:43.338966 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:45.995721 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:48.695507 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:51.289947 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:53.881018 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:56.497514 CET - Status (on expauth): INACTIVE 2026-03-02 23:04:59.100253 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:01.767439 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:04.448437 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:07.086199 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:09.735138 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:12.348190 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:14.946849 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:17.549151 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:20.193354 CET - Status (on expauth): INACTIVE 2026-03-02 23:05:22.193553 CET - "slices experiment list-resources" was not ready immediately. 2026-03-02 23:05:22.193609 CET - It was ready after: 60.446049 seconds. (Note: used wait steps of 2s) 2026-03-02 23:05:22.194834 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/792af414-6607-4c0d-95ec-30084783d8a3/step_Checkexperimentprint-resources_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices experiment list-resources --format json tst-82b02018 2026-03-02 23:05:22.194895 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/792af414-6607-4c0d-95ec-30084783d8a3/experiment-list-resources.txt': [ { "id": "r_gr-volos-bi-vm1_01kjr95q8deq9r4aap3wsd4et3", "created_at": "2026-03-02T22:02:20.809897Z", "created_by": "user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g", "experiment_id": "exp_expauth.ilabt.imec.be_01kjr95mpheykvpsk1y6j3pd5q", "friendly_name": "tst", "project_id": "proj_account.ilabt.imec.be_5pzabws7n79ydsagfg3vntg590", "service_id": "bi", "infra_id": "gr-volos-bi-vm1", "status": "inactive", "type": "vm", "description": "Minimal VM", "expires_at": "2026-03-03T00:02:00Z", "terminated_at": null, "terminated_by": null } ] 2026-03-02 23:05:22.194911 CET - Error in test step 'Check experiment print-resources': "slices experiment list-resources" returned resource in unexpected state: INACTIVE 2026-03-02 23:05:22.194986 CET - Testing extend expires_at (all resources in experiment) 2026-03-02 23:05:22.195077 CET - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-82b02018 2026-03-02 23:05:34.543654 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/792af414-6607-4c0d-95ec-30084783d8a3/step_Checkextendexpire_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-82b02018 2026-03-02 23:05:34.543766 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/792af414-6607-4c0d-95ec-30084783d8a3/experiment-extend-all.txt': Extending Experiment container from 2026-03-03 01:02 CET to 2026-03-03 02:05 CET... Extending the resources only on infrastructure gr-volos-bi-vm1. Use slices bi experiment list-resources to verify other infrastructures. ♻ Resources on infrastructure gr-volos-bi-vm1 now expire on 2026-03-03 02:05 CET. ..... Infrastructure gr-volos-bi-vm1 reported successful resource extend. An additional resource status check indicated that the extend was actually not successful! ❌ Please contact support with the following details: Experiment ID: exp_expauth.ilabt.imec.be_01kjr95mpheykvpsk1y6j3pd5q Requested Expire: 2026-03-03 02:05 CET Actual Experiment Expire: 2026-03-03 02:05 CET ✔ Overview of extended resources in experiment tst-82b02018 ┏━━━━━━━━┳━━━━━━━━┳━━━━━━━━┳━━━━━━┳━━━━━━━━┳━━━━━━━━┳━━━━━━━━┳━━━━━━━━┳━━━━━━━━┓ ┃ ┃ ┃ ┃ ┃ ┃ ┃ ┃ Expir… ┃ Expir… ┃ ┃ ┃ Frien… ┃ ┃ ┃ ┃ ┃ Creat… ┃ At ┃ At ┃ ┃ ID ┃ Name ┃ Infra ┃ Type ┃ Status ┃ Descr… ┃ At ┃ (BI) ┃ (ExpA… ┃ ┡━━━━━━━━╇━━━━━━━━╇━━━━━━━━╇━━━━━━╇━━━━━━━━╇━━━━━━━━╇━━━━━━━━╇━━━━━━━━╇━━━━━━━━┩ │ r_gr-… │ tst │ gr-vo… │ vm │ inact… │ Minim… │ 2026-… │ 2026-… │ 2026-… │ │ │ │ │ │ │ VM │ 23:02 │ 02:05 │ 01:02 │ │ │ │ │ │ │ │ CET │ CET ✔ │ CET ❌ │ └────────┴────────┴────────┴──────┴────────┴────────┴────────┴────────┴────────┘ Details stored at: /root/.slices/cli_error_details/err_2026-03-02T22:05:34+00:00.txt 2026-03-02 23:05:34.543824 CET - Error in test step 'Check extend expire': "slices bi extend" failed 2026-03-02 23:05:34.543850 CET - Testing ssh login 2026-03-02 23:05:34.554414 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-82b02018 tst 2026-03-02 23:05:35.465746 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-82b02018 tst 2026-03-02 23:05:36.419248 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-82b02018 tst 2026-03-02 23:05:37.349799 CET - Logging in using 'slices bi ssh' 2026-03-02 23:05:37.349868 CET - Forcing IPv4 only. 2026-03-02 23:05:37.350032 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-82b02018 tst -- -4 uname -a 2026-03-02 23:05:38.473369 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/792af414-6607-4c0d-95ec-30084783d8a3/step_Sshloginviaslices-cliIPv4only_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-82b02018 tst -- -4 uname -a 2026-03-02 23:05:38.473468 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/792af414-6607-4c0d-95ec-30084783d8a3/cli_ssh_ipv4_uname_stderr.txt': Host key verification failed. kex_exchange_identification: Connection closed by remote host Connection closed by UNKNOWN port 65535 2026-03-02 23:05:38.473487 CET - Error in test step 'Ssh login (via slices-cli, IPv4 only)': command returned an error: ['/opt/slices-monitor-worker/slices-cli-venv/bin/slices', 'bi', '--infra', 'gr-volos-bi-vm1', 'ssh', '--show', 'nothing', '--experiment', 'tst-82b02018', 'tst', '--', '-4', 'uname', '-a'] 2026-03-02 23:05:38.473643 CET - Run: ssh-keygen -F 10.64.45.224 2026-03-02 23:05:38.477238 CET - Run: ssh-keygen -F 10.64.45.224:22 2026-03-02 23:05:38.480363 CET - Run: ssh-keygen -F 194.177.207.42 2026-03-02 23:05:38.483328 CET - Run: ssh-keygen -F 194.177.207.42:11122 2026-03-02 23:05:38.486225 CET - Logging in using SSH over SSH proxy 2026-03-02 23:05:38.486363 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-82b02018 tst 2026-03-02 23:05:39.382671 CET - Added paramiko HostKeyEntry for 10.64.45.224 2026-03-02 23:05:39.382764 CET - Added paramiko HostKeyEntry for 10.64.45.224 2026-03-02 23:05:39.382897 CET - Added paramiko HostKeyEntry for 10.64.45.224 2026-03-02 23:05:39.383139 CET - Added paramiko HostKeyEntry for 194.177.207.42 2026-03-02 23:05:39.383156 CET - Connecting to proxy 194.177.207.42:22 2026-03-02 23:05:41.385958 CET - Error in test step 'Ssh login (with proxy)': timed out 2026-03-02 23:05:41.386009 CET - Wait 5s before retry 2026-03-02 23:05:46.386344 CET - Added paramiko HostKeyEntry for 10.64.45.224 2026-03-02 23:05:46.386447 CET - Added paramiko HostKeyEntry for 10.64.45.224 2026-03-02 23:05:46.386567 CET - Added paramiko HostKeyEntry for 10.64.45.224 2026-03-02 23:05:46.386845 CET - Added paramiko HostKeyEntry for 194.177.207.42 2026-03-02 23:05:46.386859 CET - Connecting to proxy 194.177.207.42:22 2026-03-02 23:05:48.389742 CET - Error in test step 'Ssh login (with proxy) (Retry 1)': timed out 2026-03-02 23:05:48.395925 CET - Destroying tst-82b02018 tst 2026-03-02 23:05:48.396078 CET - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-82b02018 tst