Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.57s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 391ms 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 1.07s + 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.dev187+g005dd054e (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 14:02:07.269177 UTC - Installing Slices CLI (latest stable version). 2026-07-27 14:02:07.269448 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 14:02:14.626503 UTC - Slices CLI installed. 2026-07-27 14:02:14.630268 UTC - PATH: {'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 14:02:14.633088 UTC - ls -a /root/ /scripts/ (error 2): None b"ls: cannot access '/scripts/': No such file or directory\n/root/:\n.\n..\n.cache\n.local\n.slices\nrun_test_runner.sh\nslices-cli-venv\n" 2026-07-27 14:02:14.634420 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-27 14:02:14.634597 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 14:02:14.634629 UTC - Slices CLI exe: 'slices' 2026-07-27 14:02:14.634650 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 14:02:14.640924 UTC - Run: slices --version 2026-07-27 14:02:19.327703 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 14:02:19.330152 UTC - Run: slices pubkey list --format text 2026-07-27 14:02:20.401536 UTC - Pubkey already registered 2026-07-27 14:02:20.401767 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 14:02:21.272038 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 14:02:21.272136 UTC - Check List Flavors 2026-07-27 14:02:21.272281 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-27 14:02:22.290222 UTC - Check List DiskImages 2026-07-27 14:02:22.290434 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-27 14:02:23.062042 UTC - Requesting resources 2026-07-27 14:02:23.062568 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-0a7e1885 2026-07-27 14:02:25.539094 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kyhy4kmcfhht5jg31d4rzjdy 2026-07-27 14:02:25.539450 UTC - Waiting until resource ready 2026-07-27 14:02:27.605114 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0a7e1885 tst 2026-07-27 14:02:28.945048 UTC - Status: IMAGING 2026-07-27 14:02:32.323619 UTC - Status: IMAGING 2026-07-27 14:02:36.118367 UTC - Status: STARTING 2026-07-27 14:02:39.494255 UTC - Status: STARTING 2026-07-27 14:02:42.561183 UTC - Status: STARTING 2026-07-27 14:02:45.327302 UTC - Status: STARTING 2026-07-27 14:02:48.244801 UTC - Status: STARTING 2026-07-27 14:02:51.010667 UTC - Status: STARTING 2026-07-27 14:02:53.776590 UTC - Status: STARTING 2026-07-27 14:02:56.542709 UTC - Status: STARTING 2026-07-27 14:02:59.309026 UTC - Status: STARTING 2026-07-27 14:03:02.024863 UTC - Status: STARTING 2026-07-27 14:03:04.740751 UTC - Status: STARTING 2026-07-27 14:03:07.456778 UTC - Status: STARTING 2026-07-27 14:03:10.223184 UTC - Status: STARTING 2026-07-27 14:03:12.939890 UTC - Status: STARTING 2026-07-27 14:03:15.706245 UTC - Status: STARTING 2026-07-27 14:03:18.472562 UTC - Status: STARTING 2026-07-27 14:03:21.289052 UTC - Status: STARTING 2026-07-27 14:03:24.005490 UTC - Status: STARTING 2026-07-27 14:03:26.721277 UTC - Status: STARTING 2026-07-27 14:03:29.487487 UTC - Status: STARTING 2026-07-27 14:03:32.304195 UTC - Status: STARTING 2026-07-27 14:03:35.020333 UTC - Status: STARTING 2026-07-27 14:03:37.786700 UTC - Status: STARTING 2026-07-27 14:03:40.603500 UTC - Status: STARTING 2026-07-27 14:03:43.470983 UTC - Status: STARTING 2026-07-27 14:03:46.287516 UTC - Status: STARTING 2026-07-27 14:03:49.103885 UTC - Status: STARTING 2026-07-27 14:03:51.870344 UTC - Status: STARTING 2026-07-27 14:03:54.636859 UTC - Status: STARTING 2026-07-27 14:03:57.352703 UTC - Status: STARTING 2026-07-27 14:04:00.169346 UTC - Status: STARTING 2026-07-27 14:04:03.036514 UTC - Status: STARTING 2026-07-27 14:04:05.803264 UTC - Status: STARTING 2026-07-27 14:04:08.620000 UTC - Status: STARTING 2026-07-27 14:04:11.487496 UTC - Status: STARTING 2026-07-27 14:04:14.305789 UTC - Status: STARTING 2026-07-27 14:04:17.172527 UTC - Status: STARTING 2026-07-27 14:04:19.989252 UTC - Status: STARTING 2026-07-27 14:04:22.805991 UTC - Status: STARTING 2026-07-27 14:04:25.672450 UTC - Status: STARTING 2026-07-27 14:04:28.589535 UTC - Status: STARTING 2026-07-27 14:04:31.406603 UTC - Status: STARTING 2026-07-27 14:04:34.223405 UTC - Status: STARTING 2026-07-27 14:04:37.090453 UTC - Status: STARTING 2026-07-27 14:04:39.857020 UTC - Status: STARTING 2026-07-27 14:04:42.623768 UTC - Status: STARTING 2026-07-27 14:04:45.441084 UTC - Status: STARTING 2026-07-27 14:04:48.257685 UTC - Status: STARTING 2026-07-27 14:04:51.124922 UTC - Status: STARTING 2026-07-27 14:04:53.941807 UTC - Status: STARTING 2026-07-27 14:04:56.758449 UTC - Status: STARTING 2026-07-27 14:04:59.575708 UTC - Status: STARTING 2026-07-27 14:05:02.342142 UTC - Status: STARTING 2026-07-27 14:05:05.209312 UTC - Status: STARTING 2026-07-27 14:05:07.975291 UTC - Status: STARTING 2026-07-27 14:05:10.792075 UTC - Status: STARTING 2026-07-27 14:05:13.558508 UTC - Status: STARTING 2026-07-27 14:05:16.324770 UTC - Status: STARTING 2026-07-27 14:05:19.141432 UTC - Status: STARTING 2026-07-27 14:05:21.907559 UTC - Status: STARTING 2026-07-27 14:05:24.944974 UTC - Status: STARTING 2026-07-27 14:05:28.177495 UTC - Status: STARTING 2026-07-27 14:05:31.647907 UTC - Status: STARTING 2026-07-27 14:05:34.676031 UTC - Status: STARTING 2026-07-27 14:05:38.291413 UTC - Status: STARTING 2026-07-27 14:05:41.524637 UTC - Status: STARTING 2026-07-27 14:05:44.644194 UTC - Status: STARTING 2026-07-27 14:05:47.514097 UTC - Status: UP 2026-07-27 14:05:47.514201 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyhy4jx0fwash7x2gwdxv25a 2026-07-27 14:05:47.514260 UTC - Validate resources 2026-07-27 14:05:48.331925 UTC - The fields of the created resource were validated. 2026-07-27 14:05:48.332075 UTC - Check if resources are registered in experiment 2026-07-27 14:05:48.332262 UTC - Run: slices experiment list-resources --format json tst-0a7e1885 2026-07-27 14:05:49.149233 UTC - Status (on expauth): UP 2026-07-27 14:05:49.149420 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 14:05:49.149570 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-0a7e1885 2026-07-27 14:05:53.834544 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0a7e1885 tst 2026-07-27 14:05:55.112895 UTC - Run: slices experiment list-resources --format json tst-0a7e1885 2026-07-27 14:05:56.039730 UTC - expires_at (on expauth): 2026-07-27T17:05:00Z (correctly extended) 2026-07-27 14:05:56.039841 UTC - Testing extend expires_at (single resource in experiment) 2026-07-27 14:05:56.039986 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-0a7e1885 2026-07-27 14:06:00.615560 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0a7e1885 tst 2026-07-27 14:06:01.532608 UTC - Run: slices experiment list-resources --format json tst-0a7e1885 2026-07-27 14:06:02.402598 UTC - expires_at (on expauth): 2026-07-27T18:05:00Z (correctly extended) 2026-07-27 14:06:02.402754 UTC - Testing ssh login 2026-07-27 14:06:02.581337 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-0a7e1885 tst 2026-07-27 14:06:03.603709 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-0a7e1885 tst 2026-07-27 14:06:04.782524 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-0a7e1885 tst 2026-07-27 14:06:04.782609 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-27 14:06:04.782632 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 14:06:04.794737 UTC - Destroying tst-0a7e1885 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 1630, 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 1211, 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 1141, 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 14:06:04.794962 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-0a7e1885 tst