2026-05-16 03:02:39.076401 | Job console starting 2026-05-16 03:02:39.110054 | Updating git repos 2026-05-16 03:02:39.156704 | Cloning repos into workspace 2026-05-16 03:02:39.293483 | Restoring repo states 2026-05-16 03:02:39.303894 | Merging changes 2026-05-16 03:02:39.303916 | Checking out repos 2026-05-16 03:02:39.441843 | Preparing playbooks 2026-05-16 03:02:41.179668 | Running Ansible setup 2026-05-16 03:02:47.729880 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-16 03:02:50.014972 | 2026-05-16 03:02:50.016104 | PLAY [Base pre] 2026-05-16 03:02:50.107506 | 2026-05-16 03:02:50.108332 | TASK [Setup log path fact] 2026-05-16 03:02:50.167217 | debian-bookworm | ok 2026-05-16 03:02:50.205598 | 2026-05-16 03:02:50.206450 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-16 03:02:50.270938 | debian-bookworm | ok 2026-05-16 03:02:50.293095 | 2026-05-16 03:02:50.293564 | TASK [emit-job-header : Print job information] 2026-05-16 03:02:50.420740 | # Job Information 2026-05-16 03:02:50.420939 | Ansible Version: 2.16.14 2026-05-16 03:02:50.420979 | Job: tox 2026-05-16 03:02:50.421015 | Pipeline: periodic-daily 2026-05-16 03:02:50.421039 | Executor: 521e9411259a 2026-05-16 03:02:50.421061 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-16 03:02:50.421084 | Event ID: 2b3e3103ec654a1298fa04c1c12a1f3d 2026-05-16 03:02:50.428953 | 2026-05-16 03:02:50.429067 | LOOP [emit-job-header : Print node information] 2026-05-16 03:02:50.707417 | debian-bookworm | ok: 2026-05-16 03:02:50.707614 | debian-bookworm | # Node Information 2026-05-16 03:02:50.707669 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-16 03:02:50.707702 | debian-bookworm | Hostname: debian 2026-05-16 03:02:50.707724 | debian-bookworm | Username: zuul 2026-05-16 03:02:50.707746 | debian-bookworm | Distro: Debian 12.13 2026-05-16 03:02:50.707771 | debian-bookworm | Provider: regiocloud-a 2026-05-16 03:02:50.707792 | debian-bookworm | Region: 2026-05-16 03:02:50.707813 | debian-bookworm | Label: debian-bookworm 2026-05-16 03:02:50.707833 | debian-bookworm | Product Name: OpenStack Nova 2026-05-16 03:02:50.707852 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1e:e1d4 2026-05-16 03:02:50.720440 | 2026-05-16 03:02:50.720550 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-16 03:02:51.853890 | debian-bookworm -> localhost | changed 2026-05-16 03:02:51.860979 | 2026-05-16 03:02:51.861093 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-16 03:02:55.167162 | debian-bookworm -> localhost | changed 2026-05-16 03:02:55.180047 | 2026-05-16 03:02:55.180161 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-16 03:02:55.857060 | debian-bookworm -> localhost | ok 2026-05-16 03:02:55.863380 | 2026-05-16 03:02:55.863507 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-16 03:02:55.898044 | debian-bookworm | ok 2026-05-16 03:02:55.937450 | debian-bookworm | included: /var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-16 03:02:55.947256 | 2026-05-16 03:02:55.947364 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-16 03:02:57.532088 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-16 03:02:57.532323 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/work/9c3de5183d2744e88ba4d97a9aa1ac81_id_rsa 2026-05-16 03:02:57.532363 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/work/9c3de5183d2744e88ba4d97a9aa1ac81_id_rsa.pub 2026-05-16 03:02:57.532389 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-16 03:02:57.532413 | debian-bookworm -> localhost | SHA256:xmu6Xv/KND9dGvl3RqMAge5hQU+OT9gtly+ZHDCHLe0 zuul-build-sshkey 2026-05-16 03:02:57.532435 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-16 03:02:57.532469 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-16 03:02:57.532493 | debian-bookworm -> localhost | | ...++. | 2026-05-16 03:02:57.532514 | debian-bookworm -> localhost | | oB+=o. | 2026-05-16 03:02:57.532534 | debian-bookworm -> localhost | | .o.*+= | 2026-05-16 03:02:57.532554 | debian-bookworm -> localhost | | =o.+E= | 2026-05-16 03:02:57.532574 | debian-bookworm -> localhost | | o S..= . . | 2026-05-16 03:02:57.532603 | debian-bookworm -> localhost | | o . .. o.o| 2026-05-16 03:02:57.532623 | debian-bookworm -> localhost | | + o ...*.| 2026-05-16 03:02:57.532656 | debian-bookworm -> localhost | | + + o..o =| 2026-05-16 03:02:57.532679 | debian-bookworm -> localhost | | .+. +oo. .o| 2026-05-16 03:02:57.532699 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-16 03:02:57.532765 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.111902 2026-05-16 03:02:57.539717 | 2026-05-16 03:02:57.539822 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-16 03:02:57.588257 | debian-bookworm | ok 2026-05-16 03:02:57.602893 | debian-bookworm | included: /var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-16 03:02:57.642506 | 2026-05-16 03:02:57.642650 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-16 03:02:57.680386 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:02:57.691728 | 2026-05-16 03:02:57.691869 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-16 03:02:58.514023 | debian-bookworm | changed 2026-05-16 03:02:58.526139 | 2026-05-16 03:02:58.526274 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-16 03:02:58.795201 | debian-bookworm | ok 2026-05-16 03:02:58.810286 | 2026-05-16 03:02:58.810410 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-16 03:02:59.616500 | debian-bookworm | changed 2026-05-16 03:02:59.636766 | 2026-05-16 03:02:59.636885 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-16 03:03:00.337056 | debian-bookworm | changed 2026-05-16 03:03:00.343151 | 2026-05-16 03:03:00.343244 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-16 03:03:00.367051 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:03:00.373485 | 2026-05-16 03:03:00.373574 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-16 03:03:01.458719 | debian-bookworm -> localhost | changed 2026-05-16 03:03:01.476482 | 2026-05-16 03:03:01.476623 | TASK [add-build-sshkey : Add back temp key] 2026-05-16 03:03:02.244872 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/work/9c3de5183d2744e88ba4d97a9aa1ac81_id_rsa (zuul-build-sshkey) 2026-05-16 03:03:02.245109 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.038322 2026-05-16 03:03:02.253127 | 2026-05-16 03:03:02.253230 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-16 03:03:02.878077 | debian-bookworm | ok 2026-05-16 03:03:02.883647 | 2026-05-16 03:03:02.883739 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-16 03:03:02.918953 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:03:02.931472 | 2026-05-16 03:03:02.931585 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-16 03:03:03.520881 | debian-bookworm | ok 2026-05-16 03:03:03.570074 | 2026-05-16 03:03:03.570472 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-16 03:03:03.694441 | debian-bookworm | ok 2026-05-16 03:03:03.711906 | 2026-05-16 03:03:03.712082 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-16 03:03:04.946755 | debian-bookworm -> localhost | ok 2026-05-16 03:03:04.953978 | 2026-05-16 03:03:04.954120 | TASK [validate-host : Collect information about the host] 2026-05-16 03:03:06.475775 | debian-bookworm | ok 2026-05-16 03:03:06.493604 | 2026-05-16 03:03:06.493752 | TASK [validate-host : Sanitize hostname] 2026-05-16 03:03:06.619455 | debian-bookworm | ok 2026-05-16 03:03:06.707605 | 2026-05-16 03:03:06.707770 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-16 03:03:07.442731 | debian-bookworm -> localhost | changed 2026-05-16 03:03:07.448703 | 2026-05-16 03:03:07.448810 | TASK [validate-host : Collect information about zuul worker] 2026-05-16 03:03:07.987050 | debian-bookworm | ok 2026-05-16 03:03:07.992384 | 2026-05-16 03:03:07.992495 | TASK [validate-host : Write out all zuul information for each host] 2026-05-16 03:03:09.068352 | debian-bookworm -> localhost | changed 2026-05-16 03:03:09.088790 | 2026-05-16 03:03:09.088917 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-16 03:03:09.379873 | debian-bookworm | ok 2026-05-16 03:03:09.403334 | 2026-05-16 03:03:09.403545 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-16 03:03:10.630162 | debian-bookworm | changed: 2026-05-16 03:03:10.630327 | debian-bookworm | cd+++++++++ src/ 2026-05-16 03:03:10.630361 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-16 03:03:10.630385 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-16 03:03:10.630407 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-16 03:03:10.630427 | debian-bookworm | =7.0.3 2026-05-16 03:03:37.336766 | debian-bookworm | Downloading cachetools-7.1.1-py3-none-any.whl (16 kB) 2026-05-16 03:03:37.377843 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-16 03:03:37.389313 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-16 03:03:37.450128 | debian-bookworm | Collecting filelock>=3.25 2026-05-16 03:03:37.461428 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-16 03:03:37.511444 | debian-bookworm | Collecting packaging>=26 2026-05-16 03:03:37.522545 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-16 03:03:37.533515 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.6 MB/s eta 0:00:00 2026-05-16 03:03:37.582332 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-16 03:03:37.593696 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-16 03:03:37.637132 | debian-bookworm | Collecting pluggy>=1.6 2026-05-16 03:03:37.648062 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-16 03:03:37.703817 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-16 03:03:37.715783 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-16 03:03:37.754117 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-16 03:03:37.765865 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-16 03:03:37.806941 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-16 03:03:37.818358 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-16 03:03:37.957064 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-16 03:03:37.971930 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-16 03:03:38.504662 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 14.3 MB/s eta 0:00:00 2026-05-16 03:03:38.619410 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-16 03:03:38.631991 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-16 03:03:38.657977 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.2 MB/s eta 0:00:00 2026-05-16 03:03:38.781135 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-16 03:03:39.616312 | debian-bookworm | Successfully installed cachetools-7.1.1 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.9.6 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.3.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-16 03:03:40.197719 | debian-bookworm | ok: Runtime: 0:00:03.280858 2026-05-16 03:03:40.204455 | 2026-05-16 03:03:40.204565 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-16 03:03:40.266885 | debian-bookworm | ok 2026-05-16 03:03:40.271750 | 2026-05-16 03:03:40.271849 | TASK [ensure-tox : Output tox version] 2026-05-16 03:03:40.826446 | 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-05-16 03:03:40.844571 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-16 03:03:41.322654 | debian-bookworm | ok: Runtime: 0:00:00.331591 2026-05-16 03:03:41.328344 | 2026-05-16 03:03:41.328445 | TASK [ensure-tox : Make global symlink] 2026-05-16 03:03:41.364622 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:03:41.371405 | 2026-05-16 03:03:41.371485 | PLAY RECAP 2026-05-16 03:03:41.371551 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-16 03:03:41.371592 | 2026-05-16 03:03:41.640179 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-16 03:03:41.641430 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-16 03:03:42.689492 | 2026-05-16 03:03:42.689619 | PLAY [all] 2026-05-16 03:03:42.720497 | 2026-05-16 03:03:42.720622 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-16 03:03:43.230623 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-16 03:03:43.784433 | debian-bookworm | ok: Runtime: 0:00:00.026221 2026-05-16 03:03:43.789072 | 2026-05-16 03:03:43.789182 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-16 03:03:44.340632 | debian-bookworm | ok: Runtime: 0:00:00.006708 2026-05-16 03:03:44.346749 | 2026-05-16 03:03:44.346933 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-16 03:03:44.703343 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-16 03:03:44.704726 | debian-bookworm | sudo: a password is required 2026-05-16 03:03:44.908574 | debian-bookworm | ok: Runtime: 0:00:00.019841 2026-05-16 03:03:44.916958 | 2026-05-16 03:03:44.917038 | TASK [tox : Check to see if the constraints file exists] 2026-05-16 03:03:44.960254 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:03:44.968184 | 2026-05-16 03:03:44.968282 | TASK [tox : Fail if constraints file is missing] 2026-05-16 03:03:45.011315 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:03:45.017789 | 2026-05-16 03:03:45.017891 | TASK [tox : Record file location] 2026-05-16 03:03:45.071887 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:03:45.077214 | 2026-05-16 03:03:45.077327 | TASK [tox : Install tox siblings] 2026-05-16 03:03:45.129460 | debian-bookworm | ok 2026-05-16 03:03:45.158301 | debian-bookworm | included: /var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-16 03:03:45.173723 | 2026-05-16 03:03:45.173827 | TASK [tox : Run tox without tests] 2026-05-16 03:03:46.646373 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-16 03:03:59.843470 | debian-bookworm | test: OK (13.96 seconds) 2026-05-16 03:03:59.843543 | debian-bookworm | congratulations :) (13.99 seconds) 2026-05-16 03:04:00.251882 | debian-bookworm | ok: Runtime: 0:00:14.296873 2026-05-16 03:04:00.258540 | 2026-05-16 03:04:00.258633 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-16 03:04:00.843494 | debian-bookworm | changed 2026-05-16 03:04:00.849450 | 2026-05-16 03:04:00.849557 | TASK [tox : Detect tox version] 2026-05-16 03:04:01.473684 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-16 03:04:01.942346 | debian-bookworm | ok: Runtime: 0:00:00.288876 2026-05-16 03:04:01.955195 | 2026-05-16 03:04:01.955310 | TASK [tox : Get tox envlist config] 2026-05-16 03:04:02.999859 | debian-bookworm | ok: Runtime: 0:00:00.373892 2026-05-16 03:04:03.005620 | 2026-05-16 03:04:03.005728 | TASK [tox : Install any sibling python packages] 2026-05-16 03:04:03.705844 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-16 03:04:03.712602 | 2026-05-16 03:04:03.712709 | TASK [tox : Remove tempfile] 2026-05-16 03:04:04.241377 | debian-bookworm | changed 2026-05-16 03:04:04.249043 | 2026-05-16 03:04:04.249330 | TASK [tox : Emit tox command] 2026-05-16 03:04:04.324868 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-16 03:04:04.333578 | 2026-05-16 03:04:04.333754 | TASK [tox : Run tox] 2026-05-16 03:04:04.841431 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-16 03:04:05.502009 | debian-bookworm | ............... 2026-05-16 03:04:05.502044 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-16 03:04:05.502057 | debian-bookworm | Ran 15 tests in 0.163s 2026-05-16 03:04:05.502063 | debian-bookworm | 2026-05-16 03:04:05.502069 | debian-bookworm | OK 2026-05-16 03:04:05.570329 | debian-bookworm | test: OK (0.77=setup[0.05]+cmd[0.72] seconds) 2026-05-16 03:04:05.570375 | debian-bookworm | congratulations :) (0.80 seconds) 2026-05-16 03:04:05.864590 | debian-bookworm | ok: Runtime: 0:00:01.052344 2026-05-16 03:04:05.869170 | 2026-05-16 03:04:05.869243 | TASK [tox : Look for output] 2026-05-16 03:04:06.270542 | debian-bookworm | ok 2026-05-16 03:04:06.285871 | 2026-05-16 03:04:06.285978 | TASK [tox : Return file comments to Zuul] 2026-05-16 03:04:06.319565 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:06.331412 | 2026-05-16 03:04:06.331520 | PLAY RECAP 2026-05-16 03:04:06.331595 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-16 03:04:06.331635 | 2026-05-16 03:04:06.445466 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-16 03:04:06.446371 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-16 03:04:07.172176 | 2026-05-16 03:04:07.172312 | PLAY [all] 2026-05-16 03:04:07.187132 | 2026-05-16 03:04:07.187259 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-16 03:04:07.251486 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:07.256998 | 2026-05-16 03:04:07.257122 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-16 03:04:07.324927 | debian-bookworm | ok 2026-05-16 03:04:07.330055 | 2026-05-16 03:04:07.330161 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-16 03:04:07.879267 | debian-bookworm -> localhost | changed 2026-05-16 03:04:07.887082 | 2026-05-16 03:04:07.887195 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-16 03:04:07.941532 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:07.955017 | 2026-05-16 03:04:07.955189 | TASK [fetch-tox-output : Set envlist fact] 2026-05-16 03:04:08.000697 | debian-bookworm | ok 2026-05-16 03:04:08.008596 | 2026-05-16 03:04:08.008733 | TASK [fetch-tox-output : Get tox version] 2026-05-16 03:04:08.563426 | 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-05-16 03:04:08.579874 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-16 03:04:09.038068 | debian-bookworm | ok: Runtime: 0:00:00.273647 2026-05-16 03:04:09.044357 | 2026-05-16 03:04:09.044450 | TASK [fetch-tox-output : Find all default environments] 2026-05-16 03:04:09.590611 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:09.596690 | 2026-05-16 03:04:09.596819 | TASK [fetch-tox-output : Set envlist fact] 2026-05-16 03:04:09.620506 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:09.626438 | 2026-05-16 03:04:09.626553 | TASK [fetch-tox-output : Find all default environments] 2026-05-16 03:04:10.154305 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:10.160036 | 2026-05-16 03:04:10.160149 | TASK [fetch-tox-output : Set envlist fact] 2026-05-16 03:04:10.196311 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:10.205296 | 2026-05-16 03:04:10.207928 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-16 03:04:10.255503 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:10.270240 | 2026-05-16 03:04:10.270358 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-16 03:04:11.027392 | debian-bookworm | changed: 2026-05-16 03:04:11.027591 | debian-bookworm | created directory /var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/work/logs/tox/test 2026-05-16 03:04:11.027634 | debian-bookworm | cd+++++++++ ./ 2026-05-16 03:04:11.027700 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-16 03:04:11.027756 | 2026-05-16 03:04:11.049743 | 2026-05-16 03:04:11.049866 | PLAY RECAP 2026-05-16 03:04:11.049947 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-16 03:04:11.049987 | 2026-05-16 03:04:11.224520 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-16 03:04:11.225469 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-16 03:04:12.329238 | 2026-05-16 03:04:12.329393 | PLAY [all] 2026-05-16 03:04:12.363515 | 2026-05-16 03:04:12.363697 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-16 03:04:12.641315 | debian-bookworm | changed: non-zero return code 2026-05-16 03:04:12.646799 | 2026-05-16 03:04:12.646933 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-16 03:04:12.672369 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:12.677975 | 2026-05-16 03:04:12.678082 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-16 03:04:12.739722 | 2026-05-16 03:04:12.739906 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-16 03:04:12.780454 | 2026-05-16 03:04:12.780695 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-16 03:04:12.824220 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:12.830276 | 2026-05-16 03:04:12.830380 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-16 03:04:12.866913 | 2026-05-16 03:04:12.867127 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-16 03:04:12.895715 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:12.901542 | 2026-05-16 03:04:12.901650 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-16 03:04:12.935084 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:12.941029 | 2026-05-16 03:04:12.941140 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-16 03:04:12.974569 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:12.987242 | 2026-05-16 03:04:12.987329 | PLAY RECAP 2026-05-16 03:04:12.987383 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-16 03:04:12.987409 | 2026-05-16 03:04:13.151261 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-16 03:04:13.152252 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-16 03:04:14.453966 | 2026-05-16 03:04:14.454169 | PLAY [Base post-fetch] 2026-05-16 03:04:14.489172 | 2026-05-16 03:04:14.489358 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-16 03:04:14.650076 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:14.658376 | 2026-05-16 03:04:14.658561 | TASK [fetch-output : Set log path for single node] 2026-05-16 03:04:14.735168 | debian-bookworm | ok 2026-05-16 03:04:14.742590 | 2026-05-16 03:04:14.742770 | LOOP [fetch-output : Ensure local output dirs] 2026-05-16 03:04:15.866218 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/work/logs" 2026-05-16 03:04:16.844896 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/work/artifacts" 2026-05-16 03:04:17.428388 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9c3de5183d2744e88ba4d97a9aa1ac81/work/docs" 2026-05-16 03:04:17.437834 | 2026-05-16 03:04:17.437936 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-16 03:04:18.473014 | debian-bookworm | changed: 2026-05-16 03:04:18.473235 | debian-bookworm | .d..t...... ./ 2026-05-16 03:04:18.473270 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-16 03:04:18.473308 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-16 03:04:18.473338 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-16 03:04:18.473378 | debian-bookworm | changed: All items complete 2026-05-16 03:04:18.473403 | 2026-05-16 03:04:19.106080 | debian-bookworm | changed: .d..t...... ./ 2026-05-16 03:04:19.813785 | debian-bookworm | changed: .d..t...... ./ 2026-05-16 03:04:19.833046 | 2026-05-16 03:04:19.833171 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-16 03:04:19.877573 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:19.889002 | debian-bookworm | skipping: Conditional result was False 2026-05-16 03:04:19.898768 | 2026-05-16 03:04:19.898882 | PLAY RECAP 2026-05-16 03:04:19.898936 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-16 03:04:19.898961 | 2026-05-16 03:04:20.117410 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-16 03:04:20.118391 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-16 03:04:21.354500 | 2026-05-16 03:04:21.354656 | PLAY [Base post] 2026-05-16 03:04:21.386612 | 2026-05-16 03:04:21.386775 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-16 03:04:22.026785 | debian-bookworm | changed 2026-05-16 03:04:22.035622 | 2026-05-16 03:04:22.035763 | PLAY RECAP 2026-05-16 03:04:22.035832 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-16 03:04:22.035893 | 2026-05-16 03:04:22.244818 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-16 03:04:22.245940 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-16 03:04:23.064056 | 2026-05-16 03:04:23.064252 | PLAY [Base post-logs] 2026-05-16 03:04:23.101591 | 2026-05-16 03:04:23.101955 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-16 03:04:23.690032 | localhost | changed 2026-05-16 03:04:23.704835 | 2026-05-16 03:04:23.705064 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-16 03:04:23.754850 | localhost | ok 2026-05-16 03:04:23.757635 | 2026-05-16 03:04:23.757748 | TASK [Set zuul-log-path fact] 2026-05-16 03:04:23.783015 | localhost | ok 2026-05-16 03:04:23.792040 | 2026-05-16 03:04:23.792176 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-16 03:04:23.827399 | localhost | ok 2026-05-16 03:04:23.831014 | 2026-05-16 03:04:23.831114 | TASK [upload-logs : Create log directories] 2026-05-16 03:04:24.468458 | localhost | changed 2026-05-16 03:04:24.471814 | 2026-05-16 03:04:24.471911 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-16 03:04:25.217968 | localhost -> localhost | ok: Runtime: 0:00:00.014817 2026-05-16 03:04:25.221602 | 2026-05-16 03:04:25.221729 | TASK [upload-logs : Upload logs to log server] 2026-05-16 03:04:25.798871 | localhost | Output suppressed because no_log was given 2026-05-16 03:04:25.800665 | 2026-05-16 03:04:25.800774 | LOOP [upload-logs : Compress console log and json output] 2026-05-16 03:04:25.847028 | localhost | skipping: Conditional result was False 2026-05-16 03:04:25.853205 | localhost | skipping: Conditional result was False 2026-05-16 03:04:25.857104 | 2026-05-16 03:04:25.857234 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-16 03:04:25.905125 | localhost | skipping: Conditional result was False 2026-05-16 03:04:25.905365 | 2026-05-16 03:04:25.913018 | localhost | skipping: Conditional result was False 2026-05-16 03:04:25.923843 | 2026-05-16 03:04:25.923976 | LOOP [upload-logs : Upload console log and json output]