Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.06s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 288ms warning: Failed to hardlink files; falling back to full copy. This may lead to degraded performance. If the cache and target directories are on different filesystems, hardlinking may not be supported. If this is intentional, set `export UV_LINK_MODE=copy` or use `--link-mode=copy` to suppress this warning. Installed 32 packages in 674ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.0 + cryptography==49.0.0 + dnspython==2.8.0 + h11==0.16.0 + httpcore==1.0.9 + httpx==0.28.1 + httpx-oauth==0.17.0 + idna==3.18 + invoke==3.0.3 + joserfc==1.7.4 + msgspec==0.21.1 + packaging==26.2 + paramiko==4.0.0 + pycparser==3.0 + pydantic==2.13.4 + pydantic-core==2.46.4 + pyjwt==2.13.0 + pynacl==1.6.2 + pytz==2026.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev189+g506e4860f (from file:///app) + tomli-w==1.2.0 + tomlkit==0.15.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-27 21:04:19.125168 UTC - Installing Slices CLI (latest stable version). 2026-07-27 21:04:19.125816 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 21:04:27.393734 UTC - Slices CLI installed. 2026-07-27 21:04:27.397782 UTC - OS release: PRETTY_NAME="Debian GNU/Linux 13 (trixie)" NAME="Debian GNU/Linux" VERSION_ID="13" VERSION="13 (trixie)" VERSION_CODENAME=trixie DEBIAN_VERSION_FULL=13.5 ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" 2026-07-27 21:04:27.411532 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-07-27 21:04:27.411635 UTC - env: {'PATH': '/app/.venv/bin:/usr/local/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/root/.local/bin/:/root/slices-cli-venv/bin', 'COLUMNS': '200'} 2026-07-27 21:04:27.411711 UTC - which slices: /app/.venv/bin/slices 2026-07-27 21:04:27.411769 UTC - Slices CLI exe: 'slices' 2026-07-27 21:04:27.411788 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 21:04:27.411933 UTC - Run: slices --version 2026-07-27 21:04:32.931835 UTC - 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-07-27 21:04:32.932134 UTC - Run: slices pubkey list --format text 2026-07-27 21:04:34.381649 UTC - Pubkey already registered 2026-07-27 21:04:34.381967 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 21:04:35.098850 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 21:04:35.098938 UTC - Check List Flavors 2026-07-27 21:04:35.099092 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-27 21:04:36.436634 UTC - Check List DiskImages 2026-07-27 21:04:36.436832 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-27 21:04:37.572378 UTC - Requesting resources 2026-07-27 21:04:37.573042 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-3f6e7668 2026-07-27 21:04:39.953257 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kyjp9radfr8vb75ca1dwqd03 2026-07-27 21:04:39.953427 UTC - Waiting until resource ready 2026-07-27 21:04:41.953799 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-3f6e7668 tst 2026-07-27 21:04:42.719733 UTC - Status: IMAGING 2026-07-27 21:04:45.485611 UTC - Status: IMAGING 2026-07-27 21:04:48.351947 UTC - Status: IMAGING 2026-07-27 21:04:51.328912 UTC - Status: IMAGING 2026-07-27 21:04:54.252041 UTC - Status: IMAGING 2026-07-27 21:04:57.803553 UTC - Status: IMAGING 2026-07-27 21:05:00.829356 UTC - Status: DELETED 2026-07-27 21:05:03.999993 UTC - Status: DELETED 2026-07-27 21:05:06.821028 UTC - Status: DELETED 2026-07-27 21:05:09.688534 UTC - Status: DELETED 2026-07-27 21:05:12.504977 UTC - Status: DELETED 2026-07-27 21:05:15.321223 UTC - Status: DELETED 2026-07-27 21:05:18.190666 UTC - Status: DELETED 2026-07-27 21:05:21.007362 UTC - Status: DELETED 2026-07-27 21:05:23.723194 UTC - Status: DELETED 2026-07-27 21:05:26.489421 UTC - Status: DELETED 2026-07-27 21:05:29.305949 UTC - Status: DELETED 2026-07-27 21:05:32.122807 UTC - Status: DELETED 2026-07-27 21:05:34.839812 UTC - Status: STARTING 2026-07-27 21:05:37.606592 UTC - Status: STARTING 2026-07-27 21:05:40.423043 UTC - Status: STARTING 2026-07-27 21:05:43.189647 UTC - Status: STARTING 2026-07-27 21:05:46.058349 UTC - Status: STARTING 2026-07-27 21:05:49.143370 UTC - Status: STARTING 2026-07-27 21:05:52.378791 UTC - Status: STARTING 2026-07-27 21:05:55.404256 UTC - Status: STARTING 2026-07-27 21:05:58.967516 UTC - Status: STARTING 2026-07-27 21:06:02.143315 UTC - Status: STARTING 2026-07-27 21:06:05.012919 UTC - Status: STARTING 2026-07-27 21:06:07.982262 UTC - Status: STARTING 2026-07-27 21:06:11.155223 UTC - Status: STARTING 2026-07-27 21:06:14.546808 UTC - Status: STARTING 2026-07-27 21:06:17.936699 UTC - Status: STARTING 2026-07-27 21:06:21.057440 UTC - Status: STARTING 2026-07-27 21:06:24.253109 UTC - Status: STARTING 2026-07-27 21:06:27.170521 UTC - Status: STARTING 2026-07-27 21:06:29.938045 UTC - Status: STARTING 2026-07-27 21:06:33.067346 UTC - Status: STARTING 2026-07-27 21:06:35.991469 UTC - Status: STARTING 2026-07-27 21:06:38.815716 UTC - Status: STARTING 2026-07-27 21:06:41.782847 UTC - Status: STARTING 2026-07-27 21:06:45.146495 UTC - Status: STARTING 2026-07-27 21:06:48.488952 UTC - Status: STARTING 2026-07-27 21:06:51.679540 UTC - Status: STARTING 2026-07-27 21:06:55.260884 UTC - Status: STARTING 2026-07-27 21:06:58.386804 UTC - Status: STARTING 2026-07-27 21:07:01.514109 UTC - Status: STARTING 2026-07-27 21:07:04.332573 UTC - Status: STARTING 2026-07-27 21:07:07.098854 UTC - Status: STARTING 2026-07-27 21:07:09.917205 UTC - Status: STARTING 2026-07-27 21:07:12.883841 UTC - Status: STARTING 2026-07-27 21:07:16.213125 UTC - Status: STARTING 2026-07-27 21:07:19.182094 UTC - Status: STARTING 2026-07-27 21:07:22.054663 UTC - Status: STARTING 2026-07-27 21:07:25.172280 UTC - Status: STARTING 2026-07-27 21:07:28.041317 UTC - Status: STARTING 2026-07-27 21:07:30.907522 UTC - Status: STARTING 2026-07-27 21:07:33.787715 UTC - Status: STARTING 2026-07-27 21:07:36.923157 UTC - Status: STARTING 2026-07-27 21:07:39.871559 UTC - Status: STARTING 2026-07-27 21:07:43.104515 UTC - Status: STARTING 2026-07-27 21:07:46.343434 UTC - Status: STARTING 2026-07-27 21:07:49.377037 UTC - Status: STARTING 2026-07-27 21:07:52.480358 UTC - Status: STARTING 2026-07-27 21:07:55.505737 UTC - Status: STARTING 2026-07-27 21:07:58.432514 UTC - Status: STARTING 2026-07-27 21:08:01.457765 UTC - Status: STARTING 2026-07-27 21:08:04.474220 UTC - Status: STARTING 2026-07-27 21:08:07.240067 UTC - Status: STARTING 2026-07-27 21:08:10.266194 UTC - Status: STARTING 2026-07-27 21:08:13.032675 UTC - Status: STARTING 2026-07-27 21:08:15.799117 UTC - Status: STARTING 2026-07-27 21:08:18.565771 UTC - Status: STARTING 2026-07-27 21:08:21.282112 UTC - Status: STARTING 2026-07-27 21:08:24.048631 UTC - Status: STARTING 2026-07-27 21:08:26.814948 UTC - Status: STARTING 2026-07-27 21:08:29.581260 UTC - Status: STARTING 2026-07-27 21:08:32.397943 UTC - Status: STARTING 2026-07-27 21:08:35.214756 UTC - Status: STARTING 2026-07-27 21:08:38.081859 UTC - Status: STARTING 2026-07-27 21:08:40.852778 UTC - Status: STARTING 2026-07-27 21:08:43.619609 UTC - Status: STARTING 2026-07-27 21:08:46.436350 UTC - Status: STARTING 2026-07-27 21:08:49.203099 UTC - Status: STARTING 2026-07-27 21:08:51.969571 UTC - Status: STARTING 2026-07-27 21:08:54.685653 UTC - Status: STARTING 2026-07-27 21:08:57.451896 UTC - Status: STARTING 2026-07-27 21:09:00.218275 UTC - Status: STARTING 2026-07-27 21:09:02.984748 UTC - Status: STARTING 2026-07-27 21:09:05.750902 UTC - Status: STARTING 2026-07-27 21:09:08.517218 UTC - Status: STARTING 2026-07-27 21:09:11.283510 UTC - Status: STARTING 2026-07-27 21:09:14.100329 UTC - Status: STARTING 2026-07-27 21:09:16.866800 UTC - Status: UP 2026-07-27 21:09:16.866937 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyjp9qk8e8c8hpybx2h5337n 2026-07-27 21:09:16.866989 UTC - Validate resources 2026-07-27 21:09:17.685242 UTC - The fields of the created resource were validated. 2026-07-27 21:09:17.685375 UTC - Check if resources are registered in experiment 2026-07-27 21:09:17.685590 UTC - Run: slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:18.353751 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resources_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:18.353849 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:18.353890 UTC - Error in test step 'Check experiment print-resources': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:18.353957 UTC - Wait 5s before retry 2026-07-27 21:09:24.021407 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry1_retry1_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:24.021504 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:24.021530 UTC - Error in test step 'Check experiment print-resources (Retry 1)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:24.021595 UTC - Wait 5s before retry 2026-07-27 21:09:29.738638 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry2_retry2_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:29.738717 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:29.738740 UTC - Error in test step 'Check experiment print-resources (Retry 2)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:29.738796 UTC - Wait 5s before retry 2026-07-27 21:09:35.405601 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry3_retry3_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:35.405685 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:35.405707 UTC - Error in test step 'Check experiment print-resources (Retry 3)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:35.405760 UTC - Wait 5s before retry 2026-07-27 21:09:41.122801 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry4_retry4_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:41.122895 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:41.122920 UTC - Error in test step 'Check experiment print-resources (Retry 4)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:41.122987 UTC - Wait 5s before retry 2026-07-27 21:09:46.840378 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry5_retry5_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:46.840475 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:46.840498 UTC - Error in test step 'Check experiment print-resources (Retry 5)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:46.840558 UTC - Wait 5s before retry 2026-07-27 21:09:52.508181 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry6_retry6_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:52.508320 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:52.508352 UTC - Error in test step 'Check experiment print-resources (Retry 6)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:52.508415 UTC - Wait 5s before retry 2026-07-27 21:09:58.175326 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry7_retry7_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:09:58.175410 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:09:58.175433 UTC - Error in test step 'Check experiment print-resources (Retry 7)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:09:58.175489 UTC - Wait 5s before retry 2026-07-27 21:10:03.842213 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry8_retry8_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:10:03.842294 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:10:03.842317 UTC - Error in test step 'Check experiment print-resources (Retry 8)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:10:03.842370 UTC - Wait 5s before retry 2026-07-27 21:10:09.559290 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry9_retry9_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:10:09.559370 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:10:09.559392 UTC - Error in test step 'Check experiment print-resources (Retry 9)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:10:09.559447 UTC - Wait 5s before retry 2026-07-27 21:10:15.226111 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry10_retry10_command_1.txt': slices experiment list-resources --format json tst-3f6e7668 2026-07-27 21:10:15.226192 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-07-27 21:10:15.226214 UTC - Error in test step 'Check experiment print-resources (Retry 10)': "slices experiment list-resources" returned 0 resources instead of 1 2026-07-27 21:10:15.226307 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 21:10:15.226412 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-3f6e7668 2026-07-27 21:10:16.343714 UTC - Content of log file '/test_dir/step_Checkextendexpire_command_1.txt': slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-3f6e7668 2026-07-27 21:10:16.343792 UTC - Content of log file '/test_dir/experiment-extend-all.txt': There are no active resources to extend ❌ 2026-07-27 21:10:16.343816 UTC - Error in test step 'Check extend expire': "slices bi extend" failed 2026-07-27 21:10:16.343869 UTC - Testing ssh login 2026-07-27 21:10:16.358210 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3f6e7668 tst 2026-07-27 21:10:17.074669 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3f6e7668 tst 2026-07-27 21:10:17.841258 UTC - Content of log file '/test_dir/step_CheckSSHpubkeyresource_command_1.txt': slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3f6e7668 tst 2026-07-27 21:10:17.841344 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-27 21:10:17.841368 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-27 21:10:17.848686 UTC - Destroying tst-3f6e7668 tst ERROR:slices_monitor_test_runner.main:Test Failed with exception Traceback (most recent call last): File "/app/src/slices_monitor_test_runner/main.py", line 76, in run_test return _get_test_by_test_spec(steps, test_dir, test_spec).run() ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1650, in run self._ssh_login(test_privkey_pem, ssh_agent_env_vars) ~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1231, in _ssh_login server_openssh_pubkeys = self._ssh_login_pubkey(proxy_key=False) File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1161, in _ssh_login_pubkey return server_openssh_pubkeys ^^^^^^^^^^^^^^^^^^^^^^ UnboundLocalError: cannot access local variable 'server_openssh_pubkeys' where it is not associated with a value 2026-07-27 21:10:17.848916 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-3f6e7668 tst