2025-09-09 07:03:09.300576 | Job console starting 2025-09-09 07:03:09.308811 | Updating git repos 2025-09-09 07:03:09.348117 | Cloning repos into workspace 2025-09-09 07:03:09.406637 | Restoring repo states 2025-09-09 07:03:09.416519 | Merging changes 2025-09-09 07:03:09.416536 | Checking out repos 2025-09-09 07:03:09.466545 | Preparing playbooks 2025-09-09 07:03:10.067888 | Running Ansible setup 2025-09-09 07:03:15.588379 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-09 07:03:16.423582 | 2025-09-09 07:03:16.423750 | PLAY [Base pre] 2025-09-09 07:03:16.441204 | 2025-09-09 07:03:16.441361 | TASK [Setup log path fact] 2025-09-09 07:03:16.470922 | debian-bookworm | ok 2025-09-09 07:03:16.487386 | 2025-09-09 07:03:16.487533 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-09 07:03:16.529904 | debian-bookworm | ok 2025-09-09 07:03:16.546115 | 2025-09-09 07:03:16.546333 | TASK [emit-job-header : Print job information] 2025-09-09 07:03:16.600450 | # Job Information 2025-09-09 07:03:16.600723 | Ansible Version: 2.16.14 2025-09-09 07:03:16.600770 | Job: container-image-inventory-reconciler-push 2025-09-09 07:03:16.600813 | Pipeline: post 2025-09-09 07:03:16.600844 | Executor: 521e9411259a 2025-09-09 07:03:16.600870 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/commit/394bccbb6ac7f13250230a6eb3543e61b0c88ae0 2025-09-09 07:03:16.600899 | Event ID: 072e024e-8d4b-11f0-9f62-eb0980ff47cc 2025-09-09 07:03:16.606866 | 2025-09-09 07:03:16.606991 | LOOP [emit-job-header : Print node information] 2025-09-09 07:03:16.756752 | debian-bookworm | ok: 2025-09-09 07:03:16.757055 | debian-bookworm | # Node Information 2025-09-09 07:03:16.757125 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-09 07:03:16.757407 | debian-bookworm | Hostname: debian 2025-09-09 07:03:16.757494 | debian-bookworm | Username: zuul 2025-09-09 07:03:16.757568 | debian-bookworm | Distro: Debian 12.12 2025-09-09 07:03:16.757814 | debian-bookworm | Provider: regiocloud-a 2025-09-09 07:03:16.757964 | debian-bookworm | Region: 2025-09-09 07:03:16.758020 | debian-bookworm | Label: debian-bookworm 2025-09-09 07:03:16.758077 | debian-bookworm | Product Name: OpenStack Nova 2025-09-09 07:03:16.758139 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feeb:92ec 2025-09-09 07:03:16.771733 | 2025-09-09 07:03:16.771894 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-09 07:03:17.282657 | debian-bookworm -> localhost | changed 2025-09-09 07:03:17.292445 | 2025-09-09 07:03:17.292569 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-09 07:03:18.374111 | debian-bookworm -> localhost | changed 2025-09-09 07:03:18.395107 | 2025-09-09 07:03:18.395286 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-09 07:03:18.693111 | debian-bookworm -> localhost | ok 2025-09-09 07:03:18.699959 | 2025-09-09 07:03:18.700079 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-09 07:03:18.730170 | debian-bookworm | ok 2025-09-09 07:03:18.747413 | debian-bookworm | included: /var/lib/zuul/builds/bcbe2cdc244447acaab4d72cf92bb53e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-09 07:03:18.755997 | 2025-09-09 07:03:18.756097 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-09 07:03:19.871100 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-09 07:03:19.871382 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bcbe2cdc244447acaab4d72cf92bb53e/work/bcbe2cdc244447acaab4d72cf92bb53e_id_rsa 2025-09-09 07:03:19.871425 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bcbe2cdc244447acaab4d72cf92bb53e/work/bcbe2cdc244447acaab4d72cf92bb53e_id_rsa.pub 2025-09-09 07:03:19.871453 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-09 07:03:19.871479 | debian-bookworm -> localhost | SHA256:Kv3yIudqwpjsiz4wkPcE6S9ADRyVy3DWbRW7SYzJRcg zuul-build-sshkey 2025-09-09 07:03:19.871513 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-09 07:03:19.871537 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-09 07:03:19.871558 | debian-bookworm -> localhost | |.o=oo o +=. | 2025-09-09 07:03:19.871581 | debian-bookworm -> localhost | | +o= ..E= . | 2025-09-09 07:03:19.871602 | debian-bookworm -> localhost | |.o=.. .+ + | 2025-09-09 07:03:19.871622 | debian-bookworm -> localhost | |+ oo. . o | 2025-09-09 07:03:19.871642 | debian-bookworm -> localhost | |.o + So | 2025-09-09 07:03:19.871666 | debian-bookworm -> localhost | |o . o. . | 2025-09-09 07:03:19.871687 | debian-bookworm -> localhost | |o= .. o | 2025-09-09 07:03:19.871707 | debian-bookworm -> localhost | |+oo o.+. | 2025-09-09 07:03:19.871728 | debian-bookworm -> localhost | |++oo.=.+o | 2025-09-09 07:03:19.871749 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-09 07:03:19.871805 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.597531 2025-09-09 07:03:19.878441 | 2025-09-09 07:03:19.878545 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-09 07:03:19.918931 | debian-bookworm | ok 2025-09-09 07:03:19.930090 | debian-bookworm | included: /var/lib/zuul/builds/bcbe2cdc244447acaab4d72cf92bb53e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-09 07:03:19.939565 | 2025-09-09 07:03:19.939666 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-09 07:03:19.964587 | debian-bookworm | skipping: Conditional result was False 2025-09-09 07:03:19.971854 | 2025-09-09 07:03:19.971965 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-09 07:03:20.666715 | debian-bookworm | changed 2025-09-09 07:03:20.675449 | 2025-09-09 07:03:20.675597 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-09 07:03:20.967204 | debian-bookworm | ok 2025-09-09 07:03:20.976101 | 2025-09-09 07:03:20.976257 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-09 07:03:21.702719 | debian-bookworm | changed 2025-09-09 07:03:21.713157 | 2025-09-09 07:03:21.713308 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-09 07:03:22.409244 | debian-bookworm | changed 2025-09-09 07:03:22.417342 | 2025-09-09 07:03:22.417471 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-09 07:03:22.443101 | debian-bookworm | skipping: Conditional result was False 2025-09-09 07:03:22.459167 | 2025-09-09 07:03:22.459516 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-09 07:03:22.913465 | debian-bookworm -> localhost | changed 2025-09-09 07:03:22.930741 | 2025-09-09 07:03:22.930911 | TASK [add-build-sshkey : Add back temp key] 2025-09-09 07:03:23.301517 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bcbe2cdc244447acaab4d72cf92bb53e/work/bcbe2cdc244447acaab4d72cf92bb53e_id_rsa (zuul-build-sshkey) 2025-09-09 07:03:23.301828 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020845 2025-09-09 07:03:23.311122 | 2025-09-09 07:03:23.311256 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-09 07:03:23.744554 | debian-bookworm | ok 2025-09-09 07:03:23.752213 | 2025-09-09 07:03:23.752367 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-09 07:03:23.787723 | debian-bookworm | skipping: Conditional result was False 2025-09-09 07:03:23.806065 | 2025-09-09 07:03:23.806190 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-09 07:03:24.269326 | debian-bookworm | ok 2025-09-09 07:03:24.284770 | 2025-09-09 07:03:24.284898 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-09 07:03:24.328199 | debian-bookworm | ok 2025-09-09 07:03:24.335442 | 2025-09-09 07:03:24.335574 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-09 07:03:24.633946 | debian-bookworm -> localhost | ok 2025-09-09 07:03:24.641782 | 2025-09-09 07:03:24.641908 | TASK [validate-host : Collect information about the host] 2025-09-09 07:03:26.824579 | debian-bookworm | ok 2025-09-09 07:03:26.837308 | 2025-09-09 07:03:26.837431 | TASK [validate-host : Sanitize hostname] 2025-09-09 07:03:26.970649 | debian-bookworm | ok 2025-09-09 07:03:27.015983 | 2025-09-09 07:03:27.016124 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-09 07:03:27.599606 | debian-bookworm -> localhost | changed 2025-09-09 07:03:27.605878 | 2025-09-09 07:03:27.605996 | TASK [validate-host : Collect information about zuul worker] 2025-09-09 07:03:28.036721 | debian-bookworm | ok 2025-09-09 07:03:28.044199 | 2025-09-09 07:03:28.044384 | TASK [validate-host : Write out all zuul information for each host] 2025-09-09 07:03:28.611893 | debian-bookworm -> localhost | changed 2025-09-09 07:03:28.629990 | 2025-09-09 07:03:28.630127 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-09 07:03:28.939249 | debian-bookworm | ok 2025-09-09 07:03:28.950955 | 2025-09-09 07:03:28.951090 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-09 07:03:29.996258 | debian-bookworm | changed: 2025-09-09 07:03:29.996562 | debian-bookworm | cd+++++++++ src/ 2025-09-09 07:03:29.996623 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-09 07:03:29.996666 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-09 07:03:29.996704 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-09 07:03:29.996742 | debian-bookworm | Containerfile 2025-09-09 07:03:29.996984 | debian-bookworm | 3.5.2-r0) 2025-09-09 07:04:36.842723 | debian-bookworm | #9 5.870 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-09 07:04:37.080274 | debian-bookworm | #9 5.904 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-09 07:04:37.080360 | debian-bookworm | #9 5.944 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-09 07:04:37.080381 | debian-bookworm | #9 5.957 (28/34) Installing python3 (3.12.11-r0) 2025-09-09 07:04:37.219287 | debian-bookworm | #9 6.247 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-09 07:04:37.389959 | debian-bookworm | #9 6.417 (30/34) Installing pyc (3.12.11-r0) 2025-09-09 07:04:37.390034 | debian-bookworm | #9 6.417 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-09 07:04:37.390053 | debian-bookworm | #9 6.417 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-09 07:04:37.838337 | debian-bookworm | #9 6.866 (33/34) Installing yq-go (4.46.1-r2) 2025-09-09 07:04:37.969423 | debian-bookworm | #9 6.997 (34/34) Installing .build-deps (20250909.070433) 2025-09-09 07:04:38.190011 | debian-bookworm | #9 6.999 Executing busybox-1.37.0-r18.trigger 2025-09-09 07:04:38.190092 | debian-bookworm | #9 7.007 Executing ca-certificates-20250619-r0.trigger 2025-09-09 07:04:38.190099 | debian-bookworm | #9 7.067 OK: 359 MiB in 82 packages 2025-09-09 07:04:38.540286 | debian-bookworm | #9 DONE 7.6s 2025-09-09 07:04:38.690427 | debian-bookworm | 2025-09-09 07:04:38.690562 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-09 07:04:38.690595 | debian-bookworm | #10 DONE 0.1s 2025-09-09 07:04:38.690611 | debian-bookworm | 2025-09-09 07:04:38.690624 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-09 07:04:38.690636 | debian-bookworm | #11 DONE 0.1s 2025-09-09 07:04:38.911261 | debian-bookworm | 2025-09-09 07:04:38.911346 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-09 07:04:38.911356 | debian-bookworm | #12 DONE 0.1s 2025-09-09 07:04:38.911361 | debian-bookworm | 2025-09-09 07:04:38.911366 | 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-09 07:04:39.654907 | debian-bookworm | #13 0.743 Using Python 3.13.7 environment at: /usr/local 2025-09-09 07:04:39.979688 | debian-bookworm | #13 1.026 Resolved 17 packages in 279ms 2025-09-09 07:04:39.979774 | debian-bookworm | #13 1.064 Downloading cryptography (4.4MiB) 2025-09-09 07:04:39.979785 | debian-bookworm | #13 1.067 Downloading ansible-core (2.3MiB) 2025-09-09 07:04:39.979795 | debian-bookworm | #13 1.069 Downloading netaddr (2.2MiB) 2025-09-09 07:04:40.171591 | debian-bookworm | #13 1.412 Downloading netaddr 2025-09-09 07:04:40.403730 | debian-bookworm | #13 1.499 Downloading cryptography 2025-09-09 07:04:40.624204 | debian-bookworm | #13 1.644 Downloading ansible-core 2025-09-09 07:04:40.624326 | debian-bookworm | #13 1.646 Prepared 17 packages in 616ms 2025-09-09 07:04:40.624350 | debian-bookworm | #13 1.708 Installed 17 packages in 60ms 2025-09-09 07:04:40.624368 | debian-bookworm | #13 1.708 + ansible-core==2.19.2 2025-09-09 07:04:40.624406 | debian-bookworm | #13 1.708 + certifi==2025.8.3 2025-09-09 07:04:40.624428 | debian-bookworm | #13 1.708 + cffi==2.0.0 2025-09-09 07:04:40.624446 | debian-bookworm | #13 1.709 + charset-normalizer==3.4.3 2025-09-09 07:04:40.624463 | debian-bookworm | #13 1.710 + cryptography==45.0.7 2025-09-09 07:04:40.624479 | debian-bookworm | #13 1.710 + dynaconf==3.2.11 2025-09-09 07:04:40.624496 | debian-bookworm | #13 1.710 + idna==3.10 2025-09-09 07:04:40.624513 | debian-bookworm | #13 1.710 + jinja2==3.1.6 2025-09-09 07:04:40.624530 | debian-bookworm | #13 1.711 + markupsafe==3.0.2 2025-09-09 07:04:40.624547 | debian-bookworm | #13 1.712 + netaddr==1.3.0 2025-09-09 07:04:40.624563 | debian-bookworm | #13 1.713 + packaging==25.0 2025-09-09 07:04:40.624581 | debian-bookworm | #13 1.713 + pycparser==2.22 2025-09-09 07:04:40.624599 | debian-bookworm | #13 1.713 + pynetbox==7.5.0 2025-09-09 07:04:40.624614 | debian-bookworm | #13 1.713 + pyyaml==6.0.2 2025-09-09 07:04:40.624631 | debian-bookworm | #13 1.713 + requests==2.32.5 2025-09-09 07:04:40.624648 | debian-bookworm | #13 1.713 + resolvelib==1.2.0 2025-09-09 07:04:40.624664 | debian-bookworm | #13 1.714 + urllib3==2.5.0 2025-09-09 07:04:40.638980 | debian-bookworm | #13 DONE 1.9s 2025-09-09 07:04:40.796935 | debian-bookworm | 2025-09-09 07:04:40.797042 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007840 2025-09-09 07:06:13.142576 | 2025-09-09 07:06:13.142759 | TASK [upload-logs : Upload logs to log server] 2025-09-09 07:06:13.709856 | localhost | Output suppressed because no_log was given 2025-09-09 07:06:13.713947 | 2025-09-09 07:06:13.714129 | LOOP [upload-logs : Compress console log and json output] 2025-09-09 07:06:13.777548 | localhost | skipping: Conditional result was False 2025-09-09 07:06:13.782473 | localhost | skipping: Conditional result was False 2025-09-09 07:06:13.796543 | 2025-09-09 07:06:13.796810 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-09 07:06:13.855553 | localhost | skipping: Conditional result was False 2025-09-09 07:06:13.856287 | 2025-09-09 07:06:13.859659 | localhost | skipping: Conditional result was False 2025-09-09 07:06:13.873654 | 2025-09-09 07:06:13.873874 | LOOP [upload-logs : Upload console log and json output]