2025-09-21 00:00:12.920729 | Job console starting 2025-09-21 00:00:13.003272 | Updating git repos 2025-09-21 00:00:13.063487 | Cloning repos into workspace 2025-09-21 00:00:13.132453 | Restoring repo states 2025-09-21 00:00:13.137516 | Merging changes 2025-09-21 00:00:13.137532 | Checking out repos 2025-09-21 00:00:13.234663 | Preparing playbooks 2025-09-21 00:00:14.136508 | Running Ansible setup 2025-09-21 00:00:20.220581 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-21 00:00:21.447007 | 2025-09-21 00:00:21.447121 | PLAY [Base pre] 2025-09-21 00:00:21.475182 | 2025-09-21 00:00:21.475283 | TASK [Setup log path fact] 2025-09-21 00:00:21.503283 | debian-bookworm | ok 2025-09-21 00:00:21.525545 | 2025-09-21 00:00:21.525659 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-21 00:00:21.563575 | debian-bookworm | ok 2025-09-21 00:00:21.578622 | 2025-09-21 00:00:21.578721 | TASK [emit-job-header : Print job information] 2025-09-21 00:00:21.626000 | # Job Information 2025-09-21 00:00:21.626186 | Ansible Version: 2.16.14 2025-09-21 00:00:21.626216 | Job: container-image-inventory-reconciler-push 2025-09-21 00:00:21.626248 | Pipeline: periodic-midnight 2025-09-21 00:00:21.626271 | Executor: 521e9411259a 2025-09-21 00:00:21.626288 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-21 00:00:21.626322 | Event ID: a8bdd4e915a749ec8072fd5f55a56928 2025-09-21 00:00:21.630442 | 2025-09-21 00:00:21.630533 | LOOP [emit-job-header : Print node information] 2025-09-21 00:00:21.931788 | debian-bookworm | ok: 2025-09-21 00:00:21.932005 | debian-bookworm | # Node Information 2025-09-21 00:00:21.932038 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-21 00:00:21.932060 | debian-bookworm | Hostname: debian 2025-09-21 00:00:21.932078 | debian-bookworm | Username: zuul 2025-09-21 00:00:21.932095 | debian-bookworm | Distro: Debian 12.12 2025-09-21 00:00:21.932121 | debian-bookworm | Provider: regiocloud-a 2025-09-21 00:00:21.932142 | debian-bookworm | Region: 2025-09-21 00:00:21.932160 | debian-bookworm | Label: debian-bookworm 2025-09-21 00:00:21.932178 | debian-bookworm | Product Name: OpenStack Nova 2025-09-21 00:00:21.932194 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6c:4ddc 2025-09-21 00:00:21.951336 | 2025-09-21 00:00:21.951451 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-21 00:00:23.433422 | debian-bookworm -> localhost | changed 2025-09-21 00:00:23.439218 | 2025-09-21 00:00:23.439303 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-21 00:00:26.046125 | debian-bookworm -> localhost | changed 2025-09-21 00:00:26.054689 | 2025-09-21 00:00:26.054773 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-21 00:00:26.784264 | debian-bookworm -> localhost | ok 2025-09-21 00:00:26.789668 | 2025-09-21 00:00:26.789785 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-21 00:00:26.826686 | debian-bookworm | ok 2025-09-21 00:00:26.845184 | debian-bookworm | included: /var/lib/zuul/builds/c2503e2ead484fe6b7c932d15987a646/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-21 00:00:26.852533 | 2025-09-21 00:00:26.852620 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-21 00:00:34.527660 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-21 00:00:34.527831 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c2503e2ead484fe6b7c932d15987a646/work/c2503e2ead484fe6b7c932d15987a646_id_rsa 2025-09-21 00:00:34.527862 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c2503e2ead484fe6b7c932d15987a646/work/c2503e2ead484fe6b7c932d15987a646_id_rsa.pub 2025-09-21 00:00:34.527884 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-21 00:00:34.527905 | debian-bookworm -> localhost | SHA256:iX6pty/KroS+4AxuT2IPyO0870FBoIlRKNbwRwOPNhw zuul-build-sshkey 2025-09-21 00:00:34.527931 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-21 00:00:34.527951 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-21 00:00:34.527969 | debian-bookworm -> localhost | |.++Eo+ | 2025-09-21 00:00:34.527987 | debian-bookworm -> localhost | |+o=.* . | 2025-09-21 00:00:34.528005 | debian-bookworm -> localhost | |+o * + | 2025-09-21 00:00:34.528022 | debian-bookworm -> localhost | | . o .. . | 2025-09-21 00:00:34.528038 | debian-bookworm -> localhost | | .. S | 2025-09-21 00:00:34.528058 | debian-bookworm -> localhost | |o .... . | 2025-09-21 00:00:34.528076 | debian-bookworm -> localhost | |+=.o... o | 2025-09-21 00:00:34.528093 | debian-bookworm -> localhost | |*+Bo ..oo | 2025-09-21 00:00:34.528110 | debian-bookworm -> localhost | |.++*===o.+. | 2025-09-21 00:00:34.528127 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-21 00:00:34.528169 | debian-bookworm -> localhost | ok: Runtime: 0:00:06.727283 2025-09-21 00:00:34.533309 | 2025-09-21 00:00:34.533391 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-21 00:00:34.559882 | debian-bookworm | ok 2025-09-21 00:00:34.577847 | debian-bookworm | included: /var/lib/zuul/builds/c2503e2ead484fe6b7c932d15987a646/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-21 00:00:34.595550 | 2025-09-21 00:00:34.595641 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-21 00:00:34.608200 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:00:34.612970 | 2025-09-21 00:00:34.613050 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-21 00:00:35.339215 | debian-bookworm | changed 2025-09-21 00:00:35.350010 | 2025-09-21 00:00:35.350101 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-21 00:00:35.644549 | debian-bookworm | ok 2025-09-21 00:00:35.649357 | 2025-09-21 00:00:35.649446 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-21 00:00:36.386230 | debian-bookworm | changed 2025-09-21 00:00:36.402131 | 2025-09-21 00:00:36.402239 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-21 00:00:37.162546 | debian-bookworm | changed 2025-09-21 00:00:37.167922 | 2025-09-21 00:00:37.168017 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-21 00:00:37.191220 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:00:37.197201 | 2025-09-21 00:00:37.197298 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-21 00:00:37.931123 | debian-bookworm -> localhost | changed 2025-09-21 00:00:37.946630 | 2025-09-21 00:00:37.946740 | TASK [add-build-sshkey : Add back temp key] 2025-09-21 00:00:38.377242 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c2503e2ead484fe6b7c932d15987a646/work/c2503e2ead484fe6b7c932d15987a646_id_rsa (zuul-build-sshkey) 2025-09-21 00:00:38.377470 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016478 2025-09-21 00:00:38.384811 | 2025-09-21 00:00:38.384907 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-21 00:00:38.783238 | debian-bookworm | ok 2025-09-21 00:00:38.793461 | 2025-09-21 00:00:38.793561 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-21 00:00:38.836676 | debian-bookworm | skipping: Conditional result was False 2025-09-21 00:00:38.847038 | 2025-09-21 00:00:38.847151 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-21 00:00:39.424668 | debian-bookworm | ok 2025-09-21 00:00:39.437704 | 2025-09-21 00:00:39.437812 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-21 00:00:39.477360 | debian-bookworm | ok 2025-09-21 00:00:39.484728 | 2025-09-21 00:00:39.484829 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-21 00:00:40.010520 | debian-bookworm -> localhost | ok 2025-09-21 00:00:40.016758 | 2025-09-21 00:00:40.016861 | TASK [validate-host : Collect information about the host] 2025-09-21 00:00:42.261458 | debian-bookworm | ok 2025-09-21 00:00:42.275258 | 2025-09-21 00:00:42.275372 | TASK [validate-host : Sanitize hostname] 2025-09-21 00:00:42.364552 | debian-bookworm | ok 2025-09-21 00:00:42.421707 | 2025-09-21 00:00:42.421837 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-21 00:00:43.151411 | debian-bookworm -> localhost | changed 2025-09-21 00:00:43.157283 | 2025-09-21 00:00:43.157409 | TASK [validate-host : Collect information about zuul worker] 2025-09-21 00:00:43.600249 | debian-bookworm | ok 2025-09-21 00:00:43.604955 | 2025-09-21 00:00:43.605055 | TASK [validate-host : Write out all zuul information for each host] 2025-09-21 00:00:44.253450 | debian-bookworm -> localhost | changed 2025-09-21 00:00:44.267209 | 2025-09-21 00:00:44.267346 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-21 00:00:44.596011 | debian-bookworm | ok 2025-09-21 00:00:44.607396 | 2025-09-21 00:00:44.607491 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-21 00:00:45.659025 | debian-bookworm | changed: 2025-09-21 00:00:45.659197 | debian-bookworm | cd+++++++++ src/ 2025-09-21 00:00:45.659232 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-21 00:00:45.659256 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-21 00:00:45.659279 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-21 00:00:45.659328 | debian-bookworm | Containerfile 2025-09-21 00:00:45.659465 | debian-bookworm | 3.5.2-r0) 2025-09-21 00:01:52.211677 | debian-bookworm | #9 6.275 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-21 00:01:52.453441 | debian-bookworm | #9 6.299 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-21 00:01:52.453576 | debian-bookworm | #9 6.346 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-21 00:01:52.453598 | debian-bookworm | #9 6.366 (28/34) Installing python3 (3.12.11-r0) 2025-09-21 00:01:52.803959 | debian-bookworm | #9 6.868 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-21 00:01:53.065068 | debian-bookworm | #9 7.128 (30/34) Installing pyc (3.12.11-r0) 2025-09-21 00:01:53.065195 | debian-bookworm | #9 7.128 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-21 00:01:53.065213 | debian-bookworm | #9 7.128 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-21 00:01:53.953853 | debian-bookworm | #9 8.017 (33/34) Installing yq-go (4.47.2-r0) 2025-09-21 00:01:54.220511 | debian-bookworm | #9 8.284 (34/34) Installing .build-deps (20250921.000148) 2025-09-21 00:01:54.453277 | debian-bookworm | #9 8.286 Executing busybox-1.37.0-r18.trigger 2025-09-21 00:01:54.453335 | debian-bookworm | #9 8.294 Executing ca-certificates-20250619-r0.trigger 2025-09-21 00:01:54.453343 | debian-bookworm | #9 8.366 OK: 359 MiB in 82 packages 2025-09-21 00:01:54.847526 | debian-bookworm | #9 DONE 8.9s 2025-09-21 00:01:54.976863 | debian-bookworm | 2025-09-21 00:01:54.977011 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-21 00:01:54.977070 | debian-bookworm | #10 DONE 0.1s 2025-09-21 00:01:55.219672 | debian-bookworm | 2025-09-21 00:01:55.219809 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-21 00:01:55.219860 | debian-bookworm | #11 DONE 0.1s 2025-09-21 00:01:55.219894 | debian-bookworm | 2025-09-21 00:01:55.219923 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-21 00:01:55.219955 | debian-bookworm | #12 DONE 0.2s 2025-09-21 00:01:55.375025 | debian-bookworm | 2025-09-21 00:01:55.375090 | 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-21 00:01:56.026232 | debian-bookworm | #13 0.802 Using Python 3.13.7 environment at: /usr/local 2025-09-21 00:01:56.298098 | debian-bookworm | #13 1.074 Resolved 17 packages in 269ms 2025-09-21 00:01:56.489790 | debian-bookworm | #13 1.111 Downloading netaddr (2.2MiB) 2025-09-21 00:01:56.489839 | debian-bookworm | #13 1.114 Downloading cryptography (4.5MiB) 2025-09-21 00:01:56.489845 | debian-bookworm | #13 1.115 Downloading ansible-core (2.3MiB) 2025-09-21 00:01:56.870224 | debian-bookworm | #13 1.646 Downloading netaddr 2025-09-21 00:01:56.996975 | debian-bookworm | #13 1.773 Downloading ansible-core 2025-09-21 00:01:57.109131 | debian-bookworm | #13 1.825 Downloading cryptography 2025-09-21 00:01:57.109210 | debian-bookworm | #13 1.826 Prepared 17 packages in 750ms 2025-09-21 00:01:57.109232 | debian-bookworm | #13 1.885 Installed 17 packages in 57ms 2025-09-21 00:01:57.262092 | debian-bookworm | #13 1.885 + ansible-core==2.19.2 2025-09-21 00:01:57.262234 | debian-bookworm | #13 1.885 + certifi==2025.8.3 2025-09-21 00:01:57.262279 | debian-bookworm | #13 1.885 + cffi==2.0.0 2025-09-21 00:01:57.262322 | debian-bookworm | #13 1.887 + charset-normalizer==3.4.3 2025-09-21 00:01:57.262362 | debian-bookworm | #13 1.887 + cryptography==46.0.1 2025-09-21 00:01:57.262401 | debian-bookworm | #13 1.887 + dynaconf==3.2.11 2025-09-21 00:01:57.262442 | debian-bookworm | #13 1.887 + idna==3.10 2025-09-21 00:01:57.262481 | debian-bookworm | #13 1.887 + jinja2==3.1.6 2025-09-21 00:01:57.262520 | debian-bookworm | #13 1.887 + markupsafe==3.0.2 2025-09-21 00:01:57.262581 | debian-bookworm | #13 1.887 + netaddr==1.3.0 2025-09-21 00:01:57.262624 | debian-bookworm | #13 1.887 + packaging==25.0 2025-09-21 00:01:57.262662 | debian-bookworm | #13 1.887 + pycparser==2.23 2025-09-21 00:01:57.262701 | debian-bookworm | #13 1.887 + pynetbox==7.5.0 2025-09-21 00:01:57.262739 | debian-bookworm | #13 1.887 + pyyaml==6.0.2 2025-09-21 00:01:57.262778 | debian-bookworm | #13 1.887 + requests==2.32.5 2025-09-21 00:01:57.262816 | debian-bookworm | #13 1.887 + resolvelib==1.2.0 2025-09-21 00:01:57.262855 | debian-bookworm | #13 1.887 + urllib3==2.5.0 2025-09-21 00:01:57.272475 | debian-bookworm | #13 DONE 2.0s 2025-09-21 00:01:57.428907 | debian-bookworm | 2025-09-21 00:01:57.429016 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.008096 2025-09-21 00:03:31.085443 | 2025-09-21 00:03:31.085613 | TASK [upload-logs : Upload logs to log server] 2025-09-21 00:03:31.651756 | localhost | Output suppressed because no_log was given 2025-09-21 00:03:31.654468 | 2025-09-21 00:03:31.654609 | LOOP [upload-logs : Compress console log and json output] 2025-09-21 00:03:31.706427 | localhost | skipping: Conditional result was False 2025-09-21 00:03:31.712251 | localhost | skipping: Conditional result was False 2025-09-21 00:03:31.726009 | 2025-09-21 00:03:31.726321 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-21 00:03:31.772973 | localhost | skipping: Conditional result was False 2025-09-21 00:03:31.773548 | 2025-09-21 00:03:31.777792 | localhost | skipping: Conditional result was False 2025-09-21 00:03:31.791129 | 2025-09-21 00:03:31.791439 | LOOP [upload-logs : Upload console log and json output]