2025-09-14 00:00:16.891818 | Job console starting 2025-09-14 00:00:16.907123 | Updating git repos 2025-09-14 00:00:16.947293 | Cloning repos into workspace 2025-09-14 00:00:17.069820 | Restoring repo states 2025-09-14 00:00:17.093440 | Merging changes 2025-09-14 00:00:17.093457 | Checking out repos 2025-09-14 00:00:17.218526 | Preparing playbooks 2025-09-14 00:00:18.458698 | Running Ansible setup 2025-09-14 00:00:25.855318 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-14 00:00:27.262630 | 2025-09-14 00:00:27.262746 | PLAY [Base pre] 2025-09-14 00:00:27.280594 | 2025-09-14 00:00:27.280707 | TASK [Setup log path fact] 2025-09-14 00:00:27.328572 | debian-bookworm | ok 2025-09-14 00:00:27.346667 | 2025-09-14 00:00:27.346809 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-14 00:00:27.398147 | debian-bookworm | ok 2025-09-14 00:00:27.406057 | 2025-09-14 00:00:27.406151 | TASK [emit-job-header : Print job information] 2025-09-14 00:00:27.460295 | # Job Information 2025-09-14 00:00:27.460433 | Ansible Version: 2.16.14 2025-09-14 00:00:27.460462 | Job: container-image-inventory-reconciler-push 2025-09-14 00:00:27.460490 | Pipeline: periodic-midnight 2025-09-14 00:00:27.460509 | Executor: 521e9411259a 2025-09-14 00:00:27.460526 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-14 00:00:27.460545 | Event ID: af3ecec1ba9746499bc1f34ee24fec0c 2025-09-14 00:00:27.465108 | 2025-09-14 00:00:27.465192 | LOOP [emit-job-header : Print node information] 2025-09-14 00:00:27.588790 | debian-bookworm | ok: 2025-09-14 00:00:27.588959 | debian-bookworm | # Node Information 2025-09-14 00:00:27.588994 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-14 00:00:27.589015 | debian-bookworm | Hostname: debian 2025-09-14 00:00:27.589033 | debian-bookworm | Username: zuul 2025-09-14 00:00:27.589050 | debian-bookworm | Distro: Debian 12.12 2025-09-14 00:00:27.589071 | debian-bookworm | Provider: regiocloud-a 2025-09-14 00:00:27.589089 | debian-bookworm | Region: 2025-09-14 00:00:27.589106 | debian-bookworm | Label: debian-bookworm 2025-09-14 00:00:27.589122 | debian-bookworm | Product Name: OpenStack Nova 2025-09-14 00:00:27.589138 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef9:b5d6 2025-09-14 00:00:27.620532 | 2025-09-14 00:00:27.620653 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-14 00:00:28.688292 | debian-bookworm -> localhost | changed 2025-09-14 00:00:28.693961 | 2025-09-14 00:00:28.694048 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-14 00:00:30.876249 | debian-bookworm -> localhost | changed 2025-09-14 00:00:30.886713 | 2025-09-14 00:00:30.886805 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-14 00:00:31.538244 | debian-bookworm -> localhost | ok 2025-09-14 00:00:31.543357 | 2025-09-14 00:00:31.543441 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-14 00:00:31.569887 | debian-bookworm | ok 2025-09-14 00:00:31.589739 | debian-bookworm | included: /var/lib/zuul/builds/0144fbe74c5b4252b624c5ed34fb1d54/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-14 00:00:31.605529 | 2025-09-14 00:00:31.605652 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-14 00:00:33.200150 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-14 00:00:33.200307 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0144fbe74c5b4252b624c5ed34fb1d54/work/0144fbe74c5b4252b624c5ed34fb1d54_id_rsa 2025-09-14 00:00:33.200339 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0144fbe74c5b4252b624c5ed34fb1d54/work/0144fbe74c5b4252b624c5ed34fb1d54_id_rsa.pub 2025-09-14 00:00:33.200362 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-14 00:00:33.200407 | debian-bookworm -> localhost | SHA256:oaZmd6Wg3bXEJBJs568NJx6iOslBYNOYF8NcspbZ5ho zuul-build-sshkey 2025-09-14 00:00:33.200439 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-14 00:00:33.200460 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-14 00:00:33.200479 | debian-bookworm -> localhost | | *+oo. | 2025-09-14 00:00:33.200500 | debian-bookworm -> localhost | |.= =B o.. | 2025-09-14 00:00:33.200519 | debian-bookworm -> localhost | |..o= +.oo . | 2025-09-14 00:00:33.200538 | debian-bookworm -> localhost | | o o o.= | 2025-09-14 00:00:33.200556 | debian-bookworm -> localhost | | . E .+ S.= | 2025-09-14 00:00:33.200578 | debian-bookworm -> localhost | | . o=.o+=o. | 2025-09-14 00:00:33.200598 | debian-bookworm -> localhost | | . +=.oo+B. | 2025-09-14 00:00:33.200652 | debian-bookworm -> localhost | | +o.. .o . | 2025-09-14 00:00:33.200671 | debian-bookworm -> localhost | | .o | 2025-09-14 00:00:33.200688 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-14 00:00:33.200731 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.633783 2025-09-14 00:00:33.206525 | 2025-09-14 00:00:33.206613 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-14 00:00:33.263734 | debian-bookworm | ok 2025-09-14 00:00:33.279952 | debian-bookworm | included: /var/lib/zuul/builds/0144fbe74c5b4252b624c5ed34fb1d54/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-14 00:00:33.320523 | 2025-09-14 00:00:33.320634 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-14 00:00:33.343388 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:00:33.348471 | 2025-09-14 00:00:33.348558 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-14 00:00:34.080461 | debian-bookworm | changed 2025-09-14 00:00:34.085341 | 2025-09-14 00:00:34.085423 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-14 00:00:34.394992 | debian-bookworm | ok 2025-09-14 00:00:34.399554 | 2025-09-14 00:00:34.399652 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-14 00:00:35.133166 | debian-bookworm | changed 2025-09-14 00:00:35.140125 | 2025-09-14 00:00:35.140214 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-14 00:00:35.805354 | debian-bookworm | changed 2025-09-14 00:00:35.809858 | 2025-09-14 00:00:35.809938 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-14 00:00:35.846590 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:00:35.851787 | 2025-09-14 00:00:35.851877 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-14 00:00:36.314460 | debian-bookworm -> localhost | changed 2025-09-14 00:00:36.323844 | 2025-09-14 00:00:36.323934 | TASK [add-build-sshkey : Add back temp key] 2025-09-14 00:00:36.722064 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0144fbe74c5b4252b624c5ed34fb1d54/work/0144fbe74c5b4252b624c5ed34fb1d54_id_rsa (zuul-build-sshkey) 2025-09-14 00:00:36.722245 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012121 2025-09-14 00:00:36.729986 | 2025-09-14 00:00:36.730081 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-14 00:00:37.121740 | debian-bookworm | ok 2025-09-14 00:00:37.126559 | 2025-09-14 00:00:37.126685 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-14 00:00:37.162776 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:00:37.174546 | 2025-09-14 00:00:37.174663 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-14 00:00:37.592591 | debian-bookworm | ok 2025-09-14 00:00:37.608082 | 2025-09-14 00:00:37.608200 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-14 00:00:37.655262 | debian-bookworm | ok 2025-09-14 00:00:37.659573 | 2025-09-14 00:00:37.659668 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-14 00:00:38.089923 | debian-bookworm -> localhost | ok 2025-09-14 00:00:38.095292 | 2025-09-14 00:00:38.095375 | TASK [validate-host : Collect information about the host] 2025-09-14 00:00:39.123924 | debian-bookworm | ok 2025-09-14 00:00:39.142242 | 2025-09-14 00:00:39.142347 | TASK [validate-host : Sanitize hostname] 2025-09-14 00:00:39.283816 | debian-bookworm | ok 2025-09-14 00:00:39.333391 | 2025-09-14 00:00:39.333480 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-14 00:00:40.282436 | debian-bookworm -> localhost | changed 2025-09-14 00:00:40.287148 | 2025-09-14 00:00:40.287234 | TASK [validate-host : Collect information about zuul worker] 2025-09-14 00:00:40.716933 | debian-bookworm | ok 2025-09-14 00:00:40.721307 | 2025-09-14 00:00:40.721396 | TASK [validate-host : Write out all zuul information for each host] 2025-09-14 00:00:41.250575 | debian-bookworm -> localhost | changed 2025-09-14 00:00:41.259925 | 2025-09-14 00:00:41.260023 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-14 00:00:41.538694 | debian-bookworm | ok 2025-09-14 00:00:41.543827 | 2025-09-14 00:00:41.543913 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-14 00:00:42.429718 | debian-bookworm | changed: 2025-09-14 00:00:42.429863 | debian-bookworm | cd+++++++++ src/ 2025-09-14 00:00:42.429890 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-14 00:00:42.429909 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-14 00:00:42.429926 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-14 00:00:42.429944 | debian-bookworm | Containerfile 2025-09-14 00:00:42.430054 | debian-bookworm | 3.5.2-r0) 2025-09-14 00:01:45.747017 | debian-bookworm | #9 5.675 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-14 00:01:45.992515 | debian-bookworm | #9 5.703 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-14 00:01:45.992624 | debian-bookworm | #9 5.745 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-14 00:01:45.992644 | debian-bookworm | #9 5.770 (28/34) Installing python3 (3.12.11-r0) 2025-09-14 00:01:46.166845 | debian-bookworm | #9 6.095 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-14 00:01:46.355056 | debian-bookworm | #9 6.283 (30/34) Installing pyc (3.12.11-r0) 2025-09-14 00:01:46.355134 | debian-bookworm | #9 6.283 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-14 00:01:46.355154 | debian-bookworm | #9 6.283 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-14 00:01:46.902404 | debian-bookworm | #9 6.831 (33/34) Installing yq-go (4.46.1-r2) 2025-09-14 00:01:47.073936 | debian-bookworm | #9 7.003 (34/34) Installing .build-deps (20250914.000142) 2025-09-14 00:01:47.301845 | debian-bookworm | #9 7.005 Executing busybox-1.37.0-r18.trigger 2025-09-14 00:01:47.301970 | debian-bookworm | #9 7.017 Executing ca-certificates-20250619-r0.trigger 2025-09-14 00:01:47.301989 | debian-bookworm | #9 7.080 OK: 359 MiB in 82 packages 2025-09-14 00:01:47.612147 | debian-bookworm | #9 DONE 7.5s 2025-09-14 00:01:47.730718 | debian-bookworm | 2025-09-14 00:01:47.730801 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-14 00:01:47.730813 | debian-bookworm | #10 DONE 0.1s 2025-09-14 00:01:47.912228 | debian-bookworm | 2025-09-14 00:01:47.912464 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-14 00:01:47.912483 | debian-bookworm | #11 DONE 0.1s 2025-09-14 00:01:47.912496 | debian-bookworm | 2025-09-14 00:01:47.912508 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-14 00:01:47.912520 | debian-bookworm | #12 DONE 0.1s 2025-09-14 00:01:48.072065 | debian-bookworm | 2025-09-14 00:01:48.072159 | 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-14 00:01:48.740008 | debian-bookworm | #13 0.816 Using Python 3.13.7 environment at: /usr/local 2025-09-14 00:01:49.044351 | debian-bookworm | #13 1.123 Resolved 17 packages in 304ms 2025-09-14 00:01:49.242490 | debian-bookworm | #13 1.167 Downloading netaddr (2.2MiB) 2025-09-14 00:01:49.242637 | debian-bookworm | #13 1.169 Downloading ansible-core (2.3MiB) 2025-09-14 00:01:49.242721 | debian-bookworm | #13 1.171 Downloading cryptography (4.4MiB) 2025-09-14 00:01:49.359806 | debian-bookworm | #13 1.439 Downloading netaddr 2025-09-14 00:01:49.610116 | debian-bookworm | #13 1.548 Downloading cryptography 2025-09-14 00:01:49.610178 | debian-bookworm | #13 1.688 Downloading ansible-core 2025-09-14 00:01:49.809298 | debian-bookworm | #13 1.689 Prepared 17 packages in 564ms 2025-09-14 00:01:49.809425 | debian-bookworm | #13 1.737 Installed 17 packages in 47ms 2025-09-14 00:01:49.809469 | debian-bookworm | #13 1.737 + ansible-core==2.19.2 2025-09-14 00:01:49.809513 | debian-bookworm | #13 1.737 + certifi==2025.8.3 2025-09-14 00:01:49.809552 | debian-bookworm | #13 1.738 + cffi==2.0.0 2025-09-14 00:01:49.809591 | debian-bookworm | #13 1.738 + charset-normalizer==3.4.3 2025-09-14 00:01:49.809629 | debian-bookworm | #13 1.738 + cryptography==45.0.7 2025-09-14 00:01:49.809666 | debian-bookworm | #13 1.738 + dynaconf==3.2.11 2025-09-14 00:01:49.809704 | debian-bookworm | #13 1.738 + idna==3.10 2025-09-14 00:01:49.809741 | debian-bookworm | #13 1.738 + jinja2==3.1.6 2025-09-14 00:01:49.809780 | debian-bookworm | #13 1.738 + markupsafe==3.0.2 2025-09-14 00:01:49.809818 | debian-bookworm | #13 1.738 + netaddr==1.3.0 2025-09-14 00:01:49.809855 | debian-bookworm | #13 1.738 + packaging==25.0 2025-09-14 00:01:49.809893 | debian-bookworm | #13 1.738 + pycparser==2.23 2025-09-14 00:01:49.809929 | debian-bookworm | #13 1.738 + pynetbox==7.5.0 2025-09-14 00:01:49.809970 | debian-bookworm | #13 1.738 + pyyaml==6.0.2 2025-09-14 00:01:49.810028 | debian-bookworm | #13 1.738 + requests==2.32.5 2025-09-14 00:01:49.810068 | debian-bookworm | #13 1.738 + resolvelib==1.2.0 2025-09-14 00:01:49.810105 | debian-bookworm | #13 1.738 + urllib3==2.5.0 2025-09-14 00:01:49.831182 | debian-bookworm | #13 DONE 1.9s 2025-09-14 00:01:49.991916 | debian-bookworm | 2025-09-14 00:01:49.992032 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007619 2025-09-14 00:03:28.798665 | 2025-09-14 00:03:28.798794 | TASK [upload-logs : Upload logs to log server] 2025-09-14 00:03:29.357440 | localhost | Output suppressed because no_log was given 2025-09-14 00:03:29.361197 | 2025-09-14 00:03:29.361382 | LOOP [upload-logs : Compress console log and json output] 2025-09-14 00:03:29.424630 | localhost | skipping: Conditional result was False 2025-09-14 00:03:29.430726 | localhost | skipping: Conditional result was False 2025-09-14 00:03:29.438911 | 2025-09-14 00:03:29.439156 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-14 00:03:29.493849 | localhost | skipping: Conditional result was False 2025-09-14 00:03:29.494225 | 2025-09-14 00:03:29.500563 | localhost | skipping: Conditional result was False 2025-09-14 00:03:29.509996 | 2025-09-14 00:03:29.510148 | LOOP [upload-logs : Upload console log and json output]