Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.24s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 639ms 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.25s + 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-11 14:01:25.105983 UTC - Installing Slices CLI (latest stable version). 2026-07-11 14:01:25.106218 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 14:01:34.921167 UTC - Slices CLI installed. 2026-07-11 14:01:34.921379 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-11 14:01:34.924019 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-11 14:01:34.925406 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 14:01:34.925549 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-11 14:01:34.925577 UTC - Slices CLI exe: 'slices' 2026-07-11 14:01:34.925595 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 14:01:34.925713 UTC - Run: slices --version 2026-07-11 14:01:40.813842 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-11 14:01:40.814580 UTC - Run: slices pubkey list --format text 2026-07-11 14:01:42.578651 UTC - Pubkey already registered 2026-07-11 14:01:42.578934 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 14:01:43.356991 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 14:01:43.357093 UTC - Check List Flavors 2026-07-11 14:01:43.357237 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-11 14:01:44.827110 UTC - Check List DiskImages 2026-07-11 14:01:44.827340 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-11 14:01:45.802407 UTC - Requesting resources 2026-07-11 14:01:45.804458 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-dd196eb9 2026-07-11 14:01:49.637889 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kx8qr0n9f78tmdryh3g6cdvg 2026-07-11 14:01:49.638048 UTC - Waiting until resource ready 2026-07-11 14:01:51.638418 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-dd196eb9 tst 2026-07-11 14:01:52.504130 UTC - Status: IMAGING 2026-07-11 14:01:55.270367 UTC - Status: STARTING 2026-07-11 14:01:57.986478 UTC - Status: STARTING 2026-07-11 14:02:00.702180 UTC - Status: STARTING 2026-07-11 14:02:03.468692 UTC - Status: STARTING 2026-07-11 14:02:06.285259 UTC - Status: STARTING 2026-07-11 14:02:09.001446 UTC - Status: STARTING 2026-07-11 14:02:11.768005 UTC - Status: STARTING 2026-07-11 14:02:14.534471 UTC - Status: STARTING 2026-07-11 14:02:17.300570 UTC - Status: STARTING 2026-07-11 14:02:20.066840 UTC - Status: STARTING 2026-07-11 14:02:22.883288 UTC - Status: STARTING 2026-07-11 14:02:25.649318 UTC - Status: STARTING 2026-07-11 14:02:28.416301 UTC - Status: STARTING 2026-07-11 14:02:31.233079 UTC - Status: STARTING 2026-07-11 14:02:34.049743 UTC - Status: STARTING 2026-07-11 14:02:36.866471 UTC - Status: STARTING 2026-07-11 14:02:39.582375 UTC - Status: STARTING 2026-07-11 14:02:42.298397 UTC - Status: STARTING 2026-07-11 14:02:45.014308 UTC - Status: STARTING 2026-07-11 14:02:47.780623 UTC - Status: STARTING 2026-07-11 14:02:50.496761 UTC - Status: STARTING 2026-07-11 14:02:53.213515 UTC - Status: STARTING 2026-07-11 14:02:55.930828 UTC - Status: STARTING 2026-07-11 14:02:58.696982 UTC - Status: STARTING 2026-07-11 14:03:01.463545 UTC - Status: STARTING 2026-07-11 14:03:04.179566 UTC - Status: STARTING 2026-07-11 14:03:06.946518 UTC - Status: STARTING 2026-07-11 14:03:09.763571 UTC - Status: STARTING 2026-07-11 14:03:12.581430 UTC - Status: STARTING 2026-07-11 14:03:15.297384 UTC - Status: STARTING 2026-07-11 14:03:18.013526 UTC - Status: STARTING 2026-07-11 14:03:20.779680 UTC - Status: STARTING 2026-07-11 14:03:23.495780 UTC - Status: STARTING 2026-07-11 14:03:26.212017 UTC - Status: STARTING 2026-07-11 14:03:28.928223 UTC - Status: STARTING 2026-07-11 14:03:31.644310 UTC - Status: STARTING 2026-07-11 14:03:34.360157 UTC - Status: STARTING 2026-07-11 14:03:37.126543 UTC - Status: STARTING 2026-07-11 14:03:39.843472 UTC - Status: STARTING 2026-07-11 14:03:42.659876 UTC - Status: STARTING 2026-07-11 14:03:45.376023 UTC - Status: STARTING 2026-07-11 14:03:48.092135 UTC - Status: STARTING 2026-07-11 14:03:50.858433 UTC - Status: STARTING 2026-07-11 14:03:53.575067 UTC - Status: STARTING 2026-07-11 14:03:56.291085 UTC - Status: STARTING 2026-07-11 14:03:59.007135 UTC - Status: STARTING 2026-07-11 14:04:01.774209 UTC - Status: STARTING 2026-07-11 14:04:04.540543 UTC - Status: STARTING 2026-07-11 14:04:07.257033 UTC - Status: STARTING 2026-07-11 14:04:09.972942 UTC - Status: STARTING 2026-07-11 14:04:12.688999 UTC - Status: STARTING 2026-07-11 14:04:15.405102 UTC - Status: STARTING 2026-07-11 14:04:18.171120 UTC - Status: STARTING 2026-07-11 14:04:20.887169 UTC - Status: STARTING 2026-07-11 14:04:23.603949 UTC - Status: STARTING 2026-07-11 14:04:26.370528 UTC - Status: STARTING 2026-07-11 14:04:29.086914 UTC - Status: STARTING 2026-07-11 14:04:31.903275 UTC - Status: STARTING 2026-07-11 14:04:34.669912 UTC - Status: STARTING 2026-07-11 14:04:37.386096 UTC - Status: UP 2026-07-11 14:04:37.386177 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx8qqzhcf2evxgycd2xz4k1r 2026-07-11 14:04:37.386218 UTC - Validate resources 2026-07-11 14:04:38.103399 UTC - The fields of the created resource were validated. 2026-07-11 14:04:38.103493 UTC - Check if resources are registered in experiment 2026-07-11 14:04:38.103652 UTC - Run: slices experiment list-resources --format json tst-dd196eb9 2026-07-11 14:04:38.719182 UTC - Status (on expauth): UP 2026-07-11 14:04:38.719375 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 14:04:38.719540 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-dd196eb9 2026-07-11 14:04:43.696397 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-dd196eb9 tst 2026-07-11 14:04:44.412128 UTC - Run: slices experiment list-resources --format json tst-dd196eb9 2026-07-11 14:04:45.027426 UTC - expires_at (on expauth): 2026-07-11T17:04:00Z (correctly extended) 2026-07-11 14:04:45.027532 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 14:04:45.027669 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-dd196eb9 2026-07-11 14:04:49.852921 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-dd196eb9 tst 2026-07-11 14:04:50.568575 UTC - Run: slices experiment list-resources --format json tst-dd196eb9 2026-07-11 14:04:51.183830 UTC - expires_at (on expauth): 2026-07-11T18:04:00Z (correctly extended) 2026-07-11 14:04:51.183961 UTC - Testing ssh login 2026-07-11 14:04:51.197024 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-dd196eb9 tst 2026-07-11 14:04:51.962856 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-dd196eb9 tst 2026-07-11 14:04:52.730395 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-dd196eb9 tst 2026-07-11 14:04:52.730473 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-11 14:04:52.730497 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-11 14:04:52.737008 UTC - Destroying tst-dd196eb9 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-11 14:04:52.737169 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-dd196eb9 tst