2026-09-06 02:44:19.614704 | Job console starting 2026-09-06 02:44:19.634474 | Updating git repos 2026-09-06 02:44:19.934384 | Cloning repos into workspace 2026-09-06 02:44:19.993990 | Restoring repo states 2026-09-06 02:44:20.004463 | Merging changes 2026-09-06 02:44:20.638295 | Checking out repos 2026-09-06 02:44:20.686752 | Preparing playbooks 2026-09-06 02:44:21.682422 | Running Ansible setup 2026-09-06 02:44:28.380833 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-06 02:44:29.488799 | 2026-09-06 02:44:29.488914 | PLAY [Base pre] 2026-09-06 02:44:29.534199 | 2026-09-06 02:44:29.537824 | TASK [Setup log path fact] 2026-09-06 02:44:29.572958 | debian-bookworm | ok 2026-09-06 02:44:29.608342 | 2026-09-06 02:44:29.608462 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-06 02:44:29.663416 | debian-bookworm | ok 2026-09-06 02:44:29.680692 | 2026-09-06 02:44:29.680791 | TASK [emit-job-header : Print job information] 2026-09-06 02:44:29.740536 | # Job Information 2026-09-06 02:44:29.740688 | Ansible Version: 2.16.14 2026-09-06 02:44:29.740717 | Job: tox 2026-09-06 02:44:29.740744 | Pipeline: check 2026-09-06 02:44:29.740763 | Executor: 521e9411259a 2026-09-06 02:44:29.740780 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1248 2026-09-06 02:44:29.740798 | Event ID: d5b865a0-a99c-11f1-9192-afaa0905a892 2026-09-06 02:44:29.744714 | 2026-09-06 02:44:29.744791 | LOOP [emit-job-header : Print node information] 2026-09-06 02:44:29.919051 | debian-bookworm | ok: 2026-09-06 02:44:29.919240 | debian-bookworm | # Node Information 2026-09-06 02:44:29.919271 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-06 02:44:29.919291 | debian-bookworm | Hostname: debian 2026-09-06 02:44:29.919309 | debian-bookworm | Username: zuul 2026-09-06 02:44:29.919326 | debian-bookworm | Distro: Debian 12.15 2026-09-06 02:44:29.919344 | debian-bookworm | Provider: regiocloud-a 2026-09-06 02:44:29.919362 | debian-bookworm | Region: 2026-09-06 02:44:29.919379 | debian-bookworm | Label: debian-bookworm 2026-09-06 02:44:29.919395 | debian-bookworm | Product Name: OpenStack Nova 2026-09-06 02:44:29.919411 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5f:8bb 2026-09-06 02:44:29.937836 | 2026-09-06 02:44:29.937936 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-06 02:44:30.760638 | debian-bookworm -> localhost | changed 2026-09-06 02:44:30.766342 | 2026-09-06 02:44:30.766427 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-06 02:44:32.891251 | debian-bookworm -> localhost | changed 2026-09-06 02:44:32.911692 | 2026-09-06 02:44:32.911799 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-06 02:44:33.433128 | debian-bookworm -> localhost | ok 2026-09-06 02:44:33.438003 | 2026-09-06 02:44:33.438095 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-06 02:44:33.476737 | debian-bookworm | ok 2026-09-06 02:44:33.504688 | debian-bookworm | included: /var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-06 02:44:33.514295 | 2026-09-06 02:44:33.514376 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-06 02:44:36.593305 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-06 02:44:36.593468 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/work/eb73c6a7af8442faaa1aec47ca8db6fb_id_rsa 2026-09-06 02:44:36.593499 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/work/eb73c6a7af8442faaa1aec47ca8db6fb_id_rsa.pub 2026-09-06 02:44:36.593520 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-06 02:44:36.593540 | debian-bookworm -> localhost | SHA256:aaREvfMTDypHRjbKb5nA8LMhGO8A9SBK5C1KymiREu8 zuul-build-sshkey 2026-09-06 02:44:36.593558 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-06 02:44:36.593596 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-06 02:44:36.593615 | debian-bookworm -> localhost | |++o .. | 2026-09-06 02:44:36.593633 | debian-bookworm -> localhost | |=++o.. = | 2026-09-06 02:44:36.593649 | debian-bookworm -> localhost | |oB.=.=.+.o | 2026-09-06 02:44:36.593666 | debian-bookworm -> localhost | |B.= o.Oo=.o | 2026-09-06 02:44:36.593681 | debian-bookworm -> localhost | |+oEo ..OS* + | 2026-09-06 02:44:36.593702 | debian-bookworm -> localhost | |. . o.B o . | 2026-09-06 02:44:36.593720 | debian-bookworm -> localhost | | + . | 2026-09-06 02:44:36.593736 | debian-bookworm -> localhost | | | 2026-09-06 02:44:36.593753 | debian-bookworm -> localhost | | | 2026-09-06 02:44:36.593769 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-06 02:44:36.593813 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.237624 2026-09-06 02:44:36.599103 | 2026-09-06 02:44:36.599189 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-06 02:44:36.635948 | debian-bookworm | ok 2026-09-06 02:44:36.653790 | debian-bookworm | included: /var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-06 02:44:36.668005 | 2026-09-06 02:44:36.668095 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-06 02:44:36.713708 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:44:36.718538 | 2026-09-06 02:44:36.718646 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-06 02:44:37.587693 | debian-bookworm | changed 2026-09-06 02:44:37.592180 | 2026-09-06 02:44:37.592258 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-06 02:44:37.943003 | debian-bookworm | ok 2026-09-06 02:44:37.949370 | 2026-09-06 02:44:37.949466 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-06 02:44:38.606155 | debian-bookworm | changed 2026-09-06 02:44:38.611382 | 2026-09-06 02:44:38.611478 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-06 02:44:39.376861 | debian-bookworm | changed 2026-09-06 02:44:39.388323 | 2026-09-06 02:44:39.388408 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-06 02:44:39.414947 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:44:39.420511 | 2026-09-06 02:44:39.420598 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-06 02:44:40.123455 | debian-bookworm -> localhost | changed 2026-09-06 02:44:40.132093 | 2026-09-06 02:44:40.132176 | TASK [add-build-sshkey : Add back temp key] 2026-09-06 02:44:40.932093 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/work/eb73c6a7af8442faaa1aec47ca8db6fb_id_rsa (zuul-build-sshkey) 2026-09-06 02:44:40.932272 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030464 2026-09-06 02:44:40.938274 | 2026-09-06 02:44:40.938371 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-06 02:44:41.487791 | debian-bookworm | ok 2026-09-06 02:44:41.492049 | 2026-09-06 02:44:41.492128 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-06 02:44:41.514615 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:44:41.522673 | 2026-09-06 02:44:41.522752 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-06 02:44:41.961831 | debian-bookworm | ok 2026-09-06 02:44:41.970251 | 2026-09-06 02:44:41.970329 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-06 02:44:42.000684 | debian-bookworm | ok 2026-09-06 02:44:42.004750 | 2026-09-06 02:44:42.004823 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-06 02:44:42.281381 | debian-bookworm -> localhost | ok 2026-09-06 02:44:42.286676 | 2026-09-06 02:44:42.286757 | TASK [validate-host : Collect information about the host] 2026-09-06 02:44:43.500673 | debian-bookworm | ok 2026-09-06 02:44:43.527022 | 2026-09-06 02:44:43.527117 | TASK [validate-host : Sanitize hostname] 2026-09-06 02:44:43.683524 | debian-bookworm | ok 2026-09-06 02:44:43.691111 | 2026-09-06 02:44:43.691192 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-06 02:44:44.783084 | debian-bookworm -> localhost | changed 2026-09-06 02:44:44.787644 | 2026-09-06 02:44:44.787732 | TASK [validate-host : Collect information about zuul worker] 2026-09-06 02:44:45.270041 | debian-bookworm | ok 2026-09-06 02:44:45.274693 | 2026-09-06 02:44:45.274780 | TASK [validate-host : Write out all zuul information for each host] 2026-09-06 02:44:46.100155 | debian-bookworm -> localhost | changed 2026-09-06 02:44:46.107580 | 2026-09-06 02:44:46.107658 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-06 02:44:46.386218 | debian-bookworm | ok 2026-09-06 02:44:46.390801 | 2026-09-06 02:44:46.390891 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-06 02:44:47.474000 | debian-bookworm | changed: 2026-09-06 02:44:47.474152 | debian-bookworm | cd+++++++++ src/ 2026-09-06 02:44:47.474181 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-06 02:44:47.474202 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-06 02:44:47.474221 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-09-06 02:44:47.474239 | debian-bookworm | =7.0.3 2026-09-06 02:45:25.062059 | debian-bookworm | Downloading cachetools-7.1.8-py3-none-any.whl (16 kB) 2026-09-06 02:45:25.120225 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-06 02:45:25.136859 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-06 02:45:25.225432 | debian-bookworm | Collecting filelock>=3.25 2026-09-06 02:45:25.242332 | debian-bookworm | Downloading filelock-3.32.5-py3-none-any.whl (100 kB) 2026-09-06 02:45:25.255128 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.0/100.0 kB 11.2 MB/s eta 0:00:00 2026-09-06 02:45:25.310995 | debian-bookworm | Collecting packaging>=26 2026-09-06 02:45:25.327743 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-06 02:45:25.342295 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 12.2 MB/s eta 0:00:00 2026-09-06 02:45:25.412349 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-06 02:45:25.428481 | debian-bookworm | Downloading platformdirs-4.11.7-py3-none-any.whl (23 kB) 2026-09-06 02:45:25.479032 | debian-bookworm | Collecting pluggy>=1.6 2026-09-06 02:45:25.495053 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-06 02:45:25.544235 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-06 02:45:25.561609 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-09-06 02:45:25.608635 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-06 02:45:25.623443 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-09-06 02:45:25.673098 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-06 02:45:25.688692 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-06 02:45:25.751125 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-06 02:45:25.768745 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-06 02:45:25.782101 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 5.0 MB/s eta 0:00:00 2026-09-06 02:45:25.943324 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-06 02:45:25.957973 | debian-bookworm | Downloading virtualenv-21.7.8-py3-none-any.whl (5.3 MB) 2026-09-06 02:45:26.260539 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 17.8 MB/s eta 0:00:00 2026-09-06 02:45:26.352170 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-06 02:45:26.368673 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-06 02:45:26.412328 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 12.6 MB/s eta 0:00:00 2026-09-06 02:45:26.520887 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-06 02:45:27.527234 | 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-06 02:45:27.767265 | debian-bookworm | ok: Runtime: 0:00:03.599678 2026-09-06 02:45:27.773532 | 2026-09-06 02:45:27.773755 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-06 02:45:27.805248 | debian-bookworm | ok 2026-09-06 02:45:27.810345 | 2026-09-06 02:45:27.810454 | TASK [ensure-tox : Output tox version] 2026-09-06 02:45:28.405906 | 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-06 02:45:28.428440 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-06 02:45:28.838228 | debian-bookworm | ok: Runtime: 0:00:00.406701 2026-09-06 02:45:28.844991 | 2026-09-06 02:45:28.845110 | TASK [ensure-tox : Make global symlink] 2026-09-06 02:45:28.879513 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:28.894302 | 2026-09-06 02:45:28.894410 | PLAY RECAP 2026-09-06 02:45:28.894466 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-06 02:45:28.894492 | 2026-09-06 02:45:29.112252 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-06 02:45:29.113454 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-06 02:45:30.247504 | 2026-09-06 02:45:30.247640 | PLAY [all] 2026-09-06 02:45:30.273314 | 2026-09-06 02:45:30.273441 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-06 02:45:30.751996 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-06 02:45:30.807365 | debian-bookworm | ok: Runtime: 0:00:00.028194 2026-09-06 02:45:30.813799 | 2026-09-06 02:45:30.813921 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-06 02:45:31.355741 | debian-bookworm | ok: Runtime: 0:00:00.008711 2026-09-06 02:45:31.362901 | 2026-09-06 02:45:31.363011 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-06 02:45:31.674885 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-06 02:45:31.676956 | debian-bookworm | sudo: a password is required 2026-09-06 02:45:31.907395 | debian-bookworm | ok: Runtime: 0:00:00.022039 2026-09-06 02:45:31.917025 | 2026-09-06 02:45:31.917112 | TASK [tox : Check to see if the constraints file exists] 2026-09-06 02:45:31.940319 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:31.945910 | 2026-09-06 02:45:31.946002 | TASK [tox : Fail if constraints file is missing] 2026-09-06 02:45:31.968881 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:31.974513 | 2026-09-06 02:45:31.974640 | TASK [tox : Record file location] 2026-09-06 02:45:31.997861 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:32.004269 | 2026-09-06 02:45:32.004361 | TASK [tox : Install tox siblings] 2026-09-06 02:45:32.023330 | debian-bookworm | ok 2026-09-06 02:45:32.033305 | debian-bookworm | included: /var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-06 02:45:32.038719 | 2026-09-06 02:45:32.038808 | TASK [tox : Run tox without tests] 2026-09-06 02:45:33.254167 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-09-06 02:45:46.820879 | debian-bookworm | test: OK (14.23 seconds) 2026-09-06 02:45:46.820917 | debian-bookworm | congratulations :) (14.26 seconds) 2026-09-06 02:45:47.074492 | debian-bookworm | ok: Runtime: 0:00:14.602740 2026-09-06 02:45:47.085943 | 2026-09-06 02:45:47.086107 | TASK [tox : Create a tempfile to save tox showconfig] 2026-09-06 02:45:47.522109 | debian-bookworm | changed 2026-09-06 02:45:47.527475 | 2026-09-06 02:45:47.527613 | TASK [tox : Detect tox version] 2026-09-06 02:45:48.153107 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-06 02:45:48.558132 | debian-bookworm | ok: Runtime: 0:00:00.393648 2026-09-06 02:45:48.563920 | 2026-09-06 02:45:48.564035 | TASK [tox : Get tox envlist config] 2026-09-06 02:45:49.606421 | debian-bookworm | ok: Runtime: 0:00:00.347384 2026-09-06 02:45:49.612262 | 2026-09-06 02:45:49.612382 | TASK [tox : Install any sibling python packages] 2026-09-06 02:45:50.057183 | debian-bookworm | ok: No setup.cfg, no action needed 2026-09-06 02:45:50.065068 | 2026-09-06 02:45:50.065194 | TASK [tox : Remove tempfile] 2026-09-06 02:45:50.561224 | debian-bookworm | changed 2026-09-06 02:45:50.566763 | 2026-09-06 02:45:50.566898 | TASK [tox : Emit tox command] 2026-09-06 02:45:50.633028 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-09-06 02:45:50.638417 | 2026-09-06 02:45:50.638536 | TASK [tox : Run tox] 2026-09-06 02:45:51.329431 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-09-06 02:45:51.983144 | debian-bookworm | ...........2026-09-06 02:45:51.982 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-09-06 02:45:51.990846 | 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-06 02:45:51.990892 | debian-bookworm | warnings.warn( 2026-09-06 02:45:51.994524 | debian-bookworm | 2026-09-06 02:45:51.994 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-09-06 02:45:51.995070 | debian-bookworm | 2026-09-06 02:45:51.994 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-06 02:45:51.995089 | debian-bookworm | 2026-09-06 02:45:51.994 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-09-06 02:45:51.995107 | debian-bookworm | 2026-09-06 02:45:51.994 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-06 02:45:51.995120 | debian-bookworm | 2026-09-06 02:45:51.994 | 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-06 02:45:52.001032 | debian-bookworm | ..2026-09-06 02:45:52.000 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-09-06 02:45:52.004918 | debian-bookworm | .2026-09-06 02:45:52.004 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-09-06 02:45:52.011798 | debian-bookworm | ..2026-09-06 02:45:52.011 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-09-06 02:45:52.018786 | debian-bookworm | ..2026-09-06 02:45:52.018 | 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-06 02:45:52.025004 | debian-bookworm | ..2026-09-06 02:45:52.024 | 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-06 02:45:52.031783 | debian-bookworm | ..2026-09-06 02:45:52.031 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-09-06 02:45:52.034809 | debian-bookworm | .2026-09-06 02:45:52.034 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-09-06 02:45:52.045145 | debian-bookworm | ...2026-09-06 02:45:52.044 | 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-06 02:45:52.048495 | debian-bookworm | .2026-09-06 02:45:52.048 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-09-06 02:45:52.052789 | debian-bookworm | .2026-09-06 02:45:52.052 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:52.052813 | debian-bookworm | 2026-09-06 02:45:52.052 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:52.369560 | debian-bookworm | 2026-09-06 02:45:52.369 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 02:45:52.371059 | debian-bookworm | 2026-09-06 02:45:52.370 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-06 02:45:52.371415 | debian-bookworm | 2026-09-06 02:45:52.371 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-06 02:45:52.376587 | debian-bookworm | .2026-09-06 02:45:52.376 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:52.376862 | debian-bookworm | 2026-09-06 02:45:52.376 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:52.377918 | debian-bookworm | 2026-09-06 02:45:52.377 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:52.378105 | debian-bookworm | 2026-09-06 02:45:52.377 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-09-06 02:45:52.378339 | debian-bookworm | 2026-09-06 02:45:52.378 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-09-06 02:45:52.383354 | debian-bookworm | .2026-09-06 02:45:52.383 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:52.383383 | debian-bookworm | 2026-09-06 02:45:52.383 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:52.383812 | debian-bookworm | 2026-09-06 02:45:52.383 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.384168 | debian-bookworm | 2026-09-06 02:45:52.384 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.384428 | debian-bookworm | 2026-09-06 02:45:52.384 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.384443 | debian-bookworm | 2026-09-06 02:45:52.384 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.384447 | debian-bookworm | 2026-09-06 02:45:52.384 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.385099 | debian-bookworm | 2026-09-06 02:45:52.384 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-06 02:45:52.391353 | debian-bookworm | .2026-09-06 02:45:52.391 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:52.391399 | debian-bookworm | 2026-09-06 02:45:52.391 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:52.392103 | debian-bookworm | 2026-09-06 02:45:52.391 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.392497 | debian-bookworm | 2026-09-06 02:45:52.392 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.392682 | debian-bookworm | 2026-09-06 02:45:52.392 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.392692 | debian-bookworm | 2026-09-06 02:45:52.392 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.392697 | debian-bookworm | 2026-09-06 02:45:52.392 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 02:45:52.394445 | debian-bookworm | 2026-09-06 02:45:52.394 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-06 02:45:52.394590 | debian-bookworm | 2026-09-06 02:45:52.394 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-06 02:45:52.650334 | debian-bookworm | 2026-09-06 02:45:52.649 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 02:45:52.654050 | debian-bookworm | 2026-09-06 02:45:52.653 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-06 02:45:52.654835 | debian-bookworm | 2026-09-06 02:45:52.653 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-06 02:45:52.678183 | debian-bookworm | ...2026-09-06 02:45:52.677 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-09-06 02:45:52.774366 | debian-bookworm | .2026-09-06 02:45:52.773 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-09-06 02:45:52.779100 | debian-bookworm | .2026-09-06 02:45:52.778 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-06 02:45:52.779135 | debian-bookworm | 2026-09-06 02:45:52.778 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-06 02:45:52.779664 | debian-bookworm | 2026-09-06 02:45:52.779 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-06 02:45:52.780011 | debian-bookworm | 2026-09-06 02:45:52.779 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-09-06 02:45:52.780747 | debian-bookworm | 2026-09-06 02:45:52.780 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-06 02:45:52.781160 | debian-bookworm | 2026-09-06 02:45:52.780 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-06 02:45:52.781176 | debian-bookworm | 2026-09-06 02:45:52.780 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-06 02:45:52.781181 | debian-bookworm | 2026-09-06 02:45:52.780 | 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-06 02:45:52.784621 | debian-bookworm | .2026-09-06 02:45:52.784 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:52.784647 | debian-bookworm | 2026-09-06 02:45:52.784 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:52.788537 | debian-bookworm | .2026-09-06 02:45:52.788 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:52.788559 | debian-bookworm | 2026-09-06 02:45:52.788 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:53.035996 | debian-bookworm | 2026-09-06 02:45:53.035 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 02:45:53.038271 | debian-bookworm | 2026-09-06 02:45:53.037 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.044377 | debian-bookworm | 2026-09-06 02:45:53.038 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-09-06 02:45:53.044432 | debian-bookworm | 409 conflict on fixed id 2026-09-06 02:45:53.044442 | debian-bookworm | 2026-09-06 02:45:53.038 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.047979 | debian-bookworm | .2026-09-06 02:45:53.045 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:53.048018 | debian-bookworm | 2026-09-06 02:45:53.045 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:53.291186 | debian-bookworm | 2026-09-06 02:45:53.290 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 02:45:53.293081 | debian-bookworm | 2026-09-06 02:45:53.292 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.301062 | debian-bookworm | .2026-09-06 02:45:53.300 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.301557 | debian-bookworm | 2026-09-06 02:45:53.301 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-06 02:45:53.302333 | debian-bookworm | 2026-09-06 02:45:53.302 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-06 02:45:53.304012 | debian-bookworm | 2026-09-06 02:45:53.303 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.304365 | debian-bookworm | 2026-09-06 02:45:53.304 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-06 02:45:53.304857 | debian-bookworm | 2026-09-06 02:45:53.304 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.305078 | debian-bookworm | 2026-09-06 02:45:53.304 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-06 02:45:53.305091 | debian-bookworm | 2026-09-06 02:45:53.304 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-09-06 02:45:53.311280 | debian-bookworm | .2026-09-06 02:45:53.310 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-09-06 02:45:53.311565 | debian-bookworm | 2026-09-06 02:45:53.311 | 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-06 02:45:53.320046 | debian-bookworm | .2026-09-06 02:45:53.319 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-09-06 02:45:53.320645 | debian-bookworm | 2026-09-06 02:45:53.320 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-06 02:45:53.321027 | debian-bookworm | 2026-09-06 02:45:53.320 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-09-06 02:45:53.327022 | debian-bookworm | .2026-09-06 02:45:53.326 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-09-06 02:45:53.327053 | debian-bookworm | 2026-09-06 02:45:53.326 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-09-06 02:45:53.327061 | debian-bookworm | 2026-09-06 02:45:53.326 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-09-06 02:45:53.328392 | debian-bookworm | 2026-09-06 02:45:53.328 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-09-06 02:45:53.329025 | debian-bookworm | 2026-09-06 02:45:53.328 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-09-06 02:45:53.343027 | debian-bookworm | ...2026-09-06 02:45:53.342 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.343593 | debian-bookworm | 2026-09-06 02:45:53.342 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 02:45:53.343618 | debian-bookworm | 2026-09-06 02:45:53.343 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.343623 | debian-bookworm | 2026-09-06 02:45:53.343 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 02:45:53.343629 | debian-bookworm | 2026-09-06 02:45:53.343 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-09-06 02:45:53.347056 | debian-bookworm | .2026-09-06 02:45:53.346 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-06 02:45:53.347414 | debian-bookworm | 2026-09-06 02:45:53.347 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 02:45:53.347658 | debian-bookworm | 2026-09-06 02:45:53.347 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-06 02:45:53.347686 | debian-bookworm | 2026-09-06 02:45:53.347 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 02:45:53.347984 | debian-bookworm | 2026-09-06 02:45:53.347 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-09-06 02:45:53.381641 | debian-bookworm | ..2026-09-06 02:45:53.381 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.382122 | debian-bookworm | 2026-09-06 02:45:53.381 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-09-06 02:45:53.382139 | debian-bookworm | 2026-09-06 02:45:53.381 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-09-06 02:45:53.382145 | debian-bookworm | 2026-09-06 02:45:53.381 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-09-06 02:45:53.382611 | debian-bookworm | 2026-09-06 02:45:53.382 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-09-06 02:45:53.382627 | debian-bookworm | 2026-09-06 02:45:53.382 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-09-06 02:45:53.382935 | debian-bookworm | 2026-09-06 02:45:53.382 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-09-06 02:45:53.382945 | debian-bookworm | 2026-09-06 02:45:53.382 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-09-06 02:45:53.382949 | debian-bookworm | 2026-09-06 02:45:53.382 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-09-06 02:45:53.383252 | debian-bookworm | 2026-09-06 02:45:53.382 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.383262 | debian-bookworm | 2026-09-06 02:45:53.382 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.383591 | debian-bookworm | 2026-09-06 02:45:53.383 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-09-06 02:45:53.413166 | debian-bookworm | ...2026-09-06 02:45:53.412 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-09-06 02:45:53.417536 | debian-bookworm | .2026-09-06 02:45:53.417 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 02:45:53.417570 | debian-bookworm | boom 2026-09-06 02:45:53.417821 | debian-bookworm | 2026-09-06 02:45:53.417 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 02:45:53.417839 | debian-bookworm | boom 2026-09-06 02:45:53.417968 | debian-bookworm | 2026-09-06 02:45:53.417 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 02:45:53.417979 | debian-bookworm | boom 2026-09-06 02:45:53.418184 | debian-bookworm | 2026-09-06 02:45:53.418 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 02:45:53.418194 | debian-bookworm | boom 2026-09-06 02:45:53.418372 | debian-bookworm | 2026-09-06 02:45:53.418 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 02:45:53.418384 | debian-bookworm | boom 2026-09-06 02:45:53.418539 | debian-bookworm | 2026-09-06 02:45:53.418 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-09-06 02:45:53.423376 | debian-bookworm | .2026-09-06 02:45:53.423 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-09-06 02:45:53.427328 | debian-bookworm | .2026-09-06 02:45:53.426 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 02:45:53.427356 | debian-bookworm | 2026-09-06 02:45:53.427 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 02:45:53.427901 | debian-bookworm | 2026-09-06 02:45:53.427 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-09-06 02:45:53.427939 | debian-bookworm | glance api error 2026-09-06 02:45:53.427945 | debian-bookworm | 2026-09-06 02:45:53.427 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-06 02:45:53.442036 | debian-bookworm | .......2026-09-06 02:45:53 | INFO  | main:223 - Processing file /tmp/tmpriga7mp3/example.yml 2026-09-06 02:45:53.448510 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-09-06 02:45:53.455983 | debian-bookworm | .2026-09-06 02:45:53 | INFO  | main:223 - Processing file /tmp/tmpnlxobzec/example.yml 2026-09-06 02:45:53.459268 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-06 02:45:53.459310 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-06 02:45:53.459803 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-09-06 02:45:53.462701 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmpnlxobzec/example.yml 2026-09-06 02:45:53.469783 | debian-bookworm | .2026-09-06 02:45:53 | INFO  | main:223 - Processing file /tmp/tmp1t0cw7j8/example.yml 2026-09-06 02:45:53.473350 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-06 02:45:53.473377 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-09-06 02:45:53.473981 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-09-06 02:45:53.480930 | debian-bookworm | .2026-09-06 02:45:53 | INFO  | main:223 - Processing file /tmp/tmpa12u6_zf/example.yml 2026-09-06 02:45:53.484126 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-06 02:45:53.484152 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-06 02:45:53.484658 | debian-bookworm | 2026-09-06 02:45:53 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-09-06 02:45:53.488069 | debian-bookworm | . 2026-09-06 02:45:53.488098 | debian-bookworm | ---------------------------------------------------------------------- 2026-09-06 02:45:53.488104 | debian-bookworm | Ran 74 tests in 1.529s 2026-09-06 02:45:53.488109 | debian-bookworm | 2026-09-06 02:45:53.488113 | debian-bookworm | OK 2026-09-06 02:45:53.634816 | debian-bookworm | test: OK (2.36=setup[0.05]+cmd[2.30] seconds) 2026-09-06 02:45:53.634863 | debian-bookworm | congratulations :) (2.39 seconds) 2026-09-06 02:45:53.755663 | debian-bookworm | ok: Runtime: 0:00:02.742186 2026-09-06 02:45:53.762785 | 2026-09-06 02:45:53.762922 | TASK [tox : Look for output] 2026-09-06 02:45:54.200683 | debian-bookworm | ok 2026-09-06 02:45:54.225979 | 2026-09-06 02:45:54.226127 | TASK [tox : Return file comments to Zuul] 2026-09-06 02:45:54.290682 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:54.297790 | 2026-09-06 02:45:54.297880 | PLAY RECAP 2026-09-06 02:45:54.297935 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-06 02:45:54.297959 | 2026-09-06 02:45:54.641458 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-06 02:45:54.643087 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-06 02:45:55.801685 | 2026-09-06 02:45:55.801842 | PLAY [all] 2026-09-06 02:45:55.821973 | 2026-09-06 02:45:55.822121 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-06 02:45:55.875903 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:55.880851 | 2026-09-06 02:45:55.880958 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-06 02:45:55.963414 | debian-bookworm | ok 2026-09-06 02:45:55.972168 | 2026-09-06 02:45:55.972280 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-06 02:45:56.865562 | debian-bookworm -> localhost | changed 2026-09-06 02:45:56.872861 | 2026-09-06 02:45:56.872960 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-06 02:45:56.922919 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:56.928711 | 2026-09-06 02:45:56.928812 | TASK [fetch-tox-output : Set envlist fact] 2026-09-06 02:45:57.007137 | debian-bookworm | ok 2026-09-06 02:45:57.022877 | 2026-09-06 02:45:57.022992 | TASK [fetch-tox-output : Get tox version] 2026-09-06 02:45:58.084021 | 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-06 02:45:58.103946 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-06 02:45:58.633375 | debian-bookworm | ok: Runtime: 0:00:00.367213 2026-09-06 02:45:58.637855 | 2026-09-06 02:45:58.637934 | TASK [fetch-tox-output : Find all default environments] 2026-09-06 02:45:59.208343 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:59.213205 | 2026-09-06 02:45:59.213296 | TASK [fetch-tox-output : Set envlist fact] 2026-09-06 02:45:59.276293 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:59.280916 | 2026-09-06 02:45:59.281007 | TASK [fetch-tox-output : Find all default environments] 2026-09-06 02:45:59.831896 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:59.836617 | 2026-09-06 02:45:59.836707 | TASK [fetch-tox-output : Set envlist fact] 2026-09-06 02:45:59.899914 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:45:59.904982 | 2026-09-06 02:45:59.905082 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-06 02:45:59.993050 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:46:00.003827 | 2026-09-06 02:46:00.003921 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-06 02:46:01.289737 | debian-bookworm | changed: 2026-09-06 02:46:01.289940 | debian-bookworm | created directory /var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/work/logs/tox/test 2026-09-06 02:46:01.289975 | debian-bookworm | cd+++++++++ ./ 2026-09-06 02:46:01.289996 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-09-06 02:46:01.290022 | 2026-09-06 02:46:01.304957 | 2026-09-06 02:46:01.305027 | PLAY RECAP 2026-09-06 02:46:01.305066 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-06 02:46:01.305086 | 2026-09-06 02:46:01.520999 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-06 02:46:01.521810 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-06 02:46:02.543902 | 2026-09-06 02:46:02.544013 | PLAY [all] 2026-09-06 02:46:02.565204 | 2026-09-06 02:46:02.565314 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-06 02:46:02.844453 | debian-bookworm | changed: non-zero return code 2026-09-06 02:46:02.859768 | 2026-09-06 02:46:02.859886 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-06 02:46:02.883803 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:46:02.888576 | 2026-09-06 02:46:02.888698 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-06 02:46:02.936948 | 2026-09-06 02:46:02.937102 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-06 02:46:02.975596 | 2026-09-06 02:46:02.975746 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-06 02:46:03.008403 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:46:03.013370 | 2026-09-06 02:46:03.013473 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-06 02:46:03.040813 | 2026-09-06 02:46:03.040965 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-06 02:46:03.063666 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:46:03.068763 | 2026-09-06 02:46:03.068861 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-06 02:46:03.091334 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:46:03.095982 | 2026-09-06 02:46:03.096073 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-06 02:46:03.123089 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:46:03.135953 | 2026-09-06 02:46:03.136027 | PLAY RECAP 2026-09-06 02:46:03.136070 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-06 02:46:03.136090 | 2026-09-06 02:46:03.264452 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-06 02:46:03.265413 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-06 02:46:04.585091 | 2026-09-06 02:46:04.585219 | PLAY [Base post-fetch] 2026-09-06 02:46:04.619666 | 2026-09-06 02:46:04.619792 | TASK [diagnose-network : Ensure log directory exists] 2026-09-06 02:46:05.427851 | debian-bookworm | ok 2026-09-06 02:46:05.434991 | 2026-09-06 02:46:05.435111 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-06 02:46:17.233844 | debian-bookworm | ok: Runtime: 0:00:06.080772 2026-09-06 02:46:17.240288 | 2026-09-06 02:46:17.240390 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-06 02:46:23.204731 | debian-bookworm | ok: Runtime: 0:00:00.004804 2026-09-06 02:46:23.211977 | 2026-09-06 02:46:23.212083 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-06 02:46:29.324902 | debian-bookworm | ok: Runtime: 0:00:00.011232 2026-09-06 02:46:29.351064 | 2026-09-06 02:46:29.351170 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-06 02:46:29.414529 | debian-bookworm | skipping: Conditional result was False 2026-09-06 02:46:29.420140 | 2026-09-06 02:46:29.420236 | TASK [fetch-output : Set log path for single node] 2026-09-06 02:46:29.505141 | debian-bookworm | ok 2026-09-06 02:46:29.509807 | 2026-09-06 02:46:29.509908 | LOOP [fetch-output : Ensure local output dirs] 2026-09-06 02:46:30.093317 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/work/logs" 2026-09-06 02:46:30.505061 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/work/artifacts" 2026-09-06 02:46:30.963373 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/eb73c6a7af8442faaa1aec47ca8db6fb/work/docs" 2026-09-06 02:46:30.977847 | 2026-09-06 02:46:30.977948 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-06 02:46:32.269755 | debian-bookworm | changed: 2026-09-06 02:46:32.270008 | debian-bookworm | .d..t...... ./ 2026-09-06 02:46:32.270045 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-06 02:46:32.270065 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-06 02:46:32.270083 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-06 02:46:32.270100 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-06 02:46:32.270117 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-06 02:46:32.270154 | debian-bookworm | changed: All items complete 2026-09-06 02:46:32.270176 | 2026-09-06 02:46:33.262205 | debian-bookworm | changed: .d..t...... ./ 2026-09-06 02:46:33.796011 | debian-bookworm | changed: .d..t...... ./ 2026-09-06 02:46:33.819103 | 2026-09-06 02:46:33.819198 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-06 02:46:34.208054 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.019450 2026-09-06 02:46:34.656380 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.016508 2026-09-06 02:46:34.670130 | 2026-09-06 02:46:34.670199 | PLAY RECAP 2026-09-06 02:46:34.670240 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-06 02:46:34.670260 | 2026-09-06 02:46:34.948310 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-06 02:46:34.949345 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-06 02:46:35.956567 | 2026-09-06 02:46:35.956714 | PLAY [Base post] 2026-09-06 02:46:35.977216 | 2026-09-06 02:46:35.977328 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-06 02:46:36.562454 | debian-bookworm | changed 2026-09-06 02:46:36.571001 | 2026-09-06 02:46:36.571093 | PLAY RECAP 2026-09-06 02:46:36.571154 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-06 02:46:36.571213 | 2026-09-06 02:46:36.723839 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-06 02:46:36.725224 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-06 02:46:37.882487 | 2026-09-06 02:46:37.882603 | PLAY [Base post-logs] 2026-09-06 02:46:37.891069 | 2026-09-06 02:46:37.891167 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-06 02:46:38.253020 | localhost | changed 2026-09-06 02:46:38.265697 | 2026-09-06 02:46:38.265814 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-06 02:46:38.289683 | localhost | ok 2026-09-06 02:46:38.292231 | 2026-09-06 02:46:38.292304 | TASK [Set zuul-log-path fact] 2026-09-06 02:46:38.306142 | localhost | ok 2026-09-06 02:46:38.314092 | 2026-09-06 02:46:38.314182 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-06 02:46:38.348290 | localhost | ok 2026-09-06 02:46:38.351737 | 2026-09-06 02:46:38.351819 | TASK [upload-logs : Create log directories] 2026-09-06 02:46:38.805903 | localhost | changed 2026-09-06 02:46:38.809697 | 2026-09-06 02:46:38.809775 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-06 02:46:39.205385 | localhost -> localhost | ok: Runtime: 0:00:00.002653 2026-09-06 02:46:39.208404 | 2026-09-06 02:46:39.208479 | TASK [upload-logs : Upload logs to log server] 2026-09-06 02:46:39.718997 | localhost | Output suppressed because no_log was given 2026-09-06 02:46:39.720562 | 2026-09-06 02:46:39.720644 | LOOP [upload-logs : Compress console log and json output] 2026-09-06 02:46:39.771484 | localhost | skipping: Conditional result was False 2026-09-06 02:46:39.776063 | localhost | skipping: Conditional result was False 2026-09-06 02:46:39.785854 | 2026-09-06 02:46:39.785959 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-06 02:46:39.829305 | localhost | skipping: Conditional result was False 2026-09-06 02:46:39.829516 | 2026-09-06 02:46:39.834201 | localhost | skipping: Conditional result was False 2026-09-06 02:46:39.842108 | 2026-09-06 02:46:39.842206 | LOOP [upload-logs : Upload console log and json output]