2026-07-19 02:23:50.454464 | Job console starting 2026-07-19 02:23:50.473942 | Updating git repos 2026-07-19 02:23:50.514252 | Cloning repos into workspace 2026-07-19 02:23:50.606990 | Restoring repo states 2026-07-19 02:23:50.629308 | Merging changes 2026-07-19 02:23:51.312629 | Checking out repos 2026-07-19 02:23:51.403616 | Preparing playbooks 2026-07-19 02:23:52.712645 | Running Ansible setup 2026-07-19 02:23:57.710174 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-19 02:23:59.236742 | 2026-07-19 02:23:59.236881 | PLAY [Base pre] 2026-07-19 02:23:59.261236 | 2026-07-19 02:23:59.261356 | TASK [Setup log path fact] 2026-07-19 02:23:59.280222 | debian-bookworm | ok 2026-07-19 02:23:59.296166 | 2026-07-19 02:23:59.296312 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-19 02:23:59.325141 | debian-bookworm | ok 2026-07-19 02:23:59.335787 | 2026-07-19 02:23:59.335903 | TASK [emit-job-header : Print job information] 2026-07-19 02:23:59.397124 | # Job Information 2026-07-19 02:23:59.397500 | Ansible Version: 2.16.14 2026-07-19 02:23:59.397568 | Job: tox 2026-07-19 02:23:59.397693 | Pipeline: check 2026-07-19 02:23:59.397721 | Executor: 521e9411259a 2026-07-19 02:23:59.397744 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1249 2026-07-19 02:23:59.397766 | Event ID: bc7e17b0-8318-11f1-8dbe-78fee43464e1 2026-07-19 02:23:59.405693 | 2026-07-19 02:23:59.405800 | LOOP [emit-job-header : Print node information] 2026-07-19 02:23:59.557056 | debian-bookworm | ok: 2026-07-19 02:23:59.557404 | debian-bookworm | # Node Information 2026-07-19 02:23:59.557450 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-19 02:23:59.557478 | debian-bookworm | Hostname: debian 2026-07-19 02:23:59.557502 | debian-bookworm | Username: zuul 2026-07-19 02:23:59.557524 | debian-bookworm | Distro: Debian 12.15 2026-07-19 02:23:59.557549 | debian-bookworm | Provider: regiocloud-a 2026-07-19 02:23:59.557571 | debian-bookworm | Region: 2026-07-19 02:23:59.557593 | debian-bookworm | Label: debian-bookworm 2026-07-19 02:23:59.557613 | debian-bookworm | Product Name: OpenStack Nova 2026-07-19 02:23:59.557633 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb3:dd0d 2026-07-19 02:23:59.576397 | 2026-07-19 02:23:59.576509 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-19 02:24:00.348257 | debian-bookworm -> localhost | changed 2026-07-19 02:24:00.354043 | 2026-07-19 02:24:00.354143 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-19 02:24:02.093798 | debian-bookworm -> localhost | changed 2026-07-19 02:24:02.102794 | 2026-07-19 02:24:02.102902 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-19 02:24:02.630065 | debian-bookworm -> localhost | ok 2026-07-19 02:24:02.636434 | 2026-07-19 02:24:02.636545 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-19 02:24:02.676383 | debian-bookworm | ok 2026-07-19 02:24:02.730256 | debian-bookworm | included: /var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-19 02:24:02.756368 | 2026-07-19 02:24:02.756477 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-19 02:24:05.067389 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-19 02:24:05.068502 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/work/09ee7632429048e094207720b0c11b57_id_rsa 2026-07-19 02:24:05.068569 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/work/09ee7632429048e094207720b0c11b57_id_rsa.pub 2026-07-19 02:24:05.068598 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-19 02:24:05.068624 | debian-bookworm -> localhost | SHA256:pvcIAwfvDiUc7YsJ8964VwwlbMtMAMvWj8V3B38Puyw zuul-build-sshkey 2026-07-19 02:24:05.068646 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-19 02:24:05.068678 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-19 02:24:05.068702 | debian-bookworm -> localhost | | ...o . | 2026-07-19 02:24:05.068723 | debian-bookworm -> localhost | | . o o= . o | 2026-07-19 02:24:05.068743 | debian-bookworm -> localhost | | + +=++. . o o | 2026-07-19 02:24:05.068764 | debian-bookworm -> localhost | | . . O=. . . . + | 2026-07-19 02:24:05.068784 | debian-bookworm -> localhost | | o = *oS . .| 2026-07-19 02:24:05.068808 | debian-bookworm -> localhost | | + O +o . . | 2026-07-19 02:24:05.068828 | debian-bookworm -> localhost | | = *.. E o | 2026-07-19 02:24:05.068847 | debian-bookworm -> localhost | | . =.+ o . | 2026-07-19 02:24:05.068868 | debian-bookworm -> localhost | | +oo . . | 2026-07-19 02:24:05.068888 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-19 02:24:05.068942 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.165316 2026-07-19 02:24:05.075668 | 2026-07-19 02:24:05.075757 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-19 02:24:05.114632 | debian-bookworm | ok 2026-07-19 02:24:05.136542 | debian-bookworm | included: /var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-19 02:24:05.165573 | 2026-07-19 02:24:05.167024 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-19 02:24:05.181838 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:24:05.199316 | 2026-07-19 02:24:05.199610 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-19 02:24:06.086997 | debian-bookworm | changed 2026-07-19 02:24:06.096975 | 2026-07-19 02:24:06.097073 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-19 02:24:06.405286 | debian-bookworm | ok 2026-07-19 02:24:06.410377 | 2026-07-19 02:24:06.410468 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-19 02:24:07.131463 | debian-bookworm | changed 2026-07-19 02:24:07.139272 | 2026-07-19 02:24:07.139369 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-19 02:24:07.880797 | debian-bookworm | changed 2026-07-19 02:24:07.889711 | 2026-07-19 02:24:07.889815 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-19 02:24:07.917260 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:24:07.929163 | 2026-07-19 02:24:07.929299 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-19 02:24:08.546368 | debian-bookworm -> localhost | changed 2026-07-19 02:24:08.555806 | 2026-07-19 02:24:08.557337 | TASK [add-build-sshkey : Add back temp key] 2026-07-19 02:24:08.995900 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/work/09ee7632429048e094207720b0c11b57_id_rsa (zuul-build-sshkey) 2026-07-19 02:24:08.996343 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021931 2026-07-19 02:24:09.007950 | 2026-07-19 02:24:09.008027 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-19 02:24:09.491540 | debian-bookworm | ok 2026-07-19 02:24:09.495854 | 2026-07-19 02:24:09.495933 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-19 02:24:09.542551 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:24:09.551084 | 2026-07-19 02:24:09.551181 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-19 02:24:10.193712 | debian-bookworm | ok 2026-07-19 02:24:10.227555 | 2026-07-19 02:24:10.227858 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-19 02:24:10.334367 | debian-bookworm | ok 2026-07-19 02:24:10.345147 | 2026-07-19 02:24:10.345290 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-19 02:24:10.939234 | debian-bookworm -> localhost | ok 2026-07-19 02:24:10.945895 | 2026-07-19 02:24:10.945995 | TASK [validate-host : Collect information about the host] 2026-07-19 02:24:12.210671 | debian-bookworm | ok 2026-07-19 02:24:12.232498 | 2026-07-19 02:24:12.232615 | TASK [validate-host : Sanitize hostname] 2026-07-19 02:24:12.601387 | debian-bookworm | ok 2026-07-19 02:24:12.614376 | 2026-07-19 02:24:12.614479 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-19 02:24:13.644037 | debian-bookworm -> localhost | changed 2026-07-19 02:24:13.649887 | 2026-07-19 02:24:13.649985 | TASK [validate-host : Collect information about zuul worker] 2026-07-19 02:24:14.072501 | debian-bookworm | ok 2026-07-19 02:24:14.077252 | 2026-07-19 02:24:14.077350 | TASK [validate-host : Write out all zuul information for each host] 2026-07-19 02:24:14.635554 | debian-bookworm -> localhost | changed 2026-07-19 02:24:14.644873 | 2026-07-19 02:24:14.644972 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-19 02:24:14.924782 | debian-bookworm | ok 2026-07-19 02:24:14.930682 | 2026-07-19 02:24:14.930798 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-19 02:24:16.229406 | debian-bookworm | changed: 2026-07-19 02:24:16.229629 | debian-bookworm | cd+++++++++ src/ 2026-07-19 02:24:16.229674 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-19 02:24:16.229705 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-19 02:24:16.229732 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-07-19 02:24:16.229759 | debian-bookworm | =7.0.3 2026-07-19 02:24:58.175293 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-07-19 02:24:58.220804 | debian-bookworm | Collecting colorama>=0.4.6 2026-07-19 02:24:58.237438 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-07-19 02:24:58.336760 | debian-bookworm | Collecting filelock>=3.25 2026-07-19 02:24:58.352277 | debian-bookworm | Downloading filelock-3.31.0-py3-none-any.whl (96 kB) 2026-07-19 02:24:58.370260 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 96.1/96.1 kB 6.1 MB/s eta 0:00:00 2026-07-19 02:24:58.437198 | debian-bookworm | Collecting packaging>=26 2026-07-19 02:24:58.451612 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-07-19 02:24:58.469445 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 8.0 MB/s eta 0:00:00 2026-07-19 02:24:58.534678 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-07-19 02:24:58.549727 | debian-bookworm | Downloading platformdirs-4.10.1-py3-none-any.whl (22 kB) 2026-07-19 02:24:58.606865 | debian-bookworm | Collecting pluggy>=1.6 2026-07-19 02:24:58.622122 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-07-19 02:24:58.686841 | debian-bookworm | Collecting pyproject-api>=1.10 2026-07-19 02:24:58.702867 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-07-19 02:24:58.758706 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-07-19 02:24:58.774862 | debian-bookworm | Downloading python_discovery-1.4.4-py3-none-any.whl (34 kB) 2026-07-19 02:24:58.827033 | debian-bookworm | Collecting tomli-w>=1.2 2026-07-19 02:24:58.841930 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-07-19 02:24:59.007980 | debian-bookworm | Collecting virtualenv>=21.1 2026-07-19 02:24:59.023115 | debian-bookworm | Downloading virtualenv-21.6.1-py3-none-any.whl (5.5 MB) 2026-07-19 02:24:59.422519 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.5/5.5 MB 14.0 MB/s eta 0:00:00 2026-07-19 02:24:59.534584 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-07-19 02:24:59.549117 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-07-19 02:24:59.572086 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 24.1 MB/s eta 0:00:00 2026-07-19 02:24:59.681356 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-07-19 02:25:00.568538 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.3 filelock-3.31.0 packaging-26.2 platformdirs-4.10.1 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.4 tomli-w-1.2.0 tox-4.57.0 virtualenv-21.6.1 2026-07-19 02:25:00.903757 | debian-bookworm | ok: Runtime: 0:00:03.532102 2026-07-19 02:25:00.909905 | 2026-07-19 02:25:00.910008 | TASK [ensure-tox : Export installed tox_executable path] 2026-07-19 02:25:00.985295 | debian-bookworm | ok 2026-07-19 02:25:00.990188 | 2026-07-19 02:25:00.990276 | TASK [ensure-tox : Output tox version] 2026-07-19 02:25:01.700576 | 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-19 02:25:01.719845 | debian-bookworm | 4.57.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-19 02:25:02.033772 | debian-bookworm | ok: Runtime: 0:00:00.485967 2026-07-19 02:25:02.040592 | 2026-07-19 02:25:02.040719 | TASK [ensure-tox : Make global symlink] 2026-07-19 02:25:02.100405 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:02.109817 | 2026-07-19 02:25:02.110062 | PLAY RECAP 2026-07-19 02:25:02.110158 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-07-19 02:25:02.110187 | 2026-07-19 02:25:02.560530 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-07-19 02:25:02.561571 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-19 02:25:03.425432 | 2026-07-19 02:25:03.425576 | PLAY [all] 2026-07-19 02:25:03.440234 | 2026-07-19 02:25:03.440365 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-07-19 02:25:04.021987 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-19 02:25:04.476662 | debian-bookworm | ok: Runtime: 0:00:00.026257 2026-07-19 02:25:04.482208 | 2026-07-19 02:25:04.482318 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-07-19 02:25:05.038590 | debian-bookworm | ok: Runtime: 0:00:00.006731 2026-07-19 02:25:05.044551 | 2026-07-19 02:25:05.044653 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-07-19 02:25:05.386969 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-19 02:25:05.388520 | debian-bookworm | sudo: a password is required 2026-07-19 02:25:05.603219 | debian-bookworm | ok: Runtime: 0:00:00.019298 2026-07-19 02:25:05.616775 | 2026-07-19 02:25:05.616926 | TASK [tox : Check to see if the constraints file exists] 2026-07-19 02:25:05.651770 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:05.657950 | 2026-07-19 02:25:05.658047 | TASK [tox : Fail if constraints file is missing] 2026-07-19 02:25:05.743643 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:05.757861 | 2026-07-19 02:25:05.760130 | TASK [tox : Record file location] 2026-07-19 02:25:05.834930 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:05.841421 | 2026-07-19 02:25:05.841543 | TASK [tox : Install tox siblings] 2026-07-19 02:25:05.899116 | debian-bookworm | ok 2026-07-19 02:25:05.919689 | debian-bookworm | included: /var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-07-19 02:25:05.932471 | 2026-07-19 02:25:05.932591 | TASK [tox : Run tox without tests] 2026-07-19 02:25:07.317315 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-07-19 02:25:21.547373 | debian-bookworm | test: OK (14.98 seconds) 2026-07-19 02:25:21.547430 | debian-bookworm | congratulations :) (15.02 seconds) 2026-07-19 02:25:21.987268 | debian-bookworm | ok: Runtime: 0:00:15.358463 2026-07-19 02:25:22.009139 | 2026-07-19 02:25:22.009433 | TASK [tox : Create a tempfile to save tox showconfig] 2026-07-19 02:25:22.512552 | debian-bookworm | changed 2026-07-19 02:25:22.518728 | 2026-07-19 02:25:22.518912 | TASK [tox : Detect tox version] 2026-07-19 02:25:23.144800 | debian-bookworm | 4.57.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-19 02:25:23.551039 | debian-bookworm | ok: Runtime: 0:00:00.384981 2026-07-19 02:25:23.563351 | 2026-07-19 02:25:23.563491 | TASK [tox : Get tox envlist config] 2026-07-19 02:25:24.603361 | debian-bookworm | ok: Runtime: 0:00:00.426670 2026-07-19 02:25:24.619124 | 2026-07-19 02:25:24.619442 | TASK [tox : Install any sibling python packages] 2026-07-19 02:25:25.120937 | debian-bookworm | ok: No setup.cfg, no action needed 2026-07-19 02:25:25.131543 | 2026-07-19 02:25:25.131746 | TASK [tox : Remove tempfile] 2026-07-19 02:25:25.692953 | debian-bookworm | changed 2026-07-19 02:25:25.699557 | 2026-07-19 02:25:25.699680 | TASK [tox : Emit tox command] 2026-07-19 02:25:25.740442 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-07-19 02:25:25.746276 | 2026-07-19 02:25:25.746421 | TASK [tox : Run tox] 2026-07-19 02:25:26.432760 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-07-19 02:25:28.360262 | debian-bookworm | ............................................. 2026-07-19 02:25:28.360323 | debian-bookworm | ---------------------------------------------------------------------- 2026-07-19 02:25:28.360331 | debian-bookworm | Ran 45 tests in 1.415s 2026-07-19 02:25:28.360336 | debian-bookworm | 2026-07-19 02:25:28.360342 | debian-bookworm | OK 2026-07-19 02:25:28.470553 | debian-bookworm | test: OK (2.09=setup[0.05]+cmd[2.03] seconds) 2026-07-19 02:25:28.470622 | debian-bookworm | congratulations :) (2.12 seconds) 2026-07-19 02:25:28.782162 | debian-bookworm | ok: Runtime: 0:00:02.493738 2026-07-19 02:25:28.788383 | 2026-07-19 02:25:28.788498 | TASK [tox : Look for output] 2026-07-19 02:25:29.205586 | debian-bookworm | ok 2026-07-19 02:25:29.231611 | 2026-07-19 02:25:29.231853 | TASK [tox : Return file comments to Zuul] 2026-07-19 02:25:29.284307 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:29.296095 | 2026-07-19 02:25:29.296214 | PLAY RECAP 2026-07-19 02:25:29.296289 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-07-19 02:25:29.296319 | 2026-07-19 02:25:29.451489 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-19 02:25:29.452742 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-19 02:25:30.389673 | 2026-07-19 02:25:30.390001 | PLAY [all] 2026-07-19 02:25:30.417550 | 2026-07-19 02:25:30.417778 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-07-19 02:25:30.507668 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:30.522983 | 2026-07-19 02:25:30.523349 | TASK [fetch-tox-output : Set tox log path for single node] 2026-07-19 02:25:30.567167 | debian-bookworm | ok 2026-07-19 02:25:30.574335 | 2026-07-19 02:25:30.574633 | TASK [fetch-tox-output : Ensure local tox dir] 2026-07-19 02:25:31.144757 | debian-bookworm -> localhost | changed 2026-07-19 02:25:31.156604 | 2026-07-19 02:25:31.156834 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-07-19 02:25:31.223231 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:31.230049 | 2026-07-19 02:25:31.230228 | TASK [fetch-tox-output : Set envlist fact] 2026-07-19 02:25:31.269361 | debian-bookworm | ok 2026-07-19 02:25:31.276146 | 2026-07-19 02:25:31.276268 | TASK [fetch-tox-output : Get tox version] 2026-07-19 02:25:32.192603 | 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-19 02:25:32.212624 | debian-bookworm | 4.57.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-19 02:25:32.316743 | debian-bookworm | ok: Runtime: 0:00:00.396828 2026-07-19 02:25:32.323223 | 2026-07-19 02:25:32.323345 | TASK [fetch-tox-output : Find all default environments] 2026-07-19 02:25:32.869277 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:32.876060 | 2026-07-19 02:25:32.876213 | TASK [fetch-tox-output : Set envlist fact] 2026-07-19 02:25:32.911897 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:32.924173 | 2026-07-19 02:25:32.924386 | TASK [fetch-tox-output : Find all default environments] 2026-07-19 02:25:33.458643 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:33.465586 | 2026-07-19 02:25:33.465717 | TASK [fetch-tox-output : Set envlist fact] 2026-07-19 02:25:33.531327 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:33.539837 | 2026-07-19 02:25:33.539991 | LOOP [fetch-tox-output : Copy tox logs] 2026-07-19 02:25:33.671598 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:33.684195 | 2026-07-19 02:25:33.684372 | LOOP [fetch-tox-output : Collect tox logs] 2026-07-19 02:25:34.632456 | debian-bookworm | changed: 2026-07-19 02:25:34.632682 | debian-bookworm | created directory /var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/work/logs/tox/test 2026-07-19 02:25:34.632720 | debian-bookworm | cd+++++++++ ./ 2026-07-19 02:25:34.632748 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-07-19 02:25:34.632783 | 2026-07-19 02:25:34.642100 | 2026-07-19 02:25:34.642307 | PLAY RECAP 2026-07-19 02:25:34.642367 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-07-19 02:25:34.642393 | 2026-07-19 02:25:34.864653 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-19 02:25:34.865912 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-19 02:25:36.080550 | 2026-07-19 02:25:36.080798 | PLAY [all] 2026-07-19 02:25:36.102043 | 2026-07-19 02:25:36.102240 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-07-19 02:25:36.448585 | debian-bookworm | changed: non-zero return code 2026-07-19 02:25:36.461154 | 2026-07-19 02:25:36.461326 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-07-19 02:25:36.511499 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:36.521618 | 2026-07-19 02:25:36.521765 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-07-19 02:25:36.558004 | 2026-07-19 02:25:36.558339 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-07-19 02:25:36.626976 | 2026-07-19 02:25:36.627723 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-07-19 02:25:36.671624 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:36.679384 | 2026-07-19 02:25:36.679542 | LOOP [fetch-subunit-output : Generate subunit file] 2026-07-19 02:25:36.732539 | 2026-07-19 02:25:36.732870 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-07-19 02:25:36.760388 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:36.767486 | 2026-07-19 02:25:36.767621 | TASK [fetch-subunit-output : Remove the temporary file] 2026-07-19 02:25:36.792792 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:36.806934 | 2026-07-19 02:25:36.807094 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-07-19 02:25:36.840427 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:25:36.848297 | 2026-07-19 02:25:36.848411 | PLAY RECAP 2026-07-19 02:25:36.848470 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-07-19 02:25:36.848507 | 2026-07-19 02:25:37.235991 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-19 02:25:37.237196 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-19 02:25:38.836793 | 2026-07-19 02:25:38.836996 | PLAY [Base post-fetch] 2026-07-19 02:25:38.858288 | 2026-07-19 02:25:38.858472 | TASK [diagnose-network : Ensure log directory exists] 2026-07-19 02:25:39.303472 | debian-bookworm | ok 2026-07-19 02:25:39.327355 | 2026-07-19 02:25:39.328078 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-19 02:25:51.336244 | debian-bookworm | ok: Runtime: 0:00:06.001230 2026-07-19 02:25:51.347995 | 2026-07-19 02:25:51.348456 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-19 02:25:57.405376 | debian-bookworm | ok: Runtime: 0:00:00.008807 2026-07-19 02:25:57.415625 | 2026-07-19 02:25:57.415841 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-19 02:26:03.437550 | debian-bookworm | ok: Runtime: 0:00:00.009474 2026-07-19 02:26:03.455803 | 2026-07-19 02:26:03.456134 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-19 02:26:03.514172 | debian-bookworm | skipping: Conditional result was False 2026-07-19 02:26:03.527622 | 2026-07-19 02:26:03.527821 | TASK [fetch-output : Set log path for single node] 2026-07-19 02:26:03.576903 | debian-bookworm | ok 2026-07-19 02:26:03.584884 | 2026-07-19 02:26:03.585020 | LOOP [fetch-output : Ensure local output dirs] 2026-07-19 02:26:03.922284 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/work/logs" 2026-07-19 02:26:04.206517 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/work/artifacts" 2026-07-19 02:26:04.490649 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/09ee7632429048e094207720b0c11b57/work/docs" 2026-07-19 02:26:04.509752 | 2026-07-19 02:26:04.509948 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-19 02:26:05.365085 | debian-bookworm | changed: 2026-07-19 02:26:05.365420 | debian-bookworm | .d..t...... ./ 2026-07-19 02:26:05.365471 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-19 02:26:05.365506 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-19 02:26:05.365537 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-19 02:26:05.365567 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-19 02:26:05.365597 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-19 02:26:05.365656 | debian-bookworm | changed: All items complete 2026-07-19 02:26:05.365694 | 2026-07-19 02:26:05.974867 | debian-bookworm | changed: .d..t...... ./ 2026-07-19 02:26:06.549498 | debian-bookworm | changed: .d..t...... ./ 2026-07-19 02:26:06.580831 | 2026-07-19 02:26:06.581048 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-19 02:26:06.965318 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.012584 2026-07-19 02:26:07.275770 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008383 2026-07-19 02:26:07.290266 | 2026-07-19 02:26:07.290420 | PLAY RECAP 2026-07-19 02:26:07.290488 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-19 02:26:07.290520 | 2026-07-19 02:26:07.442097 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-19 02:26:07.445092 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-19 02:26:08.239968 | 2026-07-19 02:26:08.240200 | PLAY [Base post] 2026-07-19 02:26:08.254721 | 2026-07-19 02:26:08.254922 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-19 02:26:08.882111 | debian-bookworm | changed 2026-07-19 02:26:08.891711 | 2026-07-19 02:26:08.891852 | PLAY RECAP 2026-07-19 02:26:08.891930 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-19 02:26:08.892045 | 2026-07-19 02:26:09.034088 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-19 02:26:09.035359 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-19 02:26:09.969606 | 2026-07-19 02:26:09.969809 | PLAY [Base post-logs] 2026-07-19 02:26:09.981606 | 2026-07-19 02:26:09.981768 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-19 02:26:10.465291 | localhost | changed 2026-07-19 02:26:10.486409 | 2026-07-19 02:26:10.486734 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-19 02:26:10.518152 | localhost | ok 2026-07-19 02:26:10.523810 | 2026-07-19 02:26:10.523960 | TASK [Set zuul-log-path fact] 2026-07-19 02:26:10.541811 | localhost | ok 2026-07-19 02:26:10.557661 | 2026-07-19 02:26:10.557804 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-19 02:26:10.585146 | localhost | ok 2026-07-19 02:26:10.591535 | 2026-07-19 02:26:10.591687 | TASK [upload-logs : Create log directories] 2026-07-19 02:26:11.122664 | localhost | changed 2026-07-19 02:26:11.125914 | 2026-07-19 02:26:11.126051 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-19 02:26:11.661158 | localhost -> localhost | ok: Runtime: 0:00:00.013403 2026-07-19 02:26:11.665790 | 2026-07-19 02:26:11.665917 | TASK [upload-logs : Upload logs to log server] 2026-07-19 02:26:12.245178 | localhost | Output suppressed because no_log was given 2026-07-19 02:26:12.247513 | 2026-07-19 02:26:12.252220 | LOOP [upload-logs : Compress console log and json output] 2026-07-19 02:26:12.305471 | localhost | skipping: Conditional result was False 2026-07-19 02:26:12.312299 | localhost | skipping: Conditional result was False 2026-07-19 02:26:12.319780 | 2026-07-19 02:26:12.319933 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-19 02:26:12.371988 | localhost | skipping: Conditional result was False 2026-07-19 02:26:12.372356 | 2026-07-19 02:26:12.376927 | localhost | skipping: Conditional result was False 2026-07-19 02:26:12.387391 | 2026-07-19 02:26:12.387546 | LOOP [upload-logs : Upload console log and json output]