Run Test: slices-bi-singlenode-login 2026-05-09 00:34:50.263107 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-09 00:34:53.534575 CEST - Run: slices --version 2026-05-09 00:34:53.749207 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-09 00:34:53.749518 CEST - Run: slices pubkey list --format text 2026-05-09 00:34:54.264422 CEST - Pubkey already registered 2026-05-09 00:34:54.264636 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-09 00:34:54.779590 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-09 00:34:54.779698 CEST - Check List Flavors 2026-05-09 00:34:54.779893 CEST - Run: slices bi --infra hu-budapest-bi-vm1 flavor list -f json 2026-05-09 00:34:55.695914 CEST - Check List DiskImages 2026-05-09 00:34:55.696109 CEST - Run: slices bi --infra hu-budapest-bi-vm1 diskimage list -f json 2026-05-09 00:34:56.361497 CEST - Requesting resources 2026-05-09 00:34:56.361950 CEST - Run: slices bi --infra hu-budapest-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor m2.tiny --duration 2h --experiment tst-9d098ae7 2026-05-09 00:34:59.132468 CEST - Resource ID: r_hu-budapest-bi-vm1_01kr4vnmgjfk986cd3r8s2b4jc 2026-05-09 00:34:59.132607 CEST - Waiting until resource ready 2026-05-09 00:35:01.133023 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-9d098ae7 tst 2026-05-09 00:35:01.848438 CEST - Status: IMAGING 2026-05-09 00:35:04.614400 CEST - Status: IMAGING 2026-05-09 00:35:07.330332 CEST - Status: IMAGING 2026-05-09 00:35:10.046088 CEST - Status: IMAGING 2026-05-09 00:35:12.761858 CEST - Status: IMAGING 2026-05-09 00:35:15.477924 CEST - Status: IMAGING 2026-05-09 00:35:18.194004 CEST - Status: IMAGING 2026-05-09 00:35:20.910575 CEST - Status: IMAGING 2026-05-09 00:35:23.627026 CEST - Status: IMAGING 2026-05-09 00:35:26.393151 CEST - Status: IMAGING 2026-05-09 00:35:29.159294 CEST - Status: IMAGING 2026-05-09 00:35:31.875001 CEST - Status: IMAGING 2026-05-09 00:35:34.590973 CEST - Status: IMAGING 2026-05-09 00:35:37.306734 CEST - Status: IMAGING 2026-05-09 00:35:40.022540 CEST - Status: IMAGING 2026-05-09 00:35:42.838871 CEST - Status: IMAGING 2026-05-09 00:35:45.604823 CEST - Status: IMAGING 2026-05-09 00:35:48.320732 CEST - Status: IMAGING 2026-05-09 00:35:51.136985 CEST - Status: IMAGING 2026-05-09 00:35:53.903115 CEST - Status: IMAGING 2026-05-09 00:35:56.618999 CEST - Status: IMAGING 2026-05-09 00:35:59.384996 CEST - Status: IMAGING 2026-05-09 00:36:02.100851 CEST - Status: IMAGING 2026-05-09 00:36:04.816909 CEST - Status: IMAGING 2026-05-09 00:36:07.532736 CEST - Status: IMAGING 2026-05-09 00:36:10.298713 CEST - Status: IMAGING 2026-05-09 00:36:13.014478 CEST - Status: IMAGING 2026-05-09 00:36:15.730342 CEST - Status: IMAGING 2026-05-09 00:36:18.446986 CEST - Status: IMAGING 2026-05-09 00:36:21.162992 CEST - Status: IMAGING 2026-05-09 00:36:23.929007 CEST - Status: IMAGING 2026-05-09 00:36:26.644855 CEST - Status: IMAGING 2026-05-09 00:36:29.360862 CEST - Status: IMAGING 2026-05-09 00:36:32.076538 CEST - Status: IMAGING 2026-05-09 00:36:34.842536 CEST - Status: IMAGING 2026-05-09 00:36:37.558390 CEST - Status: UP 2026-05-09 00:36:37.558467 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kr4vnk6gebaren692dg32gd0 2026-05-09 00:36:37.558513 CEST - Validate resources 2026-05-09 00:36:38.274666 CEST - The fields of the created resource were validated. 2026-05-09 00:36:38.274758 CEST - Check if resources are registered in experiment 2026-05-09 00:36:38.274967 CEST - Run: slices experiment list-resources --format json tst-9d098ae7 2026-05-09 00:36:38.890456 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:41.506534 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:44.122490 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:46.738349 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:49.303910 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:51.919649 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:54.535248 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:57.150850 CEST - Status (on expauth): IMAGING 2026-05-09 00:36:59.766394 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:02.381944 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:04.997596 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:07.613309 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:10.229113 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:12.845261 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:15.410731 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:17.976239 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:20.591949 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:23.207628 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:25.823226 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:28.438889 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:31.054433 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:33.670630 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:36.236252 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:38.851943 CEST - Status (on expauth): IMAGING 2026-05-09 00:37:40.852145 CEST - "slices experiment list-resources" was not ready immediately. 2026-05-09 00:37:40.852226 CEST - It was ready after: 62.5773 seconds. (Note: used wait steps of 2s) 2026-05-09 00:37:40.853464 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/8bc7a3e5-1420-4514-bb94-e75f575b8a0c/step_Checkexperimentprint-resources_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices experiment list-resources --format json tst-9d098ae7 2026-05-09 00:37:40.853530 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/8bc7a3e5-1420-4514-bb94-e75f575b8a0c/experiment-list-resources.txt': [ { "id": "r_hu-budapest-bi-vm1_01kr4vnmgjfk986cd3r8s2b4jc", "created_at": "2026-05-08T22:34:58.938054Z", "created_by": "user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g", "experiment_id": "exp_expauth.ilabt.imec.be_01kr4vnk6gebaren692dg32gd0", "friendly_name": "tst", "project_id": "proj_account.ilabt.imec.be_5pzabws7n79ydsagfg3vntg590", "service_id": "bi", "infra_id": "hu-budapest-bi-vm1", "status": "imaging", "type": "vm", "description": "Tiny VM", "expires_at": "2026-05-09T00:34:00Z", "terminated_at": null, "terminated_by": null } ] 2026-05-09 00:37:40.853551 CEST - Error in test step 'Check experiment print-resources': "slices experiment list-resources" returned resource in unexpected state: IMAGING 2026-05-09 00:37:40.853636 CEST - Testing extend expires_at (all resources in experiment) 2026-05-09 00:37:40.853737 CEST - Run: slices bi --infra hu-budapest-bi-vm1 extend --duration 3h --experiment tst-9d098ae7 2026-05-09 00:38:30.034204 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-9d098ae7 tst 2026-05-09 00:38:30.749716 CEST - Testing extend expires_at (single resource in experiment) 2026-05-09 00:38:30.749896 CEST - Run: slices bi --infra hu-budapest-bi-vm1 extend tst --duration 4h --experiment tst-9d098ae7 2026-05-09 00:38:36.377153 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-9d098ae7 tst 2026-05-09 00:38:37.142888 CEST - Step 'Check extend expire' took 56.29 seconds, which is longer than the warning threshold of 40 seconds 2026-05-09 00:38:37.142947 CEST - Testing ssh login 2026-05-09 00:38:37.153014 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9d098ae7 tst 2026-05-09 00:38:38.018870 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9d098ae7 tst 2026-05-09 00:38:38.885115 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9d098ae7 tst 2026-05-09 00:38:39.751137 CEST - Logging in using 'slices bi ssh' 2026-05-09 00:38:39.751209 CEST - Forcing IPv4 only. 2026-05-09 00:38:39.751357 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-9d098ae7 tst -- -4 uname -a 2026-05-09 00:38:44.527178 CEST - Forcing IPv4 only. 2026-05-09 00:38:44.527383 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-9d098ae7 tst -- -4 uptime 2026-05-09 00:38:46.446371 CEST - CLI SSH Test passed. 2026-05-09 00:38:46.446449 CEST - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-b6fa97cc-9ea7-4f9b-9541-f0 6.8.0-71-generic #71-Ubuntu SMP PREEMPT_DYNAMIC Tue Jul 22 16:52:38 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-05-09 00:38:46.446464 CEST - Uptime: 22:38:46 up 2 min, 1 user, load average: 0.15, 0.13, 0.06 2026-05-09 00:38:46.446502 CEST - Logging in using SSH over SSH proxy 2026-05-09 00:38:46.446687 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9d098ae7 tst 2026-05-09 00:38:47.413698 CEST - Added paramiko HostKeyEntry for 192.168.0.44 2026-05-09 00:38:47.413855 CEST - Added paramiko HostKeyEntry for 192.168.0.44 2026-05-09 00:38:47.414015 CEST - Added paramiko HostKeyEntry for 192.168.0.44 2026-05-09 00:38:47.415118 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-05-09 00:38:47.415220 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-05-09 00:38:47.415339 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-05-09 00:38:47.415356 CEST - Connecting to proxy 193.225.251.128:22 2026-05-09 00:38:47.945371 CEST - Connecting to 192.168.0.44:22 over proxy 2026-05-09 00:38:48.720164 CEST - SSH Test output: 2026-05-09 00:38:48.720236 CEST - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-b6fa97cc-9ea7-4f9b-9541-f0 6.8.0-71-generic #71-Ubuntu SMP PREEMPT_DYNAMIC Tue Jul 22 16:52:38 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-05-09 00:38:48.720252 CEST - Uptime: 22:38:48 up 2 min, 1 user, load average: 0.15, 0.13, 0.06 2026-05-09 00:38:48.867472 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-05-09 00:38:48.867544 CEST - lsb_release matches expected value 2026-05-09 00:38:48.867557 CEST - SSH Test passed. 2026-05-09 00:38:48.874068 CEST - Destroying tst-9d098ae7 tst 2026-05-09 00:38:48.874394 CEST - Run: slices bi --infra hu-budapest-bi-vm1 destroy --experiment tst-9d098ae7 tst