Run Test: slices-bi-singlenode-login 2026-05-03 00:34:19.114778 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-03 00:34:22.435867 CEST - Run: slices --version 2026-05-03 00:34:22.650222 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-03 00:34:22.650453 CEST - Run: slices pubkey list --format text 2026-05-03 00:34:23.265594 CEST - Pubkey already registered 2026-05-03 00:34:23.265912 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-03 00:34:23.781190 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-03 00:34:23.781341 CEST - Check List Flavors 2026-05-03 00:34:23.781549 CEST - Run: slices bi --infra hu-budapest-bi-vm1 flavor list -f json 2026-05-03 00:34:24.697754 CEST - Check List DiskImages 2026-05-03 00:34:24.697959 CEST - Run: slices bi --infra hu-budapest-bi-vm1 diskimage list -f json 2026-05-03 00:34:25.313174 CEST - Requesting resources 2026-05-03 00:34:25.313619 CEST - Run: slices bi --infra hu-budapest-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor m2.tiny --duration 2h --experiment tst-b64872d0 2026-05-03 00:34:27.331794 CEST - Resource ID: r_hu-budapest-bi-vm1_01kqnd8bf6e8s9ff6wtvak9c3d 2026-05-03 00:34:27.331925 CEST - Waiting until resource ready 2026-05-03 00:34:29.332347 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-b64872d0 tst 2026-05-03 00:34:30.047869 CEST - Status: IMAGING 2026-05-03 00:34:32.763600 CEST - Status: IMAGING 2026-05-03 00:34:35.479583 CEST - Status: IMAGING 2026-05-03 00:34:38.195417 CEST - Status: IMAGING 2026-05-03 00:34:40.911445 CEST - Status: IMAGING 2026-05-03 00:34:43.627234 CEST - Status: IMAGING 2026-05-03 00:34:46.342945 CEST - Status: IMAGING 2026-05-03 00:34:49.108972 CEST - Status: IMAGING 2026-05-03 00:34:51.825065 CEST - Status: IMAGING 2026-05-03 00:34:54.541084 CEST - Status: IMAGING 2026-05-03 00:34:57.307044 CEST - Status: IMAGING 2026-05-03 00:35:00.022751 CEST - Status: IMAGING 2026-05-03 00:35:02.739037 CEST - Status: IMAGING 2026-05-03 00:35:05.454779 CEST - Status: IMAGING 2026-05-03 00:35:08.170572 CEST - Status: IMAGING 2026-05-03 00:35:10.886509 CEST - Status: IMAGING 2026-05-03 00:35:13.652226 CEST - Status: IMAGING 2026-05-03 00:35:16.368240 CEST - Status: IMAGING 2026-05-03 00:35:19.134328 CEST - Status: IMAGING 2026-05-03 00:35:21.850088 CEST - Status: IMAGING 2026-05-03 00:35:24.565688 CEST - Status: IMAGING 2026-05-03 00:35:27.281534 CEST - Status: IMAGING 2026-05-03 00:35:29.997331 CEST - Status: IMAGING 2026-05-03 00:35:32.713096 CEST - Status: IMAGING 2026-05-03 00:35:35.428840 CEST - Status: IMAGING 2026-05-03 00:35:38.144805 CEST - Status: IMAGING 2026-05-03 00:35:40.910976 CEST - Status: IMAGING 2026-05-03 00:35:43.677429 CEST - Status: IMAGING 2026-05-03 00:35:46.393251 CEST - Status: IMAGING 2026-05-03 00:35:49.109159 CEST - Status: IMAGING 2026-05-03 00:35:51.825019 CEST - Status: IMAGING 2026-05-03 00:35:54.540895 CEST - Status: IMAGING 2026-05-03 00:35:57.256698 CEST - Status: IMAGING 2026-05-03 00:35:59.972745 CEST - Status: IMAGING 2026-05-03 00:36:02.688594 CEST - Status: IMAGING 2026-05-03 00:36:05.404304 CEST - Status: IMAGING 2026-05-03 00:36:08.120248 CEST - Status: UP 2026-05-03 00:36:08.120300 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kqnd8an0f8vbqc0h1qzwnwex 2026-05-03 00:36:08.120355 CEST - Validate resources 2026-05-03 00:36:08.836241 CEST - The fields of the created resource were validated. 2026-05-03 00:36:08.836319 CEST - Check if resources are registered in experiment 2026-05-03 00:36:08.836480 CEST - Run: slices experiment list-resources --format json tst-b64872d0 2026-05-03 00:36:09.401474 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:12.017145 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:14.582650 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:17.148212 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:19.713531 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:22.329167 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:24.895132 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:27.461230 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:30.077267 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:32.693663 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:35.309500 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:37.925097 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:40.490636 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:43.106387 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:45.772292 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:48.337760 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:50.903132 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:53.468507 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:56.084042 CEST - Status (on expauth): IMAGING 2026-05-03 00:36:58.649496 CEST - Status (on expauth): IMAGING 2026-05-03 00:37:01.265109 CEST - Status (on expauth): IMAGING 2026-05-03 00:37:03.880706 CEST - Status (on expauth): IMAGING 2026-05-03 00:37:06.446031 CEST - Status (on expauth): IMAGING 2026-05-03 00:37:09.011626 CEST - Status (on expauth): IMAGING 2026-05-03 00:37:11.011830 CEST - "slices experiment list-resources" was not ready immediately. 2026-05-03 00:37:11.011927 CEST - It was ready after: 62.175455 seconds. (Note: used wait steps of 2s) 2026-05-03 00:37:11.013177 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a43a62f2-5f62-4173-b5c1-4f73117e5f26/step_Checkexperimentprint-resources_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices experiment list-resources --format json tst-b64872d0 2026-05-03 00:37:11.013250 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a43a62f2-5f62-4173-b5c1-4f73117e5f26/experiment-list-resources.txt': [ { "id": "r_hu-budapest-bi-vm1_01kqnd8bf6e8s9ff6wtvak9c3d", "created_at": "2026-05-02T22:34:27.161432Z", "created_by": "user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g", "experiment_id": "exp_expauth.ilabt.imec.be_01kqnd8an0f8vbqc0h1qzwnwex", "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-03T00:34:00Z", "terminated_at": null, "terminated_by": null } ] 2026-05-03 00:37:11.013273 CEST - Error in test step 'Check experiment print-resources': "slices experiment list-resources" returned resource in unexpected state: IMAGING 2026-05-03 00:37:11.013356 CEST - Testing extend expires_at (all resources in experiment) 2026-05-03 00:37:11.013459 CEST - Run: slices bi --infra hu-budapest-bi-vm1 extend --duration 3h --experiment tst-b64872d0 2026-05-03 00:37:32.977510 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-b64872d0 tst 2026-05-03 00:37:33.743103 CEST - Testing extend expires_at (single resource in experiment) 2026-05-03 00:37:33.743277 CEST - Run: slices bi --infra hu-budapest-bi-vm1 extend tst --duration 4h --experiment tst-b64872d0 2026-05-03 00:37:36.062196 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-b64872d0 tst 2026-05-03 00:37:36.777612 CEST - Testing ssh login 2026-05-03 00:37:36.787227 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b64872d0 tst 2026-05-03 00:37:37.652796 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b64872d0 tst 2026-05-03 00:37:38.468590 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b64872d0 tst 2026-05-03 00:37:39.284243 CEST - Logging in using 'slices bi ssh' 2026-05-03 00:37:39.284315 CEST - Forcing IPv4 only. 2026-05-03 00:37:39.284444 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-b64872d0 tst -- -4 uname -a 2026-05-03 00:37:44.058637 CEST - Forcing IPv4 only. 2026-05-03 00:37:44.058790 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-b64872d0 tst -- -4 uptime 2026-05-03 00:37:45.776490 CEST - CLI SSH Test passed. 2026-05-03 00:37:45.776543 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-03 00:37:45.776557 CEST - Uptime: 22:37:45 up 2 min, 1 user, load average: 0.13, 0.07, 0.02 2026-05-03 00:37:45.776586 CEST - Logging in using SSH over SSH proxy 2026-05-03 00:37:45.776748 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b64872d0 tst 2026-05-03 00:37:46.642973 CEST - Added paramiko HostKeyEntry for 192.168.0.232 2026-05-03 00:37:46.643077 CEST - Added paramiko HostKeyEntry for 192.168.0.232 2026-05-03 00:37:46.643211 CEST - Added paramiko HostKeyEntry for 192.168.0.232 2026-05-03 00:37:46.643447 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-05-03 00:37:46.643497 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-05-03 00:37:46.643591 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-05-03 00:37:46.643606 CEST - Connecting to proxy 193.225.251.128:22 2026-05-03 00:37:47.173237 CEST - Connecting to 192.168.0.232:22 over proxy 2026-05-03 00:37:47.942133 CEST - SSH Test output: 2026-05-03 00:37:47.942182 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-03 00:37:47.942195 CEST - Uptime: 22:37:47 up 2 min, 1 user, load average: 0.13, 0.07, 0.02 2026-05-03 00:37:48.091988 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-05-03 00:37:48.092044 CEST - lsb_release matches expected value 2026-05-03 00:37:48.092056 CEST - SSH Test passed. 2026-05-03 00:37:48.098169 CEST - Destroying tst-b64872d0 tst 2026-05-03 00:37:48.098328 CEST - Run: slices bi --infra hu-budapest-bi-vm1 destroy --experiment tst-b64872d0 tst