2025-09-20 00:00:17.351530 | Job console starting 2025-09-20 00:00:17.371960 | Updating git repos 2025-09-20 00:00:17.414692 | Cloning repos into workspace 2025-09-20 00:00:17.508826 | Restoring repo states 2025-09-20 00:00:17.528968 | Merging changes 2025-09-20 00:00:17.528984 | Checking out repos 2025-09-20 00:00:17.625341 | Preparing playbooks 2025-09-20 00:00:18.824788 | Running Ansible setup 2025-09-20 00:00:25.407655 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-20 00:00:27.316620 | 2025-09-20 00:00:27.316738 | PLAY [Base pre] 2025-09-20 00:00:27.358023 | 2025-09-20 00:00:27.358132 | TASK [Setup log path fact] 2025-09-20 00:00:27.394485 | debian-bookworm | ok 2025-09-20 00:00:27.476639 | 2025-09-20 00:00:27.476779 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 00:00:27.544867 | debian-bookworm | ok 2025-09-20 00:00:27.582099 | 2025-09-20 00:00:27.582207 | TASK [emit-job-header : Print job information] 2025-09-20 00:00:27.662324 | # Job Information 2025-09-20 00:00:27.662487 | Ansible Version: 2.16.14 2025-09-20 00:00:27.662517 | Job: container-image-inventory-reconciler-push 2025-09-20 00:00:27.662543 | Pipeline: periodic-midnight 2025-09-20 00:00:27.662563 | Executor: 521e9411259a 2025-09-20 00:00:27.662579 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-20 00:00:27.662598 | Event ID: d5a4cb0b873343aabb1b8575cd6d914f 2025-09-20 00:00:27.666590 | 2025-09-20 00:00:27.666665 | LOOP [emit-job-header : Print node information] 2025-09-20 00:00:27.880145 | debian-bookworm | ok: 2025-09-20 00:00:27.880284 | debian-bookworm | # Node Information 2025-09-20 00:00:27.880312 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-20 00:00:27.880332 | debian-bookworm | Hostname: debian 2025-09-20 00:00:27.880351 | debian-bookworm | Username: zuul 2025-09-20 00:00:27.880416 | debian-bookworm | Distro: Debian 12.12 2025-09-20 00:00:27.880441 | debian-bookworm | Provider: regiocloud-a 2025-09-20 00:00:27.880459 | debian-bookworm | Region: 2025-09-20 00:00:27.880477 | debian-bookworm | Label: debian-bookworm 2025-09-20 00:00:27.880493 | debian-bookworm | Product Name: OpenStack Nova 2025-09-20 00:00:27.880510 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4a:6aa9 2025-09-20 00:00:27.897300 | 2025-09-20 00:00:27.897416 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-20 00:00:29.216931 | debian-bookworm -> localhost | changed 2025-09-20 00:00:29.222782 | 2025-09-20 00:00:29.222908 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-20 00:00:30.935042 | debian-bookworm -> localhost | changed 2025-09-20 00:00:30.943693 | 2025-09-20 00:00:30.943783 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-20 00:00:31.738867 | debian-bookworm -> localhost | ok 2025-09-20 00:00:31.743877 | 2025-09-20 00:00:31.743967 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-20 00:00:31.810825 | debian-bookworm | ok 2025-09-20 00:00:31.847414 | debian-bookworm | included: /var/lib/zuul/builds/31b98cc0f0074089ac66c759fd33da3d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-20 00:00:31.852315 | 2025-09-20 00:00:31.852416 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-20 00:00:35.638011 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-20 00:00:35.638189 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/31b98cc0f0074089ac66c759fd33da3d/work/31b98cc0f0074089ac66c759fd33da3d_id_rsa 2025-09-20 00:00:35.638222 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/31b98cc0f0074089ac66c759fd33da3d/work/31b98cc0f0074089ac66c759fd33da3d_id_rsa.pub 2025-09-20 00:00:35.638245 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-20 00:00:35.638265 | debian-bookworm -> localhost | SHA256:osAbG3FkcnmeLHBn0rzEKAdsTMZ/J352lq8oiMUkZs4 zuul-build-sshkey 2025-09-20 00:00:35.638290 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-20 00:00:35.638309 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-20 00:00:35.638326 | debian-bookworm -> localhost | | ==.+* | 2025-09-20 00:00:35.638344 | debian-bookworm -> localhost | | .B=* O | 2025-09-20 00:00:35.638391 | debian-bookworm -> localhost | | ..*.O o | 2025-09-20 00:00:35.638411 | debian-bookworm -> localhost | | .+o+ B . | 2025-09-20 00:00:35.638428 | debian-bookworm -> localhost | | ==+ +.oS . | 2025-09-20 00:00:35.638449 | debian-bookworm -> localhost | | E*o...o + | 2025-09-20 00:00:35.638466 | debian-bookworm -> localhost | | oo.. o o . | 2025-09-20 00:00:35.638495 | debian-bookworm -> localhost | | . . . . . | 2025-09-20 00:00:35.638513 | debian-bookworm -> localhost | | .. .. | 2025-09-20 00:00:35.638531 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-20 00:00:35.638571 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.008091 2025-09-20 00:00:35.644210 | 2025-09-20 00:00:35.644293 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-20 00:00:35.683866 | debian-bookworm | ok 2025-09-20 00:00:35.713397 | debian-bookworm | included: /var/lib/zuul/builds/31b98cc0f0074089ac66c759fd33da3d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-20 00:00:35.732252 | 2025-09-20 00:00:35.732348 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-20 00:00:35.755099 | debian-bookworm | skipping: Conditional result was False 2025-09-20 00:00:35.759792 | 2025-09-20 00:00:35.759873 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-20 00:00:36.720253 | debian-bookworm | changed 2025-09-20 00:00:36.725419 | 2025-09-20 00:00:36.725508 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-20 00:00:37.046679 | debian-bookworm | ok 2025-09-20 00:00:37.058850 | 2025-09-20 00:00:37.058958 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-20 00:00:37.770051 | debian-bookworm | changed 2025-09-20 00:00:37.784102 | 2025-09-20 00:00:37.784377 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-20 00:00:38.507645 | debian-bookworm | changed 2025-09-20 00:00:38.512389 | 2025-09-20 00:00:38.512467 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-20 00:00:38.545818 | debian-bookworm | skipping: Conditional result was False 2025-09-20 00:00:38.550604 | 2025-09-20 00:00:38.550684 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-20 00:00:39.554236 | debian-bookworm -> localhost | changed 2025-09-20 00:00:39.563089 | 2025-09-20 00:00:39.563173 | TASK [add-build-sshkey : Add back temp key] 2025-09-20 00:00:40.151740 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/31b98cc0f0074089ac66c759fd33da3d/work/31b98cc0f0074089ac66c759fd33da3d_id_rsa (zuul-build-sshkey) 2025-09-20 00:00:40.151910 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012247 2025-09-20 00:00:40.158086 | 2025-09-20 00:00:40.158166 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-20 00:00:40.650853 | debian-bookworm | ok 2025-09-20 00:00:40.654891 | 2025-09-20 00:00:40.654967 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-20 00:00:40.698181 | debian-bookworm | skipping: Conditional result was False 2025-09-20 00:00:40.706639 | 2025-09-20 00:00:40.706725 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-20 00:00:41.127300 | debian-bookworm | ok 2025-09-20 00:00:41.135842 | 2025-09-20 00:00:41.135934 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-20 00:00:41.164810 | debian-bookworm | ok 2025-09-20 00:00:41.169000 | 2025-09-20 00:00:41.169076 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-20 00:00:41.782949 | debian-bookworm -> localhost | ok 2025-09-20 00:00:41.789023 | 2025-09-20 00:00:41.789124 | TASK [validate-host : Collect information about the host] 2025-09-20 00:00:42.941939 | debian-bookworm | ok 2025-09-20 00:00:42.953563 | 2025-09-20 00:00:42.953652 | TASK [validate-host : Sanitize hostname] 2025-09-20 00:00:43.079182 | debian-bookworm | ok 2025-09-20 00:00:43.128853 | 2025-09-20 00:00:43.129085 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-20 00:00:43.921465 | debian-bookworm -> localhost | changed 2025-09-20 00:00:43.925915 | 2025-09-20 00:00:43.925993 | TASK [validate-host : Collect information about zuul worker] 2025-09-20 00:00:44.366078 | debian-bookworm | ok 2025-09-20 00:00:44.372237 | 2025-09-20 00:00:44.372326 | TASK [validate-host : Write out all zuul information for each host] 2025-09-20 00:00:44.903004 | debian-bookworm -> localhost | changed 2025-09-20 00:00:44.910641 | 2025-09-20 00:00:44.910719 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-20 00:00:45.188741 | debian-bookworm | ok 2025-09-20 00:00:45.193455 | 2025-09-20 00:00:45.193536 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-20 00:00:46.136199 | debian-bookworm | changed: 2025-09-20 00:00:46.136398 | debian-bookworm | cd+++++++++ src/ 2025-09-20 00:00:46.136436 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-20 00:00:46.136460 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-20 00:00:46.136481 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-20 00:00:46.136501 | debian-bookworm | Containerfile 2025-09-20 00:00:46.136634 | debian-bookworm | 3.5.2-r0) 2025-09-20 00:01:54.593961 | debian-bookworm | #9 7.057 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-20 00:01:54.695421 | debian-bookworm | #9 7.084 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-20 00:01:54.695490 | debian-bookworm | #9 7.140 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-20 00:01:54.695497 | debian-bookworm | #9 7.158 (28/34) Installing python3 (3.12.11-r0) 2025-09-20 00:01:55.112857 | debian-bookworm | #9 7.576 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-20 00:01:55.419499 | debian-bookworm | #9 7.882 (30/34) Installing pyc (3.12.11-r0) 2025-09-20 00:01:55.419593 | debian-bookworm | #9 7.882 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-20 00:01:55.419615 | debian-bookworm | #9 7.882 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-20 00:01:56.278561 | debian-bookworm | #9 8.741 (33/34) Installing yq-go (4.47.2-r0) 2025-09-20 00:01:56.587337 | debian-bookworm | #9 9.050 (34/34) Installing .build-deps (20250920.000150) 2025-09-20 00:01:56.805624 | debian-bookworm | #9 9.052 Executing busybox-1.37.0-r18.trigger 2025-09-20 00:01:56.805767 | debian-bookworm | #9 9.060 Executing ca-certificates-20250619-r0.trigger 2025-09-20 00:01:56.805878 | debian-bookworm | #9 9.118 OK: 359 MiB in 82 packages 2025-09-20 00:01:57.161610 | debian-bookworm | #9 DONE 9.6s 2025-09-20 00:01:57.266603 | debian-bookworm | 2025-09-20 00:01:57.266764 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-20 00:01:57.266784 | debian-bookworm | #10 DONE 0.1s 2025-09-20 00:01:57.453112 | debian-bookworm | 2025-09-20 00:01:57.453251 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-20 00:01:57.453299 | debian-bookworm | #11 DONE 0.1s 2025-09-20 00:01:57.453328 | debian-bookworm | 2025-09-20 00:01:57.453355 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-20 00:01:57.453385 | debian-bookworm | #12 DONE 0.1s 2025-09-20 00:01:57.610734 | debian-bookworm | 2025-09-20 00:01:57.610833 | 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-20 00:01:58.272719 | debian-bookworm | #13 0.813 Using Python 3.13.7 environment at: /usr/local 2025-09-20 00:01:58.548981 | debian-bookworm | #13 1.089 Resolved 17 packages in 272ms 2025-09-20 00:01:58.762402 | debian-bookworm | #13 1.141 Downloading ansible-core (2.3MiB) 2025-09-20 00:01:58.762494 | debian-bookworm | #13 1.150 Downloading netaddr (2.2MiB) 2025-09-20 00:01:58.762515 | debian-bookworm | #13 1.150 Downloading cryptography (4.5MiB) 2025-09-20 00:01:58.892312 | debian-bookworm | #13 1.432 Downloading netaddr 2025-09-20 00:01:59.109111 | debian-bookworm | #13 1.498 Downloading cryptography 2025-09-20 00:01:59.179337 | debian-bookworm | #13 1.719 Downloading ansible-core 2025-09-20 00:01:59.386776 | debian-bookworm | #13 1.721 Prepared 17 packages in 630ms 2025-09-20 00:01:59.386958 | debian-bookworm | #13 1.774 Installed 17 packages in 51ms 2025-09-20 00:01:59.386975 | debian-bookworm | #13 1.774 + ansible-core==2.19.2 2025-09-20 00:01:59.386990 | debian-bookworm | #13 1.775 + certifi==2025.8.3 2025-09-20 00:01:59.387002 | debian-bookworm | #13 1.775 + cffi==2.0.0 2025-09-20 00:01:59.387032 | debian-bookworm | #13 1.775 + charset-normalizer==3.4.3 2025-09-20 00:01:59.387045 | debian-bookworm | #13 1.775 + cryptography==46.0.1 2025-09-20 00:01:59.387056 | debian-bookworm | #13 1.775 + dynaconf==3.2.11 2025-09-20 00:01:59.387067 | debian-bookworm | #13 1.775 + idna==3.10 2025-09-20 00:01:59.387079 | debian-bookworm | #13 1.775 + jinja2==3.1.6 2025-09-20 00:01:59.387090 | debian-bookworm | #13 1.775 + markupsafe==3.0.2 2025-09-20 00:01:59.387101 | debian-bookworm | #13 1.776 + netaddr==1.3.0 2025-09-20 00:01:59.387112 | debian-bookworm | #13 1.776 + packaging==25.0 2025-09-20 00:01:59.387123 | debian-bookworm | #13 1.776 + pycparser==2.23 2025-09-20 00:01:59.387134 | debian-bookworm | #13 1.776 + pynetbox==7.5.0 2025-09-20 00:01:59.387145 | debian-bookworm | #13 1.776 + pyyaml==6.0.2 2025-09-20 00:01:59.387156 | debian-bookworm | #13 1.776 + requests==2.32.5 2025-09-20 00:01:59.387167 | debian-bookworm | #13 1.776 + resolvelib==1.2.0 2025-09-20 00:01:59.387178 | debian-bookworm | #13 1.776 + urllib3==2.5.0 2025-09-20 00:01:59.414132 | debian-bookworm | #13 DONE 2.0s 2025-09-20 00:01:59.581146 | debian-bookworm | 2025-09-20 00:01:59.581277 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007286 2025-09-20 00:03:32.724133 | 2025-09-20 00:03:32.724415 | TASK [upload-logs : Upload logs to log server] 2025-09-20 00:03:33.280406 | localhost | Output suppressed because no_log was given 2025-09-20 00:03:33.283966 | 2025-09-20 00:03:33.284132 | LOOP [upload-logs : Compress console log and json output] 2025-09-20 00:03:33.352593 | localhost | skipping: Conditional result was False 2025-09-20 00:03:33.357388 | localhost | skipping: Conditional result was False 2025-09-20 00:03:33.371084 | 2025-09-20 00:03:33.371395 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-20 00:03:33.428076 | localhost | skipping: Conditional result was False 2025-09-20 00:03:33.428864 | 2025-09-20 00:03:33.432907 | localhost | skipping: Conditional result was False 2025-09-20 00:03:33.446563 | 2025-09-20 00:03:33.446791 | LOOP [upload-logs : Upload console log and json output]