2025-09-23 14:05:41.666496 | Job console starting 2025-09-23 14:05:41.677090 | Updating git repos 2025-09-23 14:05:41.709526 | Cloning repos into workspace 2025-09-23 14:05:41.740862 | Restoring repo states 2025-09-23 14:05:41.748443 | Merging changes 2025-09-23 14:05:41.748461 | Checking out repos 2025-09-23 14:05:41.787965 | Preparing playbooks 2025-09-23 14:05:42.448298 | Running Ansible setup 2025-09-23 14:05:46.589236 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 14:05:47.345134 | 2025-09-23 14:05:47.345290 | PLAY [Base pre] 2025-09-23 14:05:47.361482 | 2025-09-23 14:05:47.361621 | TASK [Setup log path fact] 2025-09-23 14:05:47.391648 | debian-bookworm | ok 2025-09-23 14:05:47.406389 | 2025-09-23 14:05:47.406522 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 14:05:47.446524 | debian-bookworm | ok 2025-09-23 14:05:47.456659 | 2025-09-23 14:05:47.456792 | TASK [emit-job-header : Print job information] 2025-09-23 14:05:47.507501 | # Job Information 2025-09-23 14:05:47.507740 | Ansible Version: 2.16.14 2025-09-23 14:05:47.507776 | Job: defaults-container-image-inventory-reconciler-push 2025-09-23 14:05:47.507809 | Pipeline: post 2025-09-23 14:05:47.507832 | Executor: 521e9411259a 2025-09-23 14:05:47.507853 | Triggered by: https://github.com/osism/defaults/commit/79b037dbd7cbb1549e2fe77d03cbca0a3b15fed5 2025-09-23 14:05:47.507875 | Event ID: 5ff35d5c-9886-11f0-8394-ef1c93bf4388 2025-09-23 14:05:47.512826 | 2025-09-23 14:05:47.512934 | LOOP [emit-job-header : Print node information] 2025-09-23 14:05:47.627393 | debian-bookworm | ok: 2025-09-23 14:05:47.627751 | debian-bookworm | # Node Information 2025-09-23 14:05:47.627816 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 14:05:47.627862 | debian-bookworm | Hostname: debian 2025-09-23 14:05:47.627900 | debian-bookworm | Username: zuul 2025-09-23 14:05:47.627936 | debian-bookworm | Distro: Debian 12.12 2025-09-23 14:05:47.627980 | debian-bookworm | Provider: regiocloud-a 2025-09-23 14:05:47.628017 | debian-bookworm | Region: 2025-09-23 14:05:47.628053 | debian-bookworm | Label: debian-bookworm 2025-09-23 14:05:47.628089 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 14:05:47.628124 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea5:1fbe 2025-09-23 14:05:47.654230 | 2025-09-23 14:05:47.654363 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 14:05:48.117168 | debian-bookworm -> localhost | changed 2025-09-23 14:05:48.130291 | 2025-09-23 14:05:48.130438 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 14:05:49.219083 | debian-bookworm -> localhost | changed 2025-09-23 14:05:49.230257 | 2025-09-23 14:05:49.230370 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 14:05:49.518042 | debian-bookworm -> localhost | ok 2025-09-23 14:05:49.532842 | 2025-09-23 14:05:49.533006 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 14:05:49.566781 | debian-bookworm | ok 2025-09-23 14:05:49.588016 | debian-bookworm | included: /var/lib/zuul/builds/d8fbae2e338a4d9bad538bafec6ed6b2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 14:05:49.595200 | 2025-09-23 14:05:49.595311 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 14:05:50.452553 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 14:05:50.452859 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d8fbae2e338a4d9bad538bafec6ed6b2/work/d8fbae2e338a4d9bad538bafec6ed6b2_id_rsa 2025-09-23 14:05:50.452916 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d8fbae2e338a4d9bad538bafec6ed6b2/work/d8fbae2e338a4d9bad538bafec6ed6b2_id_rsa.pub 2025-09-23 14:05:50.452951 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 14:05:50.452982 | debian-bookworm -> localhost | SHA256:tGjVjXha0NWoCR5WGT3W4OC1kyukKLu4rBbIEliG2Rg zuul-build-sshkey 2025-09-23 14:05:50.453024 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 14:05:50.453054 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 14:05:50.453082 | debian-bookworm -> localhost | |E* .o+=+= | 2025-09-23 14:05:50.453110 | debian-bookworm -> localhost | |+ + +=o*=oo | 2025-09-23 14:05:50.453137 | debian-bookworm -> localhost | |.o o+oB+*. | 2025-09-23 14:05:50.453163 | debian-bookworm -> localhost | |o =.Bo o | 2025-09-23 14:05:50.453190 | debian-bookworm -> localhost | |o. . + S . . | 2025-09-23 14:05:50.453230 | debian-bookworm -> localhost | |oo + . | 2025-09-23 14:05:50.453259 | debian-bookworm -> localhost | |. . . | 2025-09-23 14:05:50.453288 | debian-bookworm -> localhost | | o . . | 2025-09-23 14:05:50.453317 | debian-bookworm -> localhost | |o.+.. | 2025-09-23 14:05:50.453344 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 14:05:50.453414 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.376393 2025-09-23 14:05:50.461848 | 2025-09-23 14:05:50.461968 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 14:05:50.496868 | debian-bookworm | ok 2025-09-23 14:05:50.509690 | debian-bookworm | included: /var/lib/zuul/builds/d8fbae2e338a4d9bad538bafec6ed6b2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 14:05:50.519232 | 2025-09-23 14:05:50.519336 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 14:05:50.543002 | debian-bookworm | skipping: Conditional result was False 2025-09-23 14:05:50.549469 | 2025-09-23 14:05:50.549595 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 14:05:51.195409 | debian-bookworm | changed 2025-09-23 14:05:51.204265 | 2025-09-23 14:05:51.204396 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 14:05:51.473602 | debian-bookworm | ok 2025-09-23 14:05:51.482176 | 2025-09-23 14:05:51.482308 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 14:05:52.197157 | debian-bookworm | changed 2025-09-23 14:05:52.206692 | 2025-09-23 14:05:52.206822 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 14:05:52.900745 | debian-bookworm | changed 2025-09-23 14:05:52.909395 | 2025-09-23 14:05:52.909556 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 14:05:52.933715 | debian-bookworm | skipping: Conditional result was False 2025-09-23 14:05:52.945015 | 2025-09-23 14:05:52.945168 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 14:05:53.394478 | debian-bookworm -> localhost | changed 2025-09-23 14:05:53.405701 | 2025-09-23 14:05:53.405819 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 14:05:53.753628 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d8fbae2e338a4d9bad538bafec6ed6b2/work/d8fbae2e338a4d9bad538bafec6ed6b2_id_rsa (zuul-build-sshkey) 2025-09-23 14:05:53.754184 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020323 2025-09-23 14:05:53.771470 | 2025-09-23 14:05:53.771679 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 14:05:54.173936 | debian-bookworm | ok 2025-09-23 14:05:54.182940 | 2025-09-23 14:05:54.183088 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 14:05:54.217516 | debian-bookworm | skipping: Conditional result was False 2025-09-23 14:05:54.237683 | 2025-09-23 14:05:54.237806 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 14:05:54.670984 | debian-bookworm | ok 2025-09-23 14:05:54.686649 | 2025-09-23 14:05:54.686793 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 14:05:54.734183 | debian-bookworm | ok 2025-09-23 14:05:54.742818 | 2025-09-23 14:05:54.742988 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 14:05:55.032051 | debian-bookworm -> localhost | ok 2025-09-23 14:05:55.043830 | 2025-09-23 14:05:55.043954 | TASK [validate-host : Collect information about the host] 2025-09-23 14:05:56.157720 | debian-bookworm | ok 2025-09-23 14:05:56.175372 | 2025-09-23 14:05:56.175496 | TASK [validate-host : Sanitize hostname] 2025-09-23 14:05:56.293920 | debian-bookworm | ok 2025-09-23 14:05:56.332815 | 2025-09-23 14:05:56.332946 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 14:05:56.905733 | debian-bookworm -> localhost | changed 2025-09-23 14:05:56.919399 | 2025-09-23 14:05:56.919599 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 14:05:57.344637 | debian-bookworm | ok 2025-09-23 14:05:57.354457 | 2025-09-23 14:05:57.354654 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 14:05:57.907097 | debian-bookworm -> localhost | changed 2025-09-23 14:05:57.924876 | 2025-09-23 14:05:57.925013 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 14:05:58.213819 | debian-bookworm | ok 2025-09-23 14:05:58.223109 | 2025-09-23 14:05:58.223254 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 14:05:59.060219 | debian-bookworm | changed: 2025-09-23 14:05:59.060512 | debian-bookworm | cd+++++++++ src/ 2025-09-23 14:05:59.060590 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 14:05:59.060632 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 14:05:59.060670 | debian-bookworm | cd+++++++++ src/github.com/osism/defaults/ 2025-09-23 14:05:59.060705 | debian-bookworm | 3.5.2-r0) 2025-09-23 14:07:04.406780 | debian-bookworm | #9 6.474 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-23 14:07:04.522877 | debian-bookworm | #9 6.513 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-23 14:07:04.522994 | debian-bookworm | #9 6.568 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-23 14:07:04.523010 | debian-bookworm | #9 6.590 (28/34) Installing python3 (3.12.11-r0) 2025-09-23 14:07:04.810481 | debian-bookworm | #9 6.878 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-23 14:07:04.977194 | debian-bookworm | #9 7.045 (30/34) Installing pyc (3.12.11-r0) 2025-09-23 14:07:04.977270 | debian-bookworm | #9 7.045 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-23 14:07:04.977278 | debian-bookworm | #9 7.045 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-23 14:07:05.446053 | debian-bookworm | #9 7.514 (33/34) Installing yq-go (4.47.2-r0) 2025-09-23 14:07:05.593773 | debian-bookworm | #9 7.661 (34/34) Installing .build-deps (20250923.140700) 2025-09-23 14:07:05.816167 | debian-bookworm | #9 7.664 Executing busybox-1.37.0-r18.trigger 2025-09-23 14:07:05.816235 | debian-bookworm | #9 7.672 Executing ca-certificates-20250619-r0.trigger 2025-09-23 14:07:05.816252 | debian-bookworm | #9 7.733 OK: 359 MiB in 82 packages 2025-09-23 14:07:06.126208 | debian-bookworm | #9 DONE 8.2s 2025-09-23 14:07:06.272254 | debian-bookworm | 2025-09-23 14:07:06.272359 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-23 14:07:06.272373 | debian-bookworm | #10 DONE 0.1s 2025-09-23 14:07:06.272431 | debian-bookworm | 2025-09-23 14:07:06.272441 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-23 14:07:06.272452 | debian-bookworm | #11 DONE 0.1s 2025-09-23 14:07:06.502175 | debian-bookworm | 2025-09-23 14:07:06.502275 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-23 14:07:06.502287 | debian-bookworm | #12 DONE 0.1s 2025-09-23 14:07:06.502295 | debian-bookworm | 2025-09-23 14:07:06.502303 | 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-23 14:07:07.269459 | debian-bookworm | #13 0.767 Using Python 3.13.7 environment at: /usr/local 2025-09-23 14:07:07.467004 | debian-bookworm | #13 1.116 Resolved 17 packages in 345ms 2025-09-23 14:07:07.669025 | debian-bookworm | #13 1.163 Downloading cryptography (4.5MiB) 2025-09-23 14:07:07.669104 | debian-bookworm | #13 1.165 Downloading ansible-core (2.3MiB) 2025-09-23 14:07:07.669110 | debian-bookworm | #13 1.167 Downloading netaddr (2.2MiB) 2025-09-23 14:07:08.036175 | debian-bookworm | #13 1.437 Downloading netaddr 2025-09-23 14:07:08.036250 | debian-bookworm | #13 1.534 Downloading cryptography 2025-09-23 14:07:08.091062 | debian-bookworm | #13 1.740 Downloading ansible-core 2025-09-23 14:07:08.305958 | debian-bookworm | #13 1.742 Prepared 17 packages in 623ms 2025-09-23 14:07:08.306067 | debian-bookworm | #13 1.802 Installed 17 packages in 59ms 2025-09-23 14:07:08.306083 | debian-bookworm | #13 1.802 + ansible-core==2.19.2 2025-09-23 14:07:08.306097 | debian-bookworm | #13 1.803 + certifi==2025.8.3 2025-09-23 14:07:08.306109 | debian-bookworm | #13 1.803 + cffi==2.0.0 2025-09-23 14:07:08.306121 | debian-bookworm | #13 1.803 + charset-normalizer==3.4.3 2025-09-23 14:07:08.306151 | debian-bookworm | #13 1.803 + cryptography==46.0.1 2025-09-23 14:07:08.306163 | debian-bookworm | #13 1.803 + dynaconf==3.2.11 2025-09-23 14:07:08.306174 | debian-bookworm | #13 1.803 + idna==3.10 2025-09-23 14:07:08.306185 | debian-bookworm | #13 1.803 + jinja2==3.1.6 2025-09-23 14:07:08.306196 | debian-bookworm | #13 1.804 + markupsafe==3.0.2 2025-09-23 14:07:08.306207 | debian-bookworm | #13 1.804 + netaddr==1.3.0 2025-09-23 14:07:08.306218 | debian-bookworm | #13 1.804 + packaging==25.0 2025-09-23 14:07:08.306228 | debian-bookworm | #13 1.804 + pycparser==2.23 2025-09-23 14:07:08.306239 | debian-bookworm | #13 1.804 + pynetbox==7.5.0 2025-09-23 14:07:08.306250 | debian-bookworm | #13 1.804 + pyyaml==6.0.2 2025-09-23 14:07:08.306261 | debian-bookworm | #13 1.805 + requests==2.32.5 2025-09-23 14:07:08.306272 | debian-bookworm | #13 1.805 + resolvelib==1.2.0 2025-09-23 14:07:08.306283 | debian-bookworm | #13 1.805 + urllib3==2.5.0 2025-09-23 14:07:08.319508 | debian-bookworm | #13 DONE 2.0s 2025-09-23 14:07:08.478869 | debian-bookworm | 2025-09-23 14:07:08.478956 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.004115 2025-09-23 14:08:46.407260 | 2025-09-23 14:08:46.407445 | TASK [upload-logs : Upload logs to log server] 2025-09-23 14:08:46.967994 | localhost | Output suppressed because no_log was given 2025-09-23 14:08:46.972743 | 2025-09-23 14:08:46.972931 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 14:08:47.028740 | localhost | skipping: Conditional result was False 2025-09-23 14:08:47.034120 | localhost | skipping: Conditional result was False 2025-09-23 14:08:47.046721 | 2025-09-23 14:08:47.046999 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 14:08:47.092929 | localhost | skipping: Conditional result was False 2025-09-23 14:08:47.093527 | 2025-09-23 14:08:47.097083 | localhost | skipping: Conditional result was False 2025-09-23 14:08:47.110783 | 2025-09-23 14:08:47.111049 | LOOP [upload-logs : Upload console log and json output]