2025-09-07 00:00:13.490906 | Job console starting 2025-09-07 00:00:13.512022 | Updating git repos 2025-09-07 00:00:13.544841 | Cloning repos into workspace 2025-09-07 00:00:13.586461 | Restoring repo states 2025-09-07 00:00:13.613021 | Merging changes 2025-09-07 00:00:13.613039 | Checking out repos 2025-09-07 00:00:13.735745 | Preparing playbooks 2025-09-07 00:00:14.727671 | Running Ansible setup 2025-09-07 00:00:20.781422 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-07 00:00:21.490360 | 2025-09-07 00:00:21.490469 | PLAY [Base pre] 2025-09-07 00:00:21.510722 | 2025-09-07 00:00:21.510893 | TASK [Setup log path fact] 2025-09-07 00:00:21.538534 | debian-bookworm | ok 2025-09-07 00:00:21.580762 | 2025-09-07 00:00:21.580900 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-07 00:00:21.625031 | debian-bookworm | ok 2025-09-07 00:00:21.636983 | 2025-09-07 00:00:21.637078 | TASK [emit-job-header : Print job information] 2025-09-07 00:00:21.698172 | # Job Information 2025-09-07 00:00:21.698310 | Ansible Version: 2.16.14 2025-09-07 00:00:21.698338 | Job: container-image-inventory-reconciler-push 2025-09-07 00:00:21.698364 | Pipeline: periodic-midnight 2025-09-07 00:00:21.698383 | Executor: 521e9411259a 2025-09-07 00:00:21.698400 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-07 00:00:21.698419 | Event ID: c525d9318f6747c88010eb54a0ce9d14 2025-09-07 00:00:21.702484 | 2025-09-07 00:00:21.702569 | LOOP [emit-job-header : Print node information] 2025-09-07 00:00:21.904344 | debian-bookworm | ok: 2025-09-07 00:00:21.904505 | debian-bookworm | # Node Information 2025-09-07 00:00:21.904539 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-07 00:00:21.904565 | debian-bookworm | Hostname: debian 2025-09-07 00:00:21.904586 | debian-bookworm | Username: zuul 2025-09-07 00:00:21.904607 | debian-bookworm | Distro: Debian 12.11 2025-09-07 00:00:21.904631 | debian-bookworm | Provider: regiocloud-a 2025-09-07 00:00:21.904652 | debian-bookworm | Region: 2025-09-07 00:00:21.904673 | debian-bookworm | Label: debian-bookworm 2025-09-07 00:00:21.904692 | debian-bookworm | Product Name: OpenStack Nova 2025-09-07 00:00:21.904711 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe22:db42 2025-09-07 00:00:21.918393 | 2025-09-07 00:00:21.918491 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-07 00:00:22.910168 | debian-bookworm -> localhost | changed 2025-09-07 00:00:22.915922 | 2025-09-07 00:00:22.916010 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-07 00:00:24.874400 | debian-bookworm -> localhost | changed 2025-09-07 00:00:24.898065 | 2025-09-07 00:00:24.898195 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-07 00:00:25.383127 | debian-bookworm -> localhost | ok 2025-09-07 00:00:25.388789 | 2025-09-07 00:00:25.388891 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-07 00:00:25.456966 | debian-bookworm | ok 2025-09-07 00:00:25.506926 | debian-bookworm | included: /var/lib/zuul/builds/163a33c553064cb5b27515434246e990/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-07 00:00:25.535556 | 2025-09-07 00:00:25.535672 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-07 00:00:27.451764 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-07 00:00:27.451929 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/163a33c553064cb5b27515434246e990/work/163a33c553064cb5b27515434246e990_id_rsa 2025-09-07 00:00:27.451959 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/163a33c553064cb5b27515434246e990/work/163a33c553064cb5b27515434246e990_id_rsa.pub 2025-09-07 00:00:27.451980 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-07 00:00:27.451999 | debian-bookworm -> localhost | SHA256:79pjCr3hmFQV+rEUdITfD7cBkPVEdgmMlIlvSKvZkUE zuul-build-sshkey 2025-09-07 00:00:27.452024 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-07 00:00:27.452042 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-07 00:00:27.452059 | debian-bookworm -> localhost | | .E*=@oo+o| 2025-09-07 00:00:27.452076 | debian-bookworm -> localhost | | =.O o+..| 2025-09-07 00:00:27.452117 | debian-bookworm -> localhost | | o X. ... | 2025-09-07 00:00:27.452135 | debian-bookworm -> localhost | | O =. o..| 2025-09-07 00:00:27.452151 | debian-bookworm -> localhost | | S = +o| 2025-09-07 00:00:27.452172 | debian-bookworm -> localhost | | = o ..| 2025-09-07 00:00:27.452189 | debian-bookworm -> localhost | | o o . | 2025-09-07 00:00:27.452205 | debian-bookworm -> localhost | | . = =o | 2025-09-07 00:00:27.452222 | debian-bookworm -> localhost | | o =+o. | 2025-09-07 00:00:27.452238 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-07 00:00:27.452281 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.923224 2025-09-07 00:00:27.457461 | 2025-09-07 00:00:27.457549 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-07 00:00:27.493964 | debian-bookworm | ok 2025-09-07 00:00:27.506498 | debian-bookworm | included: /var/lib/zuul/builds/163a33c553064cb5b27515434246e990/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-07 00:00:27.523554 | 2025-09-07 00:00:27.523645 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-07 00:00:27.546334 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:00:27.551873 | 2025-09-07 00:00:27.551956 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-07 00:00:28.471728 | debian-bookworm | changed 2025-09-07 00:00:28.477134 | 2025-09-07 00:00:28.477232 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-07 00:00:28.738417 | debian-bookworm | ok 2025-09-07 00:00:28.744704 | 2025-09-07 00:00:28.744800 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-07 00:00:29.524402 | debian-bookworm | changed 2025-09-07 00:00:29.542925 | 2025-09-07 00:00:29.543044 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-07 00:00:30.263047 | debian-bookworm | changed 2025-09-07 00:00:30.268398 | 2025-09-07 00:00:30.268495 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-07 00:00:30.317764 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:00:30.324236 | 2025-09-07 00:00:30.324339 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-07 00:00:31.208201 | debian-bookworm -> localhost | changed 2025-09-07 00:00:31.220871 | 2025-09-07 00:00:31.220986 | TASK [add-build-sshkey : Add back temp key] 2025-09-07 00:00:31.890572 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/163a33c553064cb5b27515434246e990/work/163a33c553064cb5b27515434246e990_id_rsa (zuul-build-sshkey) 2025-09-07 00:00:31.890791 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016766 2025-09-07 00:00:31.898492 | 2025-09-07 00:00:31.898588 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-07 00:00:32.598713 | debian-bookworm | ok 2025-09-07 00:00:32.611067 | 2025-09-07 00:00:32.611180 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-07 00:00:32.671950 | debian-bookworm | skipping: Conditional result was False 2025-09-07 00:00:32.682155 | 2025-09-07 00:00:32.682259 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-07 00:00:33.493278 | debian-bookworm | ok 2025-09-07 00:00:33.521369 | 2025-09-07 00:00:33.521462 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-07 00:00:33.568026 | debian-bookworm | ok 2025-09-07 00:00:33.572102 | 2025-09-07 00:00:33.572183 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-07 00:00:34.035069 | debian-bookworm -> localhost | ok 2025-09-07 00:00:34.040435 | 2025-09-07 00:00:34.040533 | TASK [validate-host : Collect information about the host] 2025-09-07 00:00:35.233150 | debian-bookworm | ok 2025-09-07 00:00:35.241323 | 2025-09-07 00:00:35.241403 | TASK [validate-host : Sanitize hostname] 2025-09-07 00:00:35.319171 | debian-bookworm | ok 2025-09-07 00:00:35.345365 | 2025-09-07 00:00:35.345451 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-07 00:00:35.824956 | debian-bookworm -> localhost | changed 2025-09-07 00:00:35.829547 | 2025-09-07 00:00:35.829666 | TASK [validate-host : Collect information about zuul worker] 2025-09-07 00:00:36.252854 | debian-bookworm | ok 2025-09-07 00:00:36.257709 | 2025-09-07 00:00:36.257804 | TASK [validate-host : Write out all zuul information for each host] 2025-09-07 00:00:37.097451 | debian-bookworm -> localhost | changed 2025-09-07 00:00:37.106999 | 2025-09-07 00:00:37.107122 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-07 00:00:37.369663 | debian-bookworm | ok 2025-09-07 00:00:37.375133 | 2025-09-07 00:00:37.375227 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-07 00:00:38.624596 | debian-bookworm | changed: 2025-09-07 00:00:38.624752 | debian-bookworm | cd+++++++++ src/ 2025-09-07 00:00:38.624787 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-07 00:00:38.624810 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-07 00:00:38.624831 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-07 00:00:38.624851 | debian-bookworm | Containerfile 2025-09-07 00:00:38.624979 | debian-bookworm | 3.5.2-r0) 2025-09-07 00:01:44.172417 | debian-bookworm | #9 7.054 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-07 00:01:44.416466 | debian-bookworm | #9 7.085 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-07 00:01:44.416599 | debian-bookworm | #9 7.130 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-07 00:01:44.416648 | debian-bookworm | #9 7.148 (28/34) Installing python3 (3.12.11-r0) 2025-09-07 00:01:44.705676 | debian-bookworm | #9 7.588 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-07 00:01:44.946509 | debian-bookworm | #9 7.829 (30/34) Installing pyc (3.12.11-r0) 2025-09-07 00:01:44.946603 | debian-bookworm | #9 7.829 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-07 00:01:44.946617 | debian-bookworm | #9 7.829 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-07 00:01:45.660635 | debian-bookworm | #9 8.543 (33/34) Installing yq-go (4.46.1-r1) 2025-09-07 00:01:45.858239 | debian-bookworm | #9 8.741 (34/34) Installing .build-deps (20250907.000139) 2025-09-07 00:01:46.070670 | debian-bookworm | #9 8.742 Executing busybox-1.37.0-r18.trigger 2025-09-07 00:01:46.070755 | debian-bookworm | #9 8.748 Executing ca-certificates-20250619-r0.trigger 2025-09-07 00:01:46.070780 | debian-bookworm | #9 8.802 OK: 359 MiB in 82 packages 2025-09-07 00:01:46.477434 | debian-bookworm | #9 DONE 9.4s 2025-09-07 00:01:46.627528 | debian-bookworm | 2025-09-07 00:01:46.627625 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-07 00:01:46.627636 | debian-bookworm | #10 DONE 0.1s 2025-09-07 00:01:46.728722 | debian-bookworm | 2025-09-07 00:01:46.728904 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-07 00:01:46.728999 | debian-bookworm | #11 DONE 0.1s 2025-09-07 00:01:46.870122 | debian-bookworm | 2025-09-07 00:01:46.870228 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-07 00:01:46.870245 | debian-bookworm | #12 DONE 0.1s 2025-09-07 00:01:47.026427 | debian-bookworm | 2025-09-07 00:01:47.026550 | 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-07 00:01:47.677820 | debian-bookworm | #13 0.802 Using Python 3.13.7 environment at: /usr/local 2025-09-07 00:01:48.026734 | debian-bookworm | #13 1.151 Resolved 17 packages in 345ms 2025-09-07 00:01:48.218920 | debian-bookworm | #13 1.190 Downloading netaddr (2.2MiB) 2025-09-07 00:01:48.218987 | debian-bookworm | #13 1.191 Downloading cryptography (4.4MiB) 2025-09-07 00:01:48.219005 | debian-bookworm | #13 1.193 Downloading ansible-core (2.3MiB) 2025-09-07 00:01:48.543831 | debian-bookworm | #13 1.566 Downloading netaddr 2025-09-07 00:01:48.543932 | debian-bookworm | #13 1.609 Downloading ansible-core 2025-09-07 00:01:48.543947 | debian-bookworm | #13 1.621 Downloading cryptography 2025-09-07 00:01:48.543957 | debian-bookworm | #13 1.622 Prepared 17 packages in 469ms 2025-09-07 00:01:48.543972 | debian-bookworm | #13 1.668 Installed 17 packages in 45ms 2025-09-07 00:01:48.699416 | debian-bookworm | #13 1.669 + ansible-core==2.19.1 2025-09-07 00:01:48.699917 | debian-bookworm | #13 1.669 + certifi==2025.8.3 2025-09-07 00:01:48.699941 | debian-bookworm | #13 1.669 + cffi==1.17.1 2025-09-07 00:01:48.699954 | debian-bookworm | #13 1.669 + charset-normalizer==3.4.3 2025-09-07 00:01:48.699966 | debian-bookworm | #13 1.669 + cryptography==45.0.7 2025-09-07 00:01:48.699977 | debian-bookworm | #13 1.669 + dynaconf==3.2.11 2025-09-07 00:01:48.699989 | debian-bookworm | #13 1.669 + idna==3.10 2025-09-07 00:01:48.700000 | debian-bookworm | #13 1.669 + jinja2==3.1.6 2025-09-07 00:01:48.700011 | debian-bookworm | #13 1.669 + markupsafe==3.0.2 2025-09-07 00:01:48.700022 | debian-bookworm | #13 1.670 + netaddr==1.3.0 2025-09-07 00:01:48.700033 | debian-bookworm | #13 1.670 + packaging==25.0 2025-09-07 00:01:48.700044 | debian-bookworm | #13 1.670 + pycparser==2.22 2025-09-07 00:01:48.700055 | debian-bookworm | #13 1.670 + pynetbox==7.5.0 2025-09-07 00:01:48.700066 | debian-bookworm | #13 1.670 + pyyaml==6.0.2 2025-09-07 00:01:48.700077 | debian-bookworm | #13 1.670 + requests==2.32.5 2025-09-07 00:01:48.700088 | debian-bookworm | #13 1.670 + resolvelib==1.2.0 2025-09-07 00:01:48.700099 | debian-bookworm | #13 1.672 + urllib3==2.5.0 2025-09-07 00:01:48.720110 | debian-bookworm | #13 DONE 1.8s 2025-09-07 00:01:48.877210 | debian-bookworm | 2025-09-07 00:01:48.877334 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.004117 2025-09-07 00:03:20.394503 | 2025-09-07 00:03:20.394616 | TASK [upload-logs : Upload logs to log server] 2025-09-07 00:03:20.967685 | localhost | Output suppressed because no_log was given 2025-09-07 00:03:20.971226 | 2025-09-07 00:03:20.971407 | LOOP [upload-logs : Compress console log and json output] 2025-09-07 00:03:21.029520 | localhost | skipping: Conditional result was False 2025-09-07 00:03:21.035242 | localhost | skipping: Conditional result was False 2025-09-07 00:03:21.041538 | 2025-09-07 00:03:21.041732 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-07 00:03:21.103064 | localhost | skipping: Conditional result was False 2025-09-07 00:03:21.103630 | 2025-09-07 00:03:21.107497 | localhost | skipping: Conditional result was False 2025-09-07 00:03:21.114453 | 2025-09-07 00:03:21.114664 | LOOP [upload-logs : Upload console log and json output]