2026-07-05 00:38:19.629456 | Job console starting 2026-07-05 00:38:19.646540 | Updating git repos 2026-07-05 00:38:19.700472 | Cloning repos into workspace 2026-07-05 00:38:19.798337 | Restoring repo states 2026-07-05 00:38:19.810643 | Merging changes 2026-07-05 00:38:20.483633 | Checking out repos 2026-07-05 00:38:20.545873 | Preparing playbooks 2026-07-05 00:38:21.780371 | Running Ansible setup 2026-07-05 00:38:27.705021 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-05 00:38:28.952923 | 2026-07-05 00:38:28.953137 | PLAY [Base pre] 2026-07-05 00:38:28.975892 | 2026-07-05 00:38:28.976089 | TASK [Setup log path fact] 2026-07-05 00:38:29.008167 | debian-bookworm | ok 2026-07-05 00:38:29.030263 | 2026-07-05 00:38:29.030454 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-05 00:38:29.059576 | debian-bookworm | ok 2026-07-05 00:38:29.070250 | 2026-07-05 00:38:29.070425 | TASK [emit-job-header : Print job information] 2026-07-05 00:38:29.112148 | # Job Information 2026-07-05 00:38:29.112373 | Ansible Version: 2.16.14 2026-07-05 00:38:29.112409 | Job: tox 2026-07-05 00:38:29.112441 | Pipeline: check 2026-07-05 00:38:29.112463 | Executor: 521e9411259a 2026-07-05 00:38:29.112483 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1248 2026-07-05 00:38:29.112504 | Event ID: cc14a5a0-7809-11f1-9255-bc89acfa79d8 2026-07-05 00:38:29.118754 | 2026-07-05 00:38:29.118902 | LOOP [emit-job-header : Print node information] 2026-07-05 00:38:29.224406 | debian-bookworm | ok: 2026-07-05 00:38:29.224651 | debian-bookworm | # Node Information 2026-07-05 00:38:29.224690 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-05 00:38:29.224716 | debian-bookworm | Hostname: debian 2026-07-05 00:38:29.224739 | debian-bookworm | Username: zuul 2026-07-05 00:38:29.224759 | debian-bookworm | Distro: Debian 12.14 2026-07-05 00:38:29.224783 | debian-bookworm | Provider: regiocloud-a 2026-07-05 00:38:29.224804 | debian-bookworm | Region: 2026-07-05 00:38:29.224825 | debian-bookworm | Label: debian-bookworm 2026-07-05 00:38:29.224845 | debian-bookworm | Product Name: OpenStack Nova 2026-07-05 00:38:29.224864 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe0b:c8d4 2026-07-05 00:38:29.244049 | 2026-07-05 00:38:29.244167 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-05 00:38:30.139639 | debian-bookworm -> localhost | changed 2026-07-05 00:38:30.148023 | 2026-07-05 00:38:30.148142 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-05 00:38:31.627956 | debian-bookworm -> localhost | changed 2026-07-05 00:38:31.640720 | 2026-07-05 00:38:31.640874 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-05 00:38:32.185984 | debian-bookworm -> localhost | ok 2026-07-05 00:38:32.193554 | 2026-07-05 00:38:32.193669 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-05 00:38:32.225389 | debian-bookworm | ok 2026-07-05 00:38:32.245741 | debian-bookworm | included: /var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-05 00:38:32.254242 | 2026-07-05 00:38:32.254353 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-05 00:38:34.893425 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-05 00:38:34.893648 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/work/f125e0f5c73d4ce6984e3e57b34a037a_id_rsa 2026-07-05 00:38:34.893689 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/work/f125e0f5c73d4ce6984e3e57b34a037a_id_rsa.pub 2026-07-05 00:38:34.893716 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-05 00:38:34.893741 | debian-bookworm -> localhost | SHA256:mB60tZJQ8wDfZKgSh7hPQEZgswErU70HqrhKVrqmizs zuul-build-sshkey 2026-07-05 00:38:34.893764 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-05 00:38:34.893792 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-05 00:38:34.893815 | debian-bookworm -> localhost | |BO.o..+.o | 2026-07-05 00:38:34.893837 | debian-bookworm -> localhost | |=o* +o.B | 2026-07-05 00:38:34.893858 | debian-bookworm -> localhost | |++ +.+o + | 2026-07-05 00:38:34.893878 | debian-bookworm -> localhost | |o.+ oo.* . | 2026-07-05 00:38:34.893898 | debian-bookworm -> localhost | |.+ o .B S | 2026-07-05 00:38:34.893924 | debian-bookworm -> localhost | |o + . o | 2026-07-05 00:38:34.893945 | debian-bookworm -> localhost | | = . | 2026-07-05 00:38:34.893965 | debian-bookworm -> localhost | |E.. | 2026-07-05 00:38:34.893986 | debian-bookworm -> localhost | |X= | 2026-07-05 00:38:34.894005 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-05 00:38:34.894054 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.957015 2026-07-05 00:38:34.911867 | 2026-07-05 00:38:34.911982 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-05 00:38:34.964292 | debian-bookworm | ok 2026-07-05 00:38:34.976656 | debian-bookworm | included: /var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-05 00:38:34.985527 | 2026-07-05 00:38:34.985631 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-05 00:38:35.019820 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:38:35.026309 | 2026-07-05 00:38:35.026419 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-05 00:38:35.840752 | debian-bookworm | changed 2026-07-05 00:38:35.846778 | 2026-07-05 00:38:35.846904 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-05 00:38:36.127765 | debian-bookworm | ok 2026-07-05 00:38:36.140966 | 2026-07-05 00:38:36.141117 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-05 00:38:36.996862 | debian-bookworm | changed 2026-07-05 00:38:37.012176 | 2026-07-05 00:38:37.012330 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-05 00:38:37.840340 | debian-bookworm | changed 2026-07-05 00:38:37.846639 | 2026-07-05 00:38:37.850934 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-05 00:38:37.901581 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:38:37.906765 | 2026-07-05 00:38:37.907044 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-05 00:38:38.724934 | debian-bookworm -> localhost | changed 2026-07-05 00:38:38.735041 | 2026-07-05 00:38:38.735142 | TASK [add-build-sshkey : Add back temp key] 2026-07-05 00:38:39.329894 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/work/f125e0f5c73d4ce6984e3e57b34a037a_id_rsa (zuul-build-sshkey) 2026-07-05 00:38:39.330124 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012547 2026-07-05 00:38:39.336659 | 2026-07-05 00:38:39.336759 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-05 00:38:39.911673 | debian-bookworm | ok 2026-07-05 00:38:39.920501 | 2026-07-05 00:38:39.920606 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-05 00:38:39.973006 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:38:39.982505 | 2026-07-05 00:38:39.982606 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-05 00:38:40.534777 | debian-bookworm | ok 2026-07-05 00:38:40.544330 | 2026-07-05 00:38:40.544436 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-05 00:38:40.607461 | debian-bookworm | ok 2026-07-05 00:38:40.612052 | 2026-07-05 00:38:40.612143 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-05 00:38:41.069935 | debian-bookworm -> localhost | ok 2026-07-05 00:38:41.075968 | 2026-07-05 00:38:41.076062 | TASK [validate-host : Collect information about the host] 2026-07-05 00:38:43.358085 | debian-bookworm | ok 2026-07-05 00:38:43.373100 | 2026-07-05 00:38:43.373225 | TASK [validate-host : Sanitize hostname] 2026-07-05 00:38:43.579789 | debian-bookworm | ok 2026-07-05 00:38:43.607097 | 2026-07-05 00:38:43.607224 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-05 00:38:44.684725 | debian-bookworm -> localhost | changed 2026-07-05 00:38:44.690904 | 2026-07-05 00:38:44.691011 | TASK [validate-host : Collect information about zuul worker] 2026-07-05 00:38:45.171231 | debian-bookworm | ok 2026-07-05 00:38:45.175729 | 2026-07-05 00:38:45.175824 | TASK [validate-host : Write out all zuul information for each host] 2026-07-05 00:38:45.935738 | debian-bookworm -> localhost | changed 2026-07-05 00:38:45.944878 | 2026-07-05 00:38:45.944986 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-05 00:38:46.254823 | debian-bookworm | ok 2026-07-05 00:38:46.260347 | 2026-07-05 00:38:46.260439 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-05 00:38:47.743956 | debian-bookworm | changed: 2026-07-05 00:38:47.744132 | debian-bookworm | cd+++++++++ src/ 2026-07-05 00:38:47.744230 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-05 00:38:47.744257 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-05 00:38:47.744279 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-07-05 00:38:47.744300 | debian-bookworm | =7.0.3 2026-07-05 00:39:26.687902 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-07-05 00:39:26.736191 | debian-bookworm | Collecting colorama>=0.4.6 2026-07-05 00:39:26.752206 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-07-05 00:39:26.823773 | debian-bookworm | Collecting filelock>=3.25 2026-07-05 00:39:26.839282 | debian-bookworm | Downloading filelock-3.29.5-py3-none-any.whl (45 kB) 2026-07-05 00:39:26.848856 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.1/45.1 kB 6.2 MB/s eta 0:00:00 2026-07-05 00:39:26.897813 | debian-bookworm | Collecting packaging>=26 2026-07-05 00:39:26.913030 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-07-05 00:39:26.926591 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 9.4 MB/s eta 0:00:00 2026-07-05 00:39:26.984208 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-07-05 00:39:27.001086 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-07-05 00:39:27.060381 | debian-bookworm | Collecting pluggy>=1.6 2026-07-05 00:39:27.075687 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-07-05 00:39:27.121287 | debian-bookworm | Collecting pyproject-api>=1.10 2026-07-05 00:39:27.137383 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-07-05 00:39:27.177019 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-07-05 00:39:27.191546 | debian-bookworm | Downloading python_discovery-1.4.3-py3-none-any.whl (33 kB) 2026-07-05 00:39:27.227933 | debian-bookworm | Collecting tomli-w>=1.2 2026-07-05 00:39:27.244081 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-07-05 00:39:27.410400 | debian-bookworm | Collecting virtualenv>=21.1 2026-07-05 00:39:27.424640 | debian-bookworm | Downloading virtualenv-21.5.1-py3-none-any.whl (4.6 MB) 2026-07-05 00:39:27.717538 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 15.8 MB/s eta 0:00:00 2026-07-05 00:39:27.822220 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-07-05 00:39:27.836929 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-07-05 00:39:27.862718 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 21.1 MB/s eta 0:00:00 2026-07-05 00:39:27.983871 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-07-05 00:39:28.824224 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.3 filelock-3.29.5 packaging-26.2 platformdirs-4.10.0 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.3 tomli-w-1.2.0 tox-4.56.1 virtualenv-21.5.1 2026-07-05 00:39:29.349920 | debian-bookworm | ok: Runtime: 0:00:03.272382 2026-07-05 00:39:29.357031 | 2026-07-05 00:39:29.357167 | TASK [ensure-tox : Export installed tox_executable path] 2026-07-05 00:39:29.420773 | debian-bookworm | ok 2026-07-05 00:39:29.434710 | 2026-07-05 00:39:29.434899 | TASK [ensure-tox : Output tox version] 2026-07-05 00:39:30.032743 | 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-07-05 00:39:30.050860 | debian-bookworm | 4.56.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-05 00:39:30.494726 | debian-bookworm | ok: Runtime: 0:00:00.346411 2026-07-05 00:39:30.501303 | 2026-07-05 00:39:30.501432 | TASK [ensure-tox : Make global symlink] 2026-07-05 00:39:30.546825 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:30.572529 | 2026-07-05 00:39:30.572693 | PLAY RECAP 2026-07-05 00:39:30.572763 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-07-05 00:39:30.572789 | 2026-07-05 00:39:30.836818 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-07-05 00:39:30.838070 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-05 00:39:31.851482 | 2026-07-05 00:39:31.851668 | PLAY [all] 2026-07-05 00:39:31.885268 | 2026-07-05 00:39:31.885461 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-07-05 00:39:32.395396 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-05 00:39:32.455349 | debian-bookworm | ok: Runtime: 0:00:00.034031 2026-07-05 00:39:32.463525 | 2026-07-05 00:39:32.463731 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-07-05 00:39:33.004682 | debian-bookworm | ok: Runtime: 0:00:00.007614 2026-07-05 00:39:33.020351 | 2026-07-05 00:39:33.020588 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-07-05 00:39:33.379575 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-05 00:39:33.383582 | debian-bookworm | sudo: a password is required 2026-07-05 00:39:33.558448 | debian-bookworm | ok: Runtime: 0:00:00.027053 2026-07-05 00:39:33.570289 | 2026-07-05 00:39:33.570431 | TASK [tox : Check to see if the constraints file exists] 2026-07-05 00:39:33.596077 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:33.604790 | 2026-07-05 00:39:33.604955 | TASK [tox : Fail if constraints file is missing] 2026-07-05 00:39:33.640199 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:33.648379 | 2026-07-05 00:39:33.648557 | TASK [tox : Record file location] 2026-07-05 00:39:33.684015 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:33.691570 | 2026-07-05 00:39:33.691758 | TASK [tox : Install tox siblings] 2026-07-05 00:39:33.733058 | debian-bookworm | ok 2026-07-05 00:39:33.745981 | debian-bookworm | included: /var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-07-05 00:39:33.753575 | 2026-07-05 00:39:33.753729 | TASK [tox : Run tox without tests] 2026-07-05 00:39:35.217483 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-07-05 00:39:49.637768 | debian-bookworm | test: OK (15.28 seconds) 2026-07-05 00:39:49.637946 | debian-bookworm | congratulations :) (15.31 seconds) 2026-07-05 00:39:49.798686 | debian-bookworm | ok: Runtime: 0:00:15.633562 2026-07-05 00:39:49.805338 | 2026-07-05 00:39:49.805481 | TASK [tox : Create a tempfile to save tox showconfig] 2026-07-05 00:39:50.587999 | debian-bookworm | changed 2026-07-05 00:39:50.596171 | 2026-07-05 00:39:50.596343 | TASK [tox : Detect tox version] 2026-07-05 00:39:51.254906 | debian-bookworm | 4.56.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-05 00:39:51.671337 | debian-bookworm | ok: Runtime: 0:00:00.338386 2026-07-05 00:39:51.676738 | 2026-07-05 00:39:51.676829 | TASK [tox : Get tox envlist config] 2026-07-05 00:39:52.740071 | debian-bookworm | ok: Runtime: 0:00:00.500895 2026-07-05 00:39:52.745882 | 2026-07-05 00:39:52.745976 | TASK [tox : Install any sibling python packages] 2026-07-05 00:39:53.312859 | debian-bookworm | ok: No setup.cfg, no action needed 2026-07-05 00:39:53.318958 | 2026-07-05 00:39:53.319080 | TASK [tox : Remove tempfile] 2026-07-05 00:39:53.816625 | debian-bookworm | changed 2026-07-05 00:39:53.824057 | 2026-07-05 00:39:53.824153 | TASK [tox : Emit tox command] 2026-07-05 00:39:53.865927 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-07-05 00:39:53.871860 | 2026-07-05 00:39:53.871962 | TASK [tox : Run tox] 2026-07-05 00:39:54.589757 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-07-05 00:39:55.463895 | debian-bookworm | .......................... 2026-07-05 00:39:55.463956 | debian-bookworm | ---------------------------------------------------------------------- 2026-07-05 00:39:55.464315 | debian-bookworm | Ran 26 tests in 0.236s 2026-07-05 00:39:55.464327 | debian-bookworm | 2026-07-05 00:39:55.464333 | debian-bookworm | OK 2026-07-05 00:39:55.583702 | debian-bookworm | test: OK (1.04=setup[0.05]+cmd[0.99] seconds) 2026-07-05 00:39:55.583777 | debian-bookworm | congratulations :) (1.07 seconds) 2026-07-05 00:39:55.925614 | debian-bookworm | ok: Runtime: 0:00:01.427423 2026-07-05 00:39:55.933694 | 2026-07-05 00:39:55.933787 | TASK [tox : Look for output] 2026-07-05 00:39:56.427515 | debian-bookworm | ok 2026-07-05 00:39:56.462100 | 2026-07-05 00:39:56.462249 | TASK [tox : Return file comments to Zuul] 2026-07-05 00:39:56.488258 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:56.495273 | 2026-07-05 00:39:56.495357 | PLAY RECAP 2026-07-05 00:39:56.495422 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-07-05 00:39:56.495452 | 2026-07-05 00:39:56.629954 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-05 00:39:56.631354 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-05 00:39:57.894724 | 2026-07-05 00:39:57.894979 | PLAY [all] 2026-07-05 00:39:57.916514 | 2026-07-05 00:39:57.916720 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-07-05 00:39:57.982958 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:57.989803 | 2026-07-05 00:39:57.989971 | TASK [fetch-tox-output : Set tox log path for single node] 2026-07-05 00:39:58.060760 | debian-bookworm | ok 2026-07-05 00:39:58.066140 | 2026-07-05 00:39:58.066285 | TASK [fetch-tox-output : Ensure local tox dir] 2026-07-05 00:39:59.352927 | debian-bookworm -> localhost | changed 2026-07-05 00:39:59.371534 | 2026-07-05 00:39:59.371821 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-07-05 00:39:59.489534 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:59.499236 | 2026-07-05 00:39:59.499405 | TASK [fetch-tox-output : Set envlist fact] 2026-07-05 00:39:59.587110 | debian-bookworm | ok 2026-07-05 00:39:59.593662 | 2026-07-05 00:39:59.593801 | TASK [fetch-tox-output : Get tox version] 2026-07-05 00:40:00.456311 | 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-07-05 00:40:00.474374 | debian-bookworm | 4.56.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-05 00:40:00.639341 | debian-bookworm | ok: Runtime: 0:00:00.337529 2026-07-05 00:40:00.645390 | 2026-07-05 00:40:00.645524 | TASK [fetch-tox-output : Find all default environments] 2026-07-05 00:40:01.216924 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:01.224628 | 2026-07-05 00:40:01.224804 | TASK [fetch-tox-output : Set envlist fact] 2026-07-05 00:40:01.290269 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:01.297575 | 2026-07-05 00:40:01.297745 | TASK [fetch-tox-output : Find all default environments] 2026-07-05 00:40:01.862674 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:01.870432 | 2026-07-05 00:40:01.870582 | TASK [fetch-tox-output : Set envlist fact] 2026-07-05 00:40:01.960751 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:01.974442 | 2026-07-05 00:40:01.974626 | LOOP [fetch-tox-output : Copy tox logs] 2026-07-05 00:40:02.095125 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:02.108620 | 2026-07-05 00:40:02.108801 | LOOP [fetch-tox-output : Collect tox logs] 2026-07-05 00:40:03.721139 | debian-bookworm | changed: 2026-07-05 00:40:03.725070 | debian-bookworm | created directory /var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/work/logs/tox/test 2026-07-05 00:40:03.727158 | debian-bookworm | cd+++++++++ ./ 2026-07-05 00:40:03.727279 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-07-05 00:40:03.727323 | 2026-07-05 00:40:03.738431 | 2026-07-05 00:40:03.738536 | PLAY RECAP 2026-07-05 00:40:03.738590 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-07-05 00:40:03.738614 | 2026-07-05 00:40:04.511373 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-05 00:40:04.512653 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-05 00:40:05.935367 | 2026-07-05 00:40:05.935593 | PLAY [all] 2026-07-05 00:40:05.977347 | 2026-07-05 00:40:05.977579 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-07-05 00:40:06.304845 | debian-bookworm | changed: non-zero return code 2026-07-05 00:40:06.309516 | 2026-07-05 00:40:06.309640 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-07-05 00:40:06.352019 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:06.361797 | 2026-07-05 00:40:06.361941 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-07-05 00:40:06.383492 | 2026-07-05 00:40:06.383750 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-07-05 00:40:06.416675 | 2026-07-05 00:40:06.416851 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-07-05 00:40:06.429910 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:06.435720 | 2026-07-05 00:40:06.435821 | LOOP [fetch-subunit-output : Generate subunit file] 2026-07-05 00:40:06.485275 | 2026-07-05 00:40:06.485467 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-07-05 00:40:06.508967 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:06.522814 | 2026-07-05 00:40:06.522954 | TASK [fetch-subunit-output : Remove the temporary file] 2026-07-05 00:40:06.561157 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:06.567102 | 2026-07-05 00:40:06.567224 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-07-05 00:40:06.610911 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:06.624379 | 2026-07-05 00:40:06.624488 | PLAY RECAP 2026-07-05 00:40:06.624545 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-07-05 00:40:06.624572 | 2026-07-05 00:40:06.901841 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-05 00:40:06.902752 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-05 00:40:07.978561 | 2026-07-05 00:40:07.978713 | PLAY [Base post-fetch] 2026-07-05 00:40:08.001008 | 2026-07-05 00:40:08.001169 | TASK [diagnose-network : Ensure log directory exists] 2026-07-05 00:40:08.491864 | debian-bookworm | ok 2026-07-05 00:40:08.506605 | 2026-07-05 00:40:08.506776 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-05 00:40:20.677842 | debian-bookworm | ok: Runtime: 0:00:05.995597 2026-07-05 00:40:20.687078 | 2026-07-05 00:40:20.687192 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-05 00:40:26.707481 | debian-bookworm | ok: Runtime: 0:00:00.009561 2026-07-05 00:40:26.712815 | 2026-07-05 00:40:26.712938 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-05 00:40:32.910214 | debian-bookworm | ok: Runtime: 0:00:00.005237 2026-07-05 00:40:32.923779 | 2026-07-05 00:40:32.923935 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-05 00:40:32.979903 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:33.007483 | 2026-07-05 00:40:33.007670 | TASK [fetch-output : Set log path for single node] 2026-07-05 00:40:33.068285 | debian-bookworm | ok 2026-07-05 00:40:33.076087 | 2026-07-05 00:40:33.076265 | LOOP [fetch-output : Ensure local output dirs] 2026-07-05 00:40:33.381844 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/work/logs" 2026-07-05 00:40:33.653156 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/work/artifacts" 2026-07-05 00:40:33.937790 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f125e0f5c73d4ce6984e3e57b34a037a/work/docs" 2026-07-05 00:40:33.952905 | 2026-07-05 00:40:33.953177 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-05 00:40:34.832638 | debian-bookworm | changed: 2026-07-05 00:40:34.832923 | debian-bookworm | .d..t...... ./ 2026-07-05 00:40:34.832966 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-05 00:40:34.832995 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-05 00:40:34.833021 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-05 00:40:34.833047 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-05 00:40:34.833071 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-05 00:40:34.833118 | debian-bookworm | changed: All items complete 2026-07-05 00:40:34.833145 | 2026-07-05 00:40:35.479547 | debian-bookworm | changed: .d..t...... ./ 2026-07-05 00:40:36.073075 | debian-bookworm | changed: .d..t...... ./ 2026-07-05 00:40:36.095385 | 2026-07-05 00:40:36.095551 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-05 00:40:36.515674 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010775 2026-07-05 00:40:36.845792 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007866 2026-07-05 00:40:36.864828 | 2026-07-05 00:40:36.864969 | PLAY RECAP 2026-07-05 00:40:36.865028 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-05 00:40:36.865053 | 2026-07-05 00:40:37.054069 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-05 00:40:37.055299 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-05 00:40:37.905617 | 2026-07-05 00:40:37.905825 | PLAY [Base post] 2026-07-05 00:40:37.921914 | 2026-07-05 00:40:37.922090 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-05 00:40:38.556662 | debian-bookworm | changed 2026-07-05 00:40:38.565644 | 2026-07-05 00:40:38.565797 | PLAY RECAP 2026-07-05 00:40:38.565871 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-05 00:40:38.565940 | 2026-07-05 00:40:38.761781 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-05 00:40:38.763025 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-05 00:40:39.660578 | 2026-07-05 00:40:39.660786 | PLAY [Base post-logs] 2026-07-05 00:40:39.672718 | 2026-07-05 00:40:39.672897 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-05 00:40:40.163472 | localhost | changed 2026-07-05 00:40:40.184819 | 2026-07-05 00:40:40.185167 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-05 00:40:40.219525 | localhost | ok 2026-07-05 00:40:40.228364 | 2026-07-05 00:40:40.228595 | TASK [Set zuul-log-path fact] 2026-07-05 00:40:40.248671 | localhost | ok 2026-07-05 00:40:40.262991 | 2026-07-05 00:40:40.263173 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-05 00:40:40.294653 | localhost | ok 2026-07-05 00:40:40.302628 | 2026-07-05 00:40:40.302889 | TASK [upload-logs : Create log directories] 2026-07-05 00:40:40.854413 | localhost | changed 2026-07-05 00:40:40.857543 | 2026-07-05 00:40:40.857662 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-05 00:40:41.431797 | localhost -> localhost | ok: Runtime: 0:00:00.005608 2026-07-05 00:40:41.443348 | 2026-07-05 00:40:41.443582 | TASK [upload-logs : Upload logs to log server] 2026-07-05 00:40:42.068017 | localhost | Output suppressed because no_log was given 2026-07-05 00:40:42.070074 | 2026-07-05 00:40:42.070181 | LOOP [upload-logs : Compress console log and json output] 2026-07-05 00:40:42.131729 | localhost | skipping: Conditional result was False 2026-07-05 00:40:42.142661 | localhost | skipping: Conditional result was False 2026-07-05 00:40:42.157525 | 2026-07-05 00:40:42.157723 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-05 00:40:42.210609 | localhost | skipping: Conditional result was False 2026-07-05 00:40:42.210985 | 2026-07-05 00:40:42.215703 | localhost | skipping: Conditional result was False 2026-07-05 00:40:42.227132 | 2026-07-05 00:40:42.227267 | LOOP [upload-logs : Upload console log and json output]