2026-05-24 03:13:42.017615 | Job console starting 2026-05-24 03:13:42.030941 | Updating git repos 2026-05-24 03:13:42.090957 | Cloning repos into workspace 2026-05-24 03:13:42.203392 | Restoring repo states 2026-05-24 03:13:42.223143 | Merging changes 2026-05-24 03:13:42.223159 | Checking out repos 2026-05-24 03:13:42.270348 | Preparing playbooks 2026-05-24 03:13:43.477160 | Running Ansible setup 2026-05-24 03:13:48.600578 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-24 03:13:49.873741 | 2026-05-24 03:13:49.873855 | PLAY [Base pre] 2026-05-24 03:13:49.890243 | 2026-05-24 03:13:49.890367 | TASK [Setup log path fact] 2026-05-24 03:13:49.921719 | debian-bookworm | ok 2026-05-24 03:13:49.938645 | 2026-05-24 03:13:49.938786 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-24 03:13:49.988284 | debian-bookworm | ok 2026-05-24 03:13:49.999077 | 2026-05-24 03:13:49.999201 | TASK [emit-job-header : Print job information] 2026-05-24 03:13:50.114092 | # Job Information 2026-05-24 03:13:50.114659 | Ansible Version: 2.16.14 2026-05-24 03:13:50.114755 | Job: tox 2026-05-24 03:13:50.114794 | Pipeline: periodic-daily 2026-05-24 03:13:50.114814 | Executor: 521e9411259a 2026-05-24 03:13:50.114864 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-24 03:13:50.114888 | Event ID: 11dbb1138fc14ca08a489c3066fe0829 2026-05-24 03:13:50.119672 | 2026-05-24 03:13:50.119753 | LOOP [emit-job-header : Print node information] 2026-05-24 03:13:50.237898 | debian-bookworm | ok: 2026-05-24 03:13:50.238168 | debian-bookworm | # Node Information 2026-05-24 03:13:50.238207 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-24 03:13:50.238233 | debian-bookworm | Hostname: debian 2026-05-24 03:13:50.238263 | debian-bookworm | Username: zuul 2026-05-24 03:13:50.238458 | debian-bookworm | Distro: Debian 12.14 2026-05-24 03:13:50.238503 | debian-bookworm | Provider: regiocloud-a 2026-05-24 03:13:50.238528 | debian-bookworm | Region: 2026-05-24 03:13:50.238578 | debian-bookworm | Label: debian-bookworm 2026-05-24 03:13:50.238601 | debian-bookworm | Product Name: OpenStack Nova 2026-05-24 03:13:50.238622 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe14:f314 2026-05-24 03:13:50.254336 | 2026-05-24 03:13:50.254473 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-24 03:13:50.965490 | debian-bookworm -> localhost | changed 2026-05-24 03:13:50.972166 | 2026-05-24 03:13:50.972255 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-24 03:13:52.937003 | debian-bookworm -> localhost | changed 2026-05-24 03:13:52.954953 | 2026-05-24 03:13:52.955051 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-24 03:13:53.802204 | debian-bookworm -> localhost | ok 2026-05-24 03:13:53.808546 | 2026-05-24 03:13:53.808679 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-24 03:13:53.868144 | debian-bookworm | ok 2026-05-24 03:13:53.894342 | debian-bookworm | included: /var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-24 03:13:53.900528 | 2026-05-24 03:13:53.900615 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-24 03:13:56.368100 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-24 03:13:56.368286 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/work/8329c90e10ad4a94a570b4d4bab6feb9_id_rsa 2026-05-24 03:13:56.368317 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/work/8329c90e10ad4a94a570b4d4bab6feb9_id_rsa.pub 2026-05-24 03:13:56.368339 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-24 03:13:56.368359 | debian-bookworm -> localhost | SHA256:PNYW3r1l2neQSiZ7EpF4qk22V3ulrOlDa7RHQju33ok zuul-build-sshkey 2026-05-24 03:13:56.368377 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-24 03:13:56.368403 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-24 03:13:56.368421 | debian-bookworm -> localhost | | | 2026-05-24 03:13:56.368439 | debian-bookworm -> localhost | | . . | 2026-05-24 03:13:56.368455 | debian-bookworm -> localhost | | . = | 2026-05-24 03:13:56.368471 | debian-bookworm -> localhost | | . = +.. . | 2026-05-24 03:13:56.368487 | debian-bookworm -> localhost | | S *.=.+ +| 2026-05-24 03:13:56.368506 | debian-bookworm -> localhost | | * + B*+oX | 2026-05-24 03:13:56.368522 | debian-bookworm -> localhost | | . o +o+B*.+| 2026-05-24 03:13:56.368538 | debian-bookworm -> localhost | | . o=++.+| 2026-05-24 03:13:56.368555 | debian-bookworm -> localhost | | o+E...| 2026-05-24 03:13:56.368572 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-24 03:13:56.368615 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.742579 2026-05-24 03:13:56.378164 | 2026-05-24 03:13:56.378251 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-24 03:13:56.395241 | debian-bookworm | ok 2026-05-24 03:13:56.404897 | debian-bookworm | included: /var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-24 03:13:56.412863 | 2026-05-24 03:13:56.412945 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-24 03:13:56.425702 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:13:56.430361 | 2026-05-24 03:13:56.430442 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-24 03:13:57.071665 | debian-bookworm | changed 2026-05-24 03:13:57.082209 | 2026-05-24 03:13:57.082384 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-24 03:13:57.333777 | debian-bookworm | ok 2026-05-24 03:13:57.341692 | 2026-05-24 03:13:57.341801 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-24 03:13:58.034411 | debian-bookworm | changed 2026-05-24 03:13:58.041196 | 2026-05-24 03:13:58.041317 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-24 03:13:58.752041 | debian-bookworm | changed 2026-05-24 03:13:58.757543 | 2026-05-24 03:13:58.757637 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-24 03:13:58.770506 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:13:58.777212 | 2026-05-24 03:13:58.777350 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-24 03:14:00.007213 | debian-bookworm -> localhost | changed 2026-05-24 03:14:00.026475 | 2026-05-24 03:14:00.026595 | TASK [add-build-sshkey : Add back temp key] 2026-05-24 03:14:00.922363 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/work/8329c90e10ad4a94a570b4d4bab6feb9_id_rsa (zuul-build-sshkey) 2026-05-24 03:14:00.922600 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025467 2026-05-24 03:14:00.930773 | 2026-05-24 03:14:00.930885 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-24 03:14:01.948776 | debian-bookworm | ok 2026-05-24 03:14:01.953151 | 2026-05-24 03:14:01.953228 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-24 03:14:02.019436 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:14:02.028497 | 2026-05-24 03:14:02.028732 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-24 03:14:02.947107 | debian-bookworm | ok 2026-05-24 03:14:02.958575 | 2026-05-24 03:14:02.958674 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-24 03:14:02.991639 | debian-bookworm | ok 2026-05-24 03:14:02.997602 | 2026-05-24 03:14:02.997694 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-24 03:14:03.632947 | debian-bookworm -> localhost | ok 2026-05-24 03:14:03.638209 | 2026-05-24 03:14:03.638296 | TASK [validate-host : Collect information about the host] 2026-05-24 03:14:04.885067 | debian-bookworm | ok 2026-05-24 03:14:04.904186 | 2026-05-24 03:14:04.904306 | TASK [validate-host : Sanitize hostname] 2026-05-24 03:14:05.183036 | debian-bookworm | ok 2026-05-24 03:14:05.307205 | 2026-05-24 03:14:05.307332 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-24 03:14:06.387855 | debian-bookworm -> localhost | changed 2026-05-24 03:14:06.393211 | 2026-05-24 03:14:06.393310 | TASK [validate-host : Collect information about zuul worker] 2026-05-24 03:14:06.958077 | debian-bookworm | ok 2026-05-24 03:14:06.963107 | 2026-05-24 03:14:06.963192 | TASK [validate-host : Write out all zuul information for each host] 2026-05-24 03:14:08.237643 | debian-bookworm -> localhost | changed 2026-05-24 03:14:08.245233 | 2026-05-24 03:14:08.245325 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-24 03:14:08.528617 | debian-bookworm | ok 2026-05-24 03:14:08.533203 | 2026-05-24 03:14:08.533288 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-24 03:14:10.006320 | debian-bookworm | changed: 2026-05-24 03:14:10.006479 | debian-bookworm | cd+++++++++ src/ 2026-05-24 03:14:10.006509 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-24 03:14:10.006530 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-24 03:14:10.006548 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-24 03:14:10.006565 | debian-bookworm | =7.0.3 2026-05-24 03:14:37.492007 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-05-24 03:14:37.538018 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-24 03:14:37.549580 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-24 03:14:37.629930 | debian-bookworm | Collecting filelock>=3.25 2026-05-24 03:14:37.641398 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-24 03:14:37.706484 | debian-bookworm | Collecting packaging>=26 2026-05-24 03:14:37.717483 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-24 03:14:37.728350 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.4 MB/s eta 0:00:00 2026-05-24 03:14:37.777828 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-24 03:14:37.788993 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-24 03:14:37.828988 | debian-bookworm | Collecting pluggy>=1.6 2026-05-24 03:14:37.839805 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-24 03:14:37.879345 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-24 03:14:37.891196 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-24 03:14:37.923543 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-24 03:14:37.934841 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-24 03:14:37.969393 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-24 03:14:37.980669 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-24 03:14:38.130300 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-24 03:14:38.141843 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-24 03:14:38.472073 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 23.3 MB/s eta 0:00:00 2026-05-24 03:14:38.585156 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-24 03:14:38.596811 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-24 03:14:38.630685 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 16.3 MB/s eta 0:00:00 2026-05-24 03:14:38.748924 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-24 03:14:39.509573 | debian-bookworm | Successfully installed cachetools-7.1.4 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-24 03:14:39.915751 | debian-bookworm | ok: Runtime: 0:00:02.931141 2026-05-24 03:14:39.925871 | 2026-05-24 03:14:39.925995 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-24 03:14:39.956707 | debian-bookworm | ok 2026-05-24 03:14:39.961886 | 2026-05-24 03:14:39.962007 | TASK [ensure-tox : Output tox version] 2026-05-24 03:14:40.454009 | 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-24 03:14:40.470519 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-24 03:14:40.540778 | debian-bookworm | ok: Runtime: 0:00:00.270847 2026-05-24 03:14:40.548670 | 2026-05-24 03:14:40.548802 | TASK [ensure-tox : Make global symlink] 2026-05-24 03:14:40.577935 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:14:40.587995 | 2026-05-24 03:14:40.588104 | PLAY RECAP 2026-05-24 03:14:40.588160 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-24 03:14:40.588187 | 2026-05-24 03:14:40.802098 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-24 03:14:40.808328 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-24 03:14:42.204652 | 2026-05-24 03:14:42.204828 | PLAY [all] 2026-05-24 03:14:42.220641 | 2026-05-24 03:14:42.220785 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-24 03:14:42.697416 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-24 03:14:42.750279 | debian-bookworm | ok: Runtime: 0:00:00.028148 2026-05-24 03:14:42.756557 | 2026-05-24 03:14:42.756702 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-24 03:14:43.287762 | debian-bookworm | ok: Runtime: 0:00:00.005982 2026-05-24 03:14:43.302956 | 2026-05-24 03:14:43.303132 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-24 03:14:43.572034 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-24 03:14:43.573393 | debian-bookworm | sudo: a password is required 2026-05-24 03:14:43.836531 | debian-bookworm | ok: Runtime: 0:00:00.015120 2026-05-24 03:14:43.847549 | 2026-05-24 03:14:43.847665 | TASK [tox : Check to see if the constraints file exists] 2026-05-24 03:14:43.874420 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:14:43.882114 | 2026-05-24 03:14:43.882294 | TASK [tox : Fail if constraints file is missing] 2026-05-24 03:14:43.911162 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:14:43.921532 | 2026-05-24 03:14:43.921665 | TASK [tox : Record file location] 2026-05-24 03:14:43.954882 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:14:43.962485 | 2026-05-24 03:14:43.962611 | TASK [tox : Install tox siblings] 2026-05-24 03:14:43.995410 | debian-bookworm | ok 2026-05-24 03:14:44.005645 | debian-bookworm | included: /var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-24 03:14:44.011420 | 2026-05-24 03:14:44.011542 | TASK [tox : Run tox without tests] 2026-05-24 03:14:45.118412 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-24 03:14:57.752138 | debian-bookworm | test: OK (13.24 seconds) 2026-05-24 03:14:57.752191 | debian-bookworm | congratulations :) (13.27 seconds) 2026-05-24 03:14:58.048032 | debian-bookworm | ok: Runtime: 0:00:13.537322 2026-05-24 03:14:58.054934 | 2026-05-24 03:14:58.055138 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-24 03:14:58.588997 | debian-bookworm | changed 2026-05-24 03:14:58.598698 | 2026-05-24 03:14:58.598827 | TASK [tox : Detect tox version] 2026-05-24 03:14:59.148756 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-24 03:14:59.653203 | debian-bookworm | ok: Runtime: 0:00:00.300138 2026-05-24 03:14:59.660139 | 2026-05-24 03:14:59.660986 | TASK [tox : Get tox envlist config] 2026-05-24 03:15:00.696169 | debian-bookworm | ok: Runtime: 0:00:00.340061 2026-05-24 03:15:00.703529 | 2026-05-24 03:15:00.703642 | TASK [tox : Install any sibling python packages] 2026-05-24 03:15:01.204388 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-24 03:15:01.211616 | 2026-05-24 03:15:01.211796 | TASK [tox : Remove tempfile] 2026-05-24 03:15:01.745117 | debian-bookworm | changed 2026-05-24 03:15:01.752904 | 2026-05-24 03:15:01.753033 | TASK [tox : Emit tox command] 2026-05-24 03:15:01.796650 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-24 03:15:01.801923 | 2026-05-24 03:15:01.802039 | TASK [tox : Run tox] 2026-05-24 03:15:02.390951 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-24 03:15:03.085083 | debian-bookworm | ............... 2026-05-24 03:15:03.085141 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-24 03:15:03.085156 | debian-bookworm | Ran 15 tests in 0.171s 2026-05-24 03:15:03.085161 | debian-bookworm | 2026-05-24 03:15:03.085168 | debian-bookworm | OK 2026-05-24 03:15:03.176285 | debian-bookworm | test: OK (0.83=setup[0.05]+cmd[0.78] seconds) 2026-05-24 03:15:03.176346 | debian-bookworm | congratulations :) (0.86 seconds) 2026-05-24 03:15:03.331984 | debian-bookworm | ok: Runtime: 0:00:01.159241 2026-05-24 03:15:03.337830 | 2026-05-24 03:15:03.337943 | TASK [tox : Look for output] 2026-05-24 03:15:03.778287 | debian-bookworm | ok 2026-05-24 03:15:03.796904 | 2026-05-24 03:15:03.797051 | TASK [tox : Return file comments to Zuul] 2026-05-24 03:15:03.883192 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:03.895152 | 2026-05-24 03:15:03.895279 | PLAY RECAP 2026-05-24 03:15:03.895340 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-24 03:15:03.895368 | 2026-05-24 03:15:04.149334 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-24 03:15:04.150436 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-24 03:15:06.369356 | 2026-05-24 03:15:06.369530 | PLAY [all] 2026-05-24 03:15:06.403018 | 2026-05-24 03:15:06.403182 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-24 03:15:06.458637 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:06.464893 | 2026-05-24 03:15:06.465030 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-24 03:15:06.505468 | debian-bookworm | ok 2026-05-24 03:15:06.510483 | 2026-05-24 03:15:06.510586 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-24 03:15:06.912100 | debian-bookworm -> localhost | changed 2026-05-24 03:15:06.929060 | 2026-05-24 03:15:06.929177 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-24 03:15:06.972725 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:06.978046 | 2026-05-24 03:15:06.978145 | TASK [fetch-tox-output : Set envlist fact] 2026-05-24 03:15:07.011276 | debian-bookworm | ok 2026-05-24 03:15:07.016853 | 2026-05-24 03:15:07.016945 | TASK [fetch-tox-output : Get tox version] 2026-05-24 03:15:07.670412 | 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-24 03:15:07.688746 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-24 03:15:08.055147 | debian-bookworm | ok: Runtime: 0:00:00.313562 2026-05-24 03:15:08.060894 | 2026-05-24 03:15:08.061002 | TASK [fetch-tox-output : Find all default environments] 2026-05-24 03:15:08.591659 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:08.596508 | 2026-05-24 03:15:08.596601 | TASK [fetch-tox-output : Set envlist fact] 2026-05-24 03:15:08.619293 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:08.623774 | 2026-05-24 03:15:08.623857 | TASK [fetch-tox-output : Find all default environments] 2026-05-24 03:15:09.151589 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:09.157416 | 2026-05-24 03:15:09.157520 | TASK [fetch-tox-output : Set envlist fact] 2026-05-24 03:15:09.195959 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:09.202520 | 2026-05-24 03:15:09.202622 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-24 03:15:09.275358 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:09.284667 | 2026-05-24 03:15:09.284785 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-24 03:15:10.247454 | debian-bookworm | changed: 2026-05-24 03:15:10.247595 | debian-bookworm | created directory /var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/work/logs/tox/test 2026-05-24 03:15:10.247623 | debian-bookworm | cd+++++++++ ./ 2026-05-24 03:15:10.247644 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-24 03:15:10.247667 | 2026-05-24 03:15:10.261031 | 2026-05-24 03:15:10.261103 | PLAY RECAP 2026-05-24 03:15:10.261144 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-24 03:15:10.261164 | 2026-05-24 03:15:10.395419 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-24 03:15:10.396337 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-24 03:15:11.328310 | 2026-05-24 03:15:11.328468 | PLAY [all] 2026-05-24 03:15:11.347642 | 2026-05-24 03:15:11.347778 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-24 03:15:11.572736 | debian-bookworm | changed: non-zero return code 2026-05-24 03:15:11.578387 | 2026-05-24 03:15:11.578524 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-24 03:15:11.619169 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:11.625479 | 2026-05-24 03:15:11.625604 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-24 03:15:11.668883 | 2026-05-24 03:15:11.669082 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-24 03:15:11.723288 | 2026-05-24 03:15:11.723578 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-24 03:15:11.748303 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:11.754801 | 2026-05-24 03:15:11.754951 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-24 03:15:11.783860 | 2026-05-24 03:15:11.784176 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-24 03:15:11.807917 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:11.825970 | 2026-05-24 03:15:11.826153 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-24 03:15:11.850681 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:11.859844 | 2026-05-24 03:15:11.859998 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-24 03:15:11.927168 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:11.934149 | 2026-05-24 03:15:11.934252 | PLAY RECAP 2026-05-24 03:15:11.934306 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-24 03:15:11.934332 | 2026-05-24 03:15:12.047303 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-24 03:15:12.048256 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-24 03:15:13.408575 | 2026-05-24 03:15:13.410722 | PLAY [Base post-fetch] 2026-05-24 03:15:13.439423 | 2026-05-24 03:15:13.439648 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-24 03:15:13.521106 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:13.528447 | 2026-05-24 03:15:13.528589 | TASK [fetch-output : Set log path for single node] 2026-05-24 03:15:13.578800 | debian-bookworm | ok 2026-05-24 03:15:13.592787 | 2026-05-24 03:15:13.592918 | LOOP [fetch-output : Ensure local output dirs] 2026-05-24 03:15:14.375693 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/work/logs" 2026-05-24 03:15:15.057787 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/work/artifacts" 2026-05-24 03:15:15.569244 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8329c90e10ad4a94a570b4d4bab6feb9/work/docs" 2026-05-24 03:15:15.581632 | 2026-05-24 03:15:15.581754 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-24 03:15:16.531904 | debian-bookworm | changed: 2026-05-24 03:15:16.532078 | debian-bookworm | .d..t...... ./ 2026-05-24 03:15:16.532109 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-24 03:15:16.532139 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-24 03:15:16.532164 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-24 03:15:16.532225 | debian-bookworm | changed: All items complete 2026-05-24 03:15:16.532248 | 2026-05-24 03:15:17.310907 | debian-bookworm | changed: .d..t...... ./ 2026-05-24 03:15:18.248898 | debian-bookworm | changed: .d..t...... ./ 2026-05-24 03:15:18.260527 | 2026-05-24 03:15:18.261726 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-24 03:15:18.317888 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:18.337702 | debian-bookworm | skipping: Conditional result was False 2026-05-24 03:15:18.354413 | 2026-05-24 03:15:18.354491 | PLAY RECAP 2026-05-24 03:15:18.354537 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-24 03:15:18.354557 | 2026-05-24 03:15:18.571291 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-24 03:15:18.572366 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-24 03:15:19.439988 | 2026-05-24 03:15:19.440112 | PLAY [Base post] 2026-05-24 03:15:19.475139 | 2026-05-24 03:15:19.475281 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-24 03:15:20.131077 | debian-bookworm | changed 2026-05-24 03:15:20.135787 | 2026-05-24 03:15:20.135858 | PLAY RECAP 2026-05-24 03:15:20.135905 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-24 03:15:20.135954 | 2026-05-24 03:15:20.344590 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-24 03:15:20.345384 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-24 03:15:21.689528 | 2026-05-24 03:15:21.689681 | PLAY [Base post-logs] 2026-05-24 03:15:21.725032 | 2026-05-24 03:15:21.725181 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-24 03:15:22.866295 | localhost | changed 2026-05-24 03:15:22.876368 | 2026-05-24 03:15:22.876499 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-24 03:15:22.968810 | localhost | ok 2026-05-24 03:15:22.971900 | 2026-05-24 03:15:22.971986 | TASK [Set zuul-log-path fact] 2026-05-24 03:15:23.009326 | localhost | ok 2026-05-24 03:15:23.017642 | 2026-05-24 03:15:23.017748 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-24 03:15:23.063617 | localhost | ok 2026-05-24 03:15:23.066675 | 2026-05-24 03:15:23.066764 | TASK [upload-logs : Create log directories] 2026-05-24 03:15:23.984629 | localhost | changed 2026-05-24 03:15:23.987260 | 2026-05-24 03:15:23.987351 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-24 03:15:24.674463 | localhost -> localhost | ok: Runtime: 0:00:00.014487 2026-05-24 03:15:24.677533 | 2026-05-24 03:15:24.677620 | TASK [upload-logs : Upload logs to log server] 2026-05-24 03:15:25.406045 | localhost | Output suppressed because no_log was given 2026-05-24 03:15:25.408210 | 2026-05-24 03:15:25.408310 | LOOP [upload-logs : Compress console log and json output] 2026-05-24 03:15:25.504235 | localhost | skipping: Conditional result was False 2026-05-24 03:15:25.523576 | localhost | skipping: Conditional result was False 2026-05-24 03:15:25.534410 | 2026-05-24 03:15:25.534511 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-24 03:15:25.650905 | localhost | skipping: Conditional result was False 2026-05-24 03:15:25.651115 | 2026-05-24 03:15:25.655346 | localhost | skipping: Conditional result was False 2026-05-24 03:15:25.660037 | 2026-05-24 03:15:25.660129 | LOOP [upload-logs : Upload console log and json output]