Run Test: slices-bi-singlenode-login 2026-06-20 08:09:13.189250 CEST - Run: /usr/local/bin/uv pip compile --upgrade /tmp/tmpqx8hkk5e.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-06-20 08:09:17.563077 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/e0dca57c-5a44-4a08-bac8-734a8abb0edb/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-06-20 08:09:32.456190 CEST - Run: slices --version 2026-06-20 08:09:34.373908 CEST - version: Slices CLI v2026.1.2 Slices CLI core v1.2.4 Slices CLI ai v1.1.0 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v1.0.0 Slices clientlib core v5.5.1 2026-06-20 08:09:34.374217 CEST - Run: slices pubkey list --format text 2026-06-20 08:09:34.939254 CEST - Pubkey already registered 2026-06-20 08:09:34.939477 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-06-20 08:09:38.514933 CEST - Refreshed infrastructure list. Total: 23 entries. 2026-06-20 08:09:38.515061 CEST - Check List Flavors 2026-06-20 08:09:38.515213 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-06-20 08:09:42.596881 CEST - Check List DiskImages 2026-06-20 08:09:42.597104 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-06-20 08:09:45.874342 CEST - Requesting resources 2026-06-20 08:09:45.874622 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-06-20 08:09:45.874721 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-14a9f9da --user-data /tmp/tmpdx18jt5n 2026-06-20 08:09:50.256325 CEST - Resource ID: r_be-gent1-bi-baremetal1_01kvhtcnywe68abswxh4955v5q 2026-06-20 08:09:50.256455 CEST - Waiting until resource ready 2026-06-20 08:09:52.256779 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-14a9f9da tst 2026-06-20 08:09:55.677113 CEST - Status: IMAGING 2026-06-20 08:10:01.049098 CEST - Status: IMAGING 2026-06-20 08:10:06.469968 CEST - Status: IMAGING 2026-06-20 08:10:10.989385 CEST - Status: IMAGING 2026-06-20 08:10:15.509145 CEST - Status: IMAGING 2026-06-20 08:10:20.030651 CEST - Status: IMAGING 2026-06-20 08:10:24.500145 CEST - Status: IMAGING 2026-06-20 08:10:29.173624 CEST - Status: IMAGING 2026-06-20 08:10:33.692477 CEST - Status: IMAGING 2026-06-20 08:10:38.261594 CEST - Status: IMAGING 2026-06-20 08:10:42.831005 CEST - Status: IMAGING 2026-06-20 08:10:47.200490 CEST - Status: IMAGING 2026-06-20 08:10:50.017015 CEST - Status: IMAGING 2026-06-20 08:10:52.783248 CEST - Status: IMAGING 2026-06-20 08:10:55.749578 CEST - Status: IMAGING 2026-06-20 08:10:58.515687 CEST - Status: IMAGING 2026-06-20 08:11:01.431876 CEST - Status: IMAGING 2026-06-20 08:11:04.197887 CEST - Status: IMAGING 2026-06-20 08:11:07.164564 CEST - Status: IMAGING 2026-06-20 08:11:09.981476 CEST - Status: IMAGING 2026-06-20 08:11:12.849256 CEST - Status: IMAGING 2026-06-20 08:11:15.565455 CEST - Status: IMAGING 2026-06-20 08:11:18.538973 CEST - Status: IMAGING 2026-06-20 08:11:21.255478 CEST - Status: IMAGING 2026-06-20 08:11:24.021230 CEST - Status: IMAGING 2026-06-20 08:11:26.737759 CEST - Status: IMAGING 2026-06-20 08:11:29.453438 CEST - Status: IMAGING 2026-06-20 08:11:32.169274 CEST - Status: IMAGING 2026-06-20 08:11:34.885326 CEST - Status: IMAGING 2026-06-20 08:11:37.651786 CEST - Status: IMAGING 2026-06-20 08:11:40.367572 CEST - Status: IMAGING 2026-06-20 08:11:43.134356 CEST - Status: IMAGING 2026-06-20 08:11:45.850360 CEST - Status: IMAGING 2026-06-20 08:11:48.616559 CEST - Status: IMAGING 2026-06-20 08:11:51.332435 CEST - Status: IMAGING 2026-06-20 08:11:54.148784 CEST - Status: IMAGING 2026-06-20 08:11:56.915642 CEST - Status: IMAGING 2026-06-20 08:11:59.681831 CEST - Status: IMAGING 2026-06-20 08:12:02.497934 CEST - Status: IMAGING 2026-06-20 08:12:05.314440 CEST - Status: IMAGING 2026-06-20 08:12:08.031392 CEST - Status: IMAGING 2026-06-20 08:12:10.797633 CEST - Status: IMAGING 2026-06-20 08:12:13.614485 CEST - Status: IMAGING 2026-06-20 08:12:16.330355 CEST - Status: IMAGING 2026-06-20 08:12:19.096481 CEST - Status: IMAGING 2026-06-20 08:12:21.812412 CEST - Status: IMAGING 2026-06-20 08:12:24.528201 CEST - Status: IMAGING 2026-06-20 08:12:27.244524 CEST - Status: IMAGING 2026-06-20 08:12:30.011212 CEST - Status: IMAGING 2026-06-20 08:12:32.727296 CEST - Status: IMAGING 2026-06-20 08:12:35.493711 CEST - Status: IMAGING 2026-06-20 08:12:38.209601 CEST - Status: IMAGING 2026-06-20 08:12:40.976560 CEST - Status: IMAGING 2026-06-20 08:12:43.743229 CEST - Status: IMAGING 2026-06-20 08:12:46.509705 CEST - Status: IMAGING 2026-06-20 08:12:49.225629 CEST - Status: IMAGING 2026-06-20 08:12:51.991833 CEST - Status: IMAGING 2026-06-20 08:12:54.708285 CEST - Status: IMAGING 2026-06-20 08:12:57.424559 CEST - Status: IMAGING 2026-06-20 08:13:00.191048 CEST - Status: STARTING 2026-06-20 08:13:02.957249 CEST - Status: UP 2026-06-20 08:13:02.957326 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kvhtcnazfzzbmjvep0jxzt3b 2026-06-20 08:13:02.957385 CEST - Validate resources 2026-06-20 08:13:03.723590 CEST - The fields of the created resource were validated. 2026-06-20 08:13:03.723686 CEST - Check if resources are registered in experiment 2026-06-20 08:13:03.723838 CEST - Run: slices experiment list-resources --format json tst-14a9f9da 2026-06-20 08:13:04.339209 CEST - Status (on expauth): UP 2026-06-20 08:13:04.339363 CEST - Testing extend expires_at (all resources in experiment) 2026-06-20 08:13:04.339498 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-14a9f9da 2026-06-20 08:13:09.365650 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-14a9f9da tst 2026-06-20 08:13:10.081406 CEST - Run: slices experiment list-resources --format json tst-14a9f9da 2026-06-20 08:13:10.696942 CEST - expires_at (on expauth): 2026-06-20T09:13:00Z (correctly extended) 2026-06-20 08:13:10.697043 CEST - Testing extend expires_at (single resource in experiment) 2026-06-20 08:13:10.697189 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-14a9f9da 2026-06-20 08:13:14.620714 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-14a9f9da tst 2026-06-20 08:13:15.336894 CEST - Run: slices experiment list-resources --format json tst-14a9f9da 2026-06-20 08:13:15.952155 CEST - expires_at (on expauth): 2026-06-20T10:13:00Z (correctly extended) 2026-06-20 08:13:15.952263 CEST - Testing ssh login 2026-06-20 08:13:15.962329 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-14a9f9da tst 2026-06-20 08:13:16.677883 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-14a9f9da tst 2026-06-20 08:13:17.343441 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-14a9f9da tst 2026-06-20 08:13:18.008964 CEST - Logging in using 'slices bi ssh' 2026-06-20 08:13:18.009059 CEST - Forcing IPv4 only. 2026-06-20 08:13:18.009206 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-14a9f9da tst -- -4 uname -a 2026-06-20 08:13:18.874901 CEST - Forcing IPv4 only. 2026-06-20 08:13:18.875105 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-14a9f9da tst -- -4 uptime 2026-06-20 08:13:19.690997 CEST - CLI SSH Test passed. 2026-06-20 08:13:19.691107 CEST - Uname: Linux tst.fhhnvo.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-06-20 08:13:19.691133 CEST - Uptime: 08:13:19 up 1 min, 0 users, load average: 0.07, 0.03, 0.01 2026-06-20 08:13:19.691173 CEST - Logging in using 'slices bi ssh' 2026-06-20 08:13:19.691232 CEST - Forcing IPv6 only. 2026-06-20 08:13:19.691400 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-14a9f9da tst -- -6 uname -a 2026-06-20 08:13:20.557378 CEST - Forcing IPv6 only. 2026-06-20 08:13:20.557555 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-14a9f9da tst -- -6 uptime 2026-06-20 08:13:21.373457 CEST - CLI SSH Test passed. 2026-06-20 08:13:21.373559 CEST - Uname: Linux tst.fhhnvo.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-06-20 08:13:21.373576 CEST - Uptime: 08:13:21 up 1 min, 0 users, load average: 0.07, 0.03, 0.01 2026-06-20 08:13:21.373618 CEST - Logging in using SSH (extra test: ignore SSH proxy) 2026-06-20 08:13:21.374152 CEST - Added paramiko HostKeyEntry for n079-16.wall1.ilabt.imec.be 2026-06-20 08:13:21.374355 CEST - Added paramiko HostKeyEntry for n079-16.wall1.ilabt.imec.be 2026-06-20 08:13:21.374416 CEST - Added paramiko HostKeyEntry for n079-16.wall1.ilabt.imec.be 2026-06-20 08:13:21.374614 CEST - Connecting to n079-16.wall1.ilabt.imec.be:22 2026-06-20 08:13:21.670483 CEST - SSH Test output: 2026-06-20 08:13:21.670570 CEST - Uname: Linux tst.fhhnvo.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-06-20 08:13:21.670590 CEST - Uptime: 08:13:21 up 1 min, 0 users, load average: 0.07, 0.03, 0.01 2026-06-20 08:13:21.773953 CEST - lsb_release: Ubuntu 22.04.5 LTS 2026-06-20 08:13:21.774065 CEST - lsb_release matches expected value 2026-06-20 08:13:21.774084 CEST - SSH Test passed. 2026-06-20 08:13:21.777826 CEST - Cloud-init user-data: Hello World. The time is now Sat, 20 Jun 2026 08:12:58 +0200! 2026-06-20 08:13:21.777957 CEST - Logging in using SSH over SSH proxy 2026-06-20 08:13:21.778143 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-14a9f9da tst 2026-06-20 08:13:22.493965 CEST - Added paramiko HostKeyEntry for n079-16.wall1.ilabt.imec.be 2026-06-20 08:13:22.494153 CEST - Added paramiko HostKeyEntry for n079-16.wall1.ilabt.imec.be 2026-06-20 08:13:22.494217 CEST - Added paramiko HostKeyEntry for n079-16.wall1.ilabt.imec.be 2026-06-20 08:13:22.494445 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-06-20 08:13:22.494496 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-06-20 08:13:22.494604 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-06-20 08:13:22.494629 CEST - Connecting to proxy bastion2.slices-be.eu:22 2026-06-20 08:13:22.764429 CEST - Connecting to n079-16.wall1.ilabt.imec.be:22 over proxy 2026-06-20 08:13:23.052390 CEST - SSH Test output: 2026-06-20 08:13:23.052460 CEST - Uname: Linux tst.fhhnvo.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-06-20 08:13:23.052478 CEST - Uptime: 08:13:22 up 1 min, 0 users, load average: 0.07, 0.03, 0.01 2026-06-20 08:13:23.160532 CEST - lsb_release: Ubuntu 22.04.5 LTS 2026-06-20 08:13:23.160608 CEST - lsb_release matches expected value 2026-06-20 08:13:23.160627 CEST - SSH Test passed. 2026-06-20 08:13:23.164988 CEST - Cloud-init user-data: Hello World. The time is now Sat, 20 Jun 2026 08:12:58 +0200! 2026-06-20 08:13:23.171196 CEST - Testing ping connectivity 2026-06-20 08:13:23.171343 CEST - Run: ping -n -w 10 -W 2 -c 5 -i 0.2 10.2.64.225 2026-06-20 08:13:24.037127 CEST - Destroying tst-14a9f9da tst 2026-06-20 08:13:24.037409 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-14a9f9da tst 2026-06-20 08:14:24.047170 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e0dca57c-5a44-4a08-bac8-734a8abb0edb/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-14a9f9da tst 2026-06-20 08:14:24.047337 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e0dca57c-5a44-4a08-bac8-734a8abb0edb/destroy.txt': 10.0% / task starting 30.0% - task starting 30.0% \ task starting 30.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting ***Terminating process due to timeout*** ***Process terminated*** 2026-06-20 08:14:24.047399 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is -15) 2026-06-20 08:14:24.047438 CEST - Step 'Destroy' took 60.01 seconds, which is longer than the warning threshold of 15 seconds