2026-09-24 23:40:13.622973 | Job console starting 2026-09-24 23:40:13.644259 | Updating git repos 2026-09-24 23:40:13.699097 | Cloning repos into workspace 2026-09-24 23:40:13.759815 | Restoring repo states 2026-09-24 23:40:13.774861 | Merging changes 2026-09-24 23:40:14.325788 | Checking out repos 2026-09-24 23:40:14.356081 | Preparing playbooks 2026-09-24 23:40:15.377690 | Running Ansible setup 2026-09-24 23:40:22.948996 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 23:40:24.622850 | 2026-09-24 23:40:24.622994 | PLAY [Base pre] 2026-09-24 23:40:24.670765 | 2026-09-24 23:40:24.670888 | TASK [Setup log path fact] 2026-09-24 23:40:24.715415 | debian-bookworm | ok 2026-09-24 23:40:24.745472 | 2026-09-24 23:40:24.745608 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 23:40:24.787688 | debian-bookworm | ok 2026-09-24 23:40:24.807464 | 2026-09-24 23:40:24.807559 | TASK [emit-job-header : Print job information] 2026-09-24 23:40:24.864789 | # Job Information 2026-09-24 23:40:24.864941 | Ansible Version: 2.16.14 2026-09-24 23:40:24.864969 | Job: generics-tox-seed 2026-09-24 23:40:24.864993 | Pipeline: check 2026-09-24 23:40:24.865011 | Executor: 521e9411259a 2026-09-24 23:40:24.865027 | Triggered by: https://github.com/osism/generics/pull/612 2026-09-24 23:40:24.865066 | Event ID: 43d00ec0-b871-11f1-99ed-8d644b10d596 2026-09-24 23:40:24.869150 | 2026-09-24 23:40:24.869234 | LOOP [emit-job-header : Print node information] 2026-09-24 23:40:25.126779 | debian-bookworm | ok: 2026-09-24 23:40:25.127008 | debian-bookworm | # Node Information 2026-09-24 23:40:25.127038 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-24 23:40:25.127086 | debian-bookworm | Hostname: debian 2026-09-24 23:40:25.127105 | debian-bookworm | Username: zuul 2026-09-24 23:40:25.127123 | debian-bookworm | Distro: Debian 12.15 2026-09-24 23:40:25.127148 | debian-bookworm | Provider: regiocloud-a 2026-09-24 23:40:25.127169 | debian-bookworm | Region: 2026-09-24 23:40:25.127187 | debian-bookworm | Label: debian-bookworm 2026-09-24 23:40:25.127204 | debian-bookworm | Product Name: OpenStack Nova 2026-09-24 23:40:25.127221 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee1:26de 2026-09-24 23:40:25.137372 | 2026-09-24 23:40:25.137487 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-24 23:40:26.109964 | debian-bookworm -> localhost | changed 2026-09-24 23:40:26.115855 | 2026-09-24 23:40:26.115944 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-24 23:40:28.340968 | debian-bookworm -> localhost | changed 2026-09-24 23:40:28.349922 | 2026-09-24 23:40:28.350014 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-24 23:40:28.872618 | debian-bookworm -> localhost | ok 2026-09-24 23:40:28.883907 | 2026-09-24 23:40:28.884003 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-24 23:40:28.913313 | debian-bookworm | ok 2026-09-24 23:40:28.976075 | debian-bookworm | included: /var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-24 23:40:28.997704 | 2026-09-24 23:40:29.004462 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-24 23:40:32.495127 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-24 23:40:32.495299 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/work/7aad6b7110ec444b849ecfde6edaa34a_id_rsa 2026-09-24 23:40:32.495329 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/work/7aad6b7110ec444b849ecfde6edaa34a_id_rsa.pub 2026-09-24 23:40:32.495351 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-24 23:40:32.495372 | debian-bookworm -> localhost | SHA256:sIMRE2j2Pwn5NRkTB+d4yUFrbqA5HCXJynAIC+uBack zuul-build-sshkey 2026-09-24 23:40:32.495390 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-24 23:40:32.495418 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-24 23:40:32.495435 | debian-bookworm -> localhost | |o. o+o..++= | 2026-09-24 23:40:32.495453 | debian-bookworm -> localhost | |++B .oooo* + | 2026-09-24 23:40:32.495469 | debian-bookworm -> localhost | |=E =.oo o+B | 2026-09-24 23:40:32.495484 | debian-bookworm -> localhost | |o . *+ *+= | 2026-09-24 23:40:32.495500 | debian-bookworm -> localhost | | . .+BoS.o | 2026-09-24 23:40:32.495521 | debian-bookworm -> localhost | | =o . | 2026-09-24 23:40:32.495538 | debian-bookworm -> localhost | | . | 2026-09-24 23:40:32.495554 | debian-bookworm -> localhost | | | 2026-09-24 23:40:32.495571 | debian-bookworm -> localhost | | | 2026-09-24 23:40:32.495587 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-24 23:40:32.495628 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.291055 2026-09-24 23:40:32.500707 | 2026-09-24 23:40:32.500785 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-24 23:40:32.528202 | debian-bookworm | ok 2026-09-24 23:40:32.544350 | debian-bookworm | included: /var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-24 23:40:32.562006 | 2026-09-24 23:40:32.562093 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-24 23:40:32.595334 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:40:32.600194 | 2026-09-24 23:40:32.600271 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-24 23:40:33.458574 | debian-bookworm | changed 2026-09-24 23:40:33.463672 | 2026-09-24 23:40:33.463754 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-24 23:40:33.718483 | debian-bookworm | ok 2026-09-24 23:40:33.723802 | 2026-09-24 23:40:33.723895 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-24 23:40:34.447616 | debian-bookworm | changed 2026-09-24 23:40:34.453061 | 2026-09-24 23:40:34.453155 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-24 23:40:35.201287 | debian-bookworm | changed 2026-09-24 23:40:35.207479 | 2026-09-24 23:40:35.207574 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-24 23:40:35.237807 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:40:35.243599 | 2026-09-24 23:40:35.243685 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-24 23:40:35.806338 | debian-bookworm -> localhost | changed 2026-09-24 23:40:35.818397 | 2026-09-24 23:40:35.818499 | TASK [add-build-sshkey : Add back temp key] 2026-09-24 23:40:36.551631 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/work/7aad6b7110ec444b849ecfde6edaa34a_id_rsa (zuul-build-sshkey) 2026-09-24 23:40:36.551867 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016301 2026-09-24 23:40:36.559559 | 2026-09-24 23:40:36.559646 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-24 23:40:37.118613 | debian-bookworm | ok 2026-09-24 23:40:37.126392 | 2026-09-24 23:40:37.126507 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-24 23:40:37.149826 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:40:37.162497 | 2026-09-24 23:40:37.162601 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-24 23:40:37.842684 | debian-bookworm | ok 2026-09-24 23:40:37.858806 | 2026-09-24 23:40:37.858911 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-24 23:40:37.961955 | debian-bookworm | ok 2026-09-24 23:40:37.967153 | 2026-09-24 23:40:37.967245 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-24 23:40:38.335245 | debian-bookworm -> localhost | ok 2026-09-24 23:40:38.341418 | 2026-09-24 23:40:38.341506 | TASK [validate-host : Collect information about the host] 2026-09-24 23:40:39.588895 | debian-bookworm | ok 2026-09-24 23:40:39.603291 | 2026-09-24 23:40:39.603368 | TASK [validate-host : Sanitize hostname] 2026-09-24 23:40:39.801065 | debian-bookworm | ok 2026-09-24 23:40:39.808215 | 2026-09-24 23:40:39.808295 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-24 23:40:40.398634 | debian-bookworm -> localhost | changed 2026-09-24 23:40:40.403152 | 2026-09-24 23:40:40.403228 | TASK [validate-host : Collect information about zuul worker] 2026-09-24 23:40:40.907019 | debian-bookworm | ok 2026-09-24 23:40:40.911563 | 2026-09-24 23:40:40.911651 | TASK [validate-host : Write out all zuul information for each host] 2026-09-24 23:40:42.064558 | debian-bookworm -> localhost | changed 2026-09-24 23:40:42.080135 | 2026-09-24 23:40:42.080233 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-24 23:40:42.421457 | debian-bookworm | ok 2026-09-24 23:40:42.426998 | 2026-09-24 23:40:42.427094 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-24 23:40:43.470405 | debian-bookworm | changed: 2026-09-24 23:40:43.470577 | debian-bookworm | cd+++++++++ src/ 2026-09-24 23:40:43.470612 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-24 23:40:43.470635 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-24 23:40:43.470656 | debian-bookworm | cd+++++++++ src/github.com/osism/generics/ 2026-09-24 23:40:43.470676 | debian-bookworm | =7.0.3 2026-09-24 23:41:34.125536 | debian-bookworm | Downloading cachetools-7.2.0-py3-none-any.whl (16 kB) 2026-09-24 23:41:34.188619 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-24 23:41:34.204246 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-24 23:41:34.283766 | debian-bookworm | Collecting filelock>=3.25 2026-09-24 23:41:34.299066 | debian-bookworm | Downloading filelock-4.0.3-py3-none-any.whl (108 kB) 2026-09-24 23:41:34.313351 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 108.4/108.4 kB 10.8 MB/s eta 0:00:00 2026-09-24 23:41:34.368623 | debian-bookworm | Collecting packaging>=26 2026-09-24 23:41:34.384247 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-24 23:41:34.400816 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 10.0 MB/s eta 0:00:00 2026-09-24 23:41:34.490124 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-24 23:41:34.507051 | debian-bookworm | Downloading platformdirs-4.11.12-py3-none-any.whl (24 kB) 2026-09-24 23:41:34.572649 | debian-bookworm | Collecting pluggy>=1.6 2026-09-24 23:41:34.588302 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-24 23:41:34.650990 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-24 23:41:34.666589 | debian-bookworm | Downloading pyproject_api-1.11.2-py3-none-any.whl (13 kB) 2026-09-24 23:41:34.821282 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-24 23:41:34.839011 | debian-bookworm | Downloading python_discovery-1.6.1-py3-none-any.whl (38 kB) 2026-09-24 23:41:34.897340 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-24 23:41:34.913737 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-24 23:41:35.012778 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-24 23:41:35.029608 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-24 23:41:35.041153 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 5.3 MB/s eta 0:00:00 2026-09-24 23:41:35.210563 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-24 23:41:35.229339 | debian-bookworm | Downloading virtualenv-21.12.1-py3-none-any.whl (5.5 MB) 2026-09-24 23:41:35.584050 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.5/5.5 MB 15.8 MB/s eta 0:00:00 2026-09-24 23:41:35.730801 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-24 23:41:35.746770 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-24 23:41:35.823474 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 6.6 MB/s eta 0:00:00 2026-09-24 23:41:35.971860 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-24 23:41:36.992660 | debian-bookworm | Successfully installed cachetools-7.2.0 colorama-0.4.6 distlib-0.4.3 filelock-4.0.3 packaging-26.3 platformdirs-4.11.12 pluggy-1.6.0 pyproject-api-1.11.2 python-discovery-1.6.1 tomli-w-1.2.0 tox-4.64.2 typing-extensions-4.16.0 virtualenv-21.12.1 2026-09-24 23:41:37.200588 | debian-bookworm | ok: Runtime: 0:00:04.156204 2026-09-24 23:41:37.205706 | 2026-09-24 23:41:37.205783 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-24 23:41:37.254705 | debian-bookworm | ok 2026-09-24 23:41:37.267917 | 2026-09-24 23:41:37.268004 | TASK [ensure-tox : Output tox version] 2026-09-24 23:41:37.980419 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-24 23:41:38.000127 | debian-bookworm | 4.64.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-24 23:41:38.325929 | debian-bookworm | ok: Runtime: 0:00:00.389104 2026-09-24 23:41:38.330749 | 2026-09-24 23:41:38.330832 | TASK [ensure-tox : Make global symlink] 2026-09-24 23:41:38.383803 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:38.414224 | 2026-09-24 23:41:38.414332 | PLAY RECAP 2026-09-24 23:41:38.414383 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-24 23:41:38.414407 | 2026-09-24 23:41:38.967928 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-24 23:41:38.972735 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-24 23:41:41.572445 | 2026-09-24 23:41:41.572564 | PLAY [all] 2026-09-24 23:41:41.611304 | 2026-09-24 23:41:41.611412 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-24 23:41:42.517570 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-24 23:41:42.685645 | debian-bookworm | ok: Runtime: 0:00:00.024142 2026-09-24 23:41:42.690098 | 2026-09-24 23:41:42.690186 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-24 23:41:43.238324 | debian-bookworm | ok: Runtime: 0:00:00.007504 2026-09-24 23:41:43.247073 | 2026-09-24 23:41:43.247169 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-24 23:41:43.783386 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-24 23:41:43.785248 | debian-bookworm | sudo: a password is required 2026-09-24 23:41:43.825428 | debian-bookworm | ok: Runtime: 0:00:00.018902 2026-09-24 23:41:43.844365 | 2026-09-24 23:41:43.844455 | TASK [tox : Check to see if the constraints file exists] 2026-09-24 23:41:43.886108 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:43.891135 | 2026-09-24 23:41:43.891225 | TASK [tox : Fail if constraints file is missing] 2026-09-24 23:41:43.934462 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:43.940024 | 2026-09-24 23:41:43.940140 | TASK [tox : Record file location] 2026-09-24 23:41:44.001662 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:44.006636 | 2026-09-24 23:41:44.006731 | TASK [tox : Install tox siblings] 2026-09-24 23:41:44.035298 | debian-bookworm | ok 2026-09-24 23:41:44.049002 | debian-bookworm | included: /var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-24 23:41:44.053484 | 2026-09-24 23:41:44.065650 | TASK [tox : Run tox without tests] 2026-09-24 23:41:44.734061 | debian-bookworm | ROOT: 238 D setup logging to DEBUG on pid 1998 [tox/report.py:249] 2026-09-24 23:41:44.767824 | debian-bookworm | seed: 271 D created app data folder /home/zuul/.cache/virtualenv [virtualenv/app_data/__init__.py:65] 2026-09-24 23:41:44.770683 | debian-bookworm | seed: 274 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [python_discovery/_discovery.py:131] 2026-09-24 23:41:44.774859 | debian-bookworm | seed: 279 D get interpreter info via cmd: /usr/bin/python3.11 /home/zuul/.local/tox/lib/python3.11/site-packages/python_discovery/_py_info_collect.py 5801ad3ab735bf413bdc695b577a6541 7b79a8047a13be9087d154a2d16090d0 [python_discovery/_cached_py_info.py:223] 2026-09-24 23:41:44.869971 | debian-bookworm | seed: 374 D wrote python info of /usr/bin/python3.11 at /home/zuul/.cache/virtualenv/py_info/5/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:159] 2026-09-24 23:41:44.870497 | debian-bookworm | seed: 374 D filesystem is case-sensitive [python_discovery/_compat.py:18] 2026-09-24 23:41:44.870679 | debian-bookworm | seed: 375 I proposed PythonInfo(spec=CPython3.11.2.final.0-64-x86_64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [python_discovery/_discovery.py:140] 2026-09-24 23:41:44.870797 | debian-bookworm | seed: 375 D accepted PythonInfo(spec=CPython3.11.2.final.0-64-x86_64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [python_discovery/_discovery.py:144] 2026-09-24 23:41:44.872783 | debian-bookworm | seed: 377 D symlink on filesystem does work [virtualenv/info.py:51] 2026-09-24 23:41:44.872974 | debian-bookworm | seed: 377 D filesystem is case-sensitive [virtualenv/info.py:28] 2026-09-24 23:41:44.910690 | debian-bookworm | seed: 414 I create virtual environment via CPython3Posix(dest=/home/zuul/src/github.com/osism/generics/.tox/seed, clear=False, no_vcs_ignore=False, venv_redirect=False, global=False) [virtualenv/run/session.py:77] 2026-09-24 23:41:44.910973 | debian-bookworm | seed: 415 D create folder /home/zuul/src/github.com/osism/generics/.tox/seed/bin [virtualenv/util/path/_sync.py:22] 2026-09-24 23:41:44.911171 | debian-bookworm | seed: 415 D create folder /home/zuul/src/github.com/osism/generics/.tox/seed/include [virtualenv/util/path/_sync.py:22] 2026-09-24 23:41:44.911260 | debian-bookworm | seed: 415 D create folder /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages [virtualenv/util/path/_sync.py:22] 2026-09-24 23:41:44.911507 | debian-bookworm | seed: 415 D write /home/zuul/src/github.com/osism/generics/.tox/seed/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:44] 2026-09-24 23:41:44.911614 | debian-bookworm | seed: 416 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.911679 | debian-bookworm | seed: 416 D implementation = CPython [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.911731 | debian-bookworm | seed: 416 D python-version = 3.11 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.911781 | debian-bookworm | seed: 416 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.911830 | debian-bookworm | seed: 416 D version = 3.11.2 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.911881 | debian-bookworm | seed: 416 D executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.911935 | debian-bookworm | seed: 416 D command = /home/zuul/.local/tox/bin/python3 -m virtualenv /home/zuul/src/github.com/osism/generics/.tox/seed [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.911990 | debian-bookworm | seed: 416 D virtualenv = 21.12.1 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.912043 | debian-bookworm | seed: 416 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.912096 | debian-bookworm | seed: 416 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.912140 | debian-bookworm | seed: 416 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.912187 | debian-bookworm | seed: 416 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:44.912561 | debian-bookworm | seed: 416 D symlink /usr/bin/python3.11 to /home/zuul/src/github.com/osism/generics/.tox/seed/bin/python [virtualenv/util/path/_sync.py:42] 2026-09-24 23:41:44.913093 | debian-bookworm | seed: 417 D ============================== target debug ============================== [virtualenv/run/session.py:79] 2026-09-24 23:41:44.913722 | debian-bookworm | seed: 418 D debug via /home/zuul/src/github.com/osism/generics/.tox/seed/bin/python /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/create/debug.py [virtualenv/create/creator.py:316] 2026-09-24 23:41:44.955968 | debian-bookworm | seed: 417 D { 2026-09-24 23:41:44.956028 | debian-bookworm | "sys": { 2026-09-24 23:41:44.956034 | debian-bookworm | "executable": "/home/zuul/src/github.com/osism/generics/.tox/seed/bin/python", 2026-09-24 23:41:44.956043 | debian-bookworm | "_base_executable": "/usr/bin/python3.11", 2026-09-24 23:41:44.956049 | debian-bookworm | "prefix": "/home/zuul/src/github.com/osism/generics/.tox/seed", 2026-09-24 23:41:44.956054 | debian-bookworm | "base_prefix": "/usr", 2026-09-24 23:41:44.956060 | debian-bookworm | "exec_prefix": "/home/zuul/src/github.com/osism/generics/.tox/seed", 2026-09-24 23:41:44.956064 | debian-bookworm | "base_exec_prefix": "/usr", 2026-09-24 23:41:44.956068 | debian-bookworm | "path": [ 2026-09-24 23:41:44.956072 | debian-bookworm | "/usr/lib/python311.zip", 2026-09-24 23:41:44.956076 | debian-bookworm | "/usr/lib/python3.11", 2026-09-24 23:41:44.956080 | debian-bookworm | "/usr/lib/python3.11/lib-dynload", 2026-09-24 23:41:44.956084 | debian-bookworm | "/home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages" 2026-09-24 23:41:44.956089 | debian-bookworm | ], 2026-09-24 23:41:44.956093 | debian-bookworm | "meta_path": [ 2026-09-24 23:41:44.956097 | debian-bookworm | "", 2026-09-24 23:41:44.956101 | debian-bookworm | "", 2026-09-24 23:41:44.956105 | debian-bookworm | "" 2026-09-24 23:41:44.956109 | debian-bookworm | ], 2026-09-24 23:41:44.956113 | debian-bookworm | "fs_encoding": "utf-8", 2026-09-24 23:41:44.956117 | debian-bookworm | "io_encoding": "utf-8" 2026-09-24 23:41:44.956121 | debian-bookworm | }, 2026-09-24 23:41:44.956125 | debian-bookworm | "version": "3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]", 2026-09-24 23:41:44.956129 | debian-bookworm | "makefile_filename": "/usr/lib/python3.11/config-3.11-x86_64-linux-gnu/Makefile", 2026-09-24 23:41:44.956133 | debian-bookworm | "os": "", 2026-09-24 23:41:44.956137 | debian-bookworm | "site": "", 2026-09-24 23:41:44.956141 | debian-bookworm | "datetime": "", 2026-09-24 23:41:44.956145 | debian-bookworm | "math": "", 2026-09-24 23:41:44.956155 | debian-bookworm | "json": "" 2026-09-24 23:41:44.956159 | debian-bookworm | } [virtualenv/run/session.py:80] 2026-09-24 23:41:44.956350 | debian-bookworm | seed: 460 I add seed packages via FromAppData(download=False, pip=bundle, setuptools=bundle, via=copy, app_data_dir=/home/zuul/.cache/virtualenv) [virtualenv/run/session.py:84] 2026-09-24 23:41:44.968591 | debian-bookworm | seed: 472 D install pip from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/pip-26.2.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:65] 2026-09-24 23:41:44.969495 | debian-bookworm | seed: 473 D install setuptools from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/setuptools-84.0.0-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:65] 2026-09-24 23:41:44.971319 | debian-bookworm | seed: 475 D build install image for setuptools-84.0.0-py3-none-any.whl to /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:81] 2026-09-24 23:41:44.971759 | debian-bookworm | seed: 476 D build install image for pip-26.2.1-py3-none-any.whl to /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:81] 2026-09-24 23:41:45.328330 | debian-bookworm | seed: 832 D copy /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/setuptools-84.0.0.virtualenv to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/setuptools-84.0.0.virtualenv [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.329162 | debian-bookworm | seed: 833 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/setuptools-84.0.0.dist-info to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/setuptools-84.0.0.dist-info [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.332232 | debian-bookworm | seed: 836 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/_distutils_hack to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/_distutils_hack [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.334880 | debian-bookworm | seed: 839 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/setuptools to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/setuptools [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.457859 | debian-bookworm | seed: 962 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any/pip to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/pip [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.467882 | debian-bookworm | seed: 972 D copy /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/distutils-precedence.pth to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/distutils-precedence.pth [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.468611 | debian-bookworm | seed: 972 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:69] 2026-09-24 23:41:45.507825 | debian-bookworm | seed: 1012 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any/pip-26.2.1.dist-info to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/pip-26.2.1.dist-info [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.511821 | debian-bookworm | seed: 1016 D copy /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any/pip-26.2.1.virtualenv to /home/zuul/src/github.com/osism/generics/.tox/seed/lib/python3.11/site-packages/pip-26.2.1.virtualenv [virtualenv/util/path/_sync.py:50] 2026-09-24 23:41:45.512551 | debian-bookworm | seed: 1017 D generated console scripts pip pip-3.11 pip3 pip3.11 [virtualenv/seed/embed/via_app_data/pip_install/base.py:69] 2026-09-24 23:41:45.513186 | debian-bookworm | seed: 1017 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python, Xonsh [virtualenv/run/session.py:90] 2026-09-24 23:41:45.525591 | debian-bookworm | seed: 1029 D write /home/zuul/src/github.com/osism/generics/.tox/seed/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:44] 2026-09-24 23:41:45.525723 | debian-bookworm | seed: 1030 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.525847 | debian-bookworm | seed: 1030 D implementation = CPython [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526005 | debian-bookworm | seed: 1030 D python-version = 3.11 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526131 | debian-bookworm | seed: 1030 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526221 | debian-bookworm | seed: 1030 D version = 3.11.2 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526341 | debian-bookworm | seed: 1030 D executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526492 | debian-bookworm | seed: 1030 D command = /home/zuul/.local/tox/bin/python3 -m virtualenv /home/zuul/src/github.com/osism/generics/.tox/seed [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526597 | debian-bookworm | seed: 1031 D virtualenv = 21.12.1 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526697 | debian-bookworm | seed: 1031 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526800 | debian-bookworm | seed: 1031 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526900 | debian-bookworm | seed: 1031 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.526998 | debian-bookworm | seed: 1031 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:54] 2026-09-24 23:41:45.531453 | debian-bookworm | seed: OK (0.77 seconds) 2026-09-24 23:41:45.531522 | debian-bookworm | congratulations :) (0.80 seconds) 2026-09-24 23:41:45.653181 | debian-bookworm | ok: Runtime: 0:00:01.159891 2026-09-24 23:41:45.659209 | 2026-09-24 23:41:45.659288 | TASK [tox : Create a tempfile to save tox showconfig] 2026-09-24 23:41:46.300998 | debian-bookworm | changed 2026-09-24 23:41:46.305053 | 2026-09-24 23:41:46.305134 | TASK [tox : Detect tox version] 2026-09-24 23:41:47.102558 | debian-bookworm | 4.64.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-24 23:41:47.374094 | debian-bookworm | ok: Runtime: 0:00:00.402417 2026-09-24 23:41:47.378961 | 2026-09-24 23:41:47.379049 | TASK [tox : Get tox envlist config] 2026-09-24 23:41:48.437756 | debian-bookworm | ok: Runtime: 0:00:00.447257 2026-09-24 23:41:48.446998 | 2026-09-24 23:41:48.447127 | TASK [tox : Install any sibling python packages] 2026-09-24 23:41:49.217960 | debian-bookworm | ok: No setup.cfg, no action needed 2026-09-24 23:41:49.222328 | 2026-09-24 23:41:49.222399 | TASK [tox : Remove tempfile] 2026-09-24 23:41:49.969334 | debian-bookworm | changed 2026-09-24 23:41:49.989378 | 2026-09-24 23:41:49.989465 | TASK [tox : Emit tox command] 2026-09-24 23:41:50.086518 | /home/zuul/.local/tox/bin/tox -eseed -vv --skip-missing-interpreters=false 2026-09-24 23:41:50.090638 | 2026-09-24 23:41:50.090725 | TASK [tox : Run tox] 2026-09-24 23:41:50.895999 | debian-bookworm | ROOT: 234 D setup logging to DEBUG on pid 2038 [tox/report.py:249] 2026-09-24 23:41:50.930476 | debian-bookworm | seed: 269 D created app data folder /home/zuul/.cache/virtualenv [virtualenv/app_data/__init__.py:65] 2026-09-24 23:41:50.933554 | debian-bookworm | seed: 272 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [python_discovery/_discovery.py:131] 2026-09-24 23:41:50.937900 | debian-bookworm | seed: 276 D got python info of /usr/bin/python3.11 from /home/zuul/.cache/virtualenv/py_info/5/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:139] 2026-09-24 23:41:50.938293 | debian-bookworm | seed: 277 D filesystem is case-sensitive [python_discovery/_compat.py:18] 2026-09-24 23:41:50.938511 | debian-bookworm | seed: 277 I proposed PythonInfo(spec=CPython3.11.2.final.0-64-x86_64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [python_discovery/_discovery.py:140] 2026-09-24 23:41:50.938597 | debian-bookworm | seed: 277 D accepted PythonInfo(spec=CPython3.11.2.final.0-64-x86_64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [python_discovery/_discovery.py:144] 2026-09-24 23:41:50.940698 | debian-bookworm | seed: 279 D symlink on filesystem does work [virtualenv/info.py:51] 2026-09-24 23:41:50.940821 | debian-bookworm | seed: 279 D filesystem is case-sensitive [virtualenv/info.py:28] 2026-09-24 23:41:50.974039 | debian-bookworm | seed: 312 W commands[0]> bash src/test-seed.sh [tox/tox_env/api.py:496] 2026-09-24 23:41:51.663765 | debian-bookworm | PASS=44 FAIL=0 2026-09-24 23:41:51.666126 | debian-bookworm | seed: 1004 I exit 0 (0.69 seconds) /home/zuul/src/github.com/osism/generics> bash src/test-seed.sh pid=2045 [tox/execute/api.py:337] 2026-09-24 23:41:51.669526 | debian-bookworm | seed: OK (0.74=setup[0.05]+cmd[0.69] seconds) 2026-09-24 23:41:51.669590 | debian-bookworm | congratulations :) (0.77 seconds) 2026-09-24 23:41:52.200821 | debian-bookworm | ok: Runtime: 0:00:01.132479 2026-09-24 23:41:52.205660 | 2026-09-24 23:41:52.205748 | TASK [tox : Look for output] 2026-09-24 23:41:52.714823 | debian-bookworm | ok 2026-09-24 23:41:52.736186 | 2026-09-24 23:41:52.736289 | TASK [tox : Return file comments to Zuul] 2026-09-24 23:41:52.826583 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:52.832592 | 2026-09-24 23:41:52.832658 | PLAY RECAP 2026-09-24 23:41:52.832706 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-24 23:41:52.832727 | 2026-09-24 23:41:53.058537 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-24 23:41:53.059382 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-24 23:41:54.200239 | 2026-09-24 23:41:54.200349 | PLAY [all] 2026-09-24 23:41:54.212197 | 2026-09-24 23:41:54.212297 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-24 23:41:54.275929 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:54.280730 | 2026-09-24 23:41:54.280833 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-24 23:41:54.318055 | debian-bookworm | ok 2026-09-24 23:41:54.322103 | 2026-09-24 23:41:54.322179 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-24 23:41:55.046250 | debian-bookworm -> localhost | changed 2026-09-24 23:41:55.052346 | 2026-09-24 23:41:55.052433 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-24 23:41:55.156420 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:55.161217 | 2026-09-24 23:41:55.161307 | TASK [fetch-tox-output : Set envlist fact] 2026-09-24 23:41:55.241535 | debian-bookworm | ok 2026-09-24 23:41:55.245938 | 2026-09-24 23:41:55.246011 | TASK [fetch-tox-output : Get tox version] 2026-09-24 23:41:56.647358 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-24 23:41:56.667156 | debian-bookworm | 4.64.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-24 23:41:56.867486 | debian-bookworm | ok: Runtime: 0:00:00.366874 2026-09-24 23:41:56.871921 | 2026-09-24 23:41:56.871999 | TASK [fetch-tox-output : Find all default environments] 2026-09-24 23:41:57.427614 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:57.432350 | 2026-09-24 23:41:57.432438 | TASK [fetch-tox-output : Set envlist fact] 2026-09-24 23:41:57.479554 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:57.485138 | 2026-09-24 23:41:57.485229 | TASK [fetch-tox-output : Find all default environments] 2026-09-24 23:41:58.022127 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:58.026887 | 2026-09-24 23:41:58.026967 | TASK [fetch-tox-output : Set envlist fact] 2026-09-24 23:41:58.120906 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:58.126042 | 2026-09-24 23:41:58.126130 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-24 23:41:58.212675 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:41:58.225626 | 2026-09-24 23:41:58.225714 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-24 23:41:59.480320 | debian-bookworm | changed: 2026-09-24 23:41:59.480485 | debian-bookworm | created directory /var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/work/logs/tox/seed 2026-09-24 23:41:59.480517 | debian-bookworm | cd+++++++++ ./ 2026-09-24 23:41:59.480539 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-09-24 23:41:59.480658 | 2026-09-24 23:41:59.493194 | 2026-09-24 23:41:59.493269 | PLAY RECAP 2026-09-24 23:41:59.493313 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-24 23:41:59.493333 | 2026-09-24 23:42:00.004773 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-24 23:42:00.005539 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-24 23:42:02.533187 | 2026-09-24 23:42:02.533312 | PLAY [all] 2026-09-24 23:42:02.554070 | 2026-09-24 23:42:02.554200 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-24 23:42:02.818357 | debian-bookworm | changed: non-zero return code 2026-09-24 23:42:02.822939 | 2026-09-24 23:42:02.823061 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-24 23:42:02.859419 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:02.867755 | 2026-09-24 23:42:02.867847 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-24 23:42:02.896443 | 2026-09-24 23:42:02.896582 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-24 23:42:02.945888 | 2026-09-24 23:42:02.946054 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-24 23:42:02.969498 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:02.981657 | 2026-09-24 23:42:02.981738 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-24 23:42:03.036965 | 2026-09-24 23:42:03.037134 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-24 23:42:03.066927 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:03.072638 | 2026-09-24 23:42:03.072734 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-24 23:42:03.108123 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:03.116440 | 2026-09-24 23:42:03.116521 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-24 23:42:03.140692 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:03.145859 | 2026-09-24 23:42:03.145915 | PLAY RECAP 2026-09-24 23:42:03.145958 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-24 23:42:03.145988 | 2026-09-24 23:42:03.308887 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-24 23:42:03.309804 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-24 23:42:04.675021 | 2026-09-24 23:42:04.675179 | PLAY [Base post-fetch] 2026-09-24 23:42:04.694879 | 2026-09-24 23:42:04.695002 | TASK [diagnose-network : Ensure log directory exists] 2026-09-24 23:42:05.082963 | debian-bookworm | ok 2026-09-24 23:42:05.090872 | 2026-09-24 23:42:05.091000 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-24 23:42:17.069721 | debian-bookworm | ok: Runtime: 0:00:06.038743 2026-09-24 23:42:17.080148 | 2026-09-24 23:42:17.080288 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-24 23:42:23.110334 | debian-bookworm | ok: Runtime: 0:00:00.007288 2026-09-24 23:42:23.118229 | 2026-09-24 23:42:23.118367 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-24 23:42:29.228896 | debian-bookworm | ok: Runtime: 0:00:00.009852 2026-09-24 23:42:29.233672 | 2026-09-24 23:42:29.233755 | TASK [diagnose-network : Evaluate node isolation verdict (pre-run gate)] 2026-09-24 23:42:29.786844 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:29.793655 | 2026-09-24 23:42:29.793765 | TASK [diagnose-network : Abort the pre-run on a fully isolated node] 2026-09-24 23:42:29.847458 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:29.870899 | 2026-09-24 23:42:29.871006 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-24 23:42:29.928898 | debian-bookworm | skipping: Conditional result was False 2026-09-24 23:42:29.934432 | 2026-09-24 23:42:29.934527 | TASK [fetch-output : Set log path for single node] 2026-09-24 23:42:29.964911 | debian-bookworm | ok 2026-09-24 23:42:29.970025 | 2026-09-24 23:42:29.970132 | LOOP [fetch-output : Ensure local output dirs] 2026-09-24 23:42:30.610839 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/work/logs" 2026-09-24 23:42:31.142931 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/work/artifacts" 2026-09-24 23:42:31.731577 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7aad6b7110ec444b849ecfde6edaa34a/work/docs" 2026-09-24 23:42:31.740920 | 2026-09-24 23:42:31.741004 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-24 23:42:33.126427 | debian-bookworm | changed: 2026-09-24 23:42:33.126616 | debian-bookworm | .d..t...... ./ 2026-09-24 23:42:33.126650 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-24 23:42:33.126674 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-24 23:42:33.126695 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-24 23:42:33.126717 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-24 23:42:33.126737 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-24 23:42:33.126771 | debian-bookworm | changed: All items complete 2026-09-24 23:42:33.126797 | 2026-09-24 23:42:33.904664 | debian-bookworm | changed: .d..t...... ./ 2026-09-24 23:42:34.776338 | debian-bookworm | changed: .d..t...... ./ 2026-09-24 23:42:34.793576 | 2026-09-24 23:42:34.793710 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-24 23:42:35.374852 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.017448 2026-09-24 23:42:35.906174 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.027577 2026-09-24 23:42:35.915691 | 2026-09-24 23:42:35.915778 | PLAY RECAP 2026-09-24 23:42:35.915823 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-24 23:42:35.915844 | 2026-09-24 23:42:36.119731 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-24 23:42:36.120507 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 23:42:37.328646 | 2026-09-24 23:42:37.328777 | PLAY [Base post] 2026-09-24 23:42:37.341470 | 2026-09-24 23:42:37.341583 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-24 23:42:38.007145 | debian-bookworm | changed 2026-09-24 23:42:38.013393 | 2026-09-24 23:42:38.013473 | PLAY RECAP 2026-09-24 23:42:38.013525 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 23:42:38.013591 | 2026-09-24 23:42:38.223518 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 23:42:38.224286 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-24 23:42:38.944400 | 2026-09-24 23:42:38.945086 | PLAY [Base post-logs] 2026-09-24 23:42:38.955696 | 2026-09-24 23:42:38.955790 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-24 23:42:39.676654 | localhost | changed 2026-09-24 23:42:39.693579 | 2026-09-24 23:42:39.694023 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-24 23:42:39.732080 | localhost | ok 2026-09-24 23:42:39.734616 | 2026-09-24 23:42:39.734690 | TASK [Set zuul-log-path fact] 2026-09-24 23:42:39.748874 | localhost | ok 2026-09-24 23:42:39.756527 | 2026-09-24 23:42:39.756604 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 23:42:39.794113 | localhost | ok 2026-09-24 23:42:39.796843 | 2026-09-24 23:42:39.796920 | TASK [upload-logs : Create log directories] 2026-09-24 23:42:40.376737 | localhost | changed 2026-09-24 23:42:40.383149 | 2026-09-24 23:42:40.383231 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-24 23:42:40.876210 | localhost -> localhost | ok: Runtime: 0:00:00.007521 2026-09-24 23:42:40.879584 | 2026-09-24 23:42:40.879673 | TASK [upload-logs : Upload logs to log server] 2026-09-24 23:42:41.357960 | localhost | Output suppressed because no_log was given 2026-09-24 23:42:41.361252 | 2026-09-24 23:42:41.362138 | LOOP [upload-logs : Compress console log and json output] 2026-09-24 23:42:41.413810 | localhost | skipping: Conditional result was False 2026-09-24 23:42:41.427044 | localhost | skipping: Conditional result was False 2026-09-24 23:42:41.437756 | 2026-09-24 23:42:41.437859 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-24 23:42:41.476954 | localhost | skipping: Conditional result was False 2026-09-24 23:42:41.477180 | 2026-09-24 23:42:41.484708 | localhost | skipping: Conditional result was False 2026-09-24 23:42:41.494811 | 2026-09-24 23:42:41.494917 | LOOP [upload-logs : Upload console log and json output]