Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.66s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 603ms 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 920ms + annotated-types==0.7.0 + anyio==4.14.1 + 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-09 21:01:24.026502 UTC - Installing Slices CLI (latest stable version). 2026-07-09 21:01:24.026964 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 21:01:36.758041 UTC - Slices CLI installed. 2026-07-09 21:01:36.758271 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-09 21:01:36.761137 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-09 21:01:36.762381 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-09 21:01:36.762543 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-09 21:01:36.762575 UTC - Slices CLI exe: 'slices' 2026-07-09 21:01:36.762594 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 21:01:36.762748 UTC - Run: slices --version 2026-07-09 21:01:42.225917 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-09 21:01:42.226274 UTC - Run: slices pubkey list --format text 2026-07-09 21:01:43.680430 UTC - Pubkey already registered 2026-07-09 21:01:43.680696 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 21:01:44.563280 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 21:01:44.563379 UTC - Check List Flavors 2026-07-09 21:01:44.563529 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-09 21:01:46.043344 UTC - Check List DiskImages 2026-07-09 21:01:46.043586 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-09 21:01:46.759575 UTC - Requesting resources 2026-07-09 21:01:46.760148 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-f4655523 2026-07-09 21:01:50.182104 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kx4azmh3ez0avcv22smkqphh 2026-07-09 21:01:50.182265 UTC - Waiting until resource ready 2026-07-09 21:01:52.182608 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-f4655523 tst 2026-07-09 21:01:52.948382 UTC - Status: IMAGING 2026-07-09 21:01:55.714467 UTC - Status: IMAGING 2026-07-09 21:01:58.480803 UTC - Status: STARTING 2026-07-09 21:02:01.247380 UTC - Status: STARTING 2026-07-09 21:02:03.963327 UTC - Status: STARTING 2026-07-09 21:02:06.730313 UTC - Status: STARTING 2026-07-09 21:02:09.547264 UTC - Status: STARTING 2026-07-09 21:02:12.263454 UTC - Status: STARTING 2026-07-09 21:02:15.029607 UTC - Status: STARTING 2026-07-09 21:02:17.846151 UTC - Status: STARTING 2026-07-09 21:02:20.612452 UTC - Status: STARTING 2026-07-09 21:02:23.429215 UTC - Status: STARTING 2026-07-09 21:02:26.195602 UTC - Status: STARTING 2026-07-09 21:02:28.962666 UTC - Status: STARTING 2026-07-09 21:02:31.779668 UTC - Status: STARTING 2026-07-09 21:02:34.596399 UTC - Status: STARTING 2026-07-09 21:02:37.412991 UTC - Status: STARTING 2026-07-09 21:02:40.229528 UTC - Status: STARTING 2026-07-09 21:02:42.996302 UTC - Status: STARTING 2026-07-09 21:02:45.762726 UTC - Status: STARTING 2026-07-09 21:02:48.529240 UTC - Status: STARTING 2026-07-09 21:02:51.295847 UTC - Status: STARTING 2026-07-09 21:02:54.063336 UTC - Status: STARTING 2026-07-09 21:02:56.779604 UTC - Status: STARTING 2026-07-09 21:02:59.597661 UTC - Status: STARTING 2026-07-09 21:03:02.464564 UTC - Status: STARTING 2026-07-09 21:03:05.230835 UTC - Status: STARTING 2026-07-09 21:03:07.997314 UTC - Status: STARTING 2026-07-09 21:03:10.713405 UTC - Status: STARTING 2026-07-09 21:03:13.479687 UTC - Status: STARTING 2026-07-09 21:03:16.246520 UTC - Status: STARTING 2026-07-09 21:03:19.012791 UTC - Status: STARTING 2026-07-09 21:03:21.729120 UTC - Status: STARTING 2026-07-09 21:03:24.445244 UTC - Status: STARTING 2026-07-09 21:03:27.211482 UTC - Status: STARTING 2026-07-09 21:03:29.977902 UTC - Status: STARTING 2026-07-09 21:03:32.694176 UTC - Status: STARTING 2026-07-09 21:03:35.460265 UTC - Status: STARTING 2026-07-09 21:03:38.277110 UTC - Status: STARTING 2026-07-09 21:03:41.043870 UTC - Status: STARTING 2026-07-09 21:03:43.768382 UTC - Status: STARTING 2026-07-09 21:03:46.484451 UTC - Status: STARTING 2026-07-09 21:03:49.200603 UTC - Status: STARTING 2026-07-09 21:03:51.967297 UTC - Status: STARTING 2026-07-09 21:03:54.734405 UTC - Status: STARTING 2026-07-09 21:03:57.501208 UTC - Status: STARTING 2026-07-09 21:04:00.217938 UTC - Status: STARTING 2026-07-09 21:04:02.984789 UTC - Status: STARTING 2026-07-09 21:04:05.701536 UTC - Status: STARTING 2026-07-09 21:04:08.518484 UTC - Status: STARTING 2026-07-09 21:04:11.285438 UTC - Status: STARTING 2026-07-09 21:04:14.102551 UTC - Status: STARTING 2026-07-09 21:04:16.818553 UTC - Status: STARTING 2026-07-09 21:04:19.534623 UTC - Status: STARTING 2026-07-09 21:04:22.301452 UTC - Status: STARTING 2026-07-09 21:04:25.068462 UTC - Status: STARTING 2026-07-09 21:04:27.784622 UTC - Status: STARTING 2026-07-09 21:04:30.500888 UTC - Status: STARTING 2026-07-09 21:04:33.317529 UTC - Status: STARTING 2026-07-09 21:04:36.134319 UTC - Status: STARTING 2026-07-09 21:04:38.850676 UTC - Status: UP 2026-07-09 21:04:38.850762 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx4azkcje7dtktn9q8ch53s0 2026-07-09 21:04:38.850804 UTC - Validate resources 2026-07-09 21:04:39.566714 UTC - The fields of the created resource were validated. 2026-07-09 21:04:39.566812 UTC - Check if resources are registered in experiment 2026-07-09 21:04:39.566969 UTC - Run: slices experiment list-resources --format json tst-f4655523 2026-07-09 21:04:40.232371 UTC - Status (on expauth): UP 2026-07-09 21:04:40.232509 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 21:04:40.232632 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-f4655523 2026-07-09 21:04:45.259228 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-f4655523 tst 2026-07-09 21:04:46.075409 UTC - Run: slices experiment list-resources --format json tst-f4655523 2026-07-09 21:04:46.741039 UTC - expires_at (on expauth): 2026-07-10T00:04:00Z (correctly extended) 2026-07-09 21:04:46.741164 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 21:04:46.741311 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-f4655523 2026-07-09 21:04:51.467117 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-f4655523 tst 2026-07-09 21:04:52.183559 UTC - Run: slices experiment list-resources --format json tst-f4655523 2026-07-09 21:04:52.799304 UTC - expires_at (on expauth): 2026-07-10T01:04:00Z (correctly extended) 2026-07-09 21:04:52.799497 UTC - Testing ssh login 2026-07-09 21:04:52.814244 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-f4655523 tst 2026-07-09 21:04:53.581230 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-f4655523 tst 2026-07-09 21:04:54.349038 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-f4655523 tst 2026-07-09 21:04:54.349136 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-09 21:04:54.349162 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-09 21:04:54.355767 UTC - Destroying tst-f4655523 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-09 21:04:54.355933 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-f4655523 tst