2025-07-04 17:08:59.325982 | Job console starting 2025-07-04 17:08:59.341689 | Updating git repos 2025-07-04 17:08:59.372422 | Cloning repos into workspace 2025-07-04 17:08:59.405775 | Restoring repo states 2025-07-04 17:08:59.416900 | Merging changes 2025-07-04 17:08:59.416926 | Checking out repos 2025-07-04 17:08:59.448352 | Preparing playbooks 2025-07-04 17:08:59.972562 | Running Ansible setup 2025-07-04 17:09:04.223702 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-04 17:09:05.008970 | 2025-07-04 17:09:05.009153 | PLAY [Base pre] 2025-07-04 17:09:05.026609 | 2025-07-04 17:09:05.026756 | TASK [Setup log path fact] 2025-07-04 17:09:05.056635 | debian-bookworm | ok 2025-07-04 17:09:05.072239 | 2025-07-04 17:09:05.072406 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-04 17:09:05.130513 | debian-bookworm | ok 2025-07-04 17:09:05.144664 | 2025-07-04 17:09:05.144854 | TASK [emit-job-header : Print job information] 2025-07-04 17:09:05.203359 | # Job Information 2025-07-04 17:09:05.203737 | Ansible Version: 2.16.14 2025-07-04 17:09:05.203872 | Job: defaults-container-image-inventory-reconciler-push 2025-07-04 17:09:05.203946 | Pipeline: post 2025-07-04 17:09:05.203996 | Executor: 521e9411259a 2025-07-04 17:09:05.204039 | Triggered by: https://github.com/osism/defaults/commit/2f0ba19341df5b8289f525bb52a85434427fee7f 2025-07-04 17:09:05.204083 | Event ID: 2d5254ba-58f9-11f0-81f0-47319ff165bd 2025-07-04 17:09:05.212489 | 2025-07-04 17:09:05.212659 | LOOP [emit-job-header : Print node information] 2025-07-04 17:09:05.328449 | debian-bookworm | ok: 2025-07-04 17:09:05.328735 | debian-bookworm | # Node Information 2025-07-04 17:09:05.328771 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-04 17:09:05.328810 | debian-bookworm | Hostname: debian 2025-07-04 17:09:05.328834 | debian-bookworm | Username: zuul 2025-07-04 17:09:05.328856 | debian-bookworm | Distro: Debian 12.11 2025-07-04 17:09:05.328888 | debian-bookworm | Provider: regiocloud-a 2025-07-04 17:09:05.328915 | debian-bookworm | Region: 2025-07-04 17:09:05.328936 | debian-bookworm | Label: debian-bookworm 2025-07-04 17:09:05.328955 | debian-bookworm | Product Name: OpenStack Nova 2025-07-04 17:09:05.328975 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee4:9bc8 2025-07-04 17:09:05.340712 | 2025-07-04 17:09:05.340872 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-04 17:09:05.845219 | debian-bookworm -> localhost | changed 2025-07-04 17:09:05.858881 | 2025-07-04 17:09:05.859046 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-04 17:09:07.032319 | debian-bookworm -> localhost | changed 2025-07-04 17:09:07.055434 | 2025-07-04 17:09:07.055754 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-04 17:09:07.390041 | debian-bookworm -> localhost | ok 2025-07-04 17:09:07.399513 | 2025-07-04 17:09:07.399820 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-04 17:09:07.433898 | debian-bookworm | ok 2025-07-04 17:09:07.462174 | debian-bookworm | included: /var/lib/zuul/builds/0c77a72202864305b4d036a6ccd7c69f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-04 17:09:07.471856 | 2025-07-04 17:09:07.471982 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-04 17:09:08.763092 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-04 17:09:08.763351 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0c77a72202864305b4d036a6ccd7c69f/work/0c77a72202864305b4d036a6ccd7c69f_id_rsa 2025-07-04 17:09:08.763394 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0c77a72202864305b4d036a6ccd7c69f/work/0c77a72202864305b4d036a6ccd7c69f_id_rsa.pub 2025-07-04 17:09:08.763423 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-04 17:09:08.763448 | debian-bookworm -> localhost | SHA256:qyX7Di8twAneTUjIlNvtjksN+bCU1UiPhKPHAYC3VG0 zuul-build-sshkey 2025-07-04 17:09:08.763485 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-04 17:09:08.763508 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-04 17:09:08.763529 | debian-bookworm -> localhost | |o+o=.oo | 2025-07-04 17:09:08.763551 | debian-bookworm -> localhost | |. * =oE= | 2025-07-04 17:09:08.763572 | debian-bookworm -> localhost | | o B *+ o | 2025-07-04 17:09:08.763592 | debian-bookworm -> localhost | | = =+o | 2025-07-04 17:09:08.763612 | debian-bookworm -> localhost | | . =*= S | 2025-07-04 17:09:08.763636 | debian-bookworm -> localhost | | ..=*o . | 2025-07-04 17:09:08.763656 | debian-bookworm -> localhost | | o+=.o | 2025-07-04 17:09:08.763676 | debian-bookworm -> localhost | | .. +B. | 2025-07-04 17:09:08.763696 | debian-bookworm -> localhost | | ..o=+ | 2025-07-04 17:09:08.763716 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-04 17:09:08.763765 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.779467 2025-07-04 17:09:08.770538 | 2025-07-04 17:09:08.770648 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-04 17:09:08.807833 | debian-bookworm | ok 2025-07-04 17:09:08.834790 | debian-bookworm | included: /var/lib/zuul/builds/0c77a72202864305b4d036a6ccd7c69f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-04 17:09:08.849991 | 2025-07-04 17:09:08.850113 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-04 17:09:08.874628 | debian-bookworm | skipping: Conditional result was False 2025-07-04 17:09:08.888671 | 2025-07-04 17:09:08.888856 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-04 17:09:09.570264 | debian-bookworm | changed 2025-07-04 17:09:09.576311 | 2025-07-04 17:09:09.576440 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-04 17:09:09.831518 | debian-bookworm | ok 2025-07-04 17:09:09.839375 | 2025-07-04 17:09:09.839674 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-04 17:09:10.527269 | debian-bookworm | changed 2025-07-04 17:09:10.537340 | 2025-07-04 17:09:10.537498 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-04 17:09:11.203024 | debian-bookworm | changed 2025-07-04 17:09:11.211690 | 2025-07-04 17:09:11.211887 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-04 17:09:11.236788 | debian-bookworm | skipping: Conditional result was False 2025-07-04 17:09:11.250633 | 2025-07-04 17:09:11.250895 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-04 17:09:11.822065 | debian-bookworm -> localhost | changed 2025-07-04 17:09:11.844034 | 2025-07-04 17:09:11.844262 | TASK [add-build-sshkey : Add back temp key] 2025-07-04 17:09:12.213577 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0c77a72202864305b4d036a6ccd7c69f/work/0c77a72202864305b4d036a6ccd7c69f_id_rsa (zuul-build-sshkey) 2025-07-04 17:09:12.213956 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021676 2025-07-04 17:09:12.225212 | 2025-07-04 17:09:12.225379 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-04 17:09:12.658937 | debian-bookworm | ok 2025-07-04 17:09:12.666753 | 2025-07-04 17:09:12.666933 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-04 17:09:12.703557 | debian-bookworm | skipping: Conditional result was False 2025-07-04 17:09:12.724624 | 2025-07-04 17:09:12.724788 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-04 17:09:13.179432 | debian-bookworm | ok 2025-07-04 17:09:13.195286 | 2025-07-04 17:09:13.195505 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-04 17:09:13.242148 | debian-bookworm | ok 2025-07-04 17:09:13.248959 | 2025-07-04 17:09:13.249095 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-04 17:09:13.558628 | debian-bookworm -> localhost | ok 2025-07-04 17:09:13.573009 | 2025-07-04 17:09:13.573166 | TASK [validate-host : Collect information about the host] 2025-07-04 17:09:14.743296 | debian-bookworm | ok 2025-07-04 17:09:14.756459 | 2025-07-04 17:09:14.756604 | TASK [validate-host : Sanitize hostname] 2025-07-04 17:09:14.867205 | debian-bookworm | ok 2025-07-04 17:09:14.916141 | 2025-07-04 17:09:14.916303 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-04 17:09:15.530123 | debian-bookworm -> localhost | changed 2025-07-04 17:09:15.543662 | 2025-07-04 17:09:15.543901 | TASK [validate-host : Collect information about zuul worker] 2025-07-04 17:09:15.969248 | debian-bookworm | ok 2025-07-04 17:09:15.977511 | 2025-07-04 17:09:15.977636 | TASK [validate-host : Write out all zuul information for each host] 2025-07-04 17:09:16.571706 | debian-bookworm -> localhost | changed 2025-07-04 17:09:16.586398 | 2025-07-04 17:09:16.586548 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-04 17:09:16.878996 | debian-bookworm | ok 2025-07-04 17:09:16.888650 | 2025-07-04 17:09:16.888784 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-04 17:09:17.749142 | debian-bookworm | changed: 2025-07-04 17:09:17.749449 | debian-bookworm | cd+++++++++ src/ 2025-07-04 17:09:17.749507 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-04 17:09:17.749548 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-04 17:09:17.749583 | debian-bookworm | cd+++++++++ src/github.com/osism/defaults/ 2025-07-04 17:09:17.749616 | debian-bookworm | 3.5.1-r0) 2025-07-04 17:10:19.818282 | debian-bookworm | #9 5.727 (25/34) Upgrading libssl3 (3.5.0-r0 -> 3.5.1-r0) 2025-07-04 17:10:20.061672 | debian-bookworm | #9 5.755 (26/34) Installing openssl-dev (3.5.1-r0) 2025-07-04 17:10:20.061760 | debian-bookworm | #9 5.799 (27/34) Installing mpdecimal (4.0.1-r0) 2025-07-04 17:10:20.061796 | debian-bookworm | #9 5.820 (28/34) Installing python3 (3.12.11-r0) 2025-07-04 17:10:20.198865 | debian-bookworm | #9 6.108 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-07-04 17:10:20.362310 | debian-bookworm | #9 6.270 (30/34) Installing pyc (3.12.11-r0) 2025-07-04 17:10:20.362364 | debian-bookworm | #9 6.270 (31/34) Installing python3-pyc (3.12.11-r0) 2025-07-04 17:10:20.362370 | debian-bookworm | #9 6.270 (32/34) Installing python3-dev (3.12.11-r0) 2025-07-04 17:10:20.809099 | debian-bookworm | #9 6.718 (33/34) Installing yq-go (4.45.4-r0) 2025-07-04 17:10:20.950647 | debian-bookworm | #9 6.860 (34/34) Installing .build-deps (20250704.171016) 2025-07-04 17:10:21.160465 | debian-bookworm | #9 6.861 Executing busybox-1.37.0-r18.trigger 2025-07-04 17:10:21.160573 | debian-bookworm | #9 6.869 Executing ca-certificates-20241121-r2.trigger 2025-07-04 17:10:21.160602 | debian-bookworm | #9 6.919 OK: 359 MiB in 82 packages 2025-07-04 17:10:21.503320 | debian-bookworm | #9 DONE 7.4s 2025-07-04 17:10:21.606991 | debian-bookworm | 2025-07-04 17:10:21.607195 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-07-04 17:10:21.607268 | debian-bookworm | #10 DONE 0.1s 2025-07-04 17:10:21.739842 | debian-bookworm | 2025-07-04 17:10:21.740002 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-07-04 17:10:21.740083 | debian-bookworm | #11 DONE 0.1s 2025-07-04 17:10:21.740133 | debian-bookworm | 2025-07-04 17:10:21.740166 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-07-04 17:10:21.740236 | debian-bookworm | #12 DONE 0.1s 2025-07-04 17:10:21.895223 | debian-bookworm | 2025-07-04 17:10:21.895364 | 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-04 17:10:22.606391 | debian-bookworm | #13 0.711 Using Python 3.13.5 environment at: /usr/local 2025-07-04 17:10:22.796816 | debian-bookworm | #13 1.053 Resolved 17 packages in 339ms 2025-07-04 17:10:22.990871 | debian-bookworm | #13 1.092 Downloading cryptography (4.4MiB) 2025-07-04 17:10:22.990940 | debian-bookworm | #13 1.095 Downloading ansible-core (2.1MiB) 2025-07-04 17:10:22.990959 | debian-bookworm | #13 1.096 Downloading netaddr (2.2MiB) 2025-07-04 17:10:23.262858 | debian-bookworm | #13 1.389 Downloading netaddr 2025-07-04 17:10:23.263041 | debian-bookworm | #13 1.424 Downloading cryptography 2025-07-04 17:10:23.456804 | debian-bookworm | #13 1.519 Downloading ansible-core 2025-07-04 17:10:23.456919 | debian-bookworm | #13 1.520 Prepared 17 packages in 465ms 2025-07-04 17:10:23.456939 | debian-bookworm | #13 1.565 Installed 17 packages in 44ms 2025-07-04 17:10:23.456956 | debian-bookworm | #13 1.566 + ansible-core==2.18.6 2025-07-04 17:10:23.456974 | debian-bookworm | #13 1.566 + certifi==2025.6.15 2025-07-04 17:10:23.456992 | debian-bookworm | #13 1.566 + cffi==1.17.1 2025-07-04 17:10:23.457008 | debian-bookworm | #13 1.566 + charset-normalizer==3.4.2 2025-07-04 17:10:23.457027 | debian-bookworm | #13 1.566 + cryptography==45.0.5 2025-07-04 17:10:23.457043 | debian-bookworm | #13 1.566 + dynaconf==3.2.11 2025-07-04 17:10:23.457059 | debian-bookworm | #13 1.566 + idna==3.10 2025-07-04 17:10:23.457074 | debian-bookworm | #13 1.566 + jinja2==3.1.6 2025-07-04 17:10:23.457088 | debian-bookworm | #13 1.566 + markupsafe==3.0.2 2025-07-04 17:10:23.457105 | debian-bookworm | #13 1.567 + netaddr==1.3.0 2025-07-04 17:10:23.457126 | debian-bookworm | #13 1.567 + packaging==25.0 2025-07-04 17:10:23.457140 | debian-bookworm | #13 1.567 + pycparser==2.22 2025-07-04 17:10:23.457155 | debian-bookworm | #13 1.567 + pynetbox==7.5.0 2025-07-04 17:10:23.457170 | debian-bookworm | #13 1.567 + pyyaml==6.0.2 2025-07-04 17:10:23.457185 | debian-bookworm | #13 1.567 + requests==2.32.4 2025-07-04 17:10:23.457208 | debian-bookworm | #13 1.567 + resolvelib==1.0.1 2025-07-04 17:10:23.457223 | debian-bookworm | #13 1.567 + urllib3==2.5.0 2025-07-04 17:10:23.457243 | debian-bookworm | #13 DONE 1.7s 2025-07-04 17:10:23.614973 | debian-bookworm | 2025-07-04 17:10:23.615062 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007862 2025-07-04 17:12:08.169238 | 2025-07-04 17:12:08.169377 | TASK [upload-logs : Upload logs to log server] 2025-07-04 17:12:08.748562 | localhost | Output suppressed because no_log was given 2025-07-04 17:12:08.753010 | 2025-07-04 17:12:08.753255 | LOOP [upload-logs : Compress console log and json output] 2025-07-04 17:12:08.813913 | localhost | skipping: Conditional result was False 2025-07-04 17:12:08.820334 | localhost | skipping: Conditional result was False 2025-07-04 17:12:08.828927 | 2025-07-04 17:12:08.829207 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-04 17:12:08.907159 | localhost | skipping: Conditional result was False 2025-07-04 17:12:08.907891 | 2025-07-04 17:12:08.911371 | localhost | skipping: Conditional result was False 2025-07-04 17:12:08.921967 | 2025-07-04 17:12:08.922092 | LOOP [upload-logs : Upload console log and json output]