2025-08-31 00:00:17.980421 | Job console starting 2025-08-31 00:00:17.997898 | Updating git repos 2025-08-31 00:00:18.032979 | Cloning repos into workspace 2025-08-31 00:00:18.274135 | Restoring repo states 2025-08-31 00:00:18.289211 | Merging changes 2025-08-31 00:00:18.289227 | Checking out repos 2025-08-31 00:00:18.373394 | Preparing playbooks 2025-08-31 00:00:19.971898 | Running Ansible setup 2025-08-31 00:00:27.046633 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-31 00:00:28.944197 | 2025-08-31 00:00:28.944313 | PLAY [Base pre] 2025-08-31 00:00:28.958664 | 2025-08-31 00:00:28.958766 | TASK [Setup log path fact] 2025-08-31 00:00:28.976201 | debian-bookworm | ok 2025-08-31 00:00:28.994261 | 2025-08-31 00:00:28.994368 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-31 00:00:29.023089 | debian-bookworm | ok 2025-08-31 00:00:29.031030 | 2025-08-31 00:00:29.031119 | TASK [emit-job-header : Print job information] 2025-08-31 00:00:29.075427 | # Job Information 2025-08-31 00:00:29.075561 | Ansible Version: 2.16.14 2025-08-31 00:00:29.075589 | Job: container-image-inventory-reconciler-push 2025-08-31 00:00:29.075615 | Pipeline: periodic-midnight 2025-08-31 00:00:29.075643 | Executor: 521e9411259a 2025-08-31 00:00:29.075660 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-08-31 00:00:29.075678 | Event ID: 6834371e7e40441792d28c11890ab70e 2025-08-31 00:00:29.079683 | 2025-08-31 00:00:29.079770 | LOOP [emit-job-header : Print node information] 2025-08-31 00:00:29.215193 | debian-bookworm | ok: 2025-08-31 00:00:29.215373 | debian-bookworm | # Node Information 2025-08-31 00:00:29.215402 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-31 00:00:29.215423 | debian-bookworm | Hostname: debian 2025-08-31 00:00:29.215441 | debian-bookworm | Username: zuul 2025-08-31 00:00:29.215458 | debian-bookworm | Distro: Debian 12.11 2025-08-31 00:00:29.215484 | debian-bookworm | Provider: regiocloud-a 2025-08-31 00:00:29.215505 | debian-bookworm | Region: 2025-08-31 00:00:29.215523 | debian-bookworm | Label: debian-bookworm 2025-08-31 00:00:29.215539 | debian-bookworm | Product Name: OpenStack Nova 2025-08-31 00:00:29.215555 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4d:3f0e 2025-08-31 00:00:29.234300 | 2025-08-31 00:00:29.234394 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-31 00:00:30.382251 | debian-bookworm -> localhost | changed 2025-08-31 00:00:30.387934 | 2025-08-31 00:00:30.388014 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-31 00:00:32.487771 | debian-bookworm -> localhost | changed 2025-08-31 00:00:32.496717 | 2025-08-31 00:00:32.496803 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-31 00:00:33.037440 | debian-bookworm -> localhost | ok 2025-08-31 00:00:33.047102 | 2025-08-31 00:00:33.047202 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-31 00:00:33.074201 | debian-bookworm | ok 2025-08-31 00:00:33.101506 | debian-bookworm | included: /var/lib/zuul/builds/a6b2eb44f5954d8ca2111cf5c13381ff/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-31 00:00:33.124842 | 2025-08-31 00:00:33.124966 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-31 00:00:36.268877 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-31 00:00:36.269036 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a6b2eb44f5954d8ca2111cf5c13381ff/work/a6b2eb44f5954d8ca2111cf5c13381ff_id_rsa 2025-08-31 00:00:36.269067 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a6b2eb44f5954d8ca2111cf5c13381ff/work/a6b2eb44f5954d8ca2111cf5c13381ff_id_rsa.pub 2025-08-31 00:00:36.269089 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-31 00:00:36.269109 | debian-bookworm -> localhost | SHA256:NgG4aPrZ027XVKlbR+8zHdRB9iYS9oLy/yl8lIMKmpI zuul-build-sshkey 2025-08-31 00:00:36.269134 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-31 00:00:36.269153 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-31 00:00:36.269170 | debian-bookworm -> localhost | | .. o .o | 2025-08-31 00:00:36.269188 | debian-bookworm -> localhost | | . . o o...| 2025-08-31 00:00:36.269205 | debian-bookworm -> localhost | | . . o . o.o =| 2025-08-31 00:00:36.269220 | debian-bookworm -> localhost | | o . + oo.+.| 2025-08-31 00:00:36.269236 | debian-bookworm -> localhost | | o S .o o.o | 2025-08-31 00:00:36.269258 | debian-bookworm -> localhost | |. ...o.o =..| 2025-08-31 00:00:36.269274 | debian-bookworm -> localhost | | . o o o + =.o oo| 2025-08-31 00:00:36.269290 | debian-bookworm -> localhost | | o E = . + o..+o| 2025-08-31 00:00:36.269306 | debian-bookworm -> localhost | | =.. oo o| 2025-08-31 00:00:36.269322 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-31 00:00:36.269360 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.172417 2025-08-31 00:00:36.274646 | 2025-08-31 00:00:36.274726 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-31 00:00:36.310989 | debian-bookworm | ok 2025-08-31 00:00:36.318232 | debian-bookworm | included: /var/lib/zuul/builds/a6b2eb44f5954d8ca2111cf5c13381ff/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-31 00:00:36.340163 | 2025-08-31 00:00:36.340253 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-31 00:00:36.363171 | debian-bookworm | skipping: Conditional result was False 2025-08-31 00:00:36.367844 | 2025-08-31 00:00:36.367923 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-31 00:00:37.255203 | debian-bookworm | changed 2025-08-31 00:00:37.259586 | 2025-08-31 00:00:37.259688 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-31 00:00:37.535108 | debian-bookworm | ok 2025-08-31 00:00:37.542981 | 2025-08-31 00:00:37.543119 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-31 00:00:38.232300 | debian-bookworm | changed 2025-08-31 00:00:38.242524 | 2025-08-31 00:00:38.242643 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-31 00:00:38.948286 | debian-bookworm | changed 2025-08-31 00:00:38.959128 | 2025-08-31 00:00:38.959244 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-31 00:00:38.992200 | debian-bookworm | skipping: Conditional result was False 2025-08-31 00:00:38.998279 | 2025-08-31 00:00:38.998376 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-31 00:00:39.903101 | debian-bookworm -> localhost | changed 2025-08-31 00:00:39.918264 | 2025-08-31 00:00:39.918369 | TASK [add-build-sshkey : Add back temp key] 2025-08-31 00:00:40.470143 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a6b2eb44f5954d8ca2111cf5c13381ff/work/a6b2eb44f5954d8ca2111cf5c13381ff_id_rsa (zuul-build-sshkey) 2025-08-31 00:00:40.470382 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.007497 2025-08-31 00:00:40.478736 | 2025-08-31 00:00:40.478861 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-31 00:00:41.136544 | debian-bookworm | ok 2025-08-31 00:00:41.142251 | 2025-08-31 00:00:41.142341 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-31 00:00:41.166164 | debian-bookworm | skipping: Conditional result was False 2025-08-31 00:00:41.175704 | 2025-08-31 00:00:41.175885 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-31 00:00:41.716506 | debian-bookworm | ok 2025-08-31 00:00:41.725215 | 2025-08-31 00:00:41.725308 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-31 00:00:41.780505 | debian-bookworm | ok 2025-08-31 00:00:41.784705 | 2025-08-31 00:00:41.784787 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-31 00:00:42.181605 | debian-bookworm -> localhost | ok 2025-08-31 00:00:42.187824 | 2025-08-31 00:00:42.187934 | TASK [validate-host : Collect information about the host] 2025-08-31 00:00:43.569448 | debian-bookworm | ok 2025-08-31 00:00:43.603264 | 2025-08-31 00:00:43.603375 | TASK [validate-host : Sanitize hostname] 2025-08-31 00:00:43.763131 | debian-bookworm | ok 2025-08-31 00:00:43.876706 | 2025-08-31 00:00:43.876818 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-31 00:00:45.542727 | debian-bookworm -> localhost | changed 2025-08-31 00:00:45.548109 | 2025-08-31 00:00:45.548201 | TASK [validate-host : Collect information about zuul worker] 2025-08-31 00:00:46.175127 | debian-bookworm | ok 2025-08-31 00:00:46.180172 | 2025-08-31 00:00:46.180267 | TASK [validate-host : Write out all zuul information for each host] 2025-08-31 00:00:47.077515 | debian-bookworm -> localhost | changed 2025-08-31 00:00:47.085455 | 2025-08-31 00:00:47.085552 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-31 00:00:47.395029 | debian-bookworm | ok 2025-08-31 00:00:47.399886 | 2025-08-31 00:00:47.399970 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-31 00:00:48.336571 | debian-bookworm | changed: 2025-08-31 00:00:48.336774 | debian-bookworm | cd+++++++++ src/ 2025-08-31 00:00:48.336810 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-31 00:00:48.336834 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-31 00:00:48.336855 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-08-31 00:00:48.336875 | debian-bookworm | Containerfile 2025-08-31 00:00:48.337005 | debian-bookworm | 3.5.2-r0) 2025-08-31 00:01:54.695874 | debian-bookworm | #9 7.081 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-08-31 00:01:54.942875 | debian-bookworm | #9 7.110 (26/34) Installing openssl-dev (3.5.2-r0) 2025-08-31 00:01:54.942940 | debian-bookworm | #9 7.156 (27/34) Installing mpdecimal (4.0.1-r0) 2025-08-31 00:01:54.942946 | debian-bookworm | #9 7.177 (28/34) Installing python3 (3.12.11-r0) 2025-08-31 00:01:55.130958 | debian-bookworm | #9 7.516 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-08-31 00:01:55.317438 | debian-bookworm | #9 7.702 (30/34) Installing pyc (3.12.11-r0) 2025-08-31 00:01:55.468143 | debian-bookworm | #9 7.702 (31/34) Installing python3-pyc (3.12.11-r0) 2025-08-31 00:01:55.468315 | debian-bookworm | #9 7.702 (32/34) Installing python3-dev (3.12.11-r0) 2025-08-31 00:01:55.875694 | debian-bookworm | #9 8.260 (33/34) Installing yq-go (4.46.1-r1) 2025-08-31 00:01:56.034484 | debian-bookworm | #9 8.419 (34/34) Installing .build-deps (20250831.000150) 2025-08-31 00:01:56.253514 | debian-bookworm | #9 8.421 Executing busybox-1.37.0-r18.trigger 2025-08-31 00:01:56.253597 | debian-bookworm | #9 8.429 Executing ca-certificates-20250619-r0.trigger 2025-08-31 00:01:56.253619 | debian-bookworm | #9 8.488 OK: 359 MiB in 82 packages 2025-08-31 00:01:56.603678 | debian-bookworm | #9 DONE 9.0s 2025-08-31 00:01:56.707196 | debian-bookworm | 2025-08-31 00:01:56.707393 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-08-31 00:01:56.707460 | debian-bookworm | #10 DONE 0.1s 2025-08-31 00:01:56.707531 | debian-bookworm | 2025-08-31 00:01:56.707564 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-08-31 00:01:56.873269 | debian-bookworm | #11 DONE 0.1s 2025-08-31 00:01:56.873407 | debian-bookworm | 2025-08-31 00:01:56.873428 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-08-31 00:01:56.873439 | debian-bookworm | #12 DONE 0.1s 2025-08-31 00:01:57.027345 | debian-bookworm | 2025-08-31 00:01:57.027536 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-08-31 00:01:57.852697 | debian-bookworm | #13 0.819 Using Python 3.13.7 environment at: /usr/local 2025-08-31 00:01:57.977250 | debian-bookworm | #13 1.096 Resolved 17 packages in 273ms 2025-08-31 00:01:58.165026 | debian-bookworm | #13 1.114 Downloading netaddr (2.2MiB) 2025-08-31 00:01:58.165125 | debian-bookworm | #13 1.133 Downloading cryptography (4.4MiB) 2025-08-31 00:01:58.165140 | debian-bookworm | #13 1.137 Downloading ansible-core (2.3MiB) 2025-08-31 00:01:58.302805 | debian-bookworm | #13 1.422 Downloading netaddr 2025-08-31 00:01:58.515855 | debian-bookworm | #13 1.525 Downloading cryptography 2025-08-31 00:01:58.515921 | debian-bookworm | #13 1.637 Downloading ansible-core 2025-08-31 00:01:58.718430 | debian-bookworm | #13 1.639 Prepared 17 packages in 541ms 2025-08-31 00:01:58.718514 | debian-bookworm | #13 1.691 Installed 17 packages in 49ms 2025-08-31 00:01:58.718530 | debian-bookworm | #13 1.691 + ansible-core==2.19.1 2025-08-31 00:01:58.718542 | debian-bookworm | #13 1.691 + certifi==2025.8.3 2025-08-31 00:01:58.718554 | debian-bookworm | #13 1.691 + cffi==1.17.1 2025-08-31 00:01:58.718565 | debian-bookworm | #13 1.691 + charset-normalizer==3.4.3 2025-08-31 00:01:58.718576 | debian-bookworm | #13 1.691 + cryptography==45.0.6 2025-08-31 00:01:58.718587 | debian-bookworm | #13 1.691 + dynaconf==3.2.11 2025-08-31 00:01:58.718599 | debian-bookworm | #13 1.691 + idna==3.10 2025-08-31 00:01:58.718610 | debian-bookworm | #13 1.691 + jinja2==3.1.6 2025-08-31 00:01:58.718621 | debian-bookworm | #13 1.691 + markupsafe==3.0.2 2025-08-31 00:01:58.718632 | debian-bookworm | #13 1.691 + netaddr==1.3.0 2025-08-31 00:01:58.718643 | debian-bookworm | #13 1.691 + packaging==25.0 2025-08-31 00:01:58.718653 | debian-bookworm | #13 1.691 + pycparser==2.22 2025-08-31 00:01:58.718664 | debian-bookworm | #13 1.691 + pynetbox==7.5.0 2025-08-31 00:01:58.718675 | debian-bookworm | #13 1.691 + pyyaml==6.0.2 2025-08-31 00:01:58.718686 | debian-bookworm | #13 1.691 + requests==2.32.5 2025-08-31 00:01:58.718697 | debian-bookworm | #13 1.691 + resolvelib==1.2.0 2025-08-31 00:01:58.718708 | debian-bookworm | #13 1.691 + urllib3==2.5.0 2025-08-31 00:01:58.719462 | debian-bookworm | #13 DONE 1.8s 2025-08-31 00:01:58.878143 | debian-bookworm | 2025-08-31 00:01:58.878195 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.005983 2025-08-31 00:03:44.958106 | 2025-08-31 00:03:44.958246 | TASK [upload-logs : Upload logs to log server] 2025-08-31 00:03:45.549360 | localhost | Output suppressed because no_log was given 2025-08-31 00:03:45.560919 | 2025-08-31 00:03:45.561088 | LOOP [upload-logs : Compress console log and json output] 2025-08-31 00:03:45.617602 | localhost | skipping: Conditional result was False 2025-08-31 00:03:45.624348 | localhost | skipping: Conditional result was False 2025-08-31 00:03:45.631376 | 2025-08-31 00:03:45.631490 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-31 00:03:45.678438 | localhost | skipping: Conditional result was False 2025-08-31 00:03:45.679200 | 2025-08-31 00:03:45.682321 | localhost | skipping: Conditional result was False 2025-08-31 00:03:45.691458 | 2025-08-31 00:03:45.691694 | LOOP [upload-logs : Upload console log and json output]