2025-07-08 00:00:23.279751 | Job console starting 2025-07-08 00:00:23.294238 | Updating git repos 2025-07-08 00:00:23.355852 | Cloning repos into workspace 2025-07-08 00:00:23.481396 | Restoring repo states 2025-07-08 00:00:23.546765 | Merging changes 2025-07-08 00:00:23.546785 | Checking out repos 2025-07-08 00:00:23.706666 | Preparing playbooks 2025-07-08 00:00:25.357651 | Running Ansible setup 2025-07-08 00:00:32.745122 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-08 00:00:34.440024 | 2025-07-08 00:00:34.440156 | PLAY [Base pre] 2025-07-08 00:00:34.466703 | 2025-07-08 00:00:34.468053 | TASK [Setup log path fact] 2025-07-08 00:00:34.501306 | debian-bookworm | ok 2025-07-08 00:00:34.530998 | 2025-07-08 00:00:34.531132 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-08 00:00:34.581873 | debian-bookworm | ok 2025-07-08 00:00:34.597040 | 2025-07-08 00:00:34.597150 | TASK [emit-job-header : Print job information] 2025-07-08 00:00:34.687870 | # Job Information 2025-07-08 00:00:34.688026 | Ansible Version: 2.16.14 2025-07-08 00:00:34.688061 | Job: container-image-inventory-reconciler-push 2025-07-08 00:00:34.688093 | Pipeline: periodic-midnight 2025-07-08 00:00:34.688115 | Executor: 521e9411259a 2025-07-08 00:00:34.688136 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-07-08 00:00:34.688157 | Event ID: 361c10decea343c39e324b95469ba2a7 2025-07-08 00:00:34.692954 | 2025-07-08 00:00:34.693044 | LOOP [emit-job-header : Print node information] 2025-07-08 00:00:34.881991 | debian-bookworm | ok: 2025-07-08 00:00:34.882220 | debian-bookworm | # Node Information 2025-07-08 00:00:34.882268 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-08 00:00:34.882293 | debian-bookworm | Hostname: debian 2025-07-08 00:00:34.882315 | debian-bookworm | Username: zuul 2025-07-08 00:00:34.882335 | debian-bookworm | Distro: Debian 12.11 2025-07-08 00:00:34.882366 | debian-bookworm | Provider: regiocloud-a 2025-07-08 00:00:34.882391 | debian-bookworm | Region: 2025-07-08 00:00:34.882411 | debian-bookworm | Label: debian-bookworm 2025-07-08 00:00:34.882431 | debian-bookworm | Product Name: OpenStack Nova 2025-07-08 00:00:34.882451 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5a:e982 2025-07-08 00:00:34.903325 | 2025-07-08 00:00:34.903433 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-08 00:00:35.864831 | debian-bookworm -> localhost | changed 2025-07-08 00:00:35.873503 | 2025-07-08 00:00:35.873619 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-08 00:00:37.840228 | debian-bookworm -> localhost | changed 2025-07-08 00:00:37.851561 | 2025-07-08 00:00:37.851660 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-08 00:00:38.295263 | debian-bookworm -> localhost | ok 2025-07-08 00:00:38.301199 | 2025-07-08 00:00:38.301329 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-08 00:00:38.341538 | debian-bookworm | ok 2025-07-08 00:00:38.385608 | debian-bookworm | included: /var/lib/zuul/builds/0bec59b852e04378a4d63833d8b3774e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-08 00:00:38.391775 | 2025-07-08 00:00:38.391869 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-08 00:00:41.028377 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-08 00:00:41.028696 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0bec59b852e04378a4d63833d8b3774e/work/0bec59b852e04378a4d63833d8b3774e_id_rsa 2025-07-08 00:00:41.028759 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0bec59b852e04378a4d63833d8b3774e/work/0bec59b852e04378a4d63833d8b3774e_id_rsa.pub 2025-07-08 00:00:41.028801 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-08 00:00:41.028838 | debian-bookworm -> localhost | SHA256:q7Tzy0gYYErfZAQ/bpZbeD5iLVI0H0zwhFiymrqRpXQ zuul-build-sshkey 2025-07-08 00:00:41.028882 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-08 00:00:41.028915 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-08 00:00:41.028946 | debian-bookworm -> localhost | | o++oo | 2025-07-08 00:00:41.028978 | debian-bookworm -> localhost | | .=.= | 2025-07-08 00:00:41.029008 | debian-bookworm -> localhost | | .o. B + | 2025-07-08 00:00:41.029037 | debian-bookworm -> localhost | |.o+.* * . | 2025-07-08 00:00:41.029066 | debian-bookworm -> localhost | |.+.E.O +S | 2025-07-08 00:00:41.029103 | debian-bookworm -> localhost | |o+. +o* . | 2025-07-08 00:00:41.029133 | debian-bookworm -> localhost | |= ..=o+. | 2025-07-08 00:00:41.029162 | debian-bookworm -> localhost | | o oo+=. | 2025-07-08 00:00:41.029193 | debian-bookworm -> localhost | |. +o+. | 2025-07-08 00:00:41.029223 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-08 00:00:41.029306 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.875344 2025-07-08 00:00:41.037765 | 2025-07-08 00:00:41.037861 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-08 00:00:41.065062 | debian-bookworm | ok 2025-07-08 00:00:41.072866 | debian-bookworm | included: /var/lib/zuul/builds/0bec59b852e04378a4d63833d8b3774e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-08 00:00:41.084662 | 2025-07-08 00:00:41.084832 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-08 00:00:41.102066 | debian-bookworm | skipping: Conditional result was False 2025-07-08 00:00:41.115901 | 2025-07-08 00:00:41.116060 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-08 00:00:41.724825 | debian-bookworm | changed 2025-07-08 00:00:41.731888 | 2025-07-08 00:00:41.732014 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-08 00:00:41.989346 | debian-bookworm | ok 2025-07-08 00:00:41.998161 | 2025-07-08 00:00:41.998316 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-08 00:00:42.649073 | debian-bookworm | changed 2025-07-08 00:00:42.657132 | 2025-07-08 00:00:42.657230 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-08 00:00:43.256447 | debian-bookworm | changed 2025-07-08 00:00:43.261094 | 2025-07-08 00:00:43.261171 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-08 00:00:43.288549 | debian-bookworm | skipping: Conditional result was False 2025-07-08 00:00:43.293648 | 2025-07-08 00:00:43.293751 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-08 00:00:43.733952 | debian-bookworm -> localhost | changed 2025-07-08 00:00:43.742569 | 2025-07-08 00:00:43.742654 | TASK [add-build-sshkey : Add back temp key] 2025-07-08 00:00:44.050949 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0bec59b852e04378a4d63833d8b3774e/work/0bec59b852e04378a4d63833d8b3774e_id_rsa (zuul-build-sshkey) 2025-07-08 00:00:44.051125 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015971 2025-07-08 00:00:44.057481 | 2025-07-08 00:00:44.057564 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-08 00:00:44.481297 | debian-bookworm | ok 2025-07-08 00:00:44.488054 | 2025-07-08 00:00:44.488158 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-08 00:00:44.511064 | debian-bookworm | skipping: Conditional result was False 2025-07-08 00:00:44.522135 | 2025-07-08 00:00:44.522242 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-08 00:00:44.954088 | debian-bookworm | ok 2025-07-08 00:00:44.964760 | 2025-07-08 00:00:44.964861 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-08 00:00:44.992737 | debian-bookworm | ok 2025-07-08 00:00:44.997743 | 2025-07-08 00:00:44.997849 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-08 00:00:45.297200 | debian-bookworm -> localhost | ok 2025-07-08 00:00:45.311732 | 2025-07-08 00:00:45.311901 | TASK [validate-host : Collect information about the host] 2025-07-08 00:00:46.388047 | debian-bookworm | ok 2025-07-08 00:00:46.409098 | 2025-07-08 00:00:46.409203 | TASK [validate-host : Sanitize hostname] 2025-07-08 00:00:46.515184 | debian-bookworm | ok 2025-07-08 00:00:46.544110 | 2025-07-08 00:00:46.544211 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-08 00:00:47.153143 | debian-bookworm -> localhost | changed 2025-07-08 00:00:47.159211 | 2025-07-08 00:00:47.159356 | TASK [validate-host : Collect information about zuul worker] 2025-07-08 00:00:47.572404 | debian-bookworm | ok 2025-07-08 00:00:47.581154 | 2025-07-08 00:00:47.581325 | TASK [validate-host : Write out all zuul information for each host] 2025-07-08 00:00:48.144067 | debian-bookworm -> localhost | changed 2025-07-08 00:00:48.154160 | 2025-07-08 00:00:48.154303 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-08 00:00:48.440681 | debian-bookworm | ok 2025-07-08 00:00:48.451028 | 2025-07-08 00:00:48.451159 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-08 00:00:49.443682 | debian-bookworm | changed: 2025-07-08 00:00:49.443901 | debian-bookworm | cd+++++++++ src/ 2025-07-08 00:00:49.443943 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-08 00:00:49.443969 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-08 00:00:49.443991 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-07-08 00:00:49.444012 | debian-bookworm | Containerfile 2025-07-08 00:00:49.444147 | debian-bookworm | 3.5.1-r0) 2025-07-08 00:01:55.473615 | debian-bookworm | #9 5.634 (25/34) Upgrading libssl3 (3.5.0-r0 -> 3.5.1-r0) 2025-07-08 00:01:55.704176 | debian-bookworm | #9 5.655 (26/34) Installing openssl-dev (3.5.1-r0) 2025-07-08 00:01:55.704275 | debian-bookworm | #9 5.695 (27/34) Installing mpdecimal (4.0.1-r0) 2025-07-08 00:01:55.704291 | debian-bookworm | #9 5.714 (28/34) Installing python3 (3.12.11-r0) 2025-07-08 00:01:55.850101 | debian-bookworm | #9 6.011 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-07-08 00:01:56.008638 | debian-bookworm | #9 6.169 (30/34) Installing pyc (3.12.11-r0) 2025-07-08 00:01:56.008694 | debian-bookworm | #9 6.169 (31/34) Installing python3-pyc (3.12.11-r0) 2025-07-08 00:01:56.008700 | debian-bookworm | #9 6.169 (32/34) Installing python3-dev (3.12.11-r0) 2025-07-08 00:01:56.465132 | debian-bookworm | #9 6.626 (33/34) Installing yq-go (4.45.4-r0) 2025-07-08 00:01:56.599676 | debian-bookworm | #9 6.760 (34/34) Installing .build-deps (20250708.000152) 2025-07-08 00:01:56.808632 | debian-bookworm | #9 6.762 Executing busybox-1.37.0-r18.trigger 2025-07-08 00:01:56.808711 | debian-bookworm | #9 6.768 Executing ca-certificates-20241121-r2.trigger 2025-07-08 00:01:56.808732 | debian-bookworm | #9 6.819 OK: 359 MiB in 82 packages 2025-07-08 00:01:57.097801 | debian-bookworm | #9 DONE 7.3s 2025-07-08 00:01:57.263694 | debian-bookworm | 2025-07-08 00:01:57.263792 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-07-08 00:01:57.277490 | debian-bookworm | #10 DONE 0.2s 2025-07-08 00:01:57.434940 | debian-bookworm | 2025-07-08 00:01:57.435087 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-07-08 00:01:57.435132 | debian-bookworm | #11 DONE 0.1s 2025-07-08 00:01:57.435159 | debian-bookworm | 2025-07-08 00:01:57.435182 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-07-08 00:01:57.435207 | debian-bookworm | #12 DONE 0.1s 2025-07-08 00:01:57.590934 | debian-bookworm | 2025-07-08 00:01:57.591028 | 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-08 00:01:58.360559 | debian-bookworm | #13 0.770 Using Python 3.13.5 environment at: /usr/local 2025-07-08 00:01:58.647492 | debian-bookworm | #13 1.207 Resolved 17 packages in 434ms 2025-07-08 00:01:58.833325 | debian-bookworm | #13 1.242 Downloading netaddr (2.2MiB) 2025-07-08 00:01:58.833409 | debian-bookworm | #13 1.242 Downloading ansible-core (2.1MiB) 2025-07-08 00:01:58.833425 | debian-bookworm | #13 1.243 Downloading cryptography (4.4MiB) 2025-07-08 00:01:59.155592 | debian-bookworm | #13 1.608 Downloading netaddr 2025-07-08 00:01:59.155670 | debian-bookworm | #13 1.701 Downloading cryptography 2025-07-08 00:01:59.155692 | debian-bookworm | #13 1.716 Downloading ansible-core 2025-07-08 00:01:59.346285 | debian-bookworm | #13 1.717 Prepared 17 packages in 506ms 2025-07-08 00:01:59.346365 | debian-bookworm | #13 1.756 Installed 17 packages in 38ms 2025-07-08 00:01:59.346395 | debian-bookworm | #13 1.756 + ansible-core==2.18.6 2025-07-08 00:01:59.346433 | debian-bookworm | #13 1.756 + certifi==2025.6.15 2025-07-08 00:01:59.346458 | debian-bookworm | #13 1.756 + cffi==1.17.1 2025-07-08 00:01:59.346483 | debian-bookworm | #13 1.756 + charset-normalizer==3.4.2 2025-07-08 00:01:59.346508 | debian-bookworm | #13 1.756 + cryptography==45.0.5 2025-07-08 00:01:59.346532 | debian-bookworm | #13 1.756 + dynaconf==3.2.11 2025-07-08 00:01:59.346556 | debian-bookworm | #13 1.756 + idna==3.10 2025-07-08 00:01:59.346581 | debian-bookworm | #13 1.756 + jinja2==3.1.6 2025-07-08 00:01:59.346604 | debian-bookworm | #13 1.756 + markupsafe==3.0.2 2025-07-08 00:01:59.346627 | debian-bookworm | #13 1.756 + netaddr==1.3.0 2025-07-08 00:01:59.346652 | debian-bookworm | #13 1.756 + packaging==25.0 2025-07-08 00:01:59.346674 | debian-bookworm | #13 1.756 + pycparser==2.22 2025-07-08 00:01:59.346697 | debian-bookworm | #13 1.756 + pynetbox==7.5.0 2025-07-08 00:01:59.346720 | debian-bookworm | #13 1.756 + pyyaml==6.0.2 2025-07-08 00:01:59.346745 | debian-bookworm | #13 1.756 + requests==2.32.4 2025-07-08 00:01:59.346767 | debian-bookworm | #13 1.756 + resolvelib==1.0.1 2025-07-08 00:01:59.346790 | debian-bookworm | #13 1.756 + urllib3==2.5.0 2025-07-08 00:01:59.361786 | debian-bookworm | #13 DONE 1.9s 2025-07-08 00:01:59.519048 | debian-bookworm | 2025-07-08 00:01:59.519125 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007225 2025-07-08 00:03:16.554148 | 2025-07-08 00:03:16.554343 | TASK [upload-logs : Upload logs to log server] 2025-07-08 00:03:17.157465 | localhost | Output suppressed because no_log was given 2025-07-08 00:03:17.160787 | 2025-07-08 00:03:17.160953 | LOOP [upload-logs : Compress console log and json output] 2025-07-08 00:03:17.211960 | localhost | skipping: Conditional result was False 2025-07-08 00:03:17.216122 | localhost | skipping: Conditional result was False 2025-07-08 00:03:17.223804 | 2025-07-08 00:03:17.223988 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-08 00:03:17.271171 | localhost | skipping: Conditional result was False 2025-07-08 00:03:17.271898 | 2025-07-08 00:03:17.275105 | localhost | skipping: Conditional result was False 2025-07-08 00:03:17.289184 | 2025-07-08 00:03:17.289500 | LOOP [upload-logs : Upload console log and json output]