2025-07-24 03:06:42.361579 | Job console starting 2025-07-24 03:06:42.379698 | Updating git repos 2025-07-24 03:06:42.400767 | Cloning repos into workspace 2025-07-24 03:06:42.451171 | Restoring repo states 2025-07-24 03:06:42.469029 | Merging changes 2025-07-24 03:06:42.469071 | Checking out repos 2025-07-24 03:06:42.528140 | Preparing playbooks 2025-07-24 03:06:43.316990 | Running Ansible setup 2025-07-24 03:06:49.338715 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-24 03:06:50.585163 | 2025-07-24 03:06:50.585299 | PLAY [Base pre] 2025-07-24 03:06:50.666990 | 2025-07-24 03:06:50.667140 | TASK [Setup log path fact] 2025-07-24 03:06:50.697761 | debian-bookworm | ok 2025-07-24 03:06:50.751572 | 2025-07-24 03:06:50.751718 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-24 03:06:50.813117 | debian-bookworm | ok 2025-07-24 03:06:50.841029 | 2025-07-24 03:06:50.841145 | TASK [emit-job-header : Print job information] 2025-07-24 03:06:50.944225 | # Job Information 2025-07-24 03:06:50.944463 | Ansible Version: 2.16.14 2025-07-24 03:06:50.944502 | Job: cfg-generics-tox-check 2025-07-24 03:06:50.944536 | Pipeline: periodic-daily 2025-07-24 03:06:50.944560 | Executor: 521e9411259a 2025-07-24 03:06:50.944582 | Triggered by: https://github.com/osism/cfg-generics 2025-07-24 03:06:50.944604 | Event ID: 90ad7968e8df4e17b461e4e632118ed0 2025-07-24 03:06:50.949553 | 2025-07-24 03:06:50.949660 | LOOP [emit-job-header : Print node information] 2025-07-24 03:06:51.205163 | debian-bookworm | ok: 2025-07-24 03:06:51.205348 | debian-bookworm | # Node Information 2025-07-24 03:06:51.205376 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-24 03:06:51.205396 | debian-bookworm | Hostname: debian 2025-07-24 03:06:51.205413 | debian-bookworm | Username: zuul 2025-07-24 03:06:51.205430 | debian-bookworm | Distro: Debian 12.11 2025-07-24 03:06:51.205449 | debian-bookworm | Provider: regiocloud-a 2025-07-24 03:06:51.205466 | debian-bookworm | Region: 2025-07-24 03:06:51.205484 | debian-bookworm | Label: debian-bookworm 2025-07-24 03:06:51.205500 | debian-bookworm | Product Name: OpenStack Nova 2025-07-24 03:06:51.205517 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7b:e5e0 2025-07-24 03:06:51.226252 | 2025-07-24 03:06:51.226381 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-24 03:06:52.365860 | debian-bookworm -> localhost | changed 2025-07-24 03:06:52.373034 | 2025-07-24 03:06:52.373149 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-24 03:06:55.374004 | debian-bookworm -> localhost | changed 2025-07-24 03:06:55.404499 | 2025-07-24 03:06:55.404684 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-24 03:06:56.084155 | debian-bookworm -> localhost | ok 2025-07-24 03:06:56.090363 | 2025-07-24 03:06:56.090463 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-24 03:06:56.121665 | debian-bookworm | ok 2025-07-24 03:06:56.155454 | debian-bookworm | included: /var/lib/zuul/builds/3b9fab20a0ad4e88b554621e3f39722f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-24 03:06:56.166123 | 2025-07-24 03:06:56.166220 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-24 03:06:57.870851 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-24 03:06:57.871104 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3b9fab20a0ad4e88b554621e3f39722f/work/3b9fab20a0ad4e88b554621e3f39722f_id_rsa 2025-07-24 03:06:57.871146 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3b9fab20a0ad4e88b554621e3f39722f/work/3b9fab20a0ad4e88b554621e3f39722f_id_rsa.pub 2025-07-24 03:06:57.871173 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-24 03:06:57.871198 | debian-bookworm -> localhost | SHA256:nIiMaZQe9POjg2Gd1xBknhI00jyW5bM2XisviX8uj4g zuul-build-sshkey 2025-07-24 03:06:57.871221 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-24 03:06:57.871263 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-24 03:06:57.871392 | debian-bookworm -> localhost | | o+++= | 2025-07-24 03:06:57.871418 | debian-bookworm -> localhost | | . +** o | 2025-07-24 03:06:57.871439 | debian-bookworm -> localhost | | +.+.* | 2025-07-24 03:06:57.871484 | debian-bookworm -> localhost | | o * * B . | 2025-07-24 03:06:57.871506 | debian-bookworm -> localhost | | B = O S | 2025-07-24 03:06:57.871532 | debian-bookworm -> localhost | | o o = + . | 2025-07-24 03:06:57.871552 | debian-bookworm -> localhost | | . o.o.. | 2025-07-24 03:06:57.871572 | debian-bookworm -> localhost | | ..o++. | 2025-07-24 03:06:57.871685 | debian-bookworm -> localhost | | E ..o*+ | 2025-07-24 03:06:57.871715 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-24 03:06:57.871804 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.851729 2025-07-24 03:06:57.883533 | 2025-07-24 03:06:57.883660 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-24 03:06:57.932530 | debian-bookworm | ok 2025-07-24 03:06:57.958955 | debian-bookworm | included: /var/lib/zuul/builds/3b9fab20a0ad4e88b554621e3f39722f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-24 03:06:57.985662 | 2025-07-24 03:06:57.985767 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-24 03:06:58.040053 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:06:58.047490 | 2025-07-24 03:06:58.047596 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-24 03:06:58.830561 | debian-bookworm | changed 2025-07-24 03:06:58.851341 | 2025-07-24 03:06:58.851451 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-24 03:06:59.134453 | debian-bookworm | ok 2025-07-24 03:06:59.143297 | 2025-07-24 03:06:59.143392 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-24 03:06:59.898474 | debian-bookworm | changed 2025-07-24 03:06:59.915053 | 2025-07-24 03:06:59.915173 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-24 03:07:00.569821 | debian-bookworm | changed 2025-07-24 03:07:00.613463 | 2025-07-24 03:07:00.613595 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-24 03:07:00.645751 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:07:00.656720 | 2025-07-24 03:07:00.656844 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-24 03:07:01.173953 | debian-bookworm -> localhost | changed 2025-07-24 03:07:01.190236 | 2025-07-24 03:07:01.190371 | TASK [add-build-sshkey : Add back temp key] 2025-07-24 03:07:01.595601 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3b9fab20a0ad4e88b554621e3f39722f/work/3b9fab20a0ad4e88b554621e3f39722f_id_rsa (zuul-build-sshkey) 2025-07-24 03:07:01.596097 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019154 2025-07-24 03:07:01.610471 | 2025-07-24 03:07:01.610649 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-24 03:07:02.071495 | debian-bookworm | ok 2025-07-24 03:07:02.081134 | 2025-07-24 03:07:02.081289 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-24 03:07:02.118289 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:07:02.144108 | 2025-07-24 03:07:02.144515 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-24 03:07:02.666137 | debian-bookworm | ok 2025-07-24 03:07:02.707037 | 2025-07-24 03:07:02.707525 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-24 03:07:02.752119 | debian-bookworm | ok 2025-07-24 03:07:02.757482 | 2025-07-24 03:07:02.757589 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-24 03:07:03.095647 | debian-bookworm -> localhost | ok 2025-07-24 03:07:03.103439 | 2025-07-24 03:07:03.103567 | TASK [validate-host : Collect information about the host] 2025-07-24 03:07:04.261488 | debian-bookworm | ok 2025-07-24 03:07:04.272323 | 2025-07-24 03:07:04.272442 | TASK [validate-host : Sanitize hostname] 2025-07-24 03:07:04.384754 | debian-bookworm | ok 2025-07-24 03:07:04.445887 | 2025-07-24 03:07:04.446045 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-24 03:07:05.332110 | debian-bookworm -> localhost | changed 2025-07-24 03:07:05.338905 | 2025-07-24 03:07:05.339032 | TASK [validate-host : Collect information about zuul worker] 2025-07-24 03:07:05.817602 | debian-bookworm | ok 2025-07-24 03:07:05.824242 | 2025-07-24 03:07:05.824399 | TASK [validate-host : Write out all zuul information for each host] 2025-07-24 03:07:06.451311 | debian-bookworm -> localhost | changed 2025-07-24 03:07:06.461471 | 2025-07-24 03:07:06.461597 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-24 03:07:06.760467 | debian-bookworm | ok 2025-07-24 03:07:06.766809 | 2025-07-24 03:07:06.766946 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-24 03:07:07.749912 | debian-bookworm | changed: 2025-07-24 03:07:07.750144 | debian-bookworm | cd+++++++++ src/ 2025-07-24 03:07:07.750379 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-24 03:07:07.750554 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-24 03:07:07.750584 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-generics/ 2025-07-24 03:07:07.750607 | debian-bookworm | =6.1 2025-07-24 03:07:30.410904 | debian-bookworm | Downloading cachetools-6.1.0-py3-none-any.whl (11 kB) 2025-07-24 03:07:30.447583 | debian-bookworm | Collecting chardet>=5.2 2025-07-24 03:07:30.454877 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-07-24 03:07:30.471754 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 13.9 MB/s eta 0:00:00 2025-07-24 03:07:30.505347 | debian-bookworm | Collecting colorama>=0.4.6 2025-07-24 03:07:30.513322 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-07-24 03:07:30.566805 | debian-bookworm | Collecting filelock>=3.18 2025-07-24 03:07:30.574195 | debian-bookworm | Downloading filelock-3.18.0-py3-none-any.whl (16 kB) 2025-07-24 03:07:30.621587 | debian-bookworm | Collecting packaging>=25 2025-07-24 03:07:30.629527 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-07-24 03:07:30.637022 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.2 MB/s eta 0:00:00 2025-07-24 03:07:30.678463 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-07-24 03:07:30.686112 | debian-bookworm | Downloading platformdirs-4.3.8-py3-none-any.whl (18 kB) 2025-07-24 03:07:30.721519 | debian-bookworm | Collecting pluggy>=1.6 2025-07-24 03:07:30.728875 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-07-24 03:07:30.762268 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-07-24 03:07:30.769795 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-07-24 03:07:30.893067 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-07-24 03:07:30.900913 | debian-bookworm | Downloading virtualenv-20.32.0-py3-none-any.whl (6.1 MB) 2025-07-24 03:07:31.163770 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.1/6.1 MB 23.5 MB/s eta 0:00:00 2025-07-24 03:07:31.271510 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-07-24 03:07:31.278715 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-07-24 03:07:31.300408 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 24.6 MB/s eta 0:00:00 2025-07-24 03:07:31.452021 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-07-24 03:07:32.240931 | debian-bookworm | Successfully installed cachetools-6.1.0 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.18.0 packaging-25.0 platformdirs-4.3.8 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.28.1 virtualenv-20.32.0 2025-07-24 03:07:32.417314 | debian-bookworm | ok: Runtime: 0:00:02.677781 2025-07-24 03:07:32.434667 | 2025-07-24 03:07:32.435018 | TASK [ensure-tox : Export installed tox_executable path] 2025-07-24 03:07:32.484350 | debian-bookworm | ok 2025-07-24 03:07:32.492224 | 2025-07-24 03:07:32.492344 | TASK [ensure-tox : Output tox version] 2025-07-24 03:07:33.036025 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-07-24 03:07:33.054310 | debian-bookworm | 4.28.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-07-24 03:07:33.571761 | debian-bookworm | ok: Runtime: 0:00:00.292379 2025-07-24 03:07:33.588521 | 2025-07-24 03:07:33.588667 | TASK [ensure-tox : Make global symlink] 2025-07-24 03:07:33.643297 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:07:33.658893 | 2025-07-24 03:07:33.659019 | PLAY RECAP 2025-07-24 03:07:33.659077 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-07-24 03:07:33.659111 | 2025-07-24 03:07:33.892495 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-07-24 03:07:33.896368 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-07-24 03:07:35.604311 | 2025-07-24 03:07:35.604505 | PLAY [all] 2025-07-24 03:07:35.658315 | 2025-07-24 03:07:35.658487 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-07-24 03:07:36.288555 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-07-24 03:07:36.722646 | debian-bookworm | ok: Runtime: 0:00:00.032541 2025-07-24 03:07:36.728929 | 2025-07-24 03:07:36.729084 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-07-24 03:07:37.258544 | debian-bookworm | ok: Runtime: 0:00:00.007561 2025-07-24 03:07:37.267178 | 2025-07-24 03:07:37.267328 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-07-24 03:07:37.583637 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-07-24 03:07:37.585260 | debian-bookworm | sudo: a password is required 2025-07-24 03:07:37.819430 | debian-bookworm | ok: Runtime: 0:00:00.017696 2025-07-24 03:07:37.830277 | 2025-07-24 03:07:37.830402 | TASK [tox : Check to see if the constraints file exists] 2025-07-24 03:07:37.890989 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:07:37.898907 | 2025-07-24 03:07:37.899063 | TASK [tox : Fail if constraints file is missing] 2025-07-24 03:07:37.990180 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:07:37.999743 | 2025-07-24 03:07:37.999925 | TASK [tox : Record file location] 2025-07-24 03:07:38.059633 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:07:38.066624 | 2025-07-24 03:07:38.066816 | TASK [tox : Install tox siblings] 2025-07-24 03:07:38.115089 | debian-bookworm | ok 2025-07-24 03:07:38.132943 | debian-bookworm | included: /var/lib/zuul/builds/3b9fab20a0ad4e88b554621e3f39722f/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-07-24 03:07:38.139197 | 2025-07-24 03:07:38.139321 | TASK [tox : Run tox without tests] 2025-07-24 03:07:38.678051 | debian-bookworm | ROOT: 137 D setup logging to DEBUG on pid 1416 [tox/report.py:222] 2025-07-24 03:07:38.748730 | debian-bookworm | check: 207 D created app data folder /home/zuul/.local/share/virtualenv [virtualenv/app_data/__init__.py:42] 2025-07-24 03:07:38.751461 | debian-bookworm | check: 210 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [virtualenv/discovery/builtin.py:78] 2025-07-24 03:07:38.752260 | debian-bookworm | check: 211 D get interpreter info via cmd: /usr/bin/python3.11 /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/discovery/py_info.py 0XmJcsNZABAEDggMGOc56SxXHz2MezmU EpeuFr7LIDKSAxxbOn4O5J1UM8qztX0i [virtualenv/discovery/cached_py_info.py:117] 2025-07-24 03:07:38.818885 | debian-bookworm | check: 278 D wrote python info of /usr/bin/python3.11 at /home/zuul/.local/share/virtualenv/py_info/2/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:152] 2025-07-24 03:07:38.819524 | debian-bookworm | check: 278 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-07-24 03:07:38.819773 | debian-bookworm | check: 279 I proposed PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:85] 2025-07-24 03:07:38.819944 | debian-bookworm | check: 279 D accepted PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:87] 2025-07-24 03:07:38.852165 | debian-bookworm | check: 311 I create virtual environment via CPython3Posix(dest=/home/zuul/src/github.com/osism/cfg-generics/.tox/check, clear=False, no_vcs_ignore=False, global=False) [virtualenv/run/session.py:52] 2025-07-24 03:07:38.852535 | debian-bookworm | check: 311 D create folder /home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin [virtualenv/util/path/_sync.py:14] 2025-07-24 03:07:38.852670 | debian-bookworm | check: 312 D create folder /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages [virtualenv/util/path/_sync.py:14] 2025-07-24 03:07:38.852879 | debian-bookworm | check: 312 D write /home/zuul/src/github.com/osism/cfg-generics/.tox/check/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-07-24 03:07:38.852939 | debian-bookworm | check: 312 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.852994 | debian-bookworm | check: 312 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.853044 | debian-bookworm | check: 312 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.853087 | debian-bookworm | check: 312 D virtualenv = 20.32.0 [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.853127 | debian-bookworm | check: 312 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.853195 | debian-bookworm | check: 312 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.853251 | debian-bookworm | check: 312 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.853319 | debian-bookworm | check: 312 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:38.853583 | debian-bookworm | check: 312 D symlink /usr/bin/python3.11 to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin/python [virtualenv/util/path/_sync.py:34] 2025-07-24 03:07:38.853894 | debian-bookworm | check: 313 D create virtualenv import hook file /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/_virtualenv.pth [virtualenv/create/via_global_ref/api.py:93] 2025-07-24 03:07:38.854098 | debian-bookworm | check: 313 D create /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/_virtualenv.py [virtualenv/create/via_global_ref/api.py:96] 2025-07-24 03:07:38.854547 | debian-bookworm | check: 313 D ============================== target debug ============================== [virtualenv/run/session.py:54] 2025-07-24 03:07:38.854767 | debian-bookworm | check: 314 D debug via /home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin/python /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/create/debug.py [virtualenv/create/creator.py:215] 2025-07-24 03:07:38.898855 | debian-bookworm | check: 313 D { 2025-07-24 03:07:38.898894 | debian-bookworm | "sys": { 2025-07-24 03:07:38.898899 | debian-bookworm | "executable": "/home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin/python", 2025-07-24 03:07:38.898904 | debian-bookworm | "_base_executable": "/usr/bin/python3.11", 2025-07-24 03:07:38.898910 | debian-bookworm | "prefix": "/home/zuul/src/github.com/osism/cfg-generics/.tox/check", 2025-07-24 03:07:38.898915 | debian-bookworm | "base_prefix": "/usr", 2025-07-24 03:07:38.898920 | debian-bookworm | "real_prefix": null, 2025-07-24 03:07:38.898924 | debian-bookworm | "exec_prefix": "/home/zuul/src/github.com/osism/cfg-generics/.tox/check", 2025-07-24 03:07:38.898931 | debian-bookworm | "base_exec_prefix": "/usr", 2025-07-24 03:07:38.898935 | debian-bookworm | "path": [ 2025-07-24 03:07:38.898940 | debian-bookworm | "/usr/lib/python311.zip", 2025-07-24 03:07:38.898944 | debian-bookworm | "/usr/lib/python3.11", 2025-07-24 03:07:38.898948 | debian-bookworm | "/usr/lib/python3.11/lib-dynload", 2025-07-24 03:07:38.898952 | debian-bookworm | "/home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages" 2025-07-24 03:07:38.898956 | debian-bookworm | ], 2025-07-24 03:07:38.898961 | debian-bookworm | "meta_path": [ 2025-07-24 03:07:38.898964 | debian-bookworm | "", 2025-07-24 03:07:38.898969 | debian-bookworm | "", 2025-07-24 03:07:38.898973 | debian-bookworm | "", 2025-07-24 03:07:38.898977 | debian-bookworm | "" 2025-07-24 03:07:38.898981 | debian-bookworm | ], 2025-07-24 03:07:38.898984 | debian-bookworm | "fs_encoding": "utf-8", 2025-07-24 03:07:38.898988 | debian-bookworm | "io_encoding": "utf-8" 2025-07-24 03:07:38.898992 | debian-bookworm | }, 2025-07-24 03:07:38.898997 | debian-bookworm | "version": "3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]", 2025-07-24 03:07:38.899001 | debian-bookworm | "makefile_filename": "/usr/lib/python3.11/config-3.11-x86_64-linux-gnu/Makefile", 2025-07-24 03:07:38.899005 | debian-bookworm | "os": "", 2025-07-24 03:07:38.899009 | debian-bookworm | "site": "", 2025-07-24 03:07:38.899013 | debian-bookworm | "datetime": "", 2025-07-24 03:07:38.899016 | debian-bookworm | "math": "", 2025-07-24 03:07:38.899020 | debian-bookworm | "json": "" 2025-07-24 03:07:38.899024 | debian-bookworm | } [virtualenv/run/session.py:55] 2025-07-24 03:07:38.899306 | debian-bookworm | check: 358 I add seed packages via FromAppData(download=False, pip=bundle, setuptools=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) [virtualenv/run/session.py:59] 2025-07-24 03:07:38.902175 | debian-bookworm | check: 361 D install pip from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/pip-25.1.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-07-24 03:07:38.903415 | debian-bookworm | check: 362 D install setuptools from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/setuptools-80.9.0-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-07-24 03:07:38.904584 | debian-bookworm | check: 363 D build install image for pip-25.1.1-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-07-24 03:07:38.905307 | debian-bookworm | check: 364 D build install image for setuptools-80.9.0-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-07-24 03:07:39.118774 | debian-bookworm | check: 577 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/_distutils_hack to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/_distutils_hack [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.120327 | debian-bookworm | check: 579 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/setuptools to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/setuptools [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.160200 | debian-bookworm | check: 619 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pip [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.293475 | debian-bookworm | check: 752 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/setuptools-80.9.0.virtualenv to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/setuptools-80.9.0.virtualenv [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.293691 | debian-bookworm | check: 753 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/distutils-precedence.pth to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/distutils-precedence.pth [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.293967 | debian-bookworm | check: 753 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/pkg_resources to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pkg_resources [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.300460 | debian-bookworm | check: 759 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/setuptools-80.9.0.dist-info to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/setuptools-80.9.0.dist-info [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.302923 | debian-bookworm | check: 762 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-07-24 03:07:39.309824 | debian-bookworm | check: 769 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip-25.1.1.dist-info to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pip-25.1.1.dist-info [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.311061 | debian-bookworm | check: 770 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip-25.1.1.virtualenv to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pip-25.1.1.virtualenv [virtualenv/util/path/_sync.py:42] 2025-07-24 03:07:39.311855 | debian-bookworm | check: 771 D generated console scripts pip pip3.11 pip-3.11 pip3 [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-07-24 03:07:39.312219 | debian-bookworm | check: 771 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python [virtualenv/run/session.py:65] 2025-07-24 03:07:39.317287 | debian-bookworm | check: 776 D write /home/zuul/src/github.com/osism/cfg-generics/.tox/check/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-07-24 03:07:39.317359 | debian-bookworm | check: 776 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.317414 | debian-bookworm | check: 776 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.317452 | debian-bookworm | check: 776 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.317506 | debian-bookworm | check: 776 D virtualenv = 20.32.0 [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.317540 | debian-bookworm | check: 776 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.317603 | debian-bookworm | check: 776 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.317646 | debian-bookworm | check: 777 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.317715 | debian-bookworm | check: 777 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-07-24 03:07:39.322336 | debian-bookworm | check: 781 W install_deps> python -I -m pip install -r requirements.txt [tox/tox_env/api.py:459] 2025-07-24 03:07:40.392228 | debian-bookworm | Collecting Jinja2==3.1.6 (from -r requirements.txt (line 1)) 2025-07-24 03:07:40.462121 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-07-24 03:07:40.546374 | debian-bookworm | Collecting PyYAML==6.0.2 (from -r requirements.txt (line 2)) 2025-07-24 03:07:40.554400 | debian-bookworm | Downloading PyYAML-6.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB) 2025-07-24 03:07:40.616558 | debian-bookworm | Collecting ansible==11.8.0 (from -r requirements.txt (line 3)) 2025-07-24 03:07:40.623980 | debian-bookworm | Downloading ansible-11.8.0-py3-none-any.whl.metadata (8.1 kB) 2025-07-24 03:07:40.658156 | debian-bookworm | Collecting packaging==25.0 (from -r requirements.txt (line 4)) 2025-07-24 03:07:40.666648 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl.metadata (3.3 kB) 2025-07-24 03:07:40.784737 | debian-bookworm | Collecting pwgen==0.8.2.post0 (from -r requirements.txt (line 5)) 2025-07-24 03:07:40.797293 | debian-bookworm | Downloading pwgen-0.8.2.post0.tar.gz (2.9 kB) 2025-07-24 03:07:40.814344 | debian-bookworm | Installing build dependencies: started 2025-07-24 03:07:41.927211 | debian-bookworm | Installing build dependencies: finished with status 'done' 2025-07-24 03:07:41.927924 | debian-bookworm | Getting requirements to build wheel: started 2025-07-24 03:07:42.366508 | debian-bookworm | Getting requirements to build wheel: finished with status 'done' 2025-07-24 03:07:42.369524 | debian-bookworm | Preparing metadata (pyproject.toml): started 2025-07-24 03:07:42.601473 | debian-bookworm | Preparing metadata (pyproject.toml): finished with status 'done' 2025-07-24 03:07:42.726373 | debian-bookworm | Collecting python-gilt==1.2.3 (from -r requirements.txt (line 6)) 2025-07-24 03:07:42.736059 | debian-bookworm | Downloading python_gilt-1.2.3-py2.py3-none-any.whl.metadata (1.4 kB) 2025-07-24 03:07:42.802341 | debian-bookworm | Collecting requests==2.32.4 (from -r requirements.txt (line 7)) 2025-07-24 03:07:42.809420 | debian-bookworm | Downloading requests-2.32.4-py3-none-any.whl.metadata (4.9 kB) 2025-07-24 03:07:42.837278 | debian-bookworm | Collecting tabulate==0.9.0 (from -r requirements.txt (line 8)) 2025-07-24 03:07:42.844338 | debian-bookworm | Downloading tabulate-0.9.0-py3-none-any.whl.metadata (34 kB) 2025-07-24 03:07:42.928939 | debian-bookworm | Collecting MarkupSafe>=2.0 (from Jinja2==3.1.6->-r requirements.txt (line 1)) 2025-07-24 03:07:42.937658 | debian-bookworm | Downloading MarkupSafe-3.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (4.0 kB) 2025-07-24 03:07:42.999804 | debian-bookworm | Collecting ansible-core~=2.18.7 (from ansible==11.8.0->-r requirements.txt (line 3)) 2025-07-24 03:07:43.007190 | debian-bookworm | Downloading ansible_core-2.18.7-py3-none-any.whl.metadata (7.7 kB) 2025-07-24 03:07:43.045362 | debian-bookworm | Collecting click (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-07-24 03:07:43.052514 | debian-bookworm | Downloading click-8.2.1-py3-none-any.whl.metadata (2.5 kB) 2025-07-24 03:07:43.083603 | debian-bookworm | Collecting colorama (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-07-24 03:07:43.090729 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-07-24 03:07:43.136349 | debian-bookworm | Collecting fasteners (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-07-24 03:07:43.144937 | debian-bookworm | Downloading fasteners-0.19-py3-none-any.whl.metadata (4.9 kB) 2025-07-24 03:07:43.179855 | debian-bookworm | Collecting sh (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-07-24 03:07:43.188763 | debian-bookworm | Downloading sh-2.2.2-py3-none-any.whl.metadata (3.7 kB) 2025-07-24 03:07:43.295888 | debian-bookworm | Collecting charset_normalizer<4,>=2 (from requests==2.32.4->-r requirements.txt (line 7)) 2025-07-24 03:07:43.303386 | debian-bookworm | Downloading charset_normalizer-3.4.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (35 kB) 2025-07-24 03:07:43.335395 | debian-bookworm | Collecting idna<4,>=2.5 (from requests==2.32.4->-r requirements.txt (line 7)) 2025-07-24 03:07:43.342668 | debian-bookworm | Downloading idna-3.10-py3-none-any.whl.metadata (10 kB) 2025-07-24 03:07:43.400429 | debian-bookworm | Collecting urllib3<3,>=1.21.1 (from requests==2.32.4->-r requirements.txt (line 7)) 2025-07-24 03:07:43.407578 | debian-bookworm | Downloading urllib3-2.5.0-py3-none-any.whl.metadata (6.5 kB) 2025-07-24 03:07:43.444942 | debian-bookworm | Collecting certifi>=2017.4.17 (from requests==2.32.4->-r requirements.txt (line 7)) 2025-07-24 03:07:43.452295 | debian-bookworm | Downloading certifi-2025.7.14-py3-none-any.whl.metadata (2.4 kB) 2025-07-24 03:07:43.684854 | debian-bookworm | Collecting cryptography (from ansible-core~=2.18.7->ansible==11.8.0->-r requirements.txt (line 3)) 2025-07-24 03:07:43.692323 | debian-bookworm | Downloading cryptography-45.0.5-cp311-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-07-24 03:07:43.734765 | debian-bookworm | Collecting resolvelib<1.1.0,>=0.5.3 (from ansible-core~=2.18.7->ansible==11.8.0->-r requirements.txt (line 3)) 2025-07-24 03:07:43.742225 | debian-bookworm | Downloading resolvelib-1.0.1-py2.py3-none-any.whl.metadata (4.0 kB) 2025-07-24 03:07:43.967659 | debian-bookworm | Collecting cffi>=1.14 (from cryptography->ansible-core~=2.18.7->ansible==11.8.0->-r requirements.txt (line 3)) 2025-07-24 03:07:43.975329 | debian-bookworm | Downloading cffi-1.17.1-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB) 2025-07-24 03:07:44.009724 | debian-bookworm | Collecting pycparser (from cffi>=1.14->cryptography->ansible-core~=2.18.7->ansible==11.8.0->-r requirements.txt (line 3)) 2025-07-24 03:07:44.017015 | debian-bookworm | Downloading pycparser-2.22-py3-none-any.whl.metadata (943 bytes) 2025-07-24 03:07:44.037394 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-07-24 03:07:44.058170 | debian-bookworm | Downloading PyYAML-6.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (762 kB) 2025-07-24 03:07:44.097048 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 763.0/763.0 kB 19.6 MB/s eta 0:00:00 2025-07-24 03:07:44.106218 | debian-bookworm | Downloading ansible-11.8.0-py3-none-any.whl (56.0 MB) 2025-07-24 03:07:46.031786 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 56.0/56.0 MB 29.1 MB/s eta 0:00:00 2025-07-24 03:07:46.040294 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-07-24 03:07:46.068488 | debian-bookworm | Downloading python_gilt-1.2.3-py2.py3-none-any.whl (22 kB) 2025-07-24 03:07:46.086290 | debian-bookworm | Downloading requests-2.32.4-py3-none-any.whl (64 kB) 2025-07-24 03:07:46.104154 | debian-bookworm | Downloading tabulate-0.9.0-py3-none-any.whl (35 kB) 2025-07-24 03:07:46.123836 | debian-bookworm | Downloading ansible_core-2.18.7-py3-none-any.whl (2.2 MB) 2025-07-24 03:07:46.212991 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.2/2.2 MB 25.3 MB/s eta 0:00:00 2025-07-24 03:07:46.222684 | debian-bookworm | Downloading charset_normalizer-3.4.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (147 kB) 2025-07-24 03:07:46.241151 | debian-bookworm | Downloading idna-3.10-py3-none-any.whl (70 kB) 2025-07-24 03:07:46.259175 | debian-bookworm | Downloading resolvelib-1.0.1-py2.py3-none-any.whl (17 kB) 2025-07-24 03:07:46.277828 | debian-bookworm | Downloading urllib3-2.5.0-py3-none-any.whl (129 kB) 2025-07-24 03:07:46.297905 | debian-bookworm | Downloading certifi-2025.7.14-py3-none-any.whl (162 kB) 2025-07-24 03:07:46.318386 | debian-bookworm | Downloading MarkupSafe-3.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (23 kB) 2025-07-24 03:07:46.335998 | debian-bookworm | Downloading click-8.2.1-py3-none-any.whl (102 kB) 2025-07-24 03:07:46.355533 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-07-24 03:07:46.374604 | debian-bookworm | Downloading cryptography-45.0.5-cp311-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-07-24 03:07:46.553271 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 25.3 MB/s eta 0:00:00 2025-07-24 03:07:46.563104 | debian-bookworm | Downloading cffi-1.17.1-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (467 kB) 2025-07-24 03:07:46.600235 | debian-bookworm | Downloading fasteners-0.19-py3-none-any.whl (18 kB) 2025-07-24 03:07:46.617353 | debian-bookworm | Downloading pycparser-2.22-py3-none-any.whl (117 kB) 2025-07-24 03:07:46.638359 | debian-bookworm | Downloading sh-2.2.2-py3-none-any.whl (38 kB) 2025-07-24 03:07:46.957619 | debian-bookworm | Building wheels for collected packages: pwgen 2025-07-24 03:07:46.958730 | debian-bookworm | Building wheel for pwgen (pyproject.toml): started 2025-07-24 03:07:47.235748 | debian-bookworm | Building wheel for pwgen (pyproject.toml): finished with status 'done' 2025-07-24 03:07:47.237418 | debian-bookworm | Created wheel for pwgen: filename=pwgen-0.8.2.post0-py3-none-any.whl size=3797 sha256=ddc9611f23b7a7a04daab7898fb73fa18f10511c74699eb6b9cc03c3fba7df6c 2025-07-24 03:07:47.238187 | debian-bookworm | Stored in directory: /home/zuul/.cache/pip/wheels/c3/81/ed/8e13725daf8a767fbde13d7583ce457f3fdf974023341fac95 2025-07-24 03:07:47.244919 | debian-bookworm | Successfully built pwgen 2025-07-24 03:07:47.483385 | debian-bookworm | Installing collected packages: resolvelib, pwgen, urllib3, tabulate, sh, PyYAML, pycparser, packaging, MarkupSafe, idna, fasteners, colorama, click, charset_normalizer, certifi, requests, python-gilt, Jinja2, cffi, cryptography, ansible-core, ansible 2025-07-24 03:08:10.617732 | debian-bookworm | 2025-07-24 03:08:10.618554 | debian-bookworm | Successfully installed Jinja2-3.1.6 MarkupSafe-3.0.2 PyYAML-6.0.2 ansible-11.8.0 ansible-core-2.18.7 certifi-2025.7.14 cffi-1.17.1 charset_normalizer-3.4.2 click-8.2.1 colorama-0.4.6 cryptography-45.0.5 fasteners-0.19 idna-3.10 packaging-25.0 pwgen-0.8.2.post0 pycparser-2.22 python-gilt-1.2.3 requests-2.32.4 resolvelib-1.0.1 sh-2.2.2 tabulate-0.9.0 urllib3-2.5.0 2025-07-24 03:08:10.740399 | debian-bookworm | check: 32199 I exit 0 (31.42 seconds) /home/zuul/src/github.com/osism/cfg-generics> python -I -m pip install -r requirements.txt pid=1429 [tox/execute/api.py:294] 2025-07-24 03:08:10.741876 | debian-bookworm | check: OK (32.00 seconds) 2025-07-24 03:08:10.741971 | debian-bookworm | congratulations :) (32.07 seconds) 2025-07-24 03:08:11.183346 | debian-bookworm | ok: Runtime: 0:00:32.271091 2025-07-24 03:08:11.189101 | 2025-07-24 03:08:11.189181 | TASK [tox : Create a tempfile to save tox showconfig] 2025-07-24 03:08:11.637843 | debian-bookworm | changed 2025-07-24 03:08:11.645665 | 2025-07-24 03:08:11.645803 | TASK [tox : Get tox envlist config] 2025-07-24 03:08:12.678509 | debian-bookworm | ok: Runtime: 0:00:00.279810 2025-07-24 03:08:12.686215 | 2025-07-24 03:08:12.686321 | TASK [tox : Install any sibling python packages] 2025-07-24 03:08:13.223041 | debian-bookworm | ok: No setup.cfg, no action needed 2025-07-24 03:08:13.228541 | 2025-07-24 03:08:13.228645 | TASK [tox : Remove tempfile] 2025-07-24 03:08:13.630856 | debian-bookworm | changed 2025-07-24 03:08:13.641052 | 2025-07-24 03:08:13.641195 | TASK [tox : Emit tox command] 2025-07-24 03:08:13.689029 | /home/zuul/.local/tox/bin/tox -echeck -vv --skip-missing-interpreters=false 2025-07-24 03:08:13.693100 | 2025-07-24 03:08:13.693179 | TASK [tox : Run tox] 2025-07-24 03:08:14.282500 | debian-bookworm | ROOT: 103 D setup logging to DEBUG on pid 1584 [tox/report.py:222] 2025-07-24 03:08:14.336417 | debian-bookworm | check: 157 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [virtualenv/discovery/builtin.py:78] 2025-07-24 03:08:14.336976 | debian-bookworm | check: 158 D got python info of /usr/bin/python3.11 from /home/zuul/.local/share/virtualenv/py_info/2/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:132] 2025-07-24 03:08:14.337620 | debian-bookworm | check: 158 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-07-24 03:08:14.337768 | debian-bookworm | check: 158 I proposed PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:85] 2025-07-24 03:08:14.337862 | debian-bookworm | check: 158 D accepted PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:87] 2025-07-24 03:08:14.361929 | debian-bookworm | check: 182 W commands[0]> python3 src/check-inventory-sorting.py [tox/tox_env/api.py:459] 2025-07-24 03:08:14.405880 | debian-bookworm | check: 226 I exit 0 (0.04 seconds) /home/zuul/src/github.com/osism/cfg-generics> python3 src/check-inventory-sorting.py pid=1591 [tox/execute/api.py:294] 2025-07-24 03:08:14.407176 | debian-bookworm | check: OK (0.07=setup[0.03]+cmd[0.04] seconds) 2025-07-24 03:08:14.407233 | debian-bookworm | congratulations :) (0.13 seconds) 2025-07-24 03:08:14.842376 | debian-bookworm | ok: Runtime: 0:00:00.291218 2025-07-24 03:08:14.847030 | 2025-07-24 03:08:14.847104 | TASK [tox : Look for output] 2025-07-24 03:08:15.483251 | debian-bookworm | ok 2025-07-24 03:08:15.498179 | 2025-07-24 03:08:15.498286 | TASK [tox : Return file comments to Zuul] 2025-07-24 03:08:15.565921 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:15.571428 | 2025-07-24 03:08:15.571498 | PLAY RECAP 2025-07-24 03:08:15.571543 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-07-24 03:08:15.571565 | 2025-07-24 03:08:15.734176 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-07-24 03:08:15.735184 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-07-24 03:08:17.153793 | 2025-07-24 03:08:17.153908 | PLAY [all] 2025-07-24 03:08:17.190095 | 2025-07-24 03:08:17.190213 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-07-24 03:08:17.294773 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:17.299863 | 2025-07-24 03:08:17.299974 | TASK [fetch-tox-output : Set tox log path for single node] 2025-07-24 03:08:17.357392 | debian-bookworm | ok 2025-07-24 03:08:17.379339 | 2025-07-24 03:08:17.379515 | TASK [fetch-tox-output : Ensure local tox dir] 2025-07-24 03:08:18.551199 | debian-bookworm -> localhost | changed 2025-07-24 03:08:18.557784 | 2025-07-24 03:08:18.557872 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-07-24 03:08:18.641663 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:18.646579 | 2025-07-24 03:08:18.646675 | TASK [fetch-tox-output : Set envlist fact] 2025-07-24 03:08:18.728382 | debian-bookworm | ok 2025-07-24 03:08:18.751756 | 2025-07-24 03:08:18.751910 | TASK [fetch-tox-output : Get tox version] 2025-07-24 03:08:19.838684 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-07-24 03:08:19.855645 | debian-bookworm | 4.28.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-07-24 03:08:20.315389 | debian-bookworm | ok: Runtime: 0:00:00.256308 2025-07-24 03:08:20.321799 | 2025-07-24 03:08:20.321905 | TASK [fetch-tox-output : Find all default environments] 2025-07-24 03:08:20.885291 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:20.890159 | 2025-07-24 03:08:20.890461 | TASK [fetch-tox-output : Set envlist fact] 2025-07-24 03:08:20.933859 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:20.939185 | 2025-07-24 03:08:20.939282 | TASK [fetch-tox-output : Find all default environments] 2025-07-24 03:08:21.496208 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:21.501119 | 2025-07-24 03:08:21.501211 | TASK [fetch-tox-output : Set envlist fact] 2025-07-24 03:08:21.555017 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:21.561748 | 2025-07-24 03:08:21.561858 | LOOP [fetch-tox-output : Copy tox logs] 2025-07-24 03:08:21.651675 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:21.660027 | 2025-07-24 03:08:21.660138 | LOOP [fetch-tox-output : Collect tox logs] 2025-07-24 03:08:23.783861 | debian-bookworm | changed: 2025-07-24 03:08:23.784092 | debian-bookworm | created directory /var/lib/zuul/builds/3b9fab20a0ad4e88b554621e3f39722f/work/logs/tox/check 2025-07-24 03:08:23.784173 | debian-bookworm | cd+++++++++ ./ 2025-07-24 03:08:23.784205 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-07-24 03:08:23.784237 | 2025-07-24 03:08:23.808885 | 2025-07-24 03:08:23.808977 | PLAY RECAP 2025-07-24 03:08:23.809023 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-07-24 03:08:23.809044 | 2025-07-24 03:08:24.323886 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-07-24 03:08:24.324838 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-07-24 03:08:26.973920 | 2025-07-24 03:08:26.974067 | PLAY [all] 2025-07-24 03:08:27.017789 | 2025-07-24 03:08:27.017933 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-07-24 03:08:27.331416 | debian-bookworm | changed: non-zero return code 2025-07-24 03:08:27.338103 | 2025-07-24 03:08:27.339566 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-07-24 03:08:27.377264 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:27.384289 | 2025-07-24 03:08:27.384393 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-07-24 03:08:27.451385 | 2025-07-24 03:08:27.451595 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-07-24 03:08:27.536815 | 2025-07-24 03:08:27.537060 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-07-24 03:08:27.613553 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:27.620449 | 2025-07-24 03:08:27.620575 | LOOP [fetch-subunit-output : Generate subunit file] 2025-07-24 03:08:27.699945 | 2025-07-24 03:08:27.700127 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-07-24 03:08:27.753654 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:27.758725 | 2025-07-24 03:08:27.766880 | TASK [fetch-subunit-output : Remove the temporary file] 2025-07-24 03:08:27.790133 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:27.795421 | 2025-07-24 03:08:27.795523 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-07-24 03:08:27.834704 | debian-bookworm | skipping: Conditional result was False 2025-07-24 03:08:27.851917 | 2025-07-24 03:08:27.852032 | PLAY RECAP 2025-07-24 03:08:27.852115 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-07-24 03:08:27.852157 | 2025-07-24 03:08:28.294048 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-07-24 03:08:28.294933 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-24 03:08:29.924294 | 2025-07-24 03:08:29.924422 | PLAY [Base post] 2025-07-24 03:08:29.956753 | 2025-07-24 03:08:29.956881 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-24 03:08:30.585257 | debian-bookworm | changed 2025-07-24 03:08:30.590949 | 2025-07-24 03:08:30.591040 | PLAY RECAP 2025-07-24 03:08:30.591090 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-24 03:08:30.591141 | 2025-07-24 03:08:30.671640 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-24 03:08:30.672451 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-24 03:08:31.542660 | 2025-07-24 03:08:31.542807 | PLAY [Base post-logs] 2025-07-24 03:08:31.557138 | 2025-07-24 03:08:31.557285 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-24 03:08:32.051053 | localhost | changed 2025-07-24 03:08:32.062524 | 2025-07-24 03:08:32.062927 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-24 03:08:32.148319 | localhost | ok 2025-07-24 03:08:32.150948 | 2025-07-24 03:08:32.151021 | TASK [Set zuul-log-path fact] 2025-07-24 03:08:32.176018 | localhost | ok 2025-07-24 03:08:32.183113 | 2025-07-24 03:08:32.183197 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-24 03:08:32.227141 | localhost | ok 2025-07-24 03:08:32.229508 | 2025-07-24 03:08:32.229599 | TASK [upload-logs : Create log directories] 2025-07-24 03:08:33.293732 | localhost | changed 2025-07-24 03:08:33.296817 | 2025-07-24 03:08:33.296893 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-24 03:08:34.447564 | localhost -> localhost | ok: Runtime: 0:00:00.004478 2025-07-24 03:08:34.450711 | 2025-07-24 03:08:34.450794 | TASK [upload-logs : Upload logs to log server] 2025-07-24 03:08:35.536546 | localhost | Output suppressed because no_log was given 2025-07-24 03:08:35.537937 | 2025-07-24 03:08:35.538013 | LOOP [upload-logs : Compress console log and json output] 2025-07-24 03:08:35.617941 | localhost | skipping: Conditional result was False 2025-07-24 03:08:35.623506 | localhost | skipping: Conditional result was False 2025-07-24 03:08:35.633657 | 2025-07-24 03:08:35.633760 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-24 03:08:35.688300 | localhost | skipping: Conditional result was False 2025-07-24 03:08:35.688547 | 2025-07-24 03:08:35.692975 | localhost | skipping: Conditional result was False 2025-07-24 03:08:35.698951 | 2025-07-24 03:08:35.699048 | LOOP [upload-logs : Upload console log and json output]