Run Test: slices-bi-singlenode-login 2026-05-22 00:09:59.872705 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-22 00:10:03.251342 CEST - Run: slices --version 2026-05-22 00:10:03.719181 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.5.1 Slices clientlib bi v5.1.2 2026-05-22 00:10:03.719951 CEST - Run: slices pubkey list --format text 2026-05-22 00:10:04.890743 CEST - Pubkey already registered 2026-05-22 00:10:04.892011 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-22 00:10:05.911050 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-22 00:10:05.911182 CEST - Check List Flavors 2026-05-22 00:10:05.911480 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-05-22 00:10:07.381557 CEST - Check List DiskImages 2026-05-22 00:10:07.381857 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-05-22 00:10:08.652217 CEST - Requesting resources 2026-05-22 00:10:08.653019 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-05-22 00:10:08.653242 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-18d504cb --user-data /tmp/tmphxjsfyjq 2026-05-22 00:10:11.881301 CEST - Resource ID: r_gr-volos-bi-vm1_01ks69djx9ex88ae1atrcm1y18 2026-05-22 00:10:11.881532 CEST - Waiting until resource ready 2026-05-22 00:10:13.882172 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-18d504cb tst 2026-05-22 00:10:15.101617 CEST - Status: IMAGING 2026-05-22 00:10:18.321312 CEST - Status: IMAGING 2026-05-22 00:10:21.540782 CEST - Status: IMAGING 2026-05-22 00:10:25.061893 CEST - Status: BOOTING 2026-05-22 00:10:28.383687 CEST - Status: BOOTING 2026-05-22 00:10:31.803832 CEST - Status: BOOTING 2026-05-22 00:10:35.123984 CEST - Status: BOOTING 2026-05-22 00:10:38.651793 CEST - Status: BOOTING 2026-05-22 00:10:42.023896 CEST - Status: BOOTING 2026-05-22 00:10:45.496048 CEST - Status: BOOTING 2026-05-22 00:10:49.133146 CEST - Status: BOOTING 2026-05-22 00:10:52.655966 CEST - Status: BOOTING 2026-05-22 00:10:56.280368 CEST - Status: BOOTING 2026-05-22 00:10:59.808824 CEST - Status: BOOTING 2026-05-22 00:11:03.432492 CEST - Status: BOOTING 2026-05-22 00:11:07.118779 CEST - Status: BOOTING 2026-05-22 00:11:10.692327 CEST - Status: BOOTING 2026-05-22 00:11:14.014521 CEST - Status: BOOTING 2026-05-22 00:11:17.539920 CEST - Status: BOOTING 2026-05-22 00:11:20.859995 CEST - Status: BOOTING 2026-05-22 00:11:24.333443 CEST - Status: BOOTING 2026-05-22 00:11:27.706708 CEST - Status: BOOTING 2026-05-22 00:11:31.228427 CEST - Status: BOOTING 2026-05-22 00:11:34.850826 CEST - Status: BOOTING 2026-05-22 00:11:38.072543 CEST - Status: BOOTING 2026-05-22 00:11:41.494104 CEST - Status: BOOTING 2026-05-22 00:11:44.865505 CEST - Status: BOOTING 2026-05-22 00:11:48.388954 CEST - Status: BOOTING 2026-05-22 00:11:51.761730 CEST - Status: BOOTING 2026-05-22 00:11:54.882725 CEST - Status: BOOTING 2026-05-22 00:11:58.152019 CEST - Status: BOOTING 2026-05-22 00:12:01.272001 CEST - Status: BOOTING 2026-05-22 00:12:04.742630 CEST - Status: UP 2026-05-22 00:12:04.742696 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01ks69dhawfhf9p51s8dq29dva 2026-05-22 00:12:04.742758 CEST - Validate resources 2026-05-22 00:12:05.811858 CEST - The fields of the created resource were validated. 2026-05-22 00:12:05.811986 CEST - Check if resources are registered in experiment 2026-05-22 00:12:05.812287 CEST - Run: slices experiment list-resources --format json tst-18d504cb 2026-05-22 00:12:06.579239 CEST - Status (on expauth): UP 2026-05-22 00:12:06.579435 CEST - Testing extend expires_at (all resources in experiment) 2026-05-22 00:12:06.579623 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-18d504cb 2026-05-22 00:12:09.302842 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-18d504cb tst 2026-05-22 00:12:10.623046 CEST - Testing extend expires_at (single resource in experiment) 2026-05-22 00:12:10.623310 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-18d504cb 2026-05-22 00:12:13.749705 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-18d504cb tst 2026-05-22 00:12:15.623657 CEST - Testing ssh login 2026-05-22 00:12:15.652439 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-18d504cb tst 2026-05-22 00:12:17.527880 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-18d504cb tst 2026-05-22 00:12:19.403089 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-18d504cb tst 2026-05-22 00:12:21.075768 CEST - Logging in using 'slices bi ssh' 2026-05-22 00:12:21.075928 CEST - Forcing IPv4 only. 2026-05-22 00:12:21.076317 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-18d504cb tst -- -4 uname -a 2026-05-22 00:12:23.270509 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/9a27d317-d876-44a7-a2c0-77a3f1234e70/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-18d504cb tst -- -4 uname -a 2026-05-22 00:12:23.270646 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/9a27d317-d876-44a7-a2c0-77a3f1234e70/cli_ssh_ipv4_uname_stderr.txt': proxy@194.177.207.42: Permission denied (publickey). kex_exchange_identification: Connection closed by remote host Connection closed by UNKNOWN port 65535 2026-05-22 00:12:23.270681 CEST - 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-18d504cb', 'tst', '--', '-4', 'uname', '-a'] 2026-05-22 00:12:23.270939 CEST - Run: ssh-keygen -F 10.64.45.190 2026-05-22 00:12:23.291511 CEST - Run: ssh-keygen -F 10.64.45.190:22 2026-05-22 00:12:23.302115 CEST - Run: ssh-keygen -F 194.177.207.42 2026-05-22 00:12:23.312101 CEST - Run: ssh-keygen -F 194.177.207.42:11122 2026-05-22 00:12:23.322501 CEST - Logging in using SSH over SSH proxy 2026-05-22 00:12:23.323444 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-18d504cb tst 2026-05-22 00:12:25.352383 CEST - Added paramiko HostKeyEntry for 10.64.45.190 2026-05-22 00:12:25.352794 CEST - Added paramiko HostKeyEntry for 10.64.45.190 2026-05-22 00:12:25.353116 CEST - Added paramiko HostKeyEntry for 10.64.45.190 2026-05-22 00:12:25.354079 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-22 00:12:25.354305 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-22 00:12:25.354397 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-22 00:12:25.354423 CEST - Connecting to proxy 194.177.207.42:11122 2026-05-22 00:12:25.931186 CEST - Error in test step 'Ssh login (with proxy)': Authentication failed. 2026-05-22 00:12:25.931308 CEST - Wait 5s before retry 2026-05-22 00:12:30.932103 CEST - Added paramiko HostKeyEntry for 10.64.45.190 2026-05-22 00:12:30.932397 CEST - Added paramiko HostKeyEntry for 10.64.45.190 2026-05-22 00:12:30.932814 CEST - Added paramiko HostKeyEntry for 10.64.45.190 2026-05-22 00:12:30.933594 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-22 00:12:30.933874 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-22 00:12:30.933993 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-22 00:12:30.934026 CEST - Connecting to proxy 194.177.207.42:11122 2026-05-22 00:12:31.504459 CEST - Error in test step 'Ssh login (with proxy) (Retry 1)': Authentication failed. 2026-05-22 00:12:31.524953 CEST - Destroying tst-18d504cb tst 2026-05-22 00:12:31.525441 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-18d504cb tst