2025-09-03 00:00:17.233021 | Job console starting 2025-09-03 00:00:17.240954 | Updating git repos 2025-09-03 00:00:17.294190 | Cloning repos into workspace 2025-09-03 00:00:17.442476 | Restoring repo states 2025-09-03 00:00:17.463785 | Merging changes 2025-09-03 00:00:17.463803 | Checking out repos 2025-09-03 00:00:17.627781 | Preparing playbooks 2025-09-03 00:00:18.580526 | Running Ansible setup 2025-09-03 00:00:25.478107 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-03 00:00:26.865367 | 2025-09-03 00:00:26.865479 | PLAY [Base pre] 2025-09-03 00:00:26.888230 | 2025-09-03 00:00:26.888336 | TASK [Setup log path fact] 2025-09-03 00:00:26.925842 | debian-bookworm | ok 2025-09-03 00:00:26.941897 | 2025-09-03 00:00:26.942014 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-03 00:00:27.022221 | debian-bookworm | ok 2025-09-03 00:00:27.030100 | 2025-09-03 00:00:27.030189 | TASK [emit-job-header : Print job information] 2025-09-03 00:00:27.092875 | # Job Information 2025-09-03 00:00:27.093062 | Ansible Version: 2.16.14 2025-09-03 00:00:27.093090 | Job: container-image-inventory-reconciler-push 2025-09-03 00:00:27.093118 | Pipeline: periodic-midnight 2025-09-03 00:00:27.093137 | Executor: 521e9411259a 2025-09-03 00:00:27.093154 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-03 00:00:27.093171 | Event ID: 1454715a444c42a389131205dde6c649 2025-09-03 00:00:27.097288 | 2025-09-03 00:00:27.097365 | LOOP [emit-job-header : Print node information] 2025-09-03 00:00:27.302856 | debian-bookworm | ok: 2025-09-03 00:00:27.303037 | debian-bookworm | # Node Information 2025-09-03 00:00:27.303064 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-03 00:00:27.303085 | debian-bookworm | Hostname: debian 2025-09-03 00:00:27.303103 | debian-bookworm | Username: zuul 2025-09-03 00:00:27.303120 | debian-bookworm | Distro: Debian 12.11 2025-09-03 00:00:27.303141 | debian-bookworm | Provider: regiocloud-a 2025-09-03 00:00:27.303159 | debian-bookworm | Region: 2025-09-03 00:00:27.303176 | debian-bookworm | Label: debian-bookworm 2025-09-03 00:00:27.303193 | debian-bookworm | Product Name: OpenStack Nova 2025-09-03 00:00:27.303209 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8f:d5c2 2025-09-03 00:00:27.322250 | 2025-09-03 00:00:27.322351 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-03 00:00:28.377047 | debian-bookworm -> localhost | changed 2025-09-03 00:00:28.382803 | 2025-09-03 00:00:28.382918 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-03 00:00:30.161791 | debian-bookworm -> localhost | changed 2025-09-03 00:00:30.171189 | 2025-09-03 00:00:30.171283 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-03 00:00:30.668551 | debian-bookworm -> localhost | ok 2025-09-03 00:00:30.673594 | 2025-09-03 00:00:30.673679 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-03 00:00:30.710776 | debian-bookworm | ok 2025-09-03 00:00:30.739459 | debian-bookworm | included: /var/lib/zuul/builds/7243029c24444f35ba3c214ba1f99304/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-03 00:00:30.760866 | 2025-09-03 00:00:30.760966 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-03 00:00:33.465726 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-03 00:00:33.465892 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7243029c24444f35ba3c214ba1f99304/work/7243029c24444f35ba3c214ba1f99304_id_rsa 2025-09-03 00:00:33.465924 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7243029c24444f35ba3c214ba1f99304/work/7243029c24444f35ba3c214ba1f99304_id_rsa.pub 2025-09-03 00:00:33.465947 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-03 00:00:33.465966 | debian-bookworm -> localhost | SHA256:vLiNqTZbT+R60YD+Qdo3bvsW08fw2foYlTV8qcXU7qw zuul-build-sshkey 2025-09-03 00:00:33.465995 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-03 00:00:33.466014 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-03 00:00:33.466032 | debian-bookworm -> localhost | | ..| 2025-09-03 00:00:33.466050 | debian-bookworm -> localhost | | + o| 2025-09-03 00:00:33.466067 | debian-bookworm -> localhost | | . Bo| 2025-09-03 00:00:33.466084 | debian-bookworm -> localhost | | ..o + *| 2025-09-03 00:00:33.466100 | debian-bookworm -> localhost | | . +So o B+| 2025-09-03 00:00:33.466120 | debian-bookworm -> localhost | | o++.+ o .oB| 2025-09-03 00:00:33.466137 | debian-bookworm -> localhost | | o.+= . o.+ | 2025-09-03 00:00:33.466154 | debian-bookworm -> localhost | | o. Oo o . Eo | 2025-09-03 00:00:33.466171 | debian-bookworm -> localhost | | .o+=.o..+. ...| 2025-09-03 00:00:33.466187 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-03 00:00:33.466226 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.597887 2025-09-03 00:00:33.471591 | 2025-09-03 00:00:33.471681 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-03 00:00:33.513607 | debian-bookworm | ok 2025-09-03 00:00:33.544041 | debian-bookworm | included: /var/lib/zuul/builds/7243029c24444f35ba3c214ba1f99304/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-03 00:00:33.566650 | 2025-09-03 00:00:33.566756 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-03 00:00:33.622895 | debian-bookworm | skipping: Conditional result was False 2025-09-03 00:00:33.627653 | 2025-09-03 00:00:33.627742 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-03 00:00:34.356544 | debian-bookworm | changed 2025-09-03 00:00:34.361289 | 2025-09-03 00:00:34.361366 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-03 00:00:34.665047 | debian-bookworm | ok 2025-09-03 00:00:34.672694 | 2025-09-03 00:00:34.672783 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-03 00:00:35.425524 | debian-bookworm | changed 2025-09-03 00:00:35.432375 | 2025-09-03 00:00:35.432477 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-03 00:00:36.180342 | debian-bookworm | changed 2025-09-03 00:00:36.187069 | 2025-09-03 00:00:36.187173 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-03 00:00:36.230373 | debian-bookworm | skipping: Conditional result was False 2025-09-03 00:00:36.236429 | 2025-09-03 00:00:36.236583 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-03 00:00:37.565457 | debian-bookworm -> localhost | changed 2025-09-03 00:00:37.577361 | 2025-09-03 00:00:37.577472 | TASK [add-build-sshkey : Add back temp key] 2025-09-03 00:00:38.417725 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7243029c24444f35ba3c214ba1f99304/work/7243029c24444f35ba3c214ba1f99304_id_rsa (zuul-build-sshkey) 2025-09-03 00:00:38.417943 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.024265 2025-09-03 00:00:38.425472 | 2025-09-03 00:00:38.425647 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-03 00:00:39.039392 | debian-bookworm | ok 2025-09-03 00:00:39.058994 | 2025-09-03 00:00:39.059112 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-03 00:00:39.082174 | debian-bookworm | skipping: Conditional result was False 2025-09-03 00:00:39.092397 | 2025-09-03 00:00:39.092503 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-03 00:00:39.708653 | debian-bookworm | ok 2025-09-03 00:00:39.720678 | 2025-09-03 00:00:39.720778 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-03 00:00:39.754546 | debian-bookworm | ok 2025-09-03 00:00:39.765429 | 2025-09-03 00:00:39.765859 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-03 00:00:40.199179 | debian-bookworm -> localhost | ok 2025-09-03 00:00:40.204500 | 2025-09-03 00:00:40.204619 | TASK [validate-host : Collect information about the host] 2025-09-03 00:00:41.523629 | debian-bookworm | ok 2025-09-03 00:00:41.533663 | 2025-09-03 00:00:41.533807 | TASK [validate-host : Sanitize hostname] 2025-09-03 00:00:41.611451 | debian-bookworm | ok 2025-09-03 00:00:41.637032 | 2025-09-03 00:00:41.637120 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-03 00:00:42.437652 | debian-bookworm -> localhost | changed 2025-09-03 00:00:42.442742 | 2025-09-03 00:00:42.442820 | TASK [validate-host : Collect information about zuul worker] 2025-09-03 00:00:42.855225 | debian-bookworm | ok 2025-09-03 00:00:42.860704 | 2025-09-03 00:00:42.860808 | TASK [validate-host : Write out all zuul information for each host] 2025-09-03 00:00:43.720221 | debian-bookworm -> localhost | changed 2025-09-03 00:00:43.739053 | 2025-09-03 00:00:43.739153 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-03 00:00:44.043211 | debian-bookworm | ok 2025-09-03 00:00:44.059799 | 2025-09-03 00:00:44.059899 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-03 00:00:45.023156 | debian-bookworm | changed: 2025-09-03 00:00:45.023344 | debian-bookworm | cd+++++++++ src/ 2025-09-03 00:00:45.023382 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-03 00:00:45.023408 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-03 00:00:45.023434 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-03 00:00:45.023456 | debian-bookworm | Containerfile 2025-09-03 00:00:45.023636 | debian-bookworm | 3.5.2-r0) 2025-09-03 00:01:48.332666 | debian-bookworm | #9 6.290 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-03 00:01:48.448028 | debian-bookworm | #9 6.322 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-03 00:01:48.448178 | debian-bookworm | #9 6.379 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-03 00:01:48.448209 | debian-bookworm | #9 6.405 (28/34) Installing python3 (3.12.11-r0) 2025-09-03 00:01:48.749276 | debian-bookworm | #9 6.707 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-03 00:01:48.914640 | debian-bookworm | #9 6.872 (30/34) Installing pyc (3.12.11-r0) 2025-09-03 00:01:48.914794 | debian-bookworm | #9 6.872 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-03 00:01:48.914847 | debian-bookworm | #9 6.872 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-03 00:01:49.430576 | debian-bookworm | #9 7.388 (33/34) Installing yq-go (4.46.1-r1) 2025-09-03 00:01:49.595798 | debian-bookworm | #9 7.553 (34/34) Installing .build-deps (20250903.000144) 2025-09-03 00:01:49.812458 | debian-bookworm | #9 7.555 Executing busybox-1.37.0-r18.trigger 2025-09-03 00:01:49.812513 | debian-bookworm | #9 7.562 Executing ca-certificates-20250619-r0.trigger 2025-09-03 00:01:49.812520 | debian-bookworm | #9 7.618 OK: 359 MiB in 82 packages 2025-09-03 00:01:50.152402 | debian-bookworm | #9 DONE 8.1s 2025-09-03 00:01:50.298810 | debian-bookworm | 2025-09-03 00:01:50.298937 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-03 00:01:50.298997 | debian-bookworm | #10 DONE 0.1s 2025-09-03 00:01:50.299033 | debian-bookworm | 2025-09-03 00:01:50.299069 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-03 00:01:50.299104 | debian-bookworm | #11 DONE 0.1s 2025-09-03 00:01:50.520889 | debian-bookworm | 2025-09-03 00:01:50.520972 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-03 00:01:50.520983 | debian-bookworm | #12 DONE 0.1s 2025-09-03 00:01:50.520991 | debian-bookworm | 2025-09-03 00:01:50.520999 | 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-03 00:01:51.283949 | debian-bookworm | #13 0.762 Using Python 3.13.7 environment at: /usr/local 2025-09-03 00:01:51.424544 | debian-bookworm | #13 1.054 Resolved 17 packages in 279ms 2025-09-03 00:01:51.607325 | debian-bookworm | #13 1.083 Downloading ansible-core (2.3MiB) 2025-09-03 00:01:51.607434 | debian-bookworm | #13 1.085 Downloading cryptography (4.4MiB) 2025-09-03 00:01:51.607461 | debian-bookworm | #13 1.086 Downloading netaddr (2.2MiB) 2025-09-03 00:01:51.760198 | debian-bookworm | #13 1.390 Downloading netaddr 2025-09-03 00:01:51.944904 | debian-bookworm | #13 1.490 Downloading cryptography 2025-09-03 00:01:52.142975 | debian-bookworm | #13 1.575 Downloading ansible-core 2025-09-03 00:01:52.143078 | debian-bookworm | #13 1.577 Prepared 17 packages in 521ms 2025-09-03 00:01:52.143091 | debian-bookworm | #13 1.622 Installed 17 packages in 44ms 2025-09-03 00:01:52.143102 | debian-bookworm | #13 1.622 + ansible-core==2.19.1 2025-09-03 00:01:52.143113 | debian-bookworm | #13 1.622 + certifi==2025.8.3 2025-09-03 00:01:52.143123 | debian-bookworm | #13 1.622 + cffi==1.17.1 2025-09-03 00:01:52.143133 | debian-bookworm | #13 1.622 + charset-normalizer==3.4.3 2025-09-03 00:01:52.143155 | debian-bookworm | #13 1.622 + cryptography==45.0.7 2025-09-03 00:01:52.143165 | debian-bookworm | #13 1.622 + dynaconf==3.2.11 2025-09-03 00:01:52.143175 | debian-bookworm | #13 1.622 + idna==3.10 2025-09-03 00:01:52.143184 | debian-bookworm | #13 1.622 + jinja2==3.1.6 2025-09-03 00:01:52.143194 | debian-bookworm | #13 1.622 + markupsafe==3.0.2 2025-09-03 00:01:52.143203 | debian-bookworm | #13 1.622 + netaddr==1.3.0 2025-09-03 00:01:52.143213 | debian-bookworm | #13 1.622 + packaging==25.0 2025-09-03 00:01:52.143223 | debian-bookworm | #13 1.622 + pycparser==2.22 2025-09-03 00:01:52.143233 | debian-bookworm | #13 1.622 + pynetbox==7.5.0 2025-09-03 00:01:52.143243 | debian-bookworm | #13 1.622 + pyyaml==6.0.2 2025-09-03 00:01:52.143255 | debian-bookworm | #13 1.622 + requests==2.32.5 2025-09-03 00:01:52.143264 | debian-bookworm | #13 1.622 + resolvelib==1.2.0 2025-09-03 00:01:52.143274 | debian-bookworm | #13 1.622 + urllib3==2.5.0 2025-09-03 00:01:52.191426 | debian-bookworm | #13 DONE 1.8s 2025-09-03 00:01:52.371608 | debian-bookworm | 2025-09-03 00:01:52.371782 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.008935 2025-09-03 00:03:21.137584 | 2025-09-03 00:03:21.137721 | TASK [upload-logs : Upload logs to log server] 2025-09-03 00:03:21.742218 | localhost | Output suppressed because no_log was given 2025-09-03 00:03:21.747076 | 2025-09-03 00:03:21.747450 | LOOP [upload-logs : Compress console log and json output] 2025-09-03 00:03:21.806956 | localhost | skipping: Conditional result was False 2025-09-03 00:03:21.833481 | localhost | skipping: Conditional result was False 2025-09-03 00:03:21.840312 | 2025-09-03 00:03:21.840493 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-03 00:03:21.904995 | localhost | skipping: Conditional result was False 2025-09-03 00:03:21.905306 | 2025-09-03 00:03:21.909496 | localhost | skipping: Conditional result was False 2025-09-03 00:03:21.914270 | 2025-09-03 00:03:21.914487 | LOOP [upload-logs : Upload console log and json output]