2025-09-17 12:41:27.427667 | Job console starting 2025-09-17 12:41:27.438884 | Updating git repos 2025-09-17 12:41:27.487300 | Cloning repos into workspace 2025-09-17 12:41:27.534084 | Restoring repo states 2025-09-17 12:41:27.542240 | Merging changes 2025-09-17 12:41:27.542257 | Checking out repos 2025-09-17 12:41:27.570402 | Preparing playbooks 2025-09-17 12:41:28.192514 | Running Ansible setup 2025-09-17 12:41:32.203805 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-17 12:41:32.949647 | 2025-09-17 12:41:32.949818 | PLAY [Base pre] 2025-09-17 12:41:32.966653 | 2025-09-17 12:41:32.966807 | TASK [Setup log path fact] 2025-09-17 12:41:32.996110 | debian-bookworm | ok 2025-09-17 12:41:33.011315 | 2025-09-17 12:41:33.011469 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-17 12:41:33.053676 | debian-bookworm | ok 2025-09-17 12:41:33.065074 | 2025-09-17 12:41:33.065205 | TASK [emit-job-header : Print job information] 2025-09-17 12:41:33.110020 | # Job Information 2025-09-17 12:41:33.110291 | Ansible Version: 2.16.14 2025-09-17 12:41:33.110352 | Job: cfg-generics-container-image-inventory-reconciler-push 2025-09-17 12:41:33.110409 | Pipeline: post 2025-09-17 12:41:33.110450 | Executor: 521e9411259a 2025-09-17 12:41:33.110486 | Triggered by: https://github.com/osism/cfg-generics/commit/fb236483c763aa17d20956d691e1d323b95677cd 2025-09-17 12:41:33.110524 | Event ID: 9da0f85c-93c3-11f0-9767-6d1614b8241d 2025-09-17 12:41:33.117700 | 2025-09-17 12:41:33.117843 | LOOP [emit-job-header : Print node information] 2025-09-17 12:41:33.229220 | debian-bookworm | ok: 2025-09-17 12:41:33.229428 | debian-bookworm | # Node Information 2025-09-17 12:41:33.229464 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-17 12:41:33.229489 | debian-bookworm | Hostname: debian 2025-09-17 12:41:33.229512 | debian-bookworm | Username: zuul 2025-09-17 12:41:33.229533 | debian-bookworm | Distro: Debian 12.12 2025-09-17 12:41:33.229558 | debian-bookworm | Provider: regiocloud-a 2025-09-17 12:41:33.229579 | debian-bookworm | Region: 2025-09-17 12:41:33.229601 | debian-bookworm | Label: debian-bookworm 2025-09-17 12:41:33.229621 | debian-bookworm | Product Name: OpenStack Nova 2025-09-17 12:41:33.229640 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefb:f092 2025-09-17 12:41:33.246679 | 2025-09-17 12:41:33.246831 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-17 12:41:33.757089 | debian-bookworm -> localhost | changed 2025-09-17 12:41:33.764723 | 2025-09-17 12:41:33.764852 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-17 12:41:34.801620 | debian-bookworm -> localhost | changed 2025-09-17 12:41:34.825886 | 2025-09-17 12:41:34.826095 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-17 12:41:35.104271 | debian-bookworm -> localhost | ok 2025-09-17 12:41:35.110750 | 2025-09-17 12:41:35.110918 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-17 12:41:35.141584 | debian-bookworm | ok 2025-09-17 12:41:35.159328 | debian-bookworm | included: /var/lib/zuul/builds/4b65ae2296d44a95b6b0e600159fc4ad/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-17 12:41:35.165516 | 2025-09-17 12:41:35.165631 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-17 12:41:36.621529 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-17 12:41:36.621730 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4b65ae2296d44a95b6b0e600159fc4ad/work/4b65ae2296d44a95b6b0e600159fc4ad_id_rsa 2025-09-17 12:41:36.621767 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4b65ae2296d44a95b6b0e600159fc4ad/work/4b65ae2296d44a95b6b0e600159fc4ad_id_rsa.pub 2025-09-17 12:41:36.621793 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-17 12:41:36.621817 | debian-bookworm -> localhost | SHA256:kpnB8O2wdFO4WO+A5Ezq01yLU+K3fj0FUPGTZybSiqo zuul-build-sshkey 2025-09-17 12:41:36.621849 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-17 12:41:36.621872 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-17 12:41:36.621892 | debian-bookworm -> localhost | | . ...o. | 2025-09-17 12:41:36.621913 | debian-bookworm -> localhost | | +o.o.. o . | 2025-09-17 12:41:36.621934 | debian-bookworm -> localhost | | **++o .. * +| 2025-09-17 12:41:36.621966 | debian-bookworm -> localhost | | ..*X=....o * | 2025-09-17 12:41:36.621987 | debian-bookworm -> localhost | | . +*=S+. .. | 2025-09-17 12:41:36.622017 | debian-bookworm -> localhost | | o *.o.. . | 2025-09-17 12:41:36.622040 | debian-bookworm -> localhost | | . o.. . . | 2025-09-17 12:41:36.622060 | debian-bookworm -> localhost | | .. . o | 2025-09-17 12:41:36.622081 | debian-bookworm -> localhost | | E... . | 2025-09-17 12:41:36.622101 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-17 12:41:36.622149 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.024031 2025-09-17 12:41:36.628345 | 2025-09-17 12:41:36.628432 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-17 12:41:36.656169 | debian-bookworm | ok 2025-09-17 12:41:36.667771 | debian-bookworm | included: /var/lib/zuul/builds/4b65ae2296d44a95b6b0e600159fc4ad/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-17 12:41:36.676377 | 2025-09-17 12:41:36.676472 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-17 12:41:36.699503 | debian-bookworm | skipping: Conditional result was False 2025-09-17 12:41:36.704962 | 2025-09-17 12:41:36.705047 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-17 12:41:37.297015 | debian-bookworm | changed 2025-09-17 12:41:37.305381 | 2025-09-17 12:41:37.305515 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-17 12:41:37.573920 | debian-bookworm | ok 2025-09-17 12:41:37.581864 | 2025-09-17 12:41:37.582009 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-17 12:41:38.234234 | debian-bookworm | changed 2025-09-17 12:41:38.243199 | 2025-09-17 12:41:38.243334 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-17 12:41:38.892784 | debian-bookworm | changed 2025-09-17 12:41:38.899004 | 2025-09-17 12:41:38.899119 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-17 12:41:38.932960 | debian-bookworm | skipping: Conditional result was False 2025-09-17 12:41:38.940214 | 2025-09-17 12:41:38.940343 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-17 12:41:39.360581 | debian-bookworm -> localhost | changed 2025-09-17 12:41:39.372509 | 2025-09-17 12:41:39.372621 | TASK [add-build-sshkey : Add back temp key] 2025-09-17 12:41:39.710157 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4b65ae2296d44a95b6b0e600159fc4ad/work/4b65ae2296d44a95b6b0e600159fc4ad_id_rsa (zuul-build-sshkey) 2025-09-17 12:41:39.710407 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018405 2025-09-17 12:41:39.722510 | 2025-09-17 12:41:39.722674 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-17 12:41:40.122345 | debian-bookworm | ok 2025-09-17 12:41:40.129175 | 2025-09-17 12:41:40.129298 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-17 12:41:40.153227 | debian-bookworm | skipping: Conditional result was False 2025-09-17 12:41:40.163760 | 2025-09-17 12:41:40.163888 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-17 12:41:40.603284 | debian-bookworm | ok 2025-09-17 12:41:40.617476 | 2025-09-17 12:41:40.617607 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-17 12:41:40.662683 | debian-bookworm | ok 2025-09-17 12:41:40.671508 | 2025-09-17 12:41:40.671657 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-17 12:41:40.959030 | debian-bookworm -> localhost | ok 2025-09-17 12:41:40.965930 | 2025-09-17 12:41:40.966061 | TASK [validate-host : Collect information about the host] 2025-09-17 12:41:42.094298 | debian-bookworm | ok 2025-09-17 12:41:42.106224 | 2025-09-17 12:41:42.106355 | TASK [validate-host : Sanitize hostname] 2025-09-17 12:41:42.224905 | debian-bookworm | ok 2025-09-17 12:41:42.266708 | 2025-09-17 12:41:42.266855 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-17 12:41:42.818262 | debian-bookworm -> localhost | changed 2025-09-17 12:41:42.824392 | 2025-09-17 12:41:42.824506 | TASK [validate-host : Collect information about zuul worker] 2025-09-17 12:41:43.243679 | debian-bookworm | ok 2025-09-17 12:41:43.251755 | 2025-09-17 12:41:43.251909 | TASK [validate-host : Write out all zuul information for each host] 2025-09-17 12:41:43.801635 | debian-bookworm -> localhost | changed 2025-09-17 12:41:43.811570 | 2025-09-17 12:41:43.811686 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-17 12:41:44.082694 | debian-bookworm | ok 2025-09-17 12:41:44.088386 | 2025-09-17 12:41:44.088489 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-17 12:41:44.928507 | debian-bookworm | changed: 2025-09-17 12:41:44.928735 | debian-bookworm | cd+++++++++ src/ 2025-09-17 12:41:44.928777 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-17 12:41:44.928806 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-17 12:41:44.928832 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-generics/ 2025-09-17 12:41:44.928857 | debian-bookworm | 3.5.2-r0) 2025-09-17 12:42:47.883535 | debian-bookworm | #9 6.794 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-17 12:42:47.987412 | debian-bookworm | #9 6.826 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-17 12:42:47.987537 | debian-bookworm | #9 6.875 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-17 12:42:47.987585 | debian-bookworm | #9 6.898 (28/34) Installing python3 (3.12.11-r0) 2025-09-17 12:42:48.327708 | debian-bookworm | #9 7.238 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-17 12:42:48.516754 | debian-bookworm | #9 7.427 (30/34) Installing pyc (3.12.11-r0) 2025-09-17 12:42:48.516842 | debian-bookworm | #9 7.427 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-17 12:42:48.516862 | debian-bookworm | #9 7.427 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-17 12:42:49.096397 | debian-bookworm | #9 8.007 (33/34) Installing yq-go (4.46.1-r2) 2025-09-17 12:42:49.273272 | debian-bookworm | #9 8.183 (34/34) Installing .build-deps (20250917.124243) 2025-09-17 12:42:49.485209 | debian-bookworm | #9 8.185 Executing busybox-1.37.0-r18.trigger 2025-09-17 12:42:49.485283 | debian-bookworm | #9 8.193 Executing ca-certificates-20250619-r0.trigger 2025-09-17 12:42:49.485302 | debian-bookworm | #9 8.245 OK: 359 MiB in 82 packages 2025-09-17 12:42:49.864699 | debian-bookworm | #9 DONE 8.8s 2025-09-17 12:42:49.972370 | debian-bookworm | 2025-09-17 12:42:49.972580 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-17 12:42:49.972648 | debian-bookworm | #10 DONE 0.1s 2025-09-17 12:42:50.141229 | debian-bookworm | 2025-09-17 12:42:50.141352 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-17 12:42:50.141371 | debian-bookworm | #11 DONE 0.1s 2025-09-17 12:42:50.141384 | debian-bookworm | 2025-09-17 12:42:50.141396 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-17 12:42:50.141408 | debian-bookworm | #12 DONE 0.1s 2025-09-17 12:42:50.296339 | debian-bookworm | 2025-09-17 12:42:50.296474 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-09-17 12:42:51.037282 | debian-bookworm | #13 0.741 Using Python 3.13.7 environment at: /usr/local 2025-09-17 12:42:51.167515 | debian-bookworm | #13 1.022 Resolved 17 packages in 278ms 2025-09-17 12:42:51.381055 | debian-bookworm | #13 1.075 Downloading ansible-core (2.3MiB) 2025-09-17 12:42:51.381233 | debian-bookworm | #13 1.084 Downloading netaddr (2.2MiB) 2025-09-17 12:42:51.381285 | debian-bookworm | #13 1.085 Downloading cryptography (4.5MiB) 2025-09-17 12:42:51.550291 | debian-bookworm | #13 1.404 Downloading netaddr 2025-09-17 12:42:51.739290 | debian-bookworm | #13 1.490 Downloading cryptography 2025-09-17 12:42:51.923512 | debian-bookworm | #13 1.594 Downloading ansible-core 2025-09-17 12:42:51.923579 | debian-bookworm | #13 1.595 Prepared 17 packages in 571ms 2025-09-17 12:42:51.923586 | debian-bookworm | #13 1.643 Installed 17 packages in 47ms 2025-09-17 12:42:51.923592 | debian-bookworm | #13 1.644 + ansible-core==2.19.2 2025-09-17 12:42:51.923598 | debian-bookworm | #13 1.645 + certifi==2025.8.3 2025-09-17 12:42:51.923604 | debian-bookworm | #13 1.646 + cffi==2.0.0 2025-09-17 12:42:51.923609 | debian-bookworm | #13 1.647 + charset-normalizer==3.4.3 2025-09-17 12:42:51.923614 | debian-bookworm | #13 1.648 + cryptography==46.0.1 2025-09-17 12:42:51.923619 | debian-bookworm | #13 1.649 + dynaconf==3.2.11 2025-09-17 12:42:51.923624 | debian-bookworm | #13 1.649 + idna==3.10 2025-09-17 12:42:51.923629 | debian-bookworm | #13 1.649 + jinja2==3.1.6 2025-09-17 12:42:51.923633 | debian-bookworm | #13 1.649 + markupsafe==3.0.2 2025-09-17 12:42:51.923638 | debian-bookworm | #13 1.649 + netaddr==1.3.0 2025-09-17 12:42:51.923642 | debian-bookworm | #13 1.649 + packaging==25.0 2025-09-17 12:42:51.923647 | debian-bookworm | #13 1.649 + pycparser==2.23 2025-09-17 12:42:51.923652 | debian-bookworm | #13 1.649 + pynetbox==7.5.0 2025-09-17 12:42:51.923656 | debian-bookworm | #13 1.649 + pyyaml==6.0.2 2025-09-17 12:42:51.923662 | debian-bookworm | #13 1.649 + requests==2.32.5 2025-09-17 12:42:51.923667 | debian-bookworm | #13 1.649 + resolvelib==1.2.0 2025-09-17 12:42:51.923671 | debian-bookworm | #13 1.649 + urllib3==2.5.0 2025-09-17 12:42:51.923676 | debian-bookworm | #13 DONE 1.8s 2025-09-17 12:42:52.093751 | debian-bookworm | 2025-09-17 12:42:52.093829 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.005985 2025-09-17 12:44:20.575395 | 2025-09-17 12:44:20.575516 | TASK [upload-logs : Upload logs to log server] 2025-09-17 12:44:21.131618 | localhost | Output suppressed because no_log was given 2025-09-17 12:44:21.135187 | 2025-09-17 12:44:21.135361 | LOOP [upload-logs : Compress console log and json output] 2025-09-17 12:44:21.191689 | localhost | skipping: Conditional result was False 2025-09-17 12:44:21.196584 | localhost | skipping: Conditional result was False 2025-09-17 12:44:21.209902 | 2025-09-17 12:44:21.210219 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-17 12:44:21.256203 | localhost | skipping: Conditional result was False 2025-09-17 12:44:21.256782 | 2025-09-17 12:44:21.260074 | localhost | skipping: Conditional result was False 2025-09-17 12:44:21.273705 | 2025-09-17 12:44:21.273925 | LOOP [upload-logs : Upload console log and json output]