Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.94s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 891ms 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 838ms + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + 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.3 + 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.2 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-16 05:01:40.259818 UTC - Installing Slices CLI (latest stable version). 2026-07-16 05:01:40.260087 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 05:01:48.130123 UTC - Slices CLI installed. 2026-07-16 05:01:48.132158 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-16 05:01:48.135167 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-16 05:01:48.136312 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 05:01:48.136438 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-16 05:01:48.136465 UTC - Slices CLI exe: 'slices' 2026-07-16 05:01:48.136493 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 05:01:48.136910 UTC - Run: slices --version 2026-07-16 05:01:53.031017 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-16 05:01:53.031339 UTC - Run: slices pubkey list --format text 2026-07-16 05:01:54.977524 UTC - Pubkey already registered 2026-07-16 05:01:54.977831 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 05:01:55.902466 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 05:01:55.902574 UTC - Check List Flavors 2026-07-16 05:01:55.902721 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-16 05:01:57.978927 UTC - Check List DiskImages 2026-07-16 05:01:57.979115 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-16 05:01:58.699902 UTC - Requesting resources 2026-07-16 05:01:58.702029 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-2e86d58e 2026-07-16 05:02:02.425202 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxmmv7ffe0h8f9zzk4nbshpe 2026-07-16 05:02:02.425387 UTC - Waiting until resource ready 2026-07-16 05:02:04.425807 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2e86d58e tst 2026-07-16 05:02:05.291686 UTC - Status: IMAGING 2026-07-16 05:02:08.263492 UTC - Status: STARTING 2026-07-16 05:02:11.218043 UTC - Status: STARTING 2026-07-16 05:02:13.984128 UTC - Status: STARTING 2026-07-16 05:02:16.750507 UTC - Status: STARTING 2026-07-16 05:02:19.516860 UTC - Status: STARTING 2026-07-16 05:02:22.283436 UTC - Status: STARTING 2026-07-16 05:02:25.049579 UTC - Status: STARTING 2026-07-16 05:02:27.815604 UTC - Status: STARTING 2026-07-16 05:02:30.582290 UTC - Status: STARTING 2026-07-16 05:02:33.298444 UTC - Status: STARTING 2026-07-16 05:02:36.114843 UTC - Status: STARTING 2026-07-16 05:02:38.881363 UTC - Status: STARTING 2026-07-16 05:02:41.597372 UTC - Status: STARTING 2026-07-16 05:02:44.363497 UTC - Status: STARTING 2026-07-16 05:02:47.130359 UTC - Status: STARTING 2026-07-16 05:02:49.846362 UTC - Status: STARTING 2026-07-16 05:02:52.612660 UTC - Status: STARTING 2026-07-16 05:02:55.328773 UTC - Status: STARTING 2026-07-16 05:02:58.095330 UTC - Status: STARTING 2026-07-16 05:03:00.861604 UTC - Status: STARTING 2026-07-16 05:03:03.577600 UTC - Status: STARTING 2026-07-16 05:03:06.293708 UTC - Status: STARTING 2026-07-16 05:03:09.060075 UTC - Status: STARTING 2026-07-16 05:03:11.776130 UTC - Status: STARTING 2026-07-16 05:03:14.542495 UTC - Status: STARTING 2026-07-16 05:03:17.258776 UTC - Status: STARTING 2026-07-16 05:03:20.025253 UTC - Status: STARTING 2026-07-16 05:03:22.791762 UTC - Status: STARTING 2026-07-16 05:03:25.507979 UTC - Status: STARTING 2026-07-16 05:03:28.224016 UTC - Status: STARTING 2026-07-16 05:03:30.990313 UTC - Status: STARTING 2026-07-16 05:03:33.756776 UTC - Status: STARTING 2026-07-16 05:03:36.522793 UTC - Status: STARTING 2026-07-16 05:03:39.238937 UTC - Status: STARTING 2026-07-16 05:03:41.955173 UTC - Status: STARTING 2026-07-16 05:03:44.671275 UTC - Status: STARTING 2026-07-16 05:03:47.387241 UTC - Status: STARTING 2026-07-16 05:03:50.153216 UTC - Status: STARTING 2026-07-16 05:03:52.919247 UTC - Status: STARTING 2026-07-16 05:03:55.635064 UTC - Status: STARTING 2026-07-16 05:03:58.351227 UTC - Status: STARTING 2026-07-16 05:04:01.067294 UTC - Status: STARTING 2026-07-16 05:04:03.783444 UTC - Status: STARTING 2026-07-16 05:04:06.549594 UTC - Status: STARTING 2026-07-16 05:04:09.316014 UTC - Status: STARTING 2026-07-16 05:04:12.082240 UTC - Status: STARTING 2026-07-16 05:04:14.798134 UTC - Status: STARTING 2026-07-16 05:04:17.564440 UTC - Status: STARTING 2026-07-16 05:04:20.280644 UTC - Status: STARTING 2026-07-16 05:04:23.046868 UTC - Status: STARTING 2026-07-16 05:04:25.763908 UTC - Status: STARTING 2026-07-16 05:04:28.530599 UTC - Status: STARTING 2026-07-16 05:04:31.297622 UTC - Status: STARTING 2026-07-16 05:04:34.013470 UTC - Status: STARTING 2026-07-16 05:04:36.779977 UTC - Status: STARTING 2026-07-16 05:04:39.546267 UTC - Status: STARTING 2026-07-16 05:04:42.312645 UTC - Status: STARTING 2026-07-16 05:04:45.028617 UTC - Status: STARTING 2026-07-16 05:04:47.744439 UTC - Status: STARTING 2026-07-16 05:04:50.460365 UTC - Status: STARTING 2026-07-16 05:04:53.226525 UTC - Status: STARTING 2026-07-16 05:04:55.942472 UTC - Status: STARTING 2026-07-16 05:04:58.708747 UTC - Status: STARTING 2026-07-16 05:05:01.475031 UTC - Status: STARTING 2026-07-16 05:05:04.191095 UTC - Status: STARTING 2026-07-16 05:05:06.907228 UTC - Status: STARTING 2026-07-16 05:05:09.673422 UTC - Status: STARTING 2026-07-16 05:05:12.389610 UTC - Status: STARTING 2026-07-16 05:05:15.156675 UTC - Status: STARTING 2026-07-16 05:05:17.923350 UTC - Status: STARTING 2026-07-16 05:05:20.692559 UTC - Status: STARTING 2026-07-16 05:05:23.519363 UTC - Status: STARTING 2026-07-16 05:05:26.498973 UTC - Status: STARTING 2026-07-16 05:05:29.481031 UTC - Status: STARTING 2026-07-16 05:05:33.238997 UTC - Status: STARTING 2026-07-16 05:05:37.353336 UTC - Status: STARTING 2026-07-16 05:05:42.098525 UTC - Status: UP 2026-07-16 05:05:42.098763 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxmmv6c5et8s6sns7jss6aj1 2026-07-16 05:05:42.098823 UTC - Validate resources 2026-07-16 05:05:43.286141 UTC - The fields of the created resource were validated. 2026-07-16 05:05:43.286245 UTC - Check if resources are registered in experiment 2026-07-16 05:05:43.286389 UTC - Run: slices experiment list-resources --format json tst-2e86d58e 2026-07-16 05:05:44.115644 UTC - Status (on expauth): UP 2026-07-16 05:05:44.115827 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 05:05:44.115965 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-2e86d58e 2026-07-16 05:05:50.149170 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2e86d58e tst 2026-07-16 05:05:50.965371 UTC - Run: slices experiment list-resources --format json tst-2e86d58e 2026-07-16 05:05:51.834120 UTC - expires_at (on expauth): 2026-07-16T08:05:00Z (correctly extended) 2026-07-16 05:05:51.834927 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 05:05:51.835143 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-2e86d58e 2026-07-16 05:05:57.485745 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2e86d58e tst 2026-07-16 05:05:58.252286 UTC - Run: slices experiment list-resources --format json tst-2e86d58e 2026-07-16 05:05:58.918149 UTC - expires_at (on expauth): 2026-07-16T09:05:00Z (correctly extended) 2026-07-16 05:05:58.919101 UTC - Testing ssh login 2026-07-16 05:05:58.938637 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2e86d58e tst 2026-07-16 05:05:59.754741 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2e86d58e tst 2026-07-16 05:06:00.523101 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-2e86d58e tst 2026-07-16 05:06:00.523185 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-16 05:06:00.523210 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-16 05:06:00.530117 UTC - Destroying tst-2e86d58e 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-16 05:06:00.530284 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-2e86d58e tst