2025-07-13 00:00:13.372648 | Job console starting 2025-07-13 00:00:13.392604 | Updating git repos 2025-07-13 00:00:13.575545 | Cloning repos into workspace 2025-07-13 00:00:13.657715 | Restoring repo states 2025-07-13 00:00:13.675788 | Merging changes 2025-07-13 00:00:13.675806 | Checking out repos 2025-07-13 00:00:13.859420 | Preparing playbooks 2025-07-13 00:00:15.014817 | Running Ansible setup 2025-07-13 00:00:21.539674 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-13 00:00:23.182392 | 2025-07-13 00:00:23.182534 | PLAY [Base pre] 2025-07-13 00:00:23.231389 | 2025-07-13 00:00:23.231526 | TASK [Setup log path fact] 2025-07-13 00:00:23.274897 | debian-bookworm | ok 2025-07-13 00:00:23.308100 | 2025-07-13 00:00:23.308235 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-13 00:00:23.381150 | debian-bookworm | ok 2025-07-13 00:00:23.402044 | 2025-07-13 00:00:23.402152 | TASK [emit-job-header : Print job information] 2025-07-13 00:00:23.476593 | # Job Information 2025-07-13 00:00:23.476746 | Ansible Version: 2.16.14 2025-07-13 00:00:23.476781 | Job: container-image-inventory-reconciler-push 2025-07-13 00:00:23.476814 | Pipeline: periodic-midnight 2025-07-13 00:00:23.476837 | Executor: 521e9411259a 2025-07-13 00:00:23.476858 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-07-13 00:00:23.476880 | Event ID: cc9afd7dce484d109cf7d5d58b55add8 2025-07-13 00:00:23.481774 | 2025-07-13 00:00:23.481863 | LOOP [emit-job-header : Print node information] 2025-07-13 00:00:23.715834 | debian-bookworm | ok: 2025-07-13 00:00:23.716025 | debian-bookworm | # Node Information 2025-07-13 00:00:23.716095 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-13 00:00:23.716124 | debian-bookworm | Hostname: debian 2025-07-13 00:00:23.716146 | debian-bookworm | Username: zuul 2025-07-13 00:00:23.716166 | debian-bookworm | Distro: Debian 12.11 2025-07-13 00:00:23.716191 | debian-bookworm | Provider: regiocloud-a 2025-07-13 00:00:23.716212 | debian-bookworm | Region: 2025-07-13 00:00:23.716233 | debian-bookworm | Label: debian-bookworm 2025-07-13 00:00:23.716293 | debian-bookworm | Product Name: OpenStack Nova 2025-07-13 00:00:23.716316 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe24:79e2 2025-07-13 00:00:23.739621 | 2025-07-13 00:00:23.739785 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-13 00:00:24.698799 | debian-bookworm -> localhost | changed 2025-07-13 00:00:24.706046 | 2025-07-13 00:00:24.706221 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-13 00:00:27.192784 | debian-bookworm -> localhost | changed 2025-07-13 00:00:27.205619 | 2025-07-13 00:00:27.205737 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-13 00:00:27.833139 | debian-bookworm -> localhost | ok 2025-07-13 00:00:27.839100 | 2025-07-13 00:00:27.839216 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-13 00:00:27.908143 | debian-bookworm | ok 2025-07-13 00:00:27.941477 | debian-bookworm | included: /var/lib/zuul/builds/6d80505e05814d63ab5647f9f5d56074/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-13 00:00:27.953584 | 2025-07-13 00:00:27.953692 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-13 00:00:31.768763 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-13 00:00:31.768999 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/6d80505e05814d63ab5647f9f5d56074/work/6d80505e05814d63ab5647f9f5d56074_id_rsa 2025-07-13 00:00:31.769040 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/6d80505e05814d63ab5647f9f5d56074/work/6d80505e05814d63ab5647f9f5d56074_id_rsa.pub 2025-07-13 00:00:31.769066 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-13 00:00:31.769090 | debian-bookworm -> localhost | SHA256:r5J4BgUKVyVv2SuGzu0br5OVZSzspiW+Xx2X4uCeOng zuul-build-sshkey 2025-07-13 00:00:31.769123 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-13 00:00:31.769145 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-13 00:00:31.769166 | debian-bookworm -> localhost | | .o.. | 2025-07-13 00:00:31.769188 | debian-bookworm -> localhost | |. . .o o | 2025-07-13 00:00:31.769208 | debian-bookworm -> localhost | | o . .+... | 2025-07-13 00:00:31.769227 | debian-bookworm -> localhost | | . o. o.+ . | 2025-07-13 00:00:31.769280 | debian-bookworm -> localhost | | ..o.S=. o o | 2025-07-13 00:00:31.769308 | debian-bookworm -> localhost | | o.o..*o + + | 2025-07-13 00:00:31.769329 | debian-bookworm -> localhost | | o+oX + o | 2025-07-13 00:00:31.769349 | debian-bookworm -> localhost | | ..@oE+ . | 2025-07-13 00:00:31.769370 | debian-bookworm -> localhost | | o=O=oo | 2025-07-13 00:00:31.769391 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-13 00:00:31.769443 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.487830 2025-07-13 00:00:31.776167 | 2025-07-13 00:00:31.776292 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-13 00:00:31.821994 | debian-bookworm | ok 2025-07-13 00:00:31.844923 | debian-bookworm | included: /var/lib/zuul/builds/6d80505e05814d63ab5647f9f5d56074/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-13 00:00:31.865252 | 2025-07-13 00:00:31.865359 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-13 00:00:31.878279 | debian-bookworm | skipping: Conditional result was False 2025-07-13 00:00:31.884037 | 2025-07-13 00:00:31.884852 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-13 00:00:32.632197 | debian-bookworm | changed 2025-07-13 00:00:32.636663 | 2025-07-13 00:00:32.636774 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-13 00:00:32.915608 | debian-bookworm | ok 2025-07-13 00:00:32.920038 | 2025-07-13 00:00:32.920121 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-13 00:00:33.766073 | debian-bookworm | changed 2025-07-13 00:00:33.771643 | 2025-07-13 00:00:33.771727 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-13 00:00:34.525138 | debian-bookworm | changed 2025-07-13 00:00:34.536108 | 2025-07-13 00:00:34.536212 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-13 00:00:34.591281 | debian-bookworm | skipping: Conditional result was False 2025-07-13 00:00:34.598422 | 2025-07-13 00:00:34.598523 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-13 00:00:35.543524 | debian-bookworm -> localhost | changed 2025-07-13 00:00:35.557862 | 2025-07-13 00:00:35.557969 | TASK [add-build-sshkey : Add back temp key] 2025-07-13 00:00:36.110536 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/6d80505e05814d63ab5647f9f5d56074/work/6d80505e05814d63ab5647f9f5d56074_id_rsa (zuul-build-sshkey) 2025-07-13 00:00:36.110752 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.037346 2025-07-13 00:00:36.118489 | 2025-07-13 00:00:36.118597 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-13 00:00:36.743991 | debian-bookworm | ok 2025-07-13 00:00:36.748908 | 2025-07-13 00:00:36.749065 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-13 00:00:36.772133 | debian-bookworm | skipping: Conditional result was False 2025-07-13 00:00:36.782744 | 2025-07-13 00:00:36.782883 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-13 00:00:37.307416 | debian-bookworm | ok 2025-07-13 00:00:37.322393 | 2025-07-13 00:00:37.322543 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-13 00:00:37.364718 | debian-bookworm | ok 2025-07-13 00:00:37.372189 | 2025-07-13 00:00:37.372318 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-13 00:00:37.701997 | debian-bookworm -> localhost | ok 2025-07-13 00:00:37.735312 | 2025-07-13 00:00:37.735444 | TASK [validate-host : Collect information about the host] 2025-07-13 00:00:38.912738 | debian-bookworm | ok 2025-07-13 00:00:38.929043 | 2025-07-13 00:00:38.929154 | TASK [validate-host : Sanitize hostname] 2025-07-13 00:00:39.122349 | debian-bookworm | ok 2025-07-13 00:00:39.188581 | 2025-07-13 00:00:39.188692 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-13 00:00:40.181593 | debian-bookworm -> localhost | changed 2025-07-13 00:00:40.187085 | 2025-07-13 00:00:40.187188 | TASK [validate-host : Collect information about zuul worker] 2025-07-13 00:00:40.735498 | debian-bookworm | ok 2025-07-13 00:00:40.740051 | 2025-07-13 00:00:40.740147 | TASK [validate-host : Write out all zuul information for each host] 2025-07-13 00:00:41.749585 | debian-bookworm -> localhost | changed 2025-07-13 00:00:41.766791 | 2025-07-13 00:00:41.766943 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-13 00:00:42.033920 | debian-bookworm | ok 2025-07-13 00:00:42.043783 | 2025-07-13 00:00:42.043896 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-13 00:00:43.052868 | debian-bookworm | changed: 2025-07-13 00:00:43.053037 | debian-bookworm | cd+++++++++ src/ 2025-07-13 00:00:43.053071 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-13 00:00:43.053095 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-13 00:00:43.053292 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-07-13 00:00:43.053324 | debian-bookworm | Containerfile 2025-07-13 00:00:43.053526 | debian-bookworm | 3.5.1-r0) 2025-07-13 00:01:47.812903 | debian-bookworm | #9 6.463 (25/34) Upgrading libssl3 (3.5.0-r0 -> 3.5.1-r0) 2025-07-13 00:01:47.917359 | debian-bookworm | #9 6.491 (26/34) Installing openssl-dev (3.5.1-r0) 2025-07-13 00:01:47.917439 | debian-bookworm | #9 6.548 (27/34) Installing mpdecimal (4.0.1-r0) 2025-07-13 00:01:47.917458 | debian-bookworm | #9 6.568 (28/34) Installing python3 (3.12.11-r0) 2025-07-13 00:01:48.233696 | debian-bookworm | #9 6.884 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-07-13 00:01:48.415871 | debian-bookworm | #9 7.066 (30/34) Installing pyc (3.12.11-r0) 2025-07-13 00:01:48.415976 | debian-bookworm | #9 7.066 (31/34) Installing python3-pyc (3.12.11-r0) 2025-07-13 00:01:48.415997 | debian-bookworm | #9 7.066 (32/34) Installing python3-dev (3.12.11-r0) 2025-07-13 00:01:48.941300 | debian-bookworm | #9 7.590 (33/34) Installing yq-go (4.46.1-r0) 2025-07-13 00:01:49.097136 | debian-bookworm | #9 7.748 (34/34) Installing .build-deps (20250713.000144) 2025-07-13 00:01:49.324392 | debian-bookworm | #9 7.749 Executing busybox-1.37.0-r18.trigger 2025-07-13 00:01:49.324490 | debian-bookworm | #9 7.757 Executing ca-certificates-20241121-r2.trigger 2025-07-13 00:01:49.324502 | debian-bookworm | #9 7.811 OK: 359 MiB in 82 packages 2025-07-13 00:01:49.707504 | debian-bookworm | #9 DONE 8.3s 2025-07-13 00:01:49.820238 | debian-bookworm | 2025-07-13 00:01:49.820340 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-07-13 00:01:49.820356 | debian-bookworm | #10 DONE 0.1s 2025-07-13 00:01:49.922002 | debian-bookworm | 2025-07-13 00:01:49.922124 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-07-13 00:01:49.922142 | debian-bookworm | #11 DONE 0.1s 2025-07-13 00:01:50.075626 | debian-bookworm | 2025-07-13 00:01:50.075740 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-07-13 00:01:50.075757 | debian-bookworm | #12 DONE 0.1s 2025-07-13 00:01:50.231797 | debian-bookworm | 2025-07-13 00:01:50.231908 | 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-13 00:01:51.071190 | debian-bookworm | #13 0.840 Using Python 3.13.5 environment at: /usr/local 2025-07-13 00:01:51.390210 | debian-bookworm | #13 1.106 Resolved 17 packages in 263ms 2025-07-13 00:01:51.390277 | debian-bookworm | #13 1.155 Downloading netaddr (2.2MiB) 2025-07-13 00:01:51.390283 | debian-bookworm | #13 1.156 Downloading ansible-core (2.1MiB) 2025-07-13 00:01:51.390289 | debian-bookworm | #13 1.159 Downloading cryptography (4.4MiB) 2025-07-13 00:01:51.506910 | debian-bookworm | #13 1.427 Downloading netaddr 2025-07-13 00:01:51.720106 | debian-bookworm | #13 1.488 Downloading cryptography 2025-07-13 00:01:51.745002 | debian-bookworm | #13 1.665 Downloading ansible-core 2025-07-13 00:01:51.943921 | debian-bookworm | #13 1.666 Prepared 17 packages in 557ms 2025-07-13 00:01:51.944091 | debian-bookworm | #13 1.712 Installed 17 packages in 45ms 2025-07-13 00:01:51.944170 | debian-bookworm | #13 1.712 + ansible-core==2.18.6 2025-07-13 00:01:51.944214 | debian-bookworm | #13 1.712 + certifi==2025.7.9 2025-07-13 00:01:51.944286 | debian-bookworm | #13 1.712 + cffi==1.17.1 2025-07-13 00:01:51.944343 | debian-bookworm | #13 1.712 + charset-normalizer==3.4.2 2025-07-13 00:01:51.944396 | debian-bookworm | #13 1.712 + cryptography==45.0.5 2025-07-13 00:01:51.944455 | debian-bookworm | #13 1.712 + dynaconf==3.2.11 2025-07-13 00:01:51.944509 | debian-bookworm | #13 1.712 + idna==3.10 2025-07-13 00:01:51.944568 | debian-bookworm | #13 1.712 + jinja2==3.1.6 2025-07-13 00:01:51.944621 | debian-bookworm | #13 1.713 + markupsafe==3.0.2 2025-07-13 00:01:51.944681 | debian-bookworm | #13 1.713 + netaddr==1.3.0 2025-07-13 00:01:51.944734 | debian-bookworm | #13 1.713 + packaging==25.0 2025-07-13 00:01:51.944792 | debian-bookworm | #13 1.713 + pycparser==2.22 2025-07-13 00:01:51.944911 | debian-bookworm | #13 1.713 + pynetbox==7.5.0 2025-07-13 00:01:51.945006 | debian-bookworm | #13 1.713 + pyyaml==6.0.2 2025-07-13 00:01:51.945065 | debian-bookworm | #13 1.713 + requests==2.32.4 2025-07-13 00:01:51.945123 | debian-bookworm | #13 1.713 + resolvelib==1.0.1 2025-07-13 00:01:51.945185 | debian-bookworm | #13 1.713 + urllib3==2.5.0 2025-07-13 00:01:51.966146 | debian-bookworm | #13 DONE 1.9s 2025-07-13 00:01:52.134334 | debian-bookworm | 2025-07-13 00:01:52.134418 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007602 2025-07-13 00:03:11.250520 | 2025-07-13 00:03:11.250639 | TASK [upload-logs : Upload logs to log server] 2025-07-13 00:03:11.868220 | localhost | Output suppressed because no_log was given 2025-07-13 00:03:11.872439 | 2025-07-13 00:03:11.872637 | LOOP [upload-logs : Compress console log and json output] 2025-07-13 00:03:11.934588 | localhost | skipping: Conditional result was False 2025-07-13 00:03:11.940416 | localhost | skipping: Conditional result was False 2025-07-13 00:03:11.956634 | 2025-07-13 00:03:11.956875 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-13 00:03:12.007471 | localhost | skipping: Conditional result was False 2025-07-13 00:03:12.008361 | 2025-07-13 00:03:12.011681 | localhost | skipping: Conditional result was False 2025-07-13 00:03:12.027614 | 2025-07-13 00:03:12.027844 | LOOP [upload-logs : Upload console log and json output]