2025-09-23 22:42:26.395747 | Job console starting 2025-09-23 22:42:26.435733 | Updating git repos 2025-09-23 22:42:26.482677 | Cloning repos into workspace 2025-09-23 22:42:26.579909 | Restoring repo states 2025-09-23 22:42:26.587722 | Merging changes 2025-09-23 22:42:27.166716 | Checking out repos 2025-09-23 22:42:27.221724 | Preparing playbooks 2025-09-23 22:42:27.830493 | Running Ansible setup 2025-09-23 22:42:32.201720 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 22:42:33.175684 | 2025-09-23 22:42:33.175893 | PLAY [Base pre] 2025-09-23 22:42:33.202260 | 2025-09-23 22:42:33.202568 | TASK [Setup log path fact] 2025-09-23 22:42:33.233302 | debian-bookworm | ok 2025-09-23 22:42:33.252393 | 2025-09-23 22:42:33.252609 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 22:42:33.283846 | debian-bookworm | ok 2025-09-23 22:42:33.293677 | 2025-09-23 22:42:33.293887 | TASK [emit-job-header : Print job information] 2025-09-23 22:42:33.337737 | # Job Information 2025-09-23 22:42:33.337934 | Ansible Version: 2.16.14 2025-09-23 22:42:33.337969 | Job: container-image-inventory-reconciler-build 2025-09-23 22:42:33.338001 | Pipeline: check 2025-09-23 22:42:33.338025 | Executor: 521e9411259a 2025-09-23 22:42:33.338045 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/431 2025-09-23 22:42:33.338068 | Event ID: 8df935d0-98ce-11f0-8798-3775cf03daa8 2025-09-23 22:42:33.344208 | 2025-09-23 22:42:33.344340 | LOOP [emit-job-header : Print node information] 2025-09-23 22:42:33.465573 | debian-bookworm | ok: 2025-09-23 22:42:33.465796 | debian-bookworm | # Node Information 2025-09-23 22:42:33.465831 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 22:42:33.465858 | debian-bookworm | Hostname: debian 2025-09-23 22:42:33.465881 | debian-bookworm | Username: zuul 2025-09-23 22:42:33.465904 | debian-bookworm | Distro: Debian 12.12 2025-09-23 22:42:33.465929 | debian-bookworm | Provider: regiocloud-a 2025-09-23 22:42:33.465951 | debian-bookworm | Region: 2025-09-23 22:42:33.465972 | debian-bookworm | Label: debian-bookworm 2025-09-23 22:42:33.465992 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 22:42:33.466012 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feed:9796 2025-09-23 22:42:33.492922 | 2025-09-23 22:42:33.493059 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 22:42:34.213965 | debian-bookworm -> localhost | changed 2025-09-23 22:42:34.220805 | 2025-09-23 22:42:34.220905 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 22:42:35.434197 | debian-bookworm -> localhost | changed 2025-09-23 22:42:35.444470 | 2025-09-23 22:42:35.444563 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 22:42:35.655358 | debian-bookworm -> localhost | ok 2025-09-23 22:42:35.661142 | 2025-09-23 22:42:35.661237 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 22:42:35.678249 | debian-bookworm | ok 2025-09-23 22:42:35.708995 | debian-bookworm | included: /var/lib/zuul/builds/cb57ebf31a044272b129ad365889cdbd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 22:42:35.714584 | 2025-09-23 22:42:35.714661 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 22:42:36.787764 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 22:42:36.787981 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/cb57ebf31a044272b129ad365889cdbd/work/cb57ebf31a044272b129ad365889cdbd_id_rsa 2025-09-23 22:42:36.788022 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/cb57ebf31a044272b129ad365889cdbd/work/cb57ebf31a044272b129ad365889cdbd_id_rsa.pub 2025-09-23 22:42:36.788049 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 22:42:36.788074 | debian-bookworm -> localhost | SHA256:AbzNDADnfu+289Z5ftKNqBYOSf8Q4Y6SbWJmGqVA7ME zuul-build-sshkey 2025-09-23 22:42:36.788104 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 22:42:36.788126 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 22:42:36.788147 | debian-bookworm -> localhost | | o..oo. | 2025-09-23 22:42:36.788168 | debian-bookworm -> localhost | | Eo o. . | 2025-09-23 22:42:36.788189 | debian-bookworm -> localhost | | o .. *.. . | 2025-09-23 22:42:36.788209 | debian-bookworm -> localhost | | o. o =.o | 2025-09-23 22:42:36.788230 | debian-bookworm -> localhost | | ..o.+S= . | 2025-09-23 22:42:36.788254 | debian-bookworm -> localhost | | o.B.* = | 2025-09-23 22:42:36.788275 | debian-bookworm -> localhost | | * +.o = o o.| 2025-09-23 22:42:36.788295 | debian-bookworm -> localhost | | . .o + = + +| 2025-09-23 22:42:36.788316 | debian-bookworm -> localhost | | .o*o. o.o | 2025-09-23 22:42:36.788336 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 22:42:36.788395 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.592955 2025-09-23 22:42:36.794639 | 2025-09-23 22:42:36.794769 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 22:42:36.812276 | debian-bookworm | ok 2025-09-23 22:42:36.821939 | debian-bookworm | included: /var/lib/zuul/builds/cb57ebf31a044272b129ad365889cdbd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 22:42:36.830873 | 2025-09-23 22:42:36.830951 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 22:42:36.854089 | debian-bookworm | skipping: Conditional result was False 2025-09-23 22:42:36.859768 | 2025-09-23 22:42:36.859849 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 22:42:37.494657 | debian-bookworm | changed 2025-09-23 22:42:37.500079 | 2025-09-23 22:42:37.500220 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 22:42:37.785727 | debian-bookworm | ok 2025-09-23 22:42:37.794259 | 2025-09-23 22:42:37.794364 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 22:42:38.464508 | debian-bookworm | changed 2025-09-23 22:42:38.478174 | 2025-09-23 22:42:38.478314 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 22:42:39.145298 | debian-bookworm | changed 2025-09-23 22:42:39.150736 | 2025-09-23 22:42:39.150823 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 22:42:39.174134 | debian-bookworm | skipping: Conditional result was False 2025-09-23 22:42:39.180724 | 2025-09-23 22:42:39.180810 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 22:42:39.594665 | debian-bookworm -> localhost | changed 2025-09-23 22:42:39.605243 | 2025-09-23 22:42:39.605341 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 22:42:39.884269 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/cb57ebf31a044272b129ad365889cdbd/work/cb57ebf31a044272b129ad365889cdbd_id_rsa (zuul-build-sshkey) 2025-09-23 22:42:39.884498 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014770 2025-09-23 22:42:39.892188 | 2025-09-23 22:42:39.892271 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 22:42:40.280459 | debian-bookworm | ok 2025-09-23 22:42:40.285190 | 2025-09-23 22:42:40.285265 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 22:42:40.324552 | debian-bookworm | skipping: Conditional result was False 2025-09-23 22:42:40.332722 | 2025-09-23 22:42:40.332799 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 22:42:40.757000 | debian-bookworm | ok 2025-09-23 22:42:40.777211 | 2025-09-23 22:42:40.777360 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 22:42:40.824672 | debian-bookworm | ok 2025-09-23 22:42:40.833106 | 2025-09-23 22:42:40.833237 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 22:42:41.120791 | debian-bookworm -> localhost | ok 2025-09-23 22:42:41.131733 | 2025-09-23 22:42:41.131886 | TASK [validate-host : Collect information about the host] 2025-09-23 22:42:42.273297 | debian-bookworm | ok 2025-09-23 22:42:42.287316 | 2025-09-23 22:42:42.287418 | TASK [validate-host : Sanitize hostname] 2025-09-23 22:42:42.404867 | debian-bookworm | ok 2025-09-23 22:42:42.441633 | 2025-09-23 22:42:42.441741 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 22:42:43.200918 | debian-bookworm -> localhost | changed 2025-09-23 22:42:43.207474 | 2025-09-23 22:42:43.207586 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 22:42:43.651686 | debian-bookworm | ok 2025-09-23 22:42:43.667587 | 2025-09-23 22:42:43.667717 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 22:42:44.252080 | debian-bookworm -> localhost | changed 2025-09-23 22:42:44.274998 | 2025-09-23 22:42:44.275139 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 22:42:44.569894 | debian-bookworm | ok 2025-09-23 22:42:44.576383 | 2025-09-23 22:42:44.576508 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 22:42:45.458315 | debian-bookworm | changed: 2025-09-23 22:42:45.458538 | debian-bookworm | cd+++++++++ src/ 2025-09-23 22:42:45.458695 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 22:42:45.458727 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 22:42:45.458750 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-23 22:42:45.458771 | debian-bookworm | Containerfile 2025-09-23 22:42:45.458947 | debian-bookworm | 3.5.2-r0) 2025-09-23 22:43:47.057388 | debian-bookworm | #9 5.555 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-23 22:43:47.057393 | debian-bookworm | #9 5.576 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-23 22:43:47.255406 | debian-bookworm | #9 5.611 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-23 22:43:47.255452 | debian-bookworm | #9 5.623 (28/34) Installing python3 (3.12.11-r0) 2025-09-23 22:43:47.373498 | debian-bookworm | #9 5.892 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-23 22:43:47.542608 | debian-bookworm | #9 6.061 (30/34) Installing pyc (3.12.11-r0) 2025-09-23 22:43:47.542665 | debian-bookworm | #9 6.061 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-23 22:43:47.542686 | debian-bookworm | #9 6.061 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-23 22:43:48.236846 | debian-bookworm | #9 6.755 (33/34) Installing yq-go (4.47.2-r0) 2025-09-23 22:43:48.468822 | debian-bookworm | #9 6.987 (34/34) Installing .build-deps (20250923.224343) 2025-09-23 22:43:48.689374 | debian-bookworm | #9 6.989 Executing busybox-1.37.0-r18.trigger 2025-09-23 22:43:48.689436 | debian-bookworm | #9 6.996 Executing ca-certificates-20250619-r0.trigger 2025-09-23 22:43:48.689443 | debian-bookworm | #9 7.055 OK: 359 MiB in 82 packages 2025-09-23 22:43:49.026882 | debian-bookworm | #9 DONE 7.5s 2025-09-23 22:43:49.181644 | debian-bookworm | 2025-09-23 22:43:49.181770 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-23 22:43:49.181841 | debian-bookworm | #10 DONE 0.1s 2025-09-23 22:43:49.181898 | debian-bookworm | 2025-09-23 22:43:49.181943 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-23 22:43:49.181980 | debian-bookworm | #11 DONE 0.1s 2025-09-23 22:43:49.401688 | debian-bookworm | 2025-09-23 22:43:49.401864 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-23 22:43:49.401927 | debian-bookworm | #12 DONE 0.1s 2025-09-23 22:43:49.401953 | debian-bookworm | 2025-09-23 22:43:49.401974 | 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-23 22:43:50.128815 | debian-bookworm | #13 0.726 Using Python 3.13.7 environment at: /usr/local 2025-09-23 22:43:50.345156 | debian-bookworm | #13 1.094 Resolved 17 packages in 365ms 2025-09-23 22:43:50.527908 | debian-bookworm | #13 1.122 Downloading cryptography (4.5MiB) 2025-09-23 22:43:50.527972 | debian-bookworm | #13 1.125 Downloading netaddr (2.2MiB) 2025-09-23 22:43:50.527990 | debian-bookworm | #13 1.125 Downloading ansible-core (2.3MiB) 2025-09-23 22:43:50.908332 | debian-bookworm | #13 1.419 Downloading netaddr 2025-09-23 22:43:50.908516 | debian-bookworm | #13 1.506 Downloading cryptography 2025-09-23 22:43:51.118505 | debian-bookworm | #13 1.658 Downloading ansible-core 2025-09-23 22:43:51.118554 | debian-bookworm | #13 1.659 Prepared 17 packages in 563ms 2025-09-23 22:43:51.118559 | debian-bookworm | #13 1.709 Installed 17 packages in 49ms 2025-09-23 22:43:51.118576 | debian-bookworm | #13 1.710 + ansible-core==2.19.2 2025-09-23 22:43:51.118588 | debian-bookworm | #13 1.712 + certifi==2025.8.3 2025-09-23 22:43:51.118593 | debian-bookworm | #13 1.714 + cffi==2.0.0 2025-09-23 22:43:51.118598 | debian-bookworm | #13 1.715 + charset-normalizer==3.4.3 2025-09-23 22:43:51.118602 | debian-bookworm | #13 1.715 + cryptography==46.0.1 2025-09-23 22:43:51.118606 | debian-bookworm | #13 1.716 + dynaconf==3.2.11 2025-09-23 22:43:51.118610 | debian-bookworm | #13 1.716 + idna==3.10 2025-09-23 22:43:51.118614 | debian-bookworm | #13 1.716 + jinja2==3.1.6 2025-09-23 22:43:51.118618 | debian-bookworm | #13 1.716 + markupsafe==3.0.2 2025-09-23 22:43:51.118622 | debian-bookworm | #13 1.716 + netaddr==1.3.0 2025-09-23 22:43:51.118626 | debian-bookworm | #13 1.716 + packaging==25.0 2025-09-23 22:43:51.118630 | debian-bookworm | #13 1.716 + pycparser==2.23 2025-09-23 22:43:51.118634 | debian-bookworm | #13 1.717 + pynetbox==7.5.0 2025-09-23 22:43:51.118637 | debian-bookworm | #13 1.717 + pyyaml==6.0.2 2025-09-23 22:43:51.118641 | debian-bookworm | #13 1.717 + requests==2.32.5 2025-09-23 22:43:51.118645 | debian-bookworm | #13 1.717 + resolvelib==1.2.0 2025-09-23 22:43:51.118649 | debian-bookworm | #13 1.717 + urllib3==2.5.0 2025-09-23 22:43:51.148081 | debian-bookworm | #13 DONE 1.9s 2025-09-23 22:43:51.318800 | debian-bookworm | 2025-09-23 22:43:51.318881 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.008031 2025-09-23 22:44:22.397883 | 2025-09-23 22:44:22.398048 | TASK [upload-logs : Upload logs to log server] 2025-09-23 22:44:22.984408 | localhost | Output suppressed because no_log was given 2025-09-23 22:44:22.986359 | 2025-09-23 22:44:22.986514 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 22:44:23.043917 | localhost | skipping: Conditional result was False 2025-09-23 22:44:23.048953 | localhost | skipping: Conditional result was False 2025-09-23 22:44:23.056982 | 2025-09-23 22:44:23.057234 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 22:44:23.103190 | localhost | skipping: Conditional result was False 2025-09-23 22:44:23.103880 | 2025-09-23 22:44:23.107318 | localhost | skipping: Conditional result was False 2025-09-23 22:44:23.119901 | 2025-09-23 22:44:23.120152 | LOOP [upload-logs : Upload console log and json output]