Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.80s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 462ms 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 251ms + 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-12 21:01:30.770635 UTC - Installing Slices CLI (latest stable version). 2026-07-12 21:01:30.770856 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-12 21:01:37.007627 UTC - Slices CLI installed. 2026-07-12 21:01:37.008279 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-12 21:01:37.011218 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-12 21:01:37.012464 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-12 21:01:37.012612 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-12 21:01:37.012643 UTC - Slices CLI exe: 'slices' 2026-07-12 21:01:37.012661 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-12 21:01:37.012842 UTC - Run: slices --version 2026-07-12 21:01:41.422030 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-12 21:01:41.422364 UTC - Run: slices pubkey list --format text 2026-07-12 21:01:43.705561 UTC - Pubkey already registered 2026-07-12 21:01:43.705868 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-12 21:01:45.055823 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-12 21:01:45.055933 UTC - Check List Flavors 2026-07-12 21:01:45.056089 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-12 21:01:47.280965 UTC - Check List DiskImages 2026-07-12 21:01:47.281148 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-12 21:01:48.110682 UTC - Requesting resources 2026-07-12 21:01:48.111220 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-e5bcd44e 2026-07-12 21:01:52.193191 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxc25v8nfw8s1dh3wgcx6q8s 2026-07-12 21:01:52.193423 UTC - Waiting until resource ready 2026-07-12 21:01:54.193840 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-e5bcd44e tst 2026-07-12 21:01:55.117209 UTC - Status: IMAGING 2026-07-12 21:01:57.984047 UTC - Status: STARTING 2026-07-12 21:02:00.750151 UTC - Status: STARTING 2026-07-12 21:02:03.516566 UTC - Status: STARTING 2026-07-12 21:02:06.283157 UTC - Status: STARTING 2026-07-12 21:02:08.998926 UTC - Status: STARTING 2026-07-12 21:02:11.714825 UTC - Status: STARTING 2026-07-12 21:02:14.481326 UTC - Status: STARTING 2026-07-12 21:02:17.197609 UTC - Status: STARTING 2026-07-12 21:02:19.914107 UTC - Status: STARTING 2026-07-12 21:02:22.630061 UTC - Status: STARTING 2026-07-12 21:02:25.346135 UTC - Status: STARTING 2026-07-12 21:02:28.112563 UTC - Status: STARTING 2026-07-12 21:02:30.879346 UTC - Status: STARTING 2026-07-12 21:02:33.645863 UTC - Status: STARTING 2026-07-12 21:02:36.462504 UTC - Status: STARTING 2026-07-12 21:02:39.228991 UTC - Status: STARTING 2026-07-12 21:02:41.945128 UTC - Status: STARTING 2026-07-12 21:02:44.661105 UTC - Status: STARTING 2026-07-12 21:02:47.427808 UTC - Status: STARTING 2026-07-12 21:02:50.144074 UTC - Status: STARTING 2026-07-12 21:02:52.860237 UTC - Status: STARTING 2026-07-12 21:02:55.576354 UTC - Status: STARTING 2026-07-12 21:02:58.292555 UTC - Status: STARTING 2026-07-12 21:03:01.008742 UTC - Status: STARTING 2026-07-12 21:03:03.724706 UTC - Status: STARTING 2026-07-12 21:03:06.441012 UTC - Status: STARTING 2026-07-12 21:03:09.157121 UTC - Status: STARTING 2026-07-12 21:03:11.873094 UTC - Status: STARTING 2026-07-12 21:03:14.639463 UTC - Status: STARTING 2026-07-12 21:03:17.355903 UTC - Status: STARTING 2026-07-12 21:03:20.122167 UTC - Status: STARTING 2026-07-12 21:03:22.838581 UTC - Status: STARTING 2026-07-12 21:03:25.605222 UTC - Status: STARTING 2026-07-12 21:03:28.321434 UTC - Status: STARTING 2026-07-12 21:03:31.037626 UTC - Status: STARTING 2026-07-12 21:03:33.803889 UTC - Status: STARTING 2026-07-12 21:03:36.570515 UTC - Status: STARTING 2026-07-12 21:03:39.337387 UTC - Status: STARTING 2026-07-12 21:03:42.053977 UTC - Status: STARTING 2026-07-12 21:03:44.820123 UTC - Status: STARTING 2026-07-12 21:03:47.536001 UTC - Status: STARTING 2026-07-12 21:03:50.251860 UTC - Status: STARTING 2026-07-12 21:03:53.018210 UTC - Status: STARTING 2026-07-12 21:03:55.734743 UTC - Status: STARTING 2026-07-12 21:03:58.450783 UTC - Status: STARTING 2026-07-12 21:04:01.217489 UTC - Status: STARTING 2026-07-12 21:04:03.984044 UTC - Status: STARTING 2026-07-12 21:04:06.750203 UTC - Status: STARTING 2026-07-12 21:04:09.466590 UTC - Status: STARTING 2026-07-12 21:04:12.182565 UTC - Status: STARTING 2026-07-12 21:04:14.898592 UTC - Status: STARTING 2026-07-12 21:04:17.664807 UTC - Status: STARTING 2026-07-12 21:04:20.380869 UTC - Status: STARTING 2026-07-12 21:04:23.096851 UTC - Status: STARTING 2026-07-12 21:04:25.812908 UTC - Status: STARTING 2026-07-12 21:04:28.529089 UTC - Status: UP 2026-07-12 21:04:28.529170 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxc25t5gfg2syd1rgezjvag8 2026-07-12 21:04:28.529231 UTC - Validate resources 2026-07-12 21:04:29.295323 UTC - The fields of the created resource were validated. 2026-07-12 21:04:29.295423 UTC - Check if resources are registered in experiment 2026-07-12 21:04:29.295589 UTC - Run: slices experiment list-resources --format json tst-e5bcd44e 2026-07-12 21:04:29.910813 UTC - Status (on expauth): UP 2026-07-12 21:04:29.910955 UTC - Testing extend expires_at (all resources in experiment) 2026-07-12 21:04:29.911082 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-e5bcd44e 2026-07-12 21:04:34.786314 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-e5bcd44e tst 2026-07-12 21:04:35.501858 UTC - Run: slices experiment list-resources --format json tst-e5bcd44e 2026-07-12 21:04:36.117062 UTC - expires_at (on expauth): 2026-07-13T00:04:00Z (correctly extended) 2026-07-12 21:04:36.117166 UTC - Testing extend expires_at (single resource in experiment) 2026-07-12 21:04:36.117316 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-e5bcd44e 2026-07-12 21:04:40.992499 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-e5bcd44e tst 2026-07-12 21:04:41.708305 UTC - Run: slices experiment list-resources --format json tst-e5bcd44e 2026-07-12 21:04:42.323765 UTC - expires_at (on expauth): 2026-07-13T01:04:00Z (correctly extended) 2026-07-12 21:04:42.323894 UTC - Testing ssh login 2026-07-12 21:04:42.336741 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e5bcd44e tst 2026-07-12 21:04:43.052112 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e5bcd44e tst 2026-07-12 21:04:43.769906 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-e5bcd44e tst 2026-07-12 21:04:43.769984 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-12 21:04:43.770007 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-12 21:04:43.776757 UTC - Destroying tst-e5bcd44e 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-12 21:04:43.776919 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-e5bcd44e tst