2026-09-07 11:03:22.275396 | Job console starting 2026-09-07 11:03:22.327191 | Updating git repos 2026-09-07 11:03:22.448065 | Cloning repos into workspace 2026-09-07 11:03:22.492173 | Restoring repo states 2026-09-07 11:03:22.515610 | Merging changes 2026-09-07 11:03:23.261731 | Checking out repos 2026-09-07 11:03:23.335334 | Preparing playbooks 2026-09-07 11:03:24.685340 | Running Ansible setup 2026-09-07 11:03:30.543942 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-07 11:03:32.147161 | 2026-09-07 11:03:32.147326 | PLAY [Base pre] 2026-09-07 11:03:32.171835 | 2026-09-07 11:03:32.171981 | TASK [Setup log path fact] 2026-09-07 11:03:32.201456 | debian-bookworm | ok 2026-09-07 11:03:32.218335 | 2026-09-07 11:03:32.218478 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 11:03:32.247206 | debian-bookworm | ok 2026-09-07 11:03:32.257093 | 2026-09-07 11:03:32.257199 | TASK [emit-job-header : Print job information] 2026-09-07 11:03:32.316888 | # Job Information 2026-09-07 11:03:32.317154 | Ansible Version: 2.16.14 2026-09-07 11:03:32.317196 | Job: tox 2026-09-07 11:03:32.317238 | Pipeline: check 2026-09-07 11:03:32.317266 | Executor: 521e9411259a 2026-09-07 11:03:32.317287 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1263 2026-09-07 11:03:32.317309 | Event ID: b7117460-aaab-11f1-993a-b2118936c17e 2026-09-07 11:03:32.322531 | 2026-09-07 11:03:32.322650 | LOOP [emit-job-header : Print node information] 2026-09-07 11:03:32.464759 | debian-bookworm | ok: 2026-09-07 11:03:32.467073 | debian-bookworm | # Node Information 2026-09-07 11:03:32.467141 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-07 11:03:32.467167 | debian-bookworm | Hostname: debian 2026-09-07 11:03:32.467189 | debian-bookworm | Username: zuul 2026-09-07 11:03:32.467208 | debian-bookworm | Distro: Debian 12.15 2026-09-07 11:03:32.467230 | debian-bookworm | Provider: regiocloud-a 2026-09-07 11:03:32.467248 | debian-bookworm | Region: 2026-09-07 11:03:32.467266 | debian-bookworm | Label: debian-bookworm 2026-09-07 11:03:32.467283 | debian-bookworm | Product Name: OpenStack Nova 2026-09-07 11:03:32.467299 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2b:df49 2026-09-07 11:03:32.485298 | 2026-09-07 11:03:32.485415 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-07 11:03:33.315059 | debian-bookworm -> localhost | changed 2026-09-07 11:03:33.322662 | 2026-09-07 11:03:33.322792 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-07 11:03:34.917471 | debian-bookworm -> localhost | changed 2026-09-07 11:03:34.930053 | 2026-09-07 11:03:34.930192 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-07 11:03:35.169627 | debian-bookworm -> localhost | ok 2026-09-07 11:03:35.175488 | 2026-09-07 11:03:35.175586 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-07 11:03:35.195028 | debian-bookworm | ok 2026-09-07 11:03:35.229565 | debian-bookworm | included: /var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-07 11:03:35.247513 | 2026-09-07 11:03:35.247628 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-07 11:03:36.602478 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-07 11:03:36.602723 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/work/1cdda5f7efd445c4987dfa28f25a6577_id_rsa 2026-09-07 11:03:36.602768 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/work/1cdda5f7efd445c4987dfa28f25a6577_id_rsa.pub 2026-09-07 11:03:36.602795 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-07 11:03:36.602820 | debian-bookworm -> localhost | SHA256:LOF/yfA9wT6FFORVgKSukS5LAiQlz/EiKL3SixH1Rh4 zuul-build-sshkey 2026-09-07 11:03:36.602857 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-07 11:03:36.602888 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-07 11:03:36.602910 | debian-bookworm -> localhost | |. + E o+.oo.| 2026-09-07 11:03:36.602932 | debian-bookworm -> localhost | |.B * . o.o | 2026-09-07 11:03:36.602952 | debian-bookworm -> localhost | |* * = . . o | 2026-09-07 11:03:36.602971 | debian-bookworm -> localhost | |.* + . o o o . | 2026-09-07 11:03:36.602995 | debian-bookworm -> localhost | |o + o S . + . | 2026-09-07 11:03:36.603018 | debian-bookworm -> localhost | | + o + * + o | 2026-09-07 11:03:36.603039 | debian-bookworm -> localhost | |. . . o + = = | 2026-09-07 11:03:36.603058 | debian-bookworm -> localhost | | o o . o | 2026-09-07 11:03:36.603079 | debian-bookworm -> localhost | | . | 2026-09-07 11:03:36.603099 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-07 11:03:36.603147 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.574763 2026-09-07 11:03:36.614408 | 2026-09-07 11:03:36.614502 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-07 11:03:36.673870 | debian-bookworm | ok 2026-09-07 11:03:36.689821 | debian-bookworm | included: /var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-07 11:03:36.708964 | 2026-09-07 11:03:36.709063 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-07 11:03:36.756938 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:03:36.763393 | 2026-09-07 11:03:36.763489 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-07 11:03:37.501991 | debian-bookworm | changed 2026-09-07 11:03:37.508446 | 2026-09-07 11:03:37.508549 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-07 11:03:37.785936 | debian-bookworm | ok 2026-09-07 11:03:37.795380 | 2026-09-07 11:03:37.795482 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-07 11:03:38.456846 | debian-bookworm | changed 2026-09-07 11:03:38.464333 | 2026-09-07 11:03:38.464433 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-07 11:03:39.089336 | debian-bookworm | changed 2026-09-07 11:03:39.097365 | 2026-09-07 11:03:39.097456 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-07 11:03:39.110951 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:03:39.116494 | 2026-09-07 11:03:39.116583 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-07 11:03:39.679117 | debian-bookworm -> localhost | changed 2026-09-07 11:03:39.689912 | 2026-09-07 11:03:39.690008 | TASK [add-build-sshkey : Add back temp key] 2026-09-07 11:03:40.119906 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/work/1cdda5f7efd445c4987dfa28f25a6577_id_rsa (zuul-build-sshkey) 2026-09-07 11:03:40.120120 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.034211 2026-09-07 11:03:40.127187 | 2026-09-07 11:03:40.127279 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-07 11:03:40.586533 | debian-bookworm | ok 2026-09-07 11:03:40.591555 | 2026-09-07 11:03:40.591642 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-07 11:03:40.614727 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:03:40.625129 | 2026-09-07 11:03:40.625237 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-07 11:03:41.059207 | debian-bookworm | ok 2026-09-07 11:03:41.074007 | 2026-09-07 11:03:41.074106 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-07 11:03:41.128881 | debian-bookworm | ok 2026-09-07 11:03:41.141242 | 2026-09-07 11:03:41.141348 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-07 11:03:41.574554 | debian-bookworm -> localhost | ok 2026-09-07 11:03:41.584544 | 2026-09-07 11:03:41.584646 | TASK [validate-host : Collect information about the host] 2026-09-07 11:03:43.137906 | debian-bookworm | ok 2026-09-07 11:03:43.155220 | 2026-09-07 11:03:43.155326 | TASK [validate-host : Sanitize hostname] 2026-09-07 11:03:43.272659 | debian-bookworm | ok 2026-09-07 11:03:43.278059 | 2026-09-07 11:03:43.278146 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-07 11:03:44.341285 | debian-bookworm -> localhost | changed 2026-09-07 11:03:44.346611 | 2026-09-07 11:03:44.346713 | TASK [validate-host : Collect information about zuul worker] 2026-09-07 11:03:45.232402 | debian-bookworm | ok 2026-09-07 11:03:45.237615 | 2026-09-07 11:03:45.237808 | TASK [validate-host : Write out all zuul information for each host] 2026-09-07 11:03:46.176035 | debian-bookworm -> localhost | changed 2026-09-07 11:03:46.183998 | 2026-09-07 11:03:46.184074 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-07 11:03:46.457043 | debian-bookworm | ok 2026-09-07 11:03:46.472260 | 2026-09-07 11:03:46.472356 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-07 11:03:47.746560 | debian-bookworm | changed: 2026-09-07 11:03:47.746727 | debian-bookworm | cd+++++++++ src/ 2026-09-07 11:03:47.746759 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-07 11:03:47.746780 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-07 11:03:47.746799 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-09-07 11:03:47.746816 | debian-bookworm | =7.0.3 2026-09-07 11:04:26.020573 | debian-bookworm | Downloading cachetools-7.1.8-py3-none-any.whl (16 kB) 2026-09-07 11:04:26.078706 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-07 11:04:26.091968 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-07 11:04:26.193739 | debian-bookworm | Collecting filelock>=3.25 2026-09-07 11:04:26.206103 | debian-bookworm | Downloading filelock-3.32.5-py3-none-any.whl (100 kB) 2026-09-07 11:04:26.221328 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.0/100.0 kB 10.6 MB/s eta 0:00:00 2026-09-07 11:04:26.291123 | debian-bookworm | Collecting packaging>=26 2026-09-07 11:04:26.304032 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-07 11:04:26.325343 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 7.7 MB/s eta 0:00:00 2026-09-07 11:04:26.396064 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-07 11:04:26.408423 | debian-bookworm | Downloading platformdirs-4.11.7-py3-none-any.whl (23 kB) 2026-09-07 11:04:26.455840 | debian-bookworm | Collecting pluggy>=1.6 2026-09-07 11:04:26.467977 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-07 11:04:26.513937 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-07 11:04:26.525819 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-09-07 11:04:26.581762 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-07 11:04:26.594367 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-09-07 11:04:26.634995 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-07 11:04:26.647039 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-07 11:04:26.725156 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-07 11:04:26.737742 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-07 11:04:26.750754 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 5.3 MB/s eta 0:00:00 2026-09-07 11:04:26.914898 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-07 11:04:26.927251 | debian-bookworm | Downloading virtualenv-21.7.8-py3-none-any.whl (5.3 MB) 2026-09-07 11:04:27.165712 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 22.7 MB/s eta 0:00:00 2026-09-07 11:04:27.268703 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-07 11:04:27.282640 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-07 11:04:27.308119 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 26.0 MB/s eta 0:00:00 2026-09-07 11:04:27.426391 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-07 11:04:28.443162 | debian-bookworm | Successfully installed cachetools-7.1.8 colorama-0.4.6 distlib-0.4.3 filelock-3.32.5 packaging-26.3 platformdirs-4.11.7 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.6.0 tomli-w-1.2.0 tox-4.61.2 typing-extensions-4.16.0 virtualenv-21.7.8 2026-09-07 11:04:28.793675 | debian-bookworm | ok: Runtime: 0:00:03.550898 2026-09-07 11:04:28.810092 | 2026-09-07 11:04:28.810220 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-07 11:04:28.851621 | debian-bookworm | ok 2026-09-07 11:04:28.856685 | 2026-09-07 11:04:28.856816 | TASK [ensure-tox : Output tox version] 2026-09-07 11:04:29.472155 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-07 11:04:29.491856 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-07 11:04:29.892949 | debian-bookworm | ok: Runtime: 0:00:00.393074 2026-09-07 11:04:29.900575 | 2026-09-07 11:04:29.900691 | TASK [ensure-tox : Make global symlink] 2026-09-07 11:04:29.930485 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:29.940371 | 2026-09-07 11:04:29.940472 | PLAY RECAP 2026-09-07 11:04:29.940526 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-07 11:04:29.940552 | 2026-09-07 11:04:30.084117 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-07 11:04:30.085768 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-07 11:04:30.873841 | 2026-09-07 11:04:30.874011 | PLAY [all] 2026-09-07 11:04:30.890342 | 2026-09-07 11:04:30.890503 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-07 11:04:31.377766 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-07 11:04:31.434440 | debian-bookworm | ok: Runtime: 0:00:00.028157 2026-09-07 11:04:31.449575 | 2026-09-07 11:04:31.449787 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-07 11:04:31.981429 | debian-bookworm | ok: Runtime: 0:00:00.008345 2026-09-07 11:04:31.992455 | 2026-09-07 11:04:31.992615 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-07 11:04:32.301099 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-07 11:04:32.303402 | debian-bookworm | sudo: a password is required 2026-09-07 11:04:32.523928 | debian-bookworm | ok: Runtime: 0:00:00.022800 2026-09-07 11:04:32.539940 | 2026-09-07 11:04:32.540066 | TASK [tox : Check to see if the constraints file exists] 2026-09-07 11:04:32.585841 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:32.592600 | 2026-09-07 11:04:32.592718 | TASK [tox : Fail if constraints file is missing] 2026-09-07 11:04:32.616048 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:32.622009 | 2026-09-07 11:04:32.622123 | TASK [tox : Record file location] 2026-09-07 11:04:32.645396 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:32.651004 | 2026-09-07 11:04:32.651108 | TASK [tox : Install tox siblings] 2026-09-07 11:04:32.680258 | debian-bookworm | ok 2026-09-07 11:04:32.689549 | debian-bookworm | included: /var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-07 11:04:32.696353 | 2026-09-07 11:04:32.696462 | TASK [tox : Run tox without tests] 2026-09-07 11:04:33.904802 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-09-07 11:04:48.115226 | debian-bookworm | test: OK (14.82 seconds) 2026-09-07 11:04:48.115348 | debian-bookworm | congratulations :) (14.85 seconds) 2026-09-07 11:04:48.242938 | debian-bookworm | ok: Runtime: 0:00:15.188218 2026-09-07 11:04:48.248676 | 2026-09-07 11:04:48.248832 | TASK [tox : Create a tempfile to save tox showconfig] 2026-09-07 11:04:48.663138 | debian-bookworm | changed 2026-09-07 11:04:48.668896 | 2026-09-07 11:04:48.669111 | TASK [tox : Detect tox version] 2026-09-07 11:04:49.278057 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-07 11:04:49.698610 | debian-bookworm | ok: Runtime: 0:00:00.367882 2026-09-07 11:04:49.704315 | 2026-09-07 11:04:49.704424 | TASK [tox : Get tox envlist config] 2026-09-07 11:04:50.732100 | debian-bookworm | ok: Runtime: 0:00:00.436567 2026-09-07 11:04:50.742877 | 2026-09-07 11:04:50.742995 | TASK [tox : Install any sibling python packages] 2026-09-07 11:04:51.208552 | debian-bookworm | ok: No setup.cfg, no action needed 2026-09-07 11:04:51.214177 | 2026-09-07 11:04:51.214281 | TASK [tox : Remove tempfile] 2026-09-07 11:04:51.710993 | debian-bookworm | changed 2026-09-07 11:04:51.719986 | 2026-09-07 11:04:51.720094 | TASK [tox : Emit tox command] 2026-09-07 11:04:51.790733 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-09-07 11:04:51.795663 | 2026-09-07 11:04:51.795807 | TASK [tox : Run tox] 2026-09-07 11:04:52.446801 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-09-07 11:04:53.017957 | debian-bookworm | ...........2026-09-07 11:04:53.017 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-09-07 11:04:53.025682 | debian-bookworm | ......../home/zuul/src/github.com/osism/openstack-image-manager/.tox/test/lib/python3.11/site-packages/openstack/resource.py:874: RemovedInSDK50Warning: The _compute_attributes method is deprecated for removal 2026-09-07 11:04:53.025725 | debian-bookworm | warnings.warn( 2026-09-07 11:04:53.029154 | debian-bookworm | 2026-09-07 11:04:53.028 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-09-07 11:04:53.029667 | debian-bookworm | 2026-09-07 11:04:53.029 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-07 11:04:53.029688 | debian-bookworm | 2026-09-07 11:04:53.029 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-09-07 11:04:53.029708 | debian-bookworm | 2026-09-07 11:04:53.029 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-07 11:04:53.029720 | debian-bookworm | 2026-09-07 11:04:53.029 | WARNING | openstack_image_manager.main:check_image_age:1334 - Image 'Ubuntu 20.04 (1)' is 20 days older than the newest image in the definition 2026-09-07 11:04:53.038689 | debian-bookworm | ..2026-09-07 11:04:53.037 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-09-07 11:04:53.043839 | debian-bookworm | .2026-09-07 11:04:53.043 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-09-07 11:04:53.056388 | debian-bookworm | ..2026-09-07 11:04:53.056 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-09-07 11:04:53.069190 | debian-bookworm | ..2026-09-07 11:04:53.068 | ERROR | openstack_image_manager.main:get_checksum_from_checksum_url:278 - Failed to fetch checksum file from https://url.com/image.qcow2.sha512: 404 2026-09-07 11:04:53.079945 | debian-bookworm | ..2026-09-07 11:04:53.079 | ERROR | openstack_image_manager.main:get_checksum_from_checksum_url:278 - Failed to fetch checksum file from https://url.com/image.qcow2.sha512: unreachable 2026-09-07 11:04:53.086956 | debian-bookworm | ..2026-09-07 11:04:53.086 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-09-07 11:04:53.090020 | debian-bookworm | .2026-09-07 11:04:53.089 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-09-07 11:04:53.100895 | debian-bookworm | ...2026-09-07 11:04:53.100 | ERROR | openstack_image_manager.main:_has_space_for_download:693 - Not enough disk to prefetch http://x/y: need ~2362232012 bytes, 1073741824 available at /tmp 2026-09-07 11:04:53.104352 | debian-bookworm | .2026-09-07 11:04:53.104 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-09-07 11:04:53.109780 | debian-bookworm | .2026-09-07 11:04:53.109 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:53.109807 | debian-bookworm | 2026-09-07 11:04:53.109 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:53.786347 | debian-bookworm | 2026-09-07 11:04:53.785 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-07 11:04:53.788278 | debian-bookworm | 2026-09-07 11:04:53.787 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-07 11:04:53.789227 | debian-bookworm | 2026-09-07 11:04:53.788 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-07 11:04:53.796575 | debian-bookworm | .2026-09-07 11:04:53.796 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:53.797068 | debian-bookworm | 2026-09-07 11:04:53.796 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:53.797963 | debian-bookworm | 2026-09-07 11:04:53.797 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:53.798359 | debian-bookworm | 2026-09-07 11:04:53.797 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-09-07 11:04:53.798388 | debian-bookworm | 2026-09-07 11:04:53.798 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-09-07 11:04:53.805702 | debian-bookworm | .2026-09-07 11:04:53.805 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:53.805729 | debian-bookworm | 2026-09-07 11:04:53.805 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:53.806115 | debian-bookworm | 2026-09-07 11:04:53.805 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.806482 | debian-bookworm | 2026-09-07 11:04:53.806 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.806497 | debian-bookworm | 2026-09-07 11:04:53.806 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.806798 | debian-bookworm | 2026-09-07 11:04:53.806 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.806810 | debian-bookworm | 2026-09-07 11:04:53.806 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.807404 | debian-bookworm | 2026-09-07 11:04:53.807 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-07 11:04:53.812505 | debian-bookworm | .2026-09-07 11:04:53.812 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:53.812531 | debian-bookworm | 2026-09-07 11:04:53.812 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:53.812911 | debian-bookworm | 2026-09-07 11:04:53.812 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.813313 | debian-bookworm | 2026-09-07 11:04:53.813 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.813327 | debian-bookworm | 2026-09-07 11:04:53.813 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.813627 | debian-bookworm | 2026-09-07 11:04:53.813 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.813638 | debian-bookworm | 2026-09-07 11:04:53.813 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-07 11:04:53.814109 | debian-bookworm | 2026-09-07 11:04:53.813 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-07 11:04:53.814125 | debian-bookworm | 2026-09-07 11:04:53.814 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-07 11:04:54.096680 | debian-bookworm | 2026-09-07 11:04:54.095 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-07 11:04:54.098969 | debian-bookworm | 2026-09-07 11:04:54.098 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.099655 | debian-bookworm | 2026-09-07 11:04:54.098 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.121602 | debian-bookworm | ...2026-09-07 11:04:54.121 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-09-07 11:04:54.222179 | debian-bookworm | .2026-09-07 11:04:54.221 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-09-07 11:04:54.226910 | debian-bookworm | .2026-09-07 11:04:54.226 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-07 11:04:54.226942 | debian-bookworm | 2026-09-07 11:04:54.226 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-07 11:04:54.227486 | debian-bookworm | 2026-09-07 11:04:54.227 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-07 11:04:54.227503 | debian-bookworm | 2026-09-07 11:04:54.227 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-09-07 11:04:54.228154 | debian-bookworm | 2026-09-07 11:04:54.227 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-07 11:04:54.228173 | debian-bookworm | 2026-09-07 11:04:54.228 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-07 11:04:54.228580 | debian-bookworm | 2026-09-07 11:04:54.228 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-07 11:04:54.228595 | debian-bookworm | 2026-09-07 11:04:54.228 | INFO | openstack_image_manager.main:manage_outdated_images:1433 - Image 'Ubuntu 20.04_2' will not be deleted, because 'keep' flag is True 2026-09-07 11:04:54.231919 | debian-bookworm | .2026-09-07 11:04:54.231 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:54.231944 | debian-bookworm | 2026-09-07 11:04:54.231 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:54.235844 | debian-bookworm | .2026-09-07 11:04:54.235 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:54.235866 | debian-bookworm | 2026-09-07 11:04:54.235 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:54.522359 | debian-bookworm | 2026-09-07 11:04:54.521 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-07 11:04:54.525206 | debian-bookworm | 2026-09-07 11:04:54.524 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.525820 | debian-bookworm | 2026-09-07 11:04:54.525 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-09-07 11:04:54.525874 | debian-bookworm | 409 conflict on fixed id 2026-09-07 11:04:54.525898 | debian-bookworm | 2026-09-07 11:04:54.525 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.535471 | debian-bookworm | .2026-09-07 11:04:54.534 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:54.535505 | debian-bookworm | 2026-09-07 11:04:54.535 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:54.800261 | debian-bookworm | 2026-09-07 11:04:54.799 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-07 11:04:54.802925 | debian-bookworm | 2026-09-07 11:04:54.802 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.813942 | debian-bookworm | .2026-09-07 11:04:54.813 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.814600 | debian-bookworm | 2026-09-07 11:04:54.814 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-07 11:04:54.815524 | debian-bookworm | 2026-09-07 11:04:54.815 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-07 11:04:54.817467 | debian-bookworm | 2026-09-07 11:04:54.817 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.817829 | debian-bookworm | 2026-09-07 11:04:54.817 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-07 11:04:54.818234 | debian-bookworm | 2026-09-07 11:04:54.818 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.818592 | debian-bookworm | 2026-09-07 11:04:54.818 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-07 11:04:54.818610 | debian-bookworm | 2026-09-07 11:04:54.818 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-09-07 11:04:54.824058 | debian-bookworm | .2026-09-07 11:04:54.823 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-09-07 11:04:54.824394 | debian-bookworm | 2026-09-07 11:04:54.823 | ERROR | openstack_image_manager.main:process_image:948 - Could not find checksum for image 'Ubuntu 20.04', check the checksums_url or checksum_url 2026-09-07 11:04:54.829702 | debian-bookworm | .2026-09-07 11:04:54.829 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-09-07 11:04:54.830234 | debian-bookworm | 2026-09-07 11:04:54.830 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-07 11:04:54.830981 | debian-bookworm | 2026-09-07 11:04:54.830 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-09-07 11:04:54.837381 | debian-bookworm | .2026-09-07 11:04:54.837 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-09-07 11:04:54.837715 | debian-bookworm | 2026-09-07 11:04:54.837 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-09-07 11:04:54.837732 | debian-bookworm | 2026-09-07 11:04:54.837 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-09-07 11:04:54.838846 | debian-bookworm | 2026-09-07 11:04:54.838 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-09-07 11:04:54.839394 | debian-bookworm | 2026-09-07 11:04:54.839 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-09-07 11:04:54.853866 | debian-bookworm | ...2026-09-07 11:04:54.853 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.854416 | debian-bookworm | 2026-09-07 11:04:54.854 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-07 11:04:54.854720 | debian-bookworm | 2026-09-07 11:04:54.854 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.855024 | debian-bookworm | 2026-09-07 11:04:54.854 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-07 11:04:54.855037 | debian-bookworm | 2026-09-07 11:04:54.854 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-09-07 11:04:54.858647 | debian-bookworm | .2026-09-07 11:04:54.858 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-07 11:04:54.859325 | debian-bookworm | 2026-09-07 11:04:54.858 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-07 11:04:54.859345 | debian-bookworm | 2026-09-07 11:04:54.858 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-07 11:04:54.859359 | debian-bookworm | 2026-09-07 11:04:54.858 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-07 11:04:54.859364 | debian-bookworm | 2026-09-07 11:04:54.858 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-09-07 11:04:54.899026 | debian-bookworm | ..2026-09-07 11:04:54.898 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.899475 | debian-bookworm | 2026-09-07 11:04:54.898 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-09-07 11:04:54.899510 | debian-bookworm | 2026-09-07 11:04:54.899 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-09-07 11:04:54.899827 | debian-bookworm | 2026-09-07 11:04:54.899 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-09-07 11:04:54.900635 | debian-bookworm | 2026-09-07 11:04:54.900 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-09-07 11:04:54.901343 | debian-bookworm | 2026-09-07 11:04:54.901 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-09-07 11:04:54.902003 | debian-bookworm | 2026-09-07 11:04:54.901 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-09-07 11:04:54.902362 | debian-bookworm | 2026-09-07 11:04:54.901 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-09-07 11:04:54.902394 | debian-bookworm | 2026-09-07 11:04:54.902 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-09-07 11:04:54.902627 | debian-bookworm | 2026-09-07 11:04:54.902 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.902877 | debian-bookworm | 2026-09-07 11:04:54.902 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.903353 | debian-bookworm | 2026-09-07 11:04:54.903 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-09-07 11:04:54.935988 | debian-bookworm | ...2026-09-07 11:04:54.935 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-09-07 11:04:54.940393 | debian-bookworm | .2026-09-07 11:04:54.940 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-07 11:04:54.940421 | debian-bookworm | boom 2026-09-07 11:04:54.940889 | debian-bookworm | 2026-09-07 11:04:54.940 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-07 11:04:54.940901 | debian-bookworm | boom 2026-09-07 11:04:54.940906 | debian-bookworm | 2026-09-07 11:04:54.940 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-07 11:04:54.940910 | debian-bookworm | boom 2026-09-07 11:04:54.940914 | debian-bookworm | 2026-09-07 11:04:54.940 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-07 11:04:54.940946 | debian-bookworm | boom 2026-09-07 11:04:54.940950 | debian-bookworm | 2026-09-07 11:04:54.940 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-07 11:04:54.940954 | debian-bookworm | boom 2026-09-07 11:04:54.941378 | debian-bookworm | 2026-09-07 11:04:54.940 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-09-07 11:04:54.945380 | debian-bookworm | .2026-09-07 11:04:54.945 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-09-07 11:04:54.949107 | debian-bookworm | .2026-09-07 11:04:54.948 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-07 11:04:54.949130 | debian-bookworm | 2026-09-07 11:04:54.948 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-07 11:04:54.949621 | debian-bookworm | 2026-09-07 11:04:54.949 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-09-07 11:04:54.949653 | debian-bookworm | glance api error 2026-09-07 11:04:54.949659 | debian-bookworm | 2026-09-07 11:04:54.949 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-07 11:04:54.963447 | debian-bookworm | .......2026-09-07 11:04:54 | INFO  | main:226 - Processing file /tmp/tmpk7uqz2_w/example.yml 2026-09-07 11:04:54.969283 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | main:235 - Skipping disabled image Example 1.0 2026-09-07 11:04:54.976812 | debian-bookworm | .2026-09-07 11:04:54 | INFO  | main:226 - Processing file /tmp/tmpy60bhrxs/example.yml 2026-09-07 11:04:54.979992 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | update_image:147 - Checking image Example 1.0 2026-09-07 11:04:54.980018 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | update_image:150 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-07 11:04:54.980430 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | update_image:181 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-09-07 11:04:54.983148 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | main:245 - Dry-run enabled, not writing /tmp/tmpy60bhrxs/example.yml 2026-09-07 11:04:54.990372 | debian-bookworm | .2026-09-07 11:04:54 | INFO  | main:226 - Processing file /tmp/tmpow9zs96a/example.yml 2026-09-07 11:04:54.993475 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | update_image:147 - Checking image Example 1.0 2026-09-07 11:04:54.993498 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | update_image:150 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-09-07 11:04:54.993870 | debian-bookworm | 2026-09-07 11:04:54 | INFO  | update_image:161 - Image Example 1.0 is up-to-date, nothing to do 2026-09-07 11:04:55.000841 | debian-bookworm | .2026-09-07 11:04:55 | INFO  | main:226 - Processing file /tmp/tmpmkgemvuw/example.yml 2026-09-07 11:04:55.003932 | debian-bookworm | 2026-09-07 11:04:55 | INFO  | update_image:147 - Checking image Example 1.0 2026-09-07 11:04:55.003955 | debian-bookworm | 2026-09-07 11:04:55 | INFO  | update_image:150 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-07 11:04:55.004408 | debian-bookworm | 2026-09-07 11:04:55 | INFO  | update_image:181 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-09-07 11:04:55.007488 | debian-bookworm | . 2026-09-07 11:04:55.007514 | debian-bookworm | ---------------------------------------------------------------------- 2026-09-07 11:04:55.007520 | debian-bookworm | Ran 74 tests in 2.013s 2026-09-07 11:04:55.007524 | debian-bookworm | 2026-09-07 11:04:55.007528 | debian-bookworm | OK 2026-09-07 11:04:55.141753 | debian-bookworm | test: OK (2.74=setup[0.05]+cmd[2.69] seconds) 2026-09-07 11:04:55.141838 | debian-bookworm | congratulations :) (2.77 seconds) 2026-09-07 11:04:55.351810 | debian-bookworm | ok: Runtime: 0:00:03.107822 2026-09-07 11:04:55.360706 | 2026-09-07 11:04:55.360838 | TASK [tox : Look for output] 2026-09-07 11:04:55.754388 | debian-bookworm | ok 2026-09-07 11:04:55.778792 | 2026-09-07 11:04:55.778991 | TASK [tox : Return file comments to Zuul] 2026-09-07 11:04:55.827103 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:55.834271 | 2026-09-07 11:04:55.834482 | PLAY RECAP 2026-09-07 11:04:55.834545 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-07 11:04:55.834572 | 2026-09-07 11:04:56.013558 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-07 11:04:56.015073 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-07 11:04:56.880171 | 2026-09-07 11:04:56.880341 | PLAY [all] 2026-09-07 11:04:56.910624 | 2026-09-07 11:04:56.910797 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-07 11:04:56.955611 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:56.962225 | 2026-09-07 11:04:56.962395 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-07 11:04:57.016809 | debian-bookworm | ok 2026-09-07 11:04:57.022295 | 2026-09-07 11:04:57.022419 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-07 11:04:57.913072 | debian-bookworm -> localhost | changed 2026-09-07 11:04:57.921261 | 2026-09-07 11:04:57.921412 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-07 11:04:57.999584 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:58.010065 | 2026-09-07 11:04:58.010196 | TASK [fetch-tox-output : Set envlist fact] 2026-09-07 11:04:58.079195 | debian-bookworm | ok 2026-09-07 11:04:58.090443 | 2026-09-07 11:04:58.090569 | TASK [fetch-tox-output : Get tox version] 2026-09-07 11:04:58.837123 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-07 11:04:58.857194 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-07 11:04:59.121626 | debian-bookworm | ok: Runtime: 0:00:00.346772 2026-09-07 11:04:59.135602 | 2026-09-07 11:04:59.135785 | TASK [fetch-tox-output : Find all default environments] 2026-09-07 11:04:59.668803 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:59.681246 | 2026-09-07 11:04:59.681456 | TASK [fetch-tox-output : Set envlist fact] 2026-09-07 11:04:59.730641 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:04:59.740814 | 2026-09-07 11:04:59.740955 | TASK [fetch-tox-output : Find all default environments] 2026-09-07 11:05:00.302376 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:00.308488 | 2026-09-07 11:05:00.308615 | TASK [fetch-tox-output : Set envlist fact] 2026-09-07 11:05:00.363602 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:00.371234 | 2026-09-07 11:05:00.371363 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-07 11:05:00.511616 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:00.525053 | 2026-09-07 11:05:00.525194 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-07 11:05:01.585121 | debian-bookworm | changed: 2026-09-07 11:05:01.585330 | debian-bookworm | created directory /var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/work/logs/tox/test 2026-09-07 11:05:01.585389 | debian-bookworm | cd+++++++++ ./ 2026-09-07 11:05:01.585415 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-09-07 11:05:01.585445 | 2026-09-07 11:05:01.604217 | 2026-09-07 11:05:01.604339 | PLAY RECAP 2026-09-07 11:05:01.604408 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-07 11:05:01.604438 | 2026-09-07 11:05:01.746365 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-07 11:05:01.747540 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-07 11:05:02.543381 | 2026-09-07 11:05:02.543505 | PLAY [all] 2026-09-07 11:05:02.557042 | 2026-09-07 11:05:02.557150 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-07 11:05:02.801283 | debian-bookworm | changed: non-zero return code 2026-09-07 11:05:02.806416 | 2026-09-07 11:05:02.806516 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-07 11:05:02.819612 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:02.825849 | 2026-09-07 11:05:02.825964 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-07 11:05:02.857736 | 2026-09-07 11:05:02.857898 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-07 11:05:02.887163 | 2026-09-07 11:05:02.887323 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-07 11:05:02.900589 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:02.906138 | 2026-09-07 11:05:02.906226 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-07 11:05:02.934040 | 2026-09-07 11:05:02.934215 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-07 11:05:02.947020 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:02.952297 | 2026-09-07 11:05:02.952395 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-07 11:05:02.965732 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:02.971879 | 2026-09-07 11:05:02.971980 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-07 11:05:02.993355 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:02.999677 | 2026-09-07 11:05:02.999754 | PLAY RECAP 2026-09-07 11:05:02.999804 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-07 11:05:02.999828 | 2026-09-07 11:05:03.087479 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-07 11:05:03.088421 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-07 11:05:03.892077 | 2026-09-07 11:05:03.892209 | PLAY [Base post-fetch] 2026-09-07 11:05:03.905511 | 2026-09-07 11:05:03.905666 | TASK [diagnose-network : Ensure log directory exists] 2026-09-07 11:05:04.298551 | debian-bookworm | ok 2026-09-07 11:05:04.306466 | 2026-09-07 11:05:04.306596 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-07 11:05:16.442743 | debian-bookworm | ok: Runtime: 0:00:06.055600 2026-09-07 11:05:16.450730 | 2026-09-07 11:05:16.450889 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-07 11:05:22.447208 | debian-bookworm | ok: Runtime: 0:00:00.008691 2026-09-07 11:05:22.452739 | 2026-09-07 11:05:22.452878 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-07 11:05:28.500288 | debian-bookworm | ok: Runtime: 0:00:00.008676 2026-09-07 11:05:28.510585 | 2026-09-07 11:05:28.510698 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-07 11:05:28.555119 | debian-bookworm | skipping: Conditional result was False 2026-09-07 11:05:28.561672 | 2026-09-07 11:05:28.561842 | TASK [fetch-output : Set log path for single node] 2026-09-07 11:05:28.603340 | debian-bookworm | ok 2026-09-07 11:05:28.608296 | 2026-09-07 11:05:28.608414 | LOOP [fetch-output : Ensure local output dirs] 2026-09-07 11:05:28.903389 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/work/logs" 2026-09-07 11:05:29.165891 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/work/artifacts" 2026-09-07 11:05:29.423570 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1cdda5f7efd445c4987dfa28f25a6577/work/docs" 2026-09-07 11:05:29.439130 | 2026-09-07 11:05:29.441371 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-07 11:05:30.209016 | debian-bookworm | changed: 2026-09-07 11:05:30.209267 | debian-bookworm | .d..t...... ./ 2026-09-07 11:05:30.209313 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-07 11:05:30.209344 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-07 11:05:30.209371 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-07 11:05:30.209399 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-07 11:05:30.209425 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-07 11:05:30.209481 | debian-bookworm | changed: All items complete 2026-09-07 11:05:30.209517 | 2026-09-07 11:05:30.736337 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 11:05:31.285700 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 11:05:31.308289 | 2026-09-07 11:05:31.308425 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-07 11:05:31.644313 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.005480 2026-09-07 11:05:31.911669 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010370 2026-09-07 11:05:31.927400 | 2026-09-07 11:05:31.927500 | PLAY RECAP 2026-09-07 11:05:31.927552 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-07 11:05:31.927588 | 2026-09-07 11:05:32.051748 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-07 11:05:32.052818 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 11:05:32.774275 | 2026-09-07 11:05:32.774438 | PLAY [Base post] 2026-09-07 11:05:32.787955 | 2026-09-07 11:05:32.788102 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-07 11:05:33.383545 | debian-bookworm | changed 2026-09-07 11:05:33.389831 | 2026-09-07 11:05:33.389940 | PLAY RECAP 2026-09-07 11:05:33.390003 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-07 11:05:33.390064 | 2026-09-07 11:05:33.513580 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 11:05:33.514676 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-07 11:05:34.333236 | 2026-09-07 11:05:34.333475 | PLAY [Base post-logs] 2026-09-07 11:05:34.345741 | 2026-09-07 11:05:34.345905 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-07 11:05:34.860303 | localhost | changed 2026-09-07 11:05:34.870802 | 2026-09-07 11:05:34.870985 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-07 11:05:34.907248 | localhost | ok 2026-09-07 11:05:34.911230 | 2026-09-07 11:05:34.911329 | TASK [Set zuul-log-path fact] 2026-09-07 11:05:34.927090 | localhost | ok 2026-09-07 11:05:34.938170 | 2026-09-07 11:05:34.938294 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 11:05:34.964145 | localhost | ok 2026-09-07 11:05:34.968039 | 2026-09-07 11:05:34.968136 | TASK [upload-logs : Create log directories] 2026-09-07 11:05:35.453814 | localhost | changed 2026-09-07 11:05:35.457336 | 2026-09-07 11:05:35.457447 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-07 11:05:35.951002 | localhost -> localhost | ok: Runtime: 0:00:00.006875 2026-09-07 11:05:35.960749 | 2026-09-07 11:05:35.960994 | TASK [upload-logs : Upload logs to log server] 2026-09-07 11:05:36.540934 | localhost | Output suppressed because no_log was given 2026-09-07 11:05:36.542785 | 2026-09-07 11:05:36.542914 | LOOP [upload-logs : Compress console log and json output] 2026-09-07 11:05:36.589548 | localhost | skipping: Conditional result was False 2026-09-07 11:05:36.595710 | localhost | skipping: Conditional result was False 2026-09-07 11:05:36.599451 | 2026-09-07 11:05:36.599586 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-07 11:05:36.661992 | localhost | skipping: Conditional result was False 2026-09-07 11:05:36.662261 | 2026-09-07 11:05:36.666960 | localhost | skipping: Conditional result was False 2026-09-07 11:05:36.677761 | 2026-09-07 11:05:36.677923 | LOOP [upload-logs : Upload console log and json output]