2025-07-09 23:51:52.588765 | Job console starting 2025-07-09 23:51:52.604181 | Updating git repos 2025-07-09 23:51:52.652961 | Cloning repos into workspace 2025-07-09 23:51:52.704601 | Restoring repo states 2025-07-09 23:51:52.713458 | Merging changes 2025-07-09 23:51:53.218433 | Checking out repos 2025-07-09 23:51:53.326995 | Preparing playbooks 2025-07-09 23:51:54.318311 | Running Ansible setup 2025-07-09 23:51:59.307240 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-09 23:52:00.318810 | 2025-07-09 23:52:00.319010 | PLAY [Base pre] 2025-07-09 23:52:00.336460 | 2025-07-09 23:52:00.336622 | TASK [Setup log path fact] 2025-07-09 23:52:00.355568 | debian-bookworm | ok 2025-07-09 23:52:00.371096 | 2025-07-09 23:52:00.371257 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-09 23:52:00.421997 | debian-bookworm | ok 2025-07-09 23:52:00.432184 | 2025-07-09 23:52:00.432338 | TASK [emit-job-header : Print job information] 2025-07-09 23:52:00.484295 | # Job Information 2025-07-09 23:52:00.484659 | Ansible Version: 2.16.14 2025-07-09 23:52:00.484734 | Job: container-image-inventory-reconciler-build 2025-07-09 23:52:00.484803 | Pipeline: check 2025-07-09 23:52:00.484854 | Executor: 521e9411259a 2025-07-09 23:52:00.484900 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/397 2025-07-09 23:52:00.484947 | Event ID: aac56d40-5d1f-11f0-967d-a31ff2cce2ed 2025-07-09 23:52:00.496034 | 2025-07-09 23:52:00.496244 | LOOP [emit-job-header : Print node information] 2025-07-09 23:52:00.641874 | debian-bookworm | ok: 2025-07-09 23:52:00.642082 | debian-bookworm | # Node Information 2025-07-09 23:52:00.642118 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-09 23:52:00.642143 | debian-bookworm | Hostname: debian 2025-07-09 23:52:00.642165 | debian-bookworm | Username: zuul 2025-07-09 23:52:00.642186 | debian-bookworm | Distro: Debian 12.11 2025-07-09 23:52:00.642210 | debian-bookworm | Provider: regiocloud-a 2025-07-09 23:52:00.642231 | debian-bookworm | Region: 2025-07-09 23:52:00.642252 | debian-bookworm | Label: debian-bookworm 2025-07-09 23:52:00.642271 | debian-bookworm | Product Name: OpenStack Nova 2025-07-09 23:52:00.642290 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2c:512c 2025-07-09 23:52:00.661011 | 2025-07-09 23:52:00.661168 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-09 23:52:01.325670 | debian-bookworm -> localhost | changed 2025-07-09 23:52:01.333260 | 2025-07-09 23:52:01.333411 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-09 23:52:02.946246 | debian-bookworm -> localhost | changed 2025-07-09 23:52:02.970127 | 2025-07-09 23:52:02.970303 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-09 23:52:03.342530 | debian-bookworm -> localhost | ok 2025-07-09 23:52:03.357607 | 2025-07-09 23:52:03.357863 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-09 23:52:03.393280 | debian-bookworm | ok 2025-07-09 23:52:03.414798 | debian-bookworm | included: /var/lib/zuul/builds/1e664dea8f404146815362f39cdd0185/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-09 23:52:03.421121 | 2025-07-09 23:52:03.421303 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-09 23:52:04.554772 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-09 23:52:04.555006 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1e664dea8f404146815362f39cdd0185/work/1e664dea8f404146815362f39cdd0185_id_rsa 2025-07-09 23:52:04.555047 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1e664dea8f404146815362f39cdd0185/work/1e664dea8f404146815362f39cdd0185_id_rsa.pub 2025-07-09 23:52:04.555074 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-09 23:52:04.555098 | debian-bookworm -> localhost | SHA256:edazw2N2WkP9o2gf14a1xbgYJcZ0K1WzPkYAxOB24Ks zuul-build-sshkey 2025-07-09 23:52:04.555128 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-09 23:52:04.555149 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-09 23:52:04.555170 | debian-bookworm -> localhost | | o+oo.oo.| 2025-07-09 23:52:04.555191 | debian-bookworm -> localhost | | o .+ o..o| 2025-07-09 23:52:04.555212 | debian-bookworm -> localhost | | + .= oo | 2025-07-09 23:52:04.555232 | debian-bookworm -> localhost | | ..oo +o+ | 2025-07-09 23:52:04.555251 | debian-bookworm -> localhost | | S.o + o+=| 2025-07-09 23:52:04.555279 | debian-bookworm -> localhost | | .o . *.+*| 2025-07-09 23:52:04.555300 | debian-bookworm -> localhost | | E O.*++| 2025-07-09 23:52:04.555347 | debian-bookworm -> localhost | | +.*+o.| 2025-07-09 23:52:04.555371 | debian-bookworm -> localhost | | ..oo | 2025-07-09 23:52:04.555391 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-09 23:52:04.555443 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.393581 2025-07-09 23:52:04.561520 | 2025-07-09 23:52:04.561609 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-09 23:52:04.578502 | debian-bookworm | ok 2025-07-09 23:52:04.587312 | debian-bookworm | included: /var/lib/zuul/builds/1e664dea8f404146815362f39cdd0185/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-09 23:52:04.595978 | 2025-07-09 23:52:04.596064 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-09 23:52:04.608762 | debian-bookworm | skipping: Conditional result was False 2025-07-09 23:52:04.614242 | 2025-07-09 23:52:04.614358 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-09 23:52:05.250155 | debian-bookworm | changed 2025-07-09 23:52:05.260381 | 2025-07-09 23:52:05.260489 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-09 23:52:05.512535 | debian-bookworm | ok 2025-07-09 23:52:05.518263 | 2025-07-09 23:52:05.518366 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-09 23:52:06.168265 | debian-bookworm | changed 2025-07-09 23:52:06.175878 | 2025-07-09 23:52:06.175990 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-09 23:52:06.791001 | debian-bookworm | changed 2025-07-09 23:52:06.797628 | 2025-07-09 23:52:06.797723 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-09 23:52:06.820983 | debian-bookworm | skipping: Conditional result was False 2025-07-09 23:52:06.828892 | 2025-07-09 23:52:06.829011 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-09 23:52:07.258594 | debian-bookworm -> localhost | changed 2025-07-09 23:52:07.274437 | 2025-07-09 23:52:07.274549 | TASK [add-build-sshkey : Add back temp key] 2025-07-09 23:52:07.601686 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1e664dea8f404146815362f39cdd0185/work/1e664dea8f404146815362f39cdd0185_id_rsa (zuul-build-sshkey) 2025-07-09 23:52:07.601889 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012021 2025-07-09 23:52:07.609265 | 2025-07-09 23:52:07.609394 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-09 23:52:07.976569 | debian-bookworm | ok 2025-07-09 23:52:07.987353 | 2025-07-09 23:52:07.987522 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-09 23:52:08.013440 | debian-bookworm | skipping: Conditional result was False 2025-07-09 23:52:08.036567 | 2025-07-09 23:52:08.036932 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-09 23:52:08.463082 | debian-bookworm | ok 2025-07-09 23:52:08.473339 | 2025-07-09 23:52:08.473440 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-09 23:52:08.501248 | debian-bookworm | ok 2025-07-09 23:52:08.505998 | 2025-07-09 23:52:08.506081 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-09 23:52:08.763169 | debian-bookworm -> localhost | ok 2025-07-09 23:52:08.769244 | 2025-07-09 23:52:08.769372 | TASK [validate-host : Collect information about the host] 2025-07-09 23:52:09.797050 | debian-bookworm | ok 2025-07-09 23:52:09.825082 | 2025-07-09 23:52:09.825388 | TASK [validate-host : Sanitize hostname] 2025-07-09 23:52:09.953296 | debian-bookworm | ok 2025-07-09 23:52:10.017512 | 2025-07-09 23:52:10.017666 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-09 23:52:10.763785 | debian-bookworm -> localhost | changed 2025-07-09 23:52:10.774601 | 2025-07-09 23:52:10.774779 | TASK [validate-host : Collect information about zuul worker] 2025-07-09 23:52:11.212988 | debian-bookworm | ok 2025-07-09 23:52:11.218464 | 2025-07-09 23:52:11.218592 | TASK [validate-host : Write out all zuul information for each host] 2025-07-09 23:52:11.833779 | debian-bookworm -> localhost | changed 2025-07-09 23:52:11.853868 | 2025-07-09 23:52:11.854016 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-09 23:52:12.147301 | debian-bookworm | ok 2025-07-09 23:52:12.159439 | 2025-07-09 23:52:12.160077 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-09 23:52:12.928462 | debian-bookworm | changed: 2025-07-09 23:52:12.928668 | debian-bookworm | cd+++++++++ src/ 2025-07-09 23:52:12.928704 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-09 23:52:12.928730 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-09 23:52:12.928753 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-07-09 23:52:12.928773 | debian-bookworm | Containerfile 2025-07-09 23:52:12.928910 | debian-bookworm | 3.5.1-r0) 2025-07-09 23:53:12.810186 | debian-bookworm | #9 6.162 (25/34) Upgrading libssl3 (3.5.0-r0 -> 3.5.1-r0) 2025-07-09 23:53:12.917340 | debian-bookworm | #9 6.195 (26/34) Installing openssl-dev (3.5.1-r0) 2025-07-09 23:53:12.917498 | debian-bookworm | #9 6.246 (27/34) Installing mpdecimal (4.0.1-r0) 2025-07-09 23:53:12.917548 | debian-bookworm | #9 6.269 (28/34) Installing python3 (3.12.11-r0) 2025-07-09 23:53:13.222203 | debian-bookworm | #9 6.574 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-07-09 23:53:13.391011 | debian-bookworm | #9 6.742 (30/34) Installing pyc (3.12.11-r0) 2025-07-09 23:53:13.542315 | debian-bookworm | #9 6.743 (31/34) Installing python3-pyc (3.12.11-r0) 2025-07-09 23:53:13.542466 | debian-bookworm | #9 6.743 (32/34) Installing python3-dev (3.12.11-r0) 2025-07-09 23:53:13.883072 | debian-bookworm | #9 7.235 (33/34) Installing yq-go (4.45.4-r0) 2025-07-09 23:53:14.041514 | debian-bookworm | #9 7.393 (34/34) Installing .build-deps (20250709.235309) 2025-07-09 23:53:14.251113 | debian-bookworm | #9 7.395 Executing busybox-1.37.0-r18.trigger 2025-07-09 23:53:14.251196 | debian-bookworm | #9 7.402 Executing ca-certificates-20241121-r2.trigger 2025-07-09 23:53:14.251204 | debian-bookworm | #9 7.453 OK: 359 MiB in 82 packages 2025-07-09 23:53:14.630923 | debian-bookworm | #9 DONE 8.0s 2025-07-09 23:53:14.778994 | debian-bookworm | 2025-07-09 23:53:14.780440 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-07-09 23:53:14.780556 | debian-bookworm | #10 DONE 0.1s 2025-07-09 23:53:14.780594 | debian-bookworm | 2025-07-09 23:53:14.780628 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-07-09 23:53:14.780664 | debian-bookworm | #11 DONE 0.1s 2025-07-09 23:53:14.997299 | debian-bookworm | 2025-07-09 23:53:14.997375 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-07-09 23:53:14.997395 | debian-bookworm | #12 DONE 0.1s 2025-07-09 23:53:14.997403 | debian-bookworm | 2025-07-09 23:53:14.997410 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-07-09 23:53:15.771933 | debian-bookworm | #13 0.774 Using Python 3.13.5 environment at: /usr/local 2025-07-09 23:53:16.045740 | debian-bookworm | #13 1.199 Resolved 17 packages in 421ms 2025-07-09 23:53:16.247276 | debian-bookworm | #13 1.247 Downloading cryptography (4.4MiB) 2025-07-09 23:53:16.247401 | debian-bookworm | #13 1.248 Downloading ansible-core (2.1MiB) 2025-07-09 23:53:16.247449 | debian-bookworm | #13 1.250 Downloading netaddr (2.2MiB) 2025-07-09 23:53:16.501302 | debian-bookworm | #13 1.655 Downloading netaddr 2025-07-09 23:53:16.632508 | debian-bookworm | #13 1.695 Downloading ansible-core 2025-07-09 23:53:16.632604 | debian-bookworm | #13 1.737 Downloading cryptography 2025-07-09 23:53:16.632624 | debian-bookworm | #13 1.737 Prepared 17 packages in 536ms 2025-07-09 23:53:16.632640 | debian-bookworm | #13 1.785 Installed 17 packages in 47ms 2025-07-09 23:53:16.632707 | debian-bookworm | #13 1.785 + ansible-core==2.18.6 2025-07-09 23:53:16.632727 | debian-bookworm | #13 1.785 + certifi==2025.7.9 2025-07-09 23:53:16.632744 | debian-bookworm | #13 1.785 + cffi==1.17.1 2025-07-09 23:53:16.632759 | debian-bookworm | #13 1.785 + charset-normalizer==3.4.2 2025-07-09 23:53:16.632789 | debian-bookworm | #13 1.785 + cryptography==45.0.5 2025-07-09 23:53:16.632805 | debian-bookworm | #13 1.785 + dynaconf==3.2.11 2025-07-09 23:53:16.632821 | debian-bookworm | #13 1.785 + idna==3.10 2025-07-09 23:53:16.632836 | debian-bookworm | #13 1.785 + jinja2==3.1.6 2025-07-09 23:53:16.775529 | debian-bookworm | #13 1.785 + markupsafe==3.0.2 2025-07-09 23:53:16.775625 | debian-bookworm | #13 1.787 + netaddr==1.3.0 2025-07-09 23:53:16.775651 | debian-bookworm | #13 1.787 + packaging==25.0 2025-07-09 23:53:16.775686 | debian-bookworm | #13 1.787 + pycparser==2.22 2025-07-09 23:53:16.775716 | debian-bookworm | #13 1.787 + pynetbox==7.5.0 2025-07-09 23:53:16.775726 | debian-bookworm | #13 1.787 + pyyaml==6.0.2 2025-07-09 23:53:16.775734 | debian-bookworm | #13 1.787 + requests==2.32.4 2025-07-09 23:53:16.775743 | debian-bookworm | #13 1.787 + resolvelib==1.0.1 2025-07-09 23:53:16.775752 | debian-bookworm | #13 1.787 + urllib3==2.5.0 2025-07-09 23:53:16.775761 | debian-bookworm | #13 DONE 1.9s 2025-07-09 23:53:16.933028 | debian-bookworm | 2025-07-09 23:53:16.933099 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.011901 2025-07-09 23:53:46.080367 | 2025-07-09 23:53:46.080493 | TASK [upload-logs : Upload logs to log server] 2025-07-09 23:53:46.677484 | localhost | Output suppressed because no_log was given 2025-07-09 23:53:46.680806 | 2025-07-09 23:53:46.680975 | LOOP [upload-logs : Compress console log and json output] 2025-07-09 23:53:46.734408 | localhost | skipping: Conditional result was False 2025-07-09 23:53:46.740396 | localhost | skipping: Conditional result was False 2025-07-09 23:53:46.744058 | 2025-07-09 23:53:46.744173 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-09 23:53:46.788364 | localhost | skipping: Conditional result was False 2025-07-09 23:53:46.788667 | 2025-07-09 23:53:46.793371 | localhost | skipping: Conditional result was False 2025-07-09 23:53:46.801791 | 2025-07-09 23:53:46.801920 | LOOP [upload-logs : Upload console log and json output]