2025-10-01 00:00:14.299922 | Job console starting 2025-10-01 00:00:14.311638 | Updating git repos 2025-10-01 00:00:14.339604 | Cloning repos into workspace 2025-10-01 00:00:14.419790 | Restoring repo states 2025-10-01 00:00:14.437820 | Merging changes 2025-10-01 00:00:14.437838 | Checking out repos 2025-10-01 00:00:14.530170 | Preparing playbooks 2025-10-01 00:00:15.907475 | Running Ansible setup 2025-10-01 00:00:23.152151 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-01 00:00:24.636610 | 2025-10-01 00:00:24.636718 | PLAY [Base pre] 2025-10-01 00:00:24.653523 | 2025-10-01 00:00:24.653623 | TASK [Setup log path fact] 2025-10-01 00:00:24.670194 | debian-bookworm | ok 2025-10-01 00:00:24.681898 | 2025-10-01 00:00:24.682005 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 00:00:24.717855 | debian-bookworm | ok 2025-10-01 00:00:24.725608 | 2025-10-01 00:00:24.725696 | TASK [emit-job-header : Print job information] 2025-10-01 00:00:24.764351 | # Job Information 2025-10-01 00:00:24.764489 | Ansible Version: 2.16.14 2025-10-01 00:00:24.764517 | Job: container-image-inventory-reconciler-push 2025-10-01 00:00:24.764543 | Pipeline: periodic-midnight 2025-10-01 00:00:24.764563 | Executor: 521e9411259a 2025-10-01 00:00:24.764580 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-10-01 00:00:24.764598 | Event ID: 09851ffce2d1410a8d70452be45f17fc 2025-10-01 00:00:24.768640 | 2025-10-01 00:00:24.768713 | LOOP [emit-job-header : Print node information] 2025-10-01 00:00:24.896162 | debian-bookworm | ok: 2025-10-01 00:00:24.896371 | debian-bookworm | # Node Information 2025-10-01 00:00:24.896410 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-01 00:00:24.896431 | debian-bookworm | Hostname: debian 2025-10-01 00:00:24.896450 | debian-bookworm | Username: zuul 2025-10-01 00:00:24.896467 | debian-bookworm | Distro: Debian 12.12 2025-10-01 00:00:24.896488 | debian-bookworm | Provider: regiocloud-a 2025-10-01 00:00:24.896506 | debian-bookworm | Region: 2025-10-01 00:00:24.896523 | debian-bookworm | Label: debian-bookworm 2025-10-01 00:00:24.896539 | debian-bookworm | Product Name: OpenStack Nova 2025-10-01 00:00:24.896554 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe46:3bd6 2025-10-01 00:00:24.914766 | 2025-10-01 00:00:24.914887 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 00:00:26.155276 | debian-bookworm -> localhost | changed 2025-10-01 00:00:26.161038 | 2025-10-01 00:00:26.161128 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 00:00:28.248349 | debian-bookworm -> localhost | changed 2025-10-01 00:00:28.258563 | 2025-10-01 00:00:28.258643 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 00:00:28.760649 | debian-bookworm -> localhost | ok 2025-10-01 00:00:28.766208 | 2025-10-01 00:00:28.766290 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 00:00:28.793014 | debian-bookworm | ok 2025-10-01 00:00:28.819161 | debian-bookworm | included: /var/lib/zuul/builds/0713d56bbbc941de85561c21d13a7c2c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 00:00:28.840444 | 2025-10-01 00:00:28.840536 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 00:00:32.944096 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-01 00:00:32.944261 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0713d56bbbc941de85561c21d13a7c2c/work/0713d56bbbc941de85561c21d13a7c2c_id_rsa 2025-10-01 00:00:32.944292 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0713d56bbbc941de85561c21d13a7c2c/work/0713d56bbbc941de85561c21d13a7c2c_id_rsa.pub 2025-10-01 00:00:32.944313 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-01 00:00:32.944331 | debian-bookworm -> localhost | SHA256:+Ouw0efSy/BqB7qoWLC87r4AWd3AYeZyXj7VaIfHOhE zuul-build-sshkey 2025-10-01 00:00:32.944359 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-01 00:00:32.944378 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-01 00:00:32.944396 | debian-bookworm -> localhost | | .=. E | 2025-10-01 00:00:32.944413 | debian-bookworm -> localhost | | =.o * | 2025-10-01 00:00:32.944430 | debian-bookworm -> localhost | | o + o * = | 2025-10-01 00:00:32.944446 | debian-bookworm -> localhost | | o + o + = | 2025-10-01 00:00:32.944462 | debian-bookworm -> localhost | |+ . + S | 2025-10-01 00:00:32.944481 | debian-bookworm -> localhost | |oo +.. | 2025-10-01 00:00:32.944497 | debian-bookworm -> localhost | |o.. o.+o. | 2025-10-01 00:00:32.944513 | debian-bookworm -> localhost | |.o. ..+oBo | 2025-10-01 00:00:32.944529 | debian-bookworm -> localhost | |=*o.. o+++=. | 2025-10-01 00:00:32.944545 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-01 00:00:32.944585 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.158480 2025-10-01 00:00:32.949649 | 2025-10-01 00:00:32.949724 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 00:00:32.991115 | debian-bookworm | ok 2025-10-01 00:00:32.999454 | debian-bookworm | included: /var/lib/zuul/builds/0713d56bbbc941de85561c21d13a7c2c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 00:00:33.022156 | 2025-10-01 00:00:33.022251 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 00:00:33.049087 | debian-bookworm | skipping: Conditional result was False 2025-10-01 00:00:33.053915 | 2025-10-01 00:00:33.054001 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 00:00:33.903606 | debian-bookworm | changed 2025-10-01 00:00:33.908224 | 2025-10-01 00:00:33.908309 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 00:00:34.214034 | debian-bookworm | ok 2025-10-01 00:00:34.218726 | 2025-10-01 00:00:34.218822 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 00:00:34.972632 | debian-bookworm | changed 2025-10-01 00:00:34.988339 | 2025-10-01 00:00:34.988446 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 00:00:35.777930 | debian-bookworm | changed 2025-10-01 00:00:35.782302 | 2025-10-01 00:00:35.782381 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 00:00:35.837373 | debian-bookworm | skipping: Conditional result was False 2025-10-01 00:00:35.843066 | 2025-10-01 00:00:35.843158 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 00:00:36.781087 | debian-bookworm -> localhost | changed 2025-10-01 00:00:36.798402 | 2025-10-01 00:00:36.798495 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 00:00:37.241533 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0713d56bbbc941de85561c21d13a7c2c/work/0713d56bbbc941de85561c21d13a7c2c_id_rsa (zuul-build-sshkey) 2025-10-01 00:00:37.241714 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010459 2025-10-01 00:00:37.248122 | 2025-10-01 00:00:37.248207 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 00:00:37.701602 | debian-bookworm | ok 2025-10-01 00:00:37.705869 | 2025-10-01 00:00:37.705948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 00:00:37.738779 | debian-bookworm | skipping: Conditional result was False 2025-10-01 00:00:37.754551 | 2025-10-01 00:00:37.754639 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 00:00:38.192693 | debian-bookworm | ok 2025-10-01 00:00:38.215405 | 2025-10-01 00:00:38.215504 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-01 00:00:38.263832 | debian-bookworm | ok 2025-10-01 00:00:38.268480 | 2025-10-01 00:00:38.268572 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-01 00:00:38.666439 | debian-bookworm -> localhost | ok 2025-10-01 00:00:38.672898 | 2025-10-01 00:00:38.672997 | TASK [validate-host : Collect information about the host] 2025-10-01 00:00:39.905141 | debian-bookworm | ok 2025-10-01 00:00:39.931480 | 2025-10-01 00:00:39.931592 | TASK [validate-host : Sanitize hostname] 2025-10-01 00:00:40.031980 | debian-bookworm | ok 2025-10-01 00:00:40.069847 | 2025-10-01 00:00:40.070013 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-01 00:00:40.884136 | debian-bookworm -> localhost | changed 2025-10-01 00:00:40.890040 | 2025-10-01 00:00:40.890132 | TASK [validate-host : Collect information about zuul worker] 2025-10-01 00:00:41.551459 | debian-bookworm | ok 2025-10-01 00:00:41.555948 | 2025-10-01 00:00:41.556038 | TASK [validate-host : Write out all zuul information for each host] 2025-10-01 00:00:42.279460 | debian-bookworm -> localhost | changed 2025-10-01 00:00:42.290750 | 2025-10-01 00:00:42.291024 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-01 00:00:42.611334 | debian-bookworm | ok 2025-10-01 00:00:42.618639 | 2025-10-01 00:00:42.618749 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-01 00:00:43.523329 | debian-bookworm | changed: 2025-10-01 00:00:43.523495 | debian-bookworm | cd+++++++++ src/ 2025-10-01 00:00:43.523528 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-01 00:00:43.523552 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-01 00:00:43.523573 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-10-01 00:00:43.523594 | debian-bookworm | Containerfile 2025-10-01 00:00:43.523725 | debian-bookworm | 3.5.4-r0) 2025-10-01 00:01:50.947399 | debian-bookworm | #9 6.936 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.4-r0) 2025-10-01 00:01:51.182667 | debian-bookworm | #9 6.963 (26/34) Installing openssl-dev (3.5.4-r0) 2025-10-01 00:01:51.182739 | debian-bookworm | #9 7.003 (27/34) Installing mpdecimal (4.0.1-r0) 2025-10-01 00:01:51.182746 | debian-bookworm | #9 7.021 (28/34) Installing python3 (3.12.11-r0) 2025-10-01 00:01:51.401394 | debian-bookworm | #9 7.390 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-10-01 00:01:51.588335 | debian-bookworm | #9 7.578 (30/34) Installing pyc (3.12.11-r0) 2025-10-01 00:01:51.588394 | debian-bookworm | #9 7.578 (31/34) Installing python3-pyc (3.12.11-r0) 2025-10-01 00:01:51.588415 | debian-bookworm | #9 7.578 (32/34) Installing python3-dev (3.12.11-r0) 2025-10-01 00:01:52.155505 | debian-bookworm | #9 8.144 (33/34) Installing yq-go (4.47.2-r0) 2025-10-01 00:01:52.317552 | debian-bookworm | #9 8.307 (34/34) Installing .build-deps (20251001.000146) 2025-10-01 00:01:52.526925 | debian-bookworm | #9 8.308 Executing busybox-1.37.0-r18.trigger 2025-10-01 00:01:52.527002 | debian-bookworm | #9 8.314 Executing ca-certificates-20250619-r0.trigger 2025-10-01 00:01:52.527014 | debian-bookworm | #9 8.366 OK: 359 MiB in 82 packages 2025-10-01 00:01:52.964546 | debian-bookworm | #9 DONE 8.9s 2025-10-01 00:01:53.092916 | debian-bookworm | 2025-10-01 00:01:53.093042 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-10-01 00:01:53.093068 | debian-bookworm | #10 DONE 0.1s 2025-10-01 00:01:53.254331 | debian-bookworm | 2025-10-01 00:01:53.254419 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-10-01 00:01:53.254444 | debian-bookworm | #11 DONE 0.1s 2025-10-01 00:01:53.254490 | debian-bookworm | 2025-10-01 00:01:53.254502 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-10-01 00:01:53.254514 | debian-bookworm | #12 DONE 0.1s 2025-10-01 00:01:53.411669 | debian-bookworm | 2025-10-01 00:01:53.411762 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-10-01 00:01:54.103572 | debian-bookworm | #13 0.843 Using Python 3.13.7 environment at: /usr/local 2025-10-01 00:01:54.387269 | debian-bookworm | #13 1.127 Resolved 17 packages in 280ms 2025-10-01 00:01:54.612758 | debian-bookworm | #13 1.161 Downloading ansible-core (2.3MiB) 2025-10-01 00:01:54.612824 | debian-bookworm | #13 1.162 Downloading netaddr (2.2MiB) 2025-10-01 00:01:54.612847 | debian-bookworm | #13 1.201 Downloading cryptography (4.5MiB) 2025-10-01 00:01:55.007189 | debian-bookworm | #13 1.567 Downloading netaddr 2025-10-01 00:01:55.007288 | debian-bookworm | #13 1.642 Downloading cryptography 2025-10-01 00:01:55.215512 | debian-bookworm | #13 1.746 Downloading ansible-core 2025-10-01 00:01:55.215625 | debian-bookworm | #13 1.747 Prepared 17 packages in 619ms 2025-10-01 00:01:55.215654 | debian-bookworm | #13 1.799 Installed 17 packages in 50ms 2025-10-01 00:01:55.215666 | debian-bookworm | #13 1.799 + ansible-core==2.19.2 2025-10-01 00:01:55.215678 | debian-bookworm | #13 1.799 + certifi==2025.8.3 2025-10-01 00:01:55.215689 | debian-bookworm | #13 1.799 + cffi==2.0.0 2025-10-01 00:01:55.215699 | debian-bookworm | #13 1.800 + charset-normalizer==3.4.3 2025-10-01 00:01:55.215709 | debian-bookworm | #13 1.800 + cryptography==46.0.1 2025-10-01 00:01:55.215719 | debian-bookworm | #13 1.800 + dynaconf==3.2.11 2025-10-01 00:01:55.215729 | debian-bookworm | #13 1.801 + idna==3.10 2025-10-01 00:01:55.215740 | debian-bookworm | #13 1.801 + jinja2==3.1.6 2025-10-01 00:01:55.215751 | debian-bookworm | #13 1.802 + markupsafe==3.0.3 2025-10-01 00:01:55.215761 | debian-bookworm | #13 1.803 + netaddr==1.3.0 2025-10-01 00:01:55.215770 | debian-bookworm | #13 1.803 + packaging==25.0 2025-10-01 00:01:55.215780 | debian-bookworm | #13 1.804 + pycparser==2.23 2025-10-01 00:01:55.215790 | debian-bookworm | #13 1.804 + pynetbox==7.5.0 2025-10-01 00:01:55.215800 | debian-bookworm | #13 1.805 + pyyaml==6.0.3 2025-10-01 00:01:55.215809 | debian-bookworm | #13 1.805 + requests==2.32.5 2025-10-01 00:01:55.215819 | debian-bookworm | #13 1.805 + resolvelib==1.2.0 2025-10-01 00:01:55.215829 | debian-bookworm | #13 1.805 + urllib3==2.5.0 2025-10-01 00:01:55.215839 | debian-bookworm | #13 DONE 2.0s 2025-10-01 00:01:55.376902 | debian-bookworm | 2025-10-01 00:01:55.377003 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007853 2025-10-01 00:03:29.849114 | 2025-10-01 00:03:29.849238 | TASK [upload-logs : Upload logs to log server] 2025-10-01 00:03:30.433521 | localhost | Output suppressed because no_log was given 2025-10-01 00:03:30.438076 | 2025-10-01 00:03:30.438263 | LOOP [upload-logs : Compress console log and json output] 2025-10-01 00:03:30.494545 | localhost | skipping: Conditional result was False 2025-10-01 00:03:30.500357 | localhost | skipping: Conditional result was False 2025-10-01 00:03:30.512511 | 2025-10-01 00:03:30.512703 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-01 00:03:30.559815 | localhost | skipping: Conditional result was False 2025-10-01 00:03:30.560470 | 2025-10-01 00:03:30.564403 | localhost | skipping: Conditional result was False 2025-10-01 00:03:30.577433 | 2025-10-01 00:03:30.577617 | LOOP [upload-logs : Upload console log and json output]