2025-09-02 00:00:16.265540 | Job console starting 2025-09-02 00:00:16.275890 | Updating git repos 2025-09-02 00:00:16.390078 | Cloning repos into workspace 2025-09-02 00:00:16.475396 | Restoring repo states 2025-09-02 00:00:16.480560 | Merging changes 2025-09-02 00:00:16.480573 | Checking out repos 2025-09-02 00:00:16.556539 | Preparing playbooks 2025-09-02 00:00:17.998023 | Running Ansible setup 2025-09-02 00:00:25.398509 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-02 00:00:26.787977 | 2025-09-02 00:00:26.788091 | PLAY [Base pre] 2025-09-02 00:00:26.829757 | 2025-09-02 00:00:26.829872 | TASK [Setup log path fact] 2025-09-02 00:00:26.857414 | debian-bookworm | ok 2025-09-02 00:00:26.903351 | 2025-09-02 00:00:26.903485 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-02 00:00:26.961325 | debian-bookworm | ok 2025-09-02 00:00:26.975511 | 2025-09-02 00:00:26.975613 | TASK [emit-job-header : Print job information] 2025-09-02 00:00:27.060077 | # Job Information 2025-09-02 00:00:27.060305 | Ansible Version: 2.16.14 2025-09-02 00:00:27.060342 | Job: container-image-inventory-reconciler-push 2025-09-02 00:00:27.060373 | Pipeline: periodic-midnight 2025-09-02 00:00:27.060397 | Executor: 521e9411259a 2025-09-02 00:00:27.060415 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-02 00:00:27.060434 | Event ID: f237e8f6632d47628084709f088b481d 2025-09-02 00:00:27.064573 | 2025-09-02 00:00:27.064653 | LOOP [emit-job-header : Print node information] 2025-09-02 00:00:27.344948 | debian-bookworm | ok: 2025-09-02 00:00:27.345156 | debian-bookworm | # Node Information 2025-09-02 00:00:27.345187 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-02 00:00:27.345231 | debian-bookworm | Hostname: debian 2025-09-02 00:00:27.345251 | debian-bookworm | Username: zuul 2025-09-02 00:00:27.345268 | debian-bookworm | Distro: Debian 12.11 2025-09-02 00:00:27.345294 | debian-bookworm | Provider: regiocloud-a 2025-09-02 00:00:27.345315 | debian-bookworm | Region: 2025-09-02 00:00:27.345333 | debian-bookworm | Label: debian-bookworm 2025-09-02 00:00:27.345350 | debian-bookworm | Product Name: OpenStack Nova 2025-09-02 00:00:27.345367 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe44:504b 2025-09-02 00:00:27.357498 | 2025-09-02 00:00:27.357588 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-02 00:00:28.617961 | debian-bookworm -> localhost | changed 2025-09-02 00:00:28.626939 | 2025-09-02 00:00:28.627032 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-02 00:00:31.019147 | debian-bookworm -> localhost | changed 2025-09-02 00:00:31.033233 | 2025-09-02 00:00:31.033418 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-02 00:00:31.962062 | debian-bookworm -> localhost | ok 2025-09-02 00:00:31.968355 | 2025-09-02 00:00:31.968463 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-02 00:00:31.999087 | debian-bookworm | ok 2025-09-02 00:00:32.050677 | debian-bookworm | included: /var/lib/zuul/builds/712d24e75b4c4c848d70d76bc6432a18/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-02 00:00:32.069719 | 2025-09-02 00:00:32.069833 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-02 00:00:35.438814 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-02 00:00:35.439051 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/712d24e75b4c4c848d70d76bc6432a18/work/712d24e75b4c4c848d70d76bc6432a18_id_rsa 2025-09-02 00:00:35.439087 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/712d24e75b4c4c848d70d76bc6432a18/work/712d24e75b4c4c848d70d76bc6432a18_id_rsa.pub 2025-09-02 00:00:35.439109 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-02 00:00:35.439128 | debian-bookworm -> localhost | SHA256:e+3+GLgHAAyio0k8uscHSA6RLuKZupUrG461AKYEfP8 zuul-build-sshkey 2025-09-02 00:00:35.439156 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-02 00:00:35.439174 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-02 00:00:35.439212 | debian-bookworm -> localhost | |... .o | 2025-09-02 00:00:35.439233 | debian-bookworm -> localhost | |+o . o | 2025-09-02 00:00:35.439251 | debian-bookworm -> localhost | |** . . | 2025-09-02 00:00:35.439267 | debian-bookworm -> localhost | |@=o . . | 2025-09-02 00:00:35.439284 | debian-bookworm -> localhost | |X++ . S. | 2025-09-02 00:00:35.439306 | debian-bookworm -> localhost | |=* o . ..o | 2025-09-02 00:00:35.439323 | debian-bookworm -> localhost | |* * . E. o.o | 2025-09-02 00:00:35.439340 | debian-bookworm -> localhost | |=* + . o.o | 2025-09-02 00:00:35.439357 | debian-bookworm -> localhost | |+=o .o+.. | 2025-09-02 00:00:35.439373 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-02 00:00:35.439418 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.242409 2025-09-02 00:00:35.445033 | 2025-09-02 00:00:35.445121 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-02 00:00:35.472212 | debian-bookworm | ok 2025-09-02 00:00:35.481021 | debian-bookworm | included: /var/lib/zuul/builds/712d24e75b4c4c848d70d76bc6432a18/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-02 00:00:35.517726 | 2025-09-02 00:00:35.517823 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-02 00:00:35.570857 | debian-bookworm | skipping: Conditional result was False 2025-09-02 00:00:35.576913 | 2025-09-02 00:00:35.577015 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-02 00:00:36.622676 | debian-bookworm | changed 2025-09-02 00:00:36.627175 | 2025-09-02 00:00:36.627259 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-02 00:00:36.908221 | debian-bookworm | ok 2025-09-02 00:00:36.915014 | 2025-09-02 00:00:36.915115 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-02 00:00:37.627882 | debian-bookworm | changed 2025-09-02 00:00:37.634285 | 2025-09-02 00:00:37.634377 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-02 00:00:38.398209 | debian-bookworm | changed 2025-09-02 00:00:38.410853 | 2025-09-02 00:00:38.410967 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-02 00:00:38.444175 | debian-bookworm | skipping: Conditional result was False 2025-09-02 00:00:38.449407 | 2025-09-02 00:00:38.449491 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-02 00:00:39.356890 | debian-bookworm -> localhost | changed 2025-09-02 00:00:39.367388 | 2025-09-02 00:00:39.367489 | TASK [add-build-sshkey : Add back temp key] 2025-09-02 00:00:39.941509 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/712d24e75b4c4c848d70d76bc6432a18/work/712d24e75b4c4c848d70d76bc6432a18_id_rsa (zuul-build-sshkey) 2025-09-02 00:00:39.941738 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011530 2025-09-02 00:00:39.953406 | 2025-09-02 00:00:39.953503 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-02 00:00:40.350755 | debian-bookworm | ok 2025-09-02 00:00:40.356715 | 2025-09-02 00:00:40.356809 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-02 00:00:40.396636 | debian-bookworm | skipping: Conditional result was False 2025-09-02 00:00:40.410680 | 2025-09-02 00:00:40.410780 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-02 00:00:40.823080 | debian-bookworm | ok 2025-09-02 00:00:40.832640 | 2025-09-02 00:00:40.832721 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-02 00:00:40.867166 | debian-bookworm | ok 2025-09-02 00:00:40.871425 | 2025-09-02 00:00:40.871495 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-02 00:00:41.342390 | debian-bookworm -> localhost | ok 2025-09-02 00:00:41.348555 | 2025-09-02 00:00:41.348665 | TASK [validate-host : Collect information about the host] 2025-09-02 00:00:42.433493 | debian-bookworm | ok 2025-09-02 00:00:42.451889 | 2025-09-02 00:00:42.452002 | TASK [validate-host : Sanitize hostname] 2025-09-02 00:00:42.623501 | debian-bookworm | ok 2025-09-02 00:00:42.678263 | 2025-09-02 00:00:42.678375 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-02 00:00:43.464625 | debian-bookworm -> localhost | changed 2025-09-02 00:00:43.473615 | 2025-09-02 00:00:43.473705 | TASK [validate-host : Collect information about zuul worker] 2025-09-02 00:00:43.888636 | debian-bookworm | ok 2025-09-02 00:00:43.892532 | 2025-09-02 00:00:43.892611 | TASK [validate-host : Write out all zuul information for each host] 2025-09-02 00:00:44.424211 | debian-bookworm -> localhost | changed 2025-09-02 00:00:44.431736 | 2025-09-02 00:00:44.431811 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-02 00:00:44.695087 | debian-bookworm | ok 2025-09-02 00:00:44.706874 | 2025-09-02 00:00:44.707084 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-02 00:00:45.768890 | debian-bookworm | changed: 2025-09-02 00:00:45.774921 | debian-bookworm | cd+++++++++ src/ 2025-09-02 00:00:45.774970 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-02 00:00:45.774996 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-02 00:00:45.775018 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-02 00:00:45.775039 | debian-bookworm | Containerfile 2025-09-02 00:00:45.775821 | debian-bookworm | 3.5.2-r0) 2025-09-02 00:01:50.111394 | debian-bookworm | #9 5.930 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-02 00:01:50.221828 | debian-bookworm | #9 5.968 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-02 00:01:50.221966 | debian-bookworm | #9 6.021 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-02 00:01:50.221990 | debian-bookworm | #9 6.040 (28/34) Installing python3 (3.12.11-r0) 2025-09-02 00:01:50.486682 | debian-bookworm | #9 6.305 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-02 00:01:50.641201 | debian-bookworm | #9 6.460 (30/34) Installing pyc (3.12.11-r0) 2025-09-02 00:01:50.641313 | debian-bookworm | #9 6.460 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-02 00:01:50.641361 | debian-bookworm | #9 6.460 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-02 00:01:51.159058 | debian-bookworm | #9 6.977 (33/34) Installing yq-go (4.46.1-r1) 2025-09-02 00:01:51.330083 | debian-bookworm | #9 7.149 (34/34) Installing .build-deps (20250902.000146) 2025-09-02 00:01:51.545648 | debian-bookworm | #9 7.150 Executing busybox-1.37.0-r18.trigger 2025-09-02 00:01:51.545827 | debian-bookworm | #9 7.157 Executing ca-certificates-20250619-r0.trigger 2025-09-02 00:01:51.545851 | debian-bookworm | #9 7.213 OK: 359 MiB in 82 packages 2025-09-02 00:01:51.928809 | debian-bookworm | #9 DONE 7.7s 2025-09-02 00:01:52.036828 | debian-bookworm | 2025-09-02 00:01:52.037028 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-02 00:01:52.037090 | debian-bookworm | #10 DONE 0.1s 2025-09-02 00:01:52.188074 | debian-bookworm | 2025-09-02 00:01:52.188221 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-02 00:01:52.188282 | debian-bookworm | #11 DONE 0.1s 2025-09-02 00:01:52.188335 | debian-bookworm | 2025-09-02 00:01:52.188393 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-02 00:01:52.188476 | debian-bookworm | #12 DONE 0.1s 2025-09-02 00:01:52.342871 | debian-bookworm | 2025-09-02 00:01:52.342992 | 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-02 00:01:52.978887 | debian-bookworm | #13 0.786 Using Python 3.13.7 environment at: /usr/local 2025-09-02 00:01:53.370346 | debian-bookworm | #13 1.178 Resolved 17 packages in 389ms 2025-09-02 00:01:53.559001 | debian-bookworm | #13 1.215 Downloading cryptography (4.4MiB) 2025-09-02 00:01:53.559082 | debian-bookworm | #13 1.215 Downloading netaddr (2.2MiB) 2025-09-02 00:01:53.559101 | debian-bookworm | #13 1.216 Downloading ansible-core (2.3MiB) 2025-09-02 00:01:53.940111 | debian-bookworm | #13 1.545 Downloading netaddr 2025-09-02 00:01:53.940221 | debian-bookworm | #13 1.597 Downloading cryptography 2025-09-02 00:01:53.965844 | debian-bookworm | #13 1.773 Downloading ansible-core 2025-09-02 00:01:53.966006 | debian-bookworm | #13 1.773 Prepared 17 packages in 594ms 2025-09-02 00:01:54.163817 | debian-bookworm | #13 1.830 Installed 17 packages in 56ms 2025-09-02 00:01:54.163942 | debian-bookworm | #13 1.831 + ansible-core==2.19.1 2025-09-02 00:01:54.163993 | debian-bookworm | #13 1.831 + certifi==2025.8.3 2025-09-02 00:01:54.164025 | debian-bookworm | #13 1.831 + cffi==1.17.1 2025-09-02 00:01:54.164080 | debian-bookworm | #13 1.831 + charset-normalizer==3.4.3 2025-09-02 00:01:54.164112 | debian-bookworm | #13 1.831 + cryptography==45.0.7 2025-09-02 00:01:54.164155 | debian-bookworm | #13 1.831 + dynaconf==3.2.11 2025-09-02 00:01:54.164193 | debian-bookworm | #13 1.831 + idna==3.10 2025-09-02 00:01:54.164223 | debian-bookworm | #13 1.831 + jinja2==3.1.6 2025-09-02 00:01:54.164275 | debian-bookworm | #13 1.831 + markupsafe==3.0.2 2025-09-02 00:01:54.164306 | debian-bookworm | #13 1.831 + netaddr==1.3.0 2025-09-02 00:01:54.164348 | debian-bookworm | #13 1.831 + packaging==25.0 2025-09-02 00:01:54.164386 | debian-bookworm | #13 1.831 + pycparser==2.22 2025-09-02 00:01:54.164423 | debian-bookworm | #13 1.831 + pynetbox==7.5.0 2025-09-02 00:01:54.164474 | debian-bookworm | #13 1.831 + pyyaml==6.0.2 2025-09-02 00:01:54.164505 | debian-bookworm | #13 1.831 + requests==2.32.5 2025-09-02 00:01:54.164556 | debian-bookworm | #13 1.831 + resolvelib==1.2.0 2025-09-02 00:01:54.164586 | debian-bookworm | #13 1.831 + urllib3==2.5.0 2025-09-02 00:01:54.164633 | debian-bookworm | #13 DONE 2.0s 2025-09-02 00:01:54.320000 | debian-bookworm | 2025-09-02 00:01:54.320097 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.008037 2025-09-02 00:03:27.528943 | 2025-09-02 00:03:27.529152 | TASK [upload-logs : Upload logs to log server] 2025-09-02 00:03:28.121810 | localhost | Output suppressed because no_log was given 2025-09-02 00:03:28.126571 | 2025-09-02 00:03:28.126781 | LOOP [upload-logs : Compress console log and json output] 2025-09-02 00:03:28.178094 | localhost | skipping: Conditional result was False 2025-09-02 00:03:28.186201 | localhost | skipping: Conditional result was False 2025-09-02 00:03:28.197034 | 2025-09-02 00:03:28.197188 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-02 00:03:28.264146 | localhost | skipping: Conditional result was False 2025-09-02 00:03:28.264497 | 2025-09-02 00:03:28.269122 | localhost | skipping: Conditional result was False 2025-09-02 00:03:28.284411 | 2025-09-02 00:03:28.284625 | LOOP [upload-logs : Upload console log and json output]