2025-08-27 00:00:16.090172 | Job console starting 2025-08-27 00:00:16.099315 | Updating git repos 2025-08-27 00:00:16.406856 | Cloning repos into workspace 2025-08-27 00:00:16.509477 | Restoring repo states 2025-08-27 00:00:16.531474 | Merging changes 2025-08-27 00:00:16.531489 | Checking out repos 2025-08-27 00:00:16.626009 | Preparing playbooks 2025-08-27 00:00:17.908804 | Running Ansible setup 2025-08-27 00:00:24.176395 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-27 00:00:25.417339 | 2025-08-27 00:00:25.417447 | PLAY [Base pre] 2025-08-27 00:00:25.430573 | 2025-08-27 00:00:25.430666 | TASK [Setup log path fact] 2025-08-27 00:00:25.457187 | debian-bookworm | ok 2025-08-27 00:00:25.480747 | 2025-08-27 00:00:25.480861 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 00:00:25.519798 | debian-bookworm | ok 2025-08-27 00:00:25.530616 | 2025-08-27 00:00:25.530708 | TASK [emit-job-header : Print job information] 2025-08-27 00:00:25.601408 | # Job Information 2025-08-27 00:00:25.601597 | Ansible Version: 2.16.14 2025-08-27 00:00:25.601628 | Job: container-image-inventory-reconciler-push 2025-08-27 00:00:25.601662 | Pipeline: periodic-midnight 2025-08-27 00:00:25.601682 | Executor: 521e9411259a 2025-08-27 00:00:25.601698 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-08-27 00:00:25.601716 | Event ID: 35d317642b4042fc88ca5078140e2716 2025-08-27 00:00:25.605833 | 2025-08-27 00:00:25.605918 | LOOP [emit-job-header : Print node information] 2025-08-27 00:00:25.849040 | debian-bookworm | ok: 2025-08-27 00:00:25.849225 | debian-bookworm | # Node Information 2025-08-27 00:00:25.849279 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-27 00:00:25.849300 | debian-bookworm | Hostname: debian 2025-08-27 00:00:25.849319 | debian-bookworm | Username: zuul 2025-08-27 00:00:25.849336 | debian-bookworm | Distro: Debian 12.11 2025-08-27 00:00:25.849359 | debian-bookworm | Provider: regiocloud-a 2025-08-27 00:00:25.849378 | debian-bookworm | Region: 2025-08-27 00:00:25.849396 | debian-bookworm | Label: debian-bookworm 2025-08-27 00:00:25.849412 | debian-bookworm | Product Name: OpenStack Nova 2025-08-27 00:00:25.849428 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe9e:ccb 2025-08-27 00:00:25.863936 | 2025-08-27 00:00:25.864031 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-27 00:00:26.704544 | debian-bookworm -> localhost | changed 2025-08-27 00:00:26.711468 | 2025-08-27 00:00:26.711568 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-27 00:00:28.986978 | debian-bookworm -> localhost | changed 2025-08-27 00:00:28.997961 | 2025-08-27 00:00:28.998067 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-27 00:00:29.506024 | debian-bookworm -> localhost | ok 2025-08-27 00:00:29.512041 | 2025-08-27 00:00:29.512148 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-27 00:00:29.560870 | debian-bookworm | ok 2025-08-27 00:00:29.603277 | debian-bookworm | included: /var/lib/zuul/builds/d9bf8c00985e424a8ab6ae97151f408a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-27 00:00:29.617849 | 2025-08-27 00:00:29.617957 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-27 00:00:32.207784 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-27 00:00:32.207995 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d9bf8c00985e424a8ab6ae97151f408a/work/d9bf8c00985e424a8ab6ae97151f408a_id_rsa 2025-08-27 00:00:32.208035 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d9bf8c00985e424a8ab6ae97151f408a/work/d9bf8c00985e424a8ab6ae97151f408a_id_rsa.pub 2025-08-27 00:00:32.208062 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-27 00:00:32.208084 | debian-bookworm -> localhost | SHA256:X+VAgNsRu7jGWdTfIlz0haxy2kwlV0SXDmLpxYUAdUE zuul-build-sshkey 2025-08-27 00:00:32.208115 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-27 00:00:32.208137 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-27 00:00:32.208157 | debian-bookworm -> localhost | | o==*E=*B| 2025-08-27 00:00:32.208178 | debian-bookworm -> localhost | | . .Bo*=oo| 2025-08-27 00:00:32.208198 | debian-bookworm -> localhost | | o=.=== .| 2025-08-27 00:00:32.208217 | debian-bookworm -> localhost | | .oo++* o | 2025-08-27 00:00:32.208247 | debian-bookworm -> localhost | | S oB+ + .| 2025-08-27 00:00:32.208275 | debian-bookworm -> localhost | | . =..o. . | 2025-08-27 00:00:32.208297 | debian-bookworm -> localhost | | = . | 2025-08-27 00:00:32.208319 | debian-bookworm -> localhost | | . | 2025-08-27 00:00:32.208340 | debian-bookworm -> localhost | | | 2025-08-27 00:00:32.208360 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-27 00:00:32.208410 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.735844 2025-08-27 00:00:32.214778 | 2025-08-27 00:00:32.214906 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-27 00:00:32.273429 | debian-bookworm | ok 2025-08-27 00:00:32.294510 | debian-bookworm | included: /var/lib/zuul/builds/d9bf8c00985e424a8ab6ae97151f408a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-27 00:00:32.314998 | 2025-08-27 00:00:32.315109 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-27 00:00:32.378859 | debian-bookworm | skipping: Conditional result was False 2025-08-27 00:00:32.384616 | 2025-08-27 00:00:32.384727 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-27 00:00:33.058828 | debian-bookworm | changed 2025-08-27 00:00:33.064047 | 2025-08-27 00:00:33.064133 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-27 00:00:33.292604 | debian-bookworm | ok 2025-08-27 00:00:33.296960 | 2025-08-27 00:00:33.297035 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-27 00:00:34.014276 | debian-bookworm | changed 2025-08-27 00:00:34.047094 | 2025-08-27 00:00:34.047219 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-27 00:00:34.750160 | debian-bookworm | changed 2025-08-27 00:00:34.756052 | 2025-08-27 00:00:34.756146 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-27 00:00:34.788993 | debian-bookworm | skipping: Conditional result was False 2025-08-27 00:00:34.794951 | 2025-08-27 00:00:34.795048 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-27 00:00:35.807490 | debian-bookworm -> localhost | changed 2025-08-27 00:00:35.823865 | 2025-08-27 00:00:35.823973 | TASK [add-build-sshkey : Add back temp key] 2025-08-27 00:00:36.574583 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d9bf8c00985e424a8ab6ae97151f408a/work/d9bf8c00985e424a8ab6ae97151f408a_id_rsa (zuul-build-sshkey) 2025-08-27 00:00:36.574799 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.045135 2025-08-27 00:00:36.583623 | 2025-08-27 00:00:36.583724 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-27 00:00:37.433686 | debian-bookworm | ok 2025-08-27 00:00:37.437744 | 2025-08-27 00:00:37.437825 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-27 00:00:37.481064 | debian-bookworm | skipping: Conditional result was False 2025-08-27 00:00:37.489674 | 2025-08-27 00:00:37.489766 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-27 00:00:37.900741 | debian-bookworm | ok 2025-08-27 00:00:37.913499 | 2025-08-27 00:00:37.913594 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-27 00:00:37.965130 | debian-bookworm | ok 2025-08-27 00:00:37.969137 | 2025-08-27 00:00:37.969210 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-27 00:00:38.708933 | debian-bookworm -> localhost | ok 2025-08-27 00:00:38.714405 | 2025-08-27 00:00:38.714497 | TASK [validate-host : Collect information about the host] 2025-08-27 00:00:39.951339 | debian-bookworm | ok 2025-08-27 00:00:39.965810 | 2025-08-27 00:00:39.965903 | TASK [validate-host : Sanitize hostname] 2025-08-27 00:00:40.070759 | debian-bookworm | ok 2025-08-27 00:00:40.144980 | 2025-08-27 00:00:40.145074 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-27 00:00:40.972794 | debian-bookworm -> localhost | changed 2025-08-27 00:00:40.978346 | 2025-08-27 00:00:40.978448 | TASK [validate-host : Collect information about zuul worker] 2025-08-27 00:00:41.413197 | debian-bookworm | ok 2025-08-27 00:00:41.417683 | 2025-08-27 00:00:41.417774 | TASK [validate-host : Write out all zuul information for each host] 2025-08-27 00:00:42.089591 | debian-bookworm -> localhost | changed 2025-08-27 00:00:42.097138 | 2025-08-27 00:00:42.097254 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-27 00:00:42.349503 | debian-bookworm | ok 2025-08-27 00:00:42.353814 | 2025-08-27 00:00:42.353887 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-27 00:00:43.317975 | debian-bookworm | changed: 2025-08-27 00:00:43.318639 | debian-bookworm | cd+++++++++ src/ 2025-08-27 00:00:43.318794 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-27 00:00:43.318916 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-27 00:00:43.319020 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-08-27 00:00:43.319127 | debian-bookworm | Containerfile 2025-08-27 00:00:43.319568 | debian-bookworm | 3.5.2-r0) 2025-08-27 00:02:34.549851 | debian-bookworm | #9 5.791 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-08-27 00:02:34.652481 | debian-bookworm | #9 5.821 (26/34) Installing openssl-dev (3.5.2-r0) 2025-08-27 00:02:34.652541 | debian-bookworm | #9 5.870 (27/34) Installing mpdecimal (4.0.1-r0) 2025-08-27 00:02:34.652549 | debian-bookworm | #9 5.894 (28/34) Installing python3 (3.12.11-r0) 2025-08-27 00:02:34.945651 | debian-bookworm | #9 6.175 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-08-27 00:02:35.098358 | debian-bookworm | #9 6.339 (30/34) Installing pyc (3.12.11-r0) 2025-08-27 00:02:35.099471 | debian-bookworm | #9 6.339 (31/34) Installing python3-pyc (3.12.11-r0) 2025-08-27 00:02:35.099488 | debian-bookworm | #9 6.339 (32/34) Installing python3-dev (3.12.11-r0) 2025-08-27 00:02:35.563397 | debian-bookworm | #9 6.798 (33/34) Installing yq-go (4.46.1-r1) 2025-08-27 00:02:35.696801 | debian-bookworm | #9 6.938 (34/34) Installing .build-deps (20250827.000231) 2025-08-27 00:02:35.922206 | debian-bookworm | #9 6.940 Executing busybox-1.37.0-r18.trigger 2025-08-27 00:02:35.922401 | debian-bookworm | #9 6.947 Executing ca-certificates-20250619-r0.trigger 2025-08-27 00:02:35.922431 | debian-bookworm | #9 7.013 OK: 359 MiB in 82 packages 2025-08-27 00:02:36.235872 | debian-bookworm | #9 DONE 7.5s 2025-08-27 00:02:36.451478 | debian-bookworm | 2025-08-27 00:02:36.451680 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-08-27 00:02:36.451702 | debian-bookworm | #10 DONE 0.1s 2025-08-27 00:02:36.451715 | debian-bookworm | 2025-08-27 00:02:36.451761 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-08-27 00:02:36.451775 | debian-bookworm | #11 DONE 0.1s 2025-08-27 00:02:36.582765 | debian-bookworm | 2025-08-27 00:02:36.582862 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-08-27 00:02:36.582876 | debian-bookworm | #12 DONE 0.1s 2025-08-27 00:02:36.738500 | debian-bookworm | 2025-08-27 00:02:36.739883 | 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-27 00:02:37.418324 | debian-bookworm | #13 0.830 Using Python 3.13.7 environment at: /usr/local 2025-08-27 00:02:37.979044 | debian-bookworm | #13 1.197 Resolved 17 packages in 365ms 2025-08-27 00:02:37.979224 | debian-bookworm | #13 1.237 Downloading ansible-core (2.3MiB) 2025-08-27 00:02:37.979256 | debian-bookworm | #13 1.239 Downloading cryptography (4.4MiB) 2025-08-27 00:02:37.979268 | debian-bookworm | #13 1.240 Downloading netaddr (2.2MiB) 2025-08-27 00:02:38.116749 | debian-bookworm | #13 1.529 Downloading netaddr 2025-08-27 00:02:38.231927 | debian-bookworm | #13 1.609 Downloading cryptography 2025-08-27 00:02:38.410843 | debian-bookworm | #13 1.644 Downloading ansible-core 2025-08-27 00:02:38.410913 | debian-bookworm | #13 1.645 Prepared 17 packages in 447ms 2025-08-27 00:02:38.410925 | debian-bookworm | #13 1.686 Installed 17 packages in 40ms 2025-08-27 00:02:38.410934 | debian-bookworm | #13 1.686 + ansible-core==2.19.1 2025-08-27 00:02:38.410944 | debian-bookworm | #13 1.687 + certifi==2025.8.3 2025-08-27 00:02:38.410993 | debian-bookworm | #13 1.687 + cffi==1.17.1 2025-08-27 00:02:38.411004 | debian-bookworm | #13 1.687 + charset-normalizer==3.4.3 2025-08-27 00:02:38.411013 | debian-bookworm | #13 1.687 + cryptography==45.0.6 2025-08-27 00:02:38.411021 | debian-bookworm | #13 1.687 + dynaconf==3.2.11 2025-08-27 00:02:38.411031 | debian-bookworm | #13 1.687 + idna==3.10 2025-08-27 00:02:38.411054 | debian-bookworm | #13 1.687 + jinja2==3.1.6 2025-08-27 00:02:38.411064 | debian-bookworm | #13 1.687 + markupsafe==3.0.2 2025-08-27 00:02:38.411072 | debian-bookworm | #13 1.687 + netaddr==1.3.0 2025-08-27 00:02:38.411081 | debian-bookworm | #13 1.687 + packaging==25.0 2025-08-27 00:02:38.411089 | debian-bookworm | #13 1.687 + pycparser==2.22 2025-08-27 00:02:38.411098 | debian-bookworm | #13 1.687 + pynetbox==7.5.0 2025-08-27 00:02:38.411106 | debian-bookworm | #13 1.687 + pyyaml==6.0.2 2025-08-27 00:02:38.411115 | debian-bookworm | #13 1.687 + requests==2.32.5 2025-08-27 00:02:38.411123 | debian-bookworm | #13 1.687 + resolvelib==1.2.0 2025-08-27 00:02:38.411132 | debian-bookworm | #13 1.687 + urllib3==2.5.0 2025-08-27 00:02:38.411141 | debian-bookworm | #13 DONE 1.8s 2025-08-27 00:02:38.568697 | debian-bookworm | 2025-08-27 00:02:38.568787 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.010633 2025-08-27 00:04:06.074744 | 2025-08-27 00:04:06.075336 | TASK [upload-logs : Upload logs to log server] 2025-08-27 00:04:06.651783 | localhost | Output suppressed because no_log was given 2025-08-27 00:04:06.654413 | 2025-08-27 00:04:06.654575 | LOOP [upload-logs : Compress console log and json output] 2025-08-27 00:04:06.707116 | localhost | skipping: Conditional result was False 2025-08-27 00:04:06.714013 | localhost | skipping: Conditional result was False 2025-08-27 00:04:06.727447 | 2025-08-27 00:04:06.727626 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-27 00:04:06.780249 | localhost | skipping: Conditional result was False 2025-08-27 00:04:06.780705 | 2025-08-27 00:04:06.784898 | localhost | skipping: Conditional result was False 2025-08-27 00:04:06.797140 | 2025-08-27 00:04:06.797315 | LOOP [upload-logs : Upload console log and json output]