2025-07-10 13:48:39.581257 | Job console starting 2025-07-10 13:48:39.600710 | Updating git repos 2025-07-10 13:48:39.638261 | Cloning repos into workspace 2025-07-10 13:48:39.674475 | Restoring repo states 2025-07-10 13:48:39.681364 | Merging changes 2025-07-10 13:48:40.197599 | Checking out repos 2025-07-10 13:48:40.270968 | Preparing playbooks 2025-07-10 13:48:41.323063 | Running Ansible setup 2025-07-10 13:48:46.243552 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-10 13:48:47.044590 | 2025-07-10 13:48:47.044800 | PLAY [Base pre] 2025-07-10 13:48:47.069684 | 2025-07-10 13:48:47.069848 | TASK [Setup log path fact] 2025-07-10 13:48:47.090291 | debian-bookworm | ok 2025-07-10 13:48:47.107282 | 2025-07-10 13:48:47.107446 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-10 13:48:47.157543 | debian-bookworm | ok 2025-07-10 13:48:47.174221 | 2025-07-10 13:48:47.174361 | TASK [emit-job-header : Print job information] 2025-07-10 13:48:47.216086 | # Job Information 2025-07-10 13:48:47.216345 | Ansible Version: 2.16.14 2025-07-10 13:48:47.216384 | Job: container-image-inventory-reconciler-build 2025-07-10 13:48:47.216420 | Pipeline: check 2025-07-10 13:48:47.216443 | Executor: 521e9411259a 2025-07-10 13:48:47.216464 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/404 2025-07-10 13:48:47.216486 | Event ID: 906c6050-5d94-11f0-89a3-e175583b6f21 2025-07-10 13:48:47.221820 | 2025-07-10 13:48:47.221951 | LOOP [emit-job-header : Print node information] 2025-07-10 13:48:47.371031 | debian-bookworm | ok: 2025-07-10 13:48:47.371303 | debian-bookworm | # Node Information 2025-07-10 13:48:47.371345 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-10 13:48:47.371371 | debian-bookworm | Hostname: debian 2025-07-10 13:48:47.371394 | debian-bookworm | Username: zuul 2025-07-10 13:48:47.371415 | debian-bookworm | Distro: Debian 12.11 2025-07-10 13:48:47.371439 | debian-bookworm | Provider: regiocloud-a 2025-07-10 13:48:47.371460 | debian-bookworm | Region: 2025-07-10 13:48:47.371481 | debian-bookworm | Label: debian-bookworm 2025-07-10 13:48:47.371501 | debian-bookworm | Product Name: OpenStack Nova 2025-07-10 13:48:47.371519 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe16:bf21 2025-07-10 13:48:47.393845 | 2025-07-10 13:48:47.393988 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-10 13:48:47.894999 | debian-bookworm -> localhost | changed 2025-07-10 13:48:47.902270 | 2025-07-10 13:48:47.902389 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-10 13:48:49.273257 | debian-bookworm -> localhost | changed 2025-07-10 13:48:49.284772 | 2025-07-10 13:48:49.284912 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-10 13:48:49.701375 | debian-bookworm -> localhost | ok 2025-07-10 13:48:49.712521 | 2025-07-10 13:48:49.712687 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-10 13:48:49.744704 | debian-bookworm | ok 2025-07-10 13:48:49.789443 | debian-bookworm | included: /var/lib/zuul/builds/8ee2c1527e5a4e91b1bbcbe6453f82e5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-10 13:48:49.797823 | 2025-07-10 13:48:49.797938 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-10 13:48:51.370226 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-10 13:48:51.370470 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/8ee2c1527e5a4e91b1bbcbe6453f82e5/work/8ee2c1527e5a4e91b1bbcbe6453f82e5_id_rsa 2025-07-10 13:48:51.370509 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/8ee2c1527e5a4e91b1bbcbe6453f82e5/work/8ee2c1527e5a4e91b1bbcbe6453f82e5_id_rsa.pub 2025-07-10 13:48:51.370536 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-10 13:48:51.370562 | debian-bookworm -> localhost | SHA256:dMBSbgWZee4W6apZVwNwM4BGdLsvbzycvAX7Y22qZN8 zuul-build-sshkey 2025-07-10 13:48:51.370601 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-10 13:48:51.370624 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-10 13:48:51.370678 | debian-bookworm -> localhost | | oo+B*= | 2025-07-10 13:48:51.370701 | debian-bookworm -> localhost | | +o=*.o | 2025-07-10 13:48:51.370721 | debian-bookworm -> localhost | | . .=oo. | 2025-07-10 13:48:51.370742 | debian-bookworm -> localhost | | o o+. | 2025-07-10 13:48:51.370762 | debian-bookworm -> localhost | | So..o | 2025-07-10 13:48:51.370785 | debian-bookworm -> localhost | | .++ . | 2025-07-10 13:48:51.370806 | debian-bookworm -> localhost | | o**... | 2025-07-10 13:48:51.370826 | debian-bookworm -> localhost | | o.*B+o.o | 2025-07-10 13:48:51.370896 | debian-bookworm -> localhost | | o. .+=++E | 2025-07-10 13:48:51.370919 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-10 13:48:51.370974 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.906389 2025-07-10 13:48:51.377744 | 2025-07-10 13:48:51.377882 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-10 13:48:51.418446 | debian-bookworm | ok 2025-07-10 13:48:51.432775 | debian-bookworm | included: /var/lib/zuul/builds/8ee2c1527e5a4e91b1bbcbe6453f82e5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-10 13:48:51.452485 | 2025-07-10 13:48:51.452650 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-10 13:48:51.490041 | debian-bookworm | skipping: Conditional result was False 2025-07-10 13:48:51.497781 | 2025-07-10 13:48:51.497940 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-10 13:48:52.217036 | debian-bookworm | changed 2025-07-10 13:48:52.223781 | 2025-07-10 13:48:52.223898 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-10 13:48:52.491019 | debian-bookworm | ok 2025-07-10 13:48:52.497136 | 2025-07-10 13:48:52.497259 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-10 13:48:53.204603 | debian-bookworm | changed 2025-07-10 13:48:53.214356 | 2025-07-10 13:48:53.214496 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-10 13:48:53.871160 | debian-bookworm | changed 2025-07-10 13:48:53.876756 | 2025-07-10 13:48:53.876867 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-10 13:48:53.900954 | debian-bookworm | skipping: Conditional result was False 2025-07-10 13:48:53.909517 | 2025-07-10 13:48:53.909683 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-10 13:48:54.432815 | debian-bookworm -> localhost | changed 2025-07-10 13:48:54.444495 | 2025-07-10 13:48:54.444661 | TASK [add-build-sshkey : Add back temp key] 2025-07-10 13:48:54.802169 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/8ee2c1527e5a4e91b1bbcbe6453f82e5/work/8ee2c1527e5a4e91b1bbcbe6453f82e5_id_rsa (zuul-build-sshkey) 2025-07-10 13:48:54.802616 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025404 2025-07-10 13:48:54.814481 | 2025-07-10 13:48:54.814605 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-10 13:48:55.268773 | debian-bookworm | ok 2025-07-10 13:48:55.274106 | 2025-07-10 13:48:55.274235 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-10 13:48:55.308460 | debian-bookworm | skipping: Conditional result was False 2025-07-10 13:48:55.319407 | 2025-07-10 13:48:55.319536 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-10 13:48:55.784213 | debian-bookworm | ok 2025-07-10 13:48:55.795535 | 2025-07-10 13:48:55.795712 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-10 13:48:55.837477 | debian-bookworm | ok 2025-07-10 13:48:55.847800 | 2025-07-10 13:48:55.847941 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-10 13:48:56.176786 | debian-bookworm -> localhost | ok 2025-07-10 13:48:56.183671 | 2025-07-10 13:48:56.183805 | TASK [validate-host : Collect information about the host] 2025-07-10 13:48:57.376748 | debian-bookworm | ok 2025-07-10 13:48:57.399607 | 2025-07-10 13:48:57.399801 | TASK [validate-host : Sanitize hostname] 2025-07-10 13:48:57.551799 | debian-bookworm | ok 2025-07-10 13:48:57.642287 | 2025-07-10 13:48:57.642438 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-10 13:48:58.398578 | debian-bookworm -> localhost | changed 2025-07-10 13:48:58.410388 | 2025-07-10 13:48:58.410569 | TASK [validate-host : Collect information about zuul worker] 2025-07-10 13:48:58.835045 | debian-bookworm | ok 2025-07-10 13:48:58.841251 | 2025-07-10 13:48:58.841383 | TASK [validate-host : Write out all zuul information for each host] 2025-07-10 13:48:59.512012 | debian-bookworm -> localhost | changed 2025-07-10 13:48:59.522625 | 2025-07-10 13:48:59.522871 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-10 13:48:59.848022 | debian-bookworm | ok 2025-07-10 13:48:59.857301 | 2025-07-10 13:48:59.857442 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-10 13:49:00.763418 | debian-bookworm | changed: 2025-07-10 13:49:00.763798 | debian-bookworm | cd+++++++++ src/ 2025-07-10 13:49:00.763864 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-10 13:49:00.763905 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-10 13:49:00.763942 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-07-10 13:49:00.763975 | debian-bookworm | Containerfile 2025-07-10 13:49:00.764190 | debian-bookworm | 3.5.1-r0) 2025-07-10 13:50:01.883964 | debian-bookworm | #9 5.534 (25/34) Upgrading libssl3 (3.5.0-r0 -> 3.5.1-r0) 2025-07-10 13:50:02.114038 | debian-bookworm | #9 5.558 (26/34) Installing openssl-dev (3.5.1-r0) 2025-07-10 13:50:02.114162 | debian-bookworm | #9 5.595 (27/34) Installing mpdecimal (4.0.1-r0) 2025-07-10 13:50:02.114179 | debian-bookworm | #9 5.614 (28/34) Installing python3 (3.12.11-r0) 2025-07-10 13:50:02.297221 | debian-bookworm | #9 5.948 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-07-10 13:50:02.480140 | debian-bookworm | #9 6.130 (30/34) Installing pyc (3.12.11-r0) 2025-07-10 13:50:02.480226 | debian-bookworm | #9 6.130 (31/34) Installing python3-pyc (3.12.11-r0) 2025-07-10 13:50:02.480247 | debian-bookworm | #9 6.130 (32/34) Installing python3-dev (3.12.11-r0) 2025-07-10 13:50:03.025675 | debian-bookworm | #9 6.676 (33/34) Installing yq-go (4.45.4-r0) 2025-07-10 13:50:03.174681 | debian-bookworm | #9 6.825 (34/34) Installing .build-deps (20250710.134958) 2025-07-10 13:50:03.390845 | debian-bookworm | #9 6.827 Executing busybox-1.37.0-r18.trigger 2025-07-10 13:50:03.390932 | debian-bookworm | #9 6.834 Executing ca-certificates-20241121-r2.trigger 2025-07-10 13:50:03.390955 | debian-bookworm | #9 6.890 OK: 359 MiB in 82 packages 2025-07-10 13:50:03.714958 | debian-bookworm | #9 DONE 7.4s 2025-07-10 13:50:03.861641 | debian-bookworm | 2025-07-10 13:50:03.861769 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-07-10 13:50:03.861811 | debian-bookworm | #10 DONE 0.1s 2025-07-10 13:50:03.861836 | debian-bookworm | 2025-07-10 13:50:03.861862 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-07-10 13:50:03.861884 | debian-bookworm | #11 DONE 0.1s 2025-07-10 13:50:04.092802 | debian-bookworm | 2025-07-10 13:50:04.092887 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-07-10 13:50:04.092896 | debian-bookworm | #12 DONE 0.1s 2025-07-10 13:50:04.092903 | debian-bookworm | 2025-07-10 13:50:04.092911 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-07-10 13:50:04.670495 | debian-bookworm | #13 0.729 Using Python 3.13.5 environment at: /usr/local 2025-07-10 13:50:05.106617 | debian-bookworm | #13 0.970 Resolved 17 packages in 238ms 2025-07-10 13:50:05.106716 | debian-bookworm | #13 1.013 Downloading ansible-core (2.1MiB) 2025-07-10 13:50:05.106727 | debian-bookworm | #13 1.013 Downloading cryptography (4.4MiB) 2025-07-10 13:50:05.106735 | debian-bookworm | #13 1.014 Downloading netaddr (2.2MiB) 2025-07-10 13:50:05.304116 | debian-bookworm | #13 1.363 Downloading netaddr 2025-07-10 13:50:05.424094 | debian-bookworm | #13 1.430 Downloading cryptography 2025-07-10 13:50:05.424156 | debian-bookworm | #13 1.483 Downloading ansible-core 2025-07-10 13:50:05.616459 | debian-bookworm | #13 1.484 Prepared 17 packages in 511ms 2025-07-10 13:50:05.616560 | debian-bookworm | #13 1.527 Installed 17 packages in 42ms 2025-07-10 13:50:05.616595 | debian-bookworm | #13 1.527 + ansible-core==2.18.6 2025-07-10 13:50:05.616621 | debian-bookworm | #13 1.527 + certifi==2025.7.9 2025-07-10 13:50:05.616645 | debian-bookworm | #13 1.527 + cffi==1.17.1 2025-07-10 13:50:05.616678 | debian-bookworm | #13 1.527 + charset-normalizer==3.4.2 2025-07-10 13:50:05.616700 | debian-bookworm | #13 1.527 + cryptography==45.0.5 2025-07-10 13:50:05.616723 | debian-bookworm | #13 1.527 + dynaconf==3.2.11 2025-07-10 13:50:05.616767 | debian-bookworm | #13 1.527 + idna==3.10 2025-07-10 13:50:05.616791 | debian-bookworm | #13 1.527 + jinja2==3.1.6 2025-07-10 13:50:05.616813 | debian-bookworm | #13 1.527 + markupsafe==3.0.2 2025-07-10 13:50:05.616835 | debian-bookworm | #13 1.527 + netaddr==1.3.0 2025-07-10 13:50:05.616858 | debian-bookworm | #13 1.527 + packaging==25.0 2025-07-10 13:50:05.616880 | debian-bookworm | #13 1.527 + pycparser==2.22 2025-07-10 13:50:05.616902 | debian-bookworm | #13 1.527 + pynetbox==7.5.0 2025-07-10 13:50:05.616925 | debian-bookworm | #13 1.527 + pyyaml==6.0.2 2025-07-10 13:50:05.616950 | debian-bookworm | #13 1.527 + requests==2.32.4 2025-07-10 13:50:05.616972 | debian-bookworm | #13 1.527 + resolvelib==1.0.1 2025-07-10 13:50:05.616993 | debian-bookworm | #13 1.527 + urllib3==2.5.0 2025-07-10 13:50:05.617024 | debian-bookworm | #13 DONE 1.7s 2025-07-10 13:50:05.773015 | debian-bookworm | 2025-07-10 13:50:05.773135 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.008236 2025-07-10 13:50:34.999936 | 2025-07-10 13:50:35.000170 | TASK [upload-logs : Upload logs to log server] 2025-07-10 13:50:35.565350 | localhost | Output suppressed because no_log was given 2025-07-10 13:50:35.567864 | 2025-07-10 13:50:35.567996 | LOOP [upload-logs : Compress console log and json output] 2025-07-10 13:50:35.622648 | localhost | skipping: Conditional result was False 2025-07-10 13:50:35.627431 | localhost | skipping: Conditional result was False 2025-07-10 13:50:35.641877 | 2025-07-10 13:50:35.642110 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-10 13:50:35.692445 | localhost | skipping: Conditional result was False 2025-07-10 13:50:35.693134 | 2025-07-10 13:50:35.696252 | localhost | skipping: Conditional result was False 2025-07-10 13:50:35.704954 | 2025-07-10 13:50:35.705208 | LOOP [upload-logs : Upload console log and json output]