2025-08-21 18:08:29.106639 | Job console starting 2025-08-21 18:08:29.140208 | Updating git repos 2025-08-21 18:08:29.167722 | Cloning repos into workspace 2025-08-21 18:08:29.220608 | Restoring repo states 2025-08-21 18:08:29.236895 | Merging changes 2025-08-21 18:08:29.776674 | Checking out repos 2025-08-21 18:08:29.842240 | Preparing playbooks 2025-08-21 18:08:30.681434 | Running Ansible setup 2025-08-21 18:08:35.571351 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-21 18:08:36.598120 | 2025-08-21 18:08:36.598287 | PLAY [Base pre] 2025-08-21 18:08:36.614656 | 2025-08-21 18:08:36.614803 | TASK [Setup log path fact] 2025-08-21 18:08:36.644529 | debian-bookworm | ok 2025-08-21 18:08:36.660005 | 2025-08-21 18:08:36.660163 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-21 18:08:36.689748 | debian-bookworm | ok 2025-08-21 18:08:36.699746 | 2025-08-21 18:08:36.699869 | TASK [emit-job-header : Print job information] 2025-08-21 18:08:36.739052 | # Job Information 2025-08-21 18:08:36.739236 | Ansible Version: 2.16.14 2025-08-21 18:08:36.739271 | Job: container-image-inventory-reconciler-build 2025-08-21 18:08:36.739304 | Pipeline: check 2025-08-21 18:08:36.739327 | Executor: 521e9411259a 2025-08-21 18:08:36.739349 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/416 2025-08-21 18:08:36.739372 | Event ID: d2083c50-7eb9-11f0-976e-faee6cd52b98 2025-08-21 18:08:36.744368 | 2025-08-21 18:08:36.744489 | LOOP [emit-job-header : Print node information] 2025-08-21 18:08:36.915430 | debian-bookworm | ok: 2025-08-21 18:08:36.915627 | debian-bookworm | # Node Information 2025-08-21 18:08:36.915662 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-21 18:08:36.915688 | debian-bookworm | Hostname: debian 2025-08-21 18:08:36.915710 | debian-bookworm | Username: zuul 2025-08-21 18:08:36.915732 | debian-bookworm | Distro: Debian 12.11 2025-08-21 18:08:36.915756 | debian-bookworm | Provider: regiocloud-a 2025-08-21 18:08:36.915778 | debian-bookworm | Region: 2025-08-21 18:08:36.915799 | debian-bookworm | Label: debian-bookworm 2025-08-21 18:08:36.915819 | debian-bookworm | Product Name: OpenStack Nova 2025-08-21 18:08:36.915839 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe04:cf47 2025-08-21 18:08:36.929054 | 2025-08-21 18:08:36.929185 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-21 18:08:37.620662 | debian-bookworm -> localhost | changed 2025-08-21 18:08:37.628040 | 2025-08-21 18:08:37.628164 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-21 18:08:38.711863 | debian-bookworm -> localhost | changed 2025-08-21 18:08:38.722460 | 2025-08-21 18:08:38.722566 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-21 18:08:39.006666 | debian-bookworm -> localhost | ok 2025-08-21 18:08:39.013925 | 2025-08-21 18:08:39.014058 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-21 18:08:39.049721 | debian-bookworm | ok 2025-08-21 18:08:39.083183 | debian-bookworm | included: /var/lib/zuul/builds/5d95a79dcd1b452f928175778ac6c956/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-21 18:08:39.089164 | 2025-08-21 18:08:39.089259 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-21 18:08:40.684948 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-21 18:08:40.685157 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5d95a79dcd1b452f928175778ac6c956/work/5d95a79dcd1b452f928175778ac6c956_id_rsa 2025-08-21 18:08:40.685197 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5d95a79dcd1b452f928175778ac6c956/work/5d95a79dcd1b452f928175778ac6c956_id_rsa.pub 2025-08-21 18:08:40.685225 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-21 18:08:40.685251 | debian-bookworm -> localhost | SHA256:nJBQeGyAYElJ/X2fxFi7Ey6BnRisGdneOXyVpSo4/4Q zuul-build-sshkey 2025-08-21 18:08:40.685281 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-21 18:08:40.685304 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-21 18:08:40.685326 | debian-bookworm -> localhost | |+=+.o== o. | 2025-08-21 18:08:40.685348 | debian-bookworm -> localhost | |.o...++= . o. | 2025-08-21 18:08:40.685370 | debian-bookworm -> localhost | | .oO B * o. | 2025-08-21 18:08:40.685390 | debian-bookworm -> localhost | | + B.% *. | 2025-08-21 18:08:40.685410 | debian-bookworm -> localhost | | oS.B.+ | 2025-08-21 18:08:40.685437 | debian-bookworm -> localhost | | o.o* | 2025-08-21 18:08:40.685458 | debian-bookworm -> localhost | | E... | 2025-08-21 18:08:40.685478 | debian-bookworm -> localhost | | o | 2025-08-21 18:08:40.685498 | debian-bookworm -> localhost | | . | 2025-08-21 18:08:40.685518 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-21 18:08:40.685565 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.697250 2025-08-21 18:08:40.691818 | 2025-08-21 18:08:40.691903 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-21 18:08:40.718703 | debian-bookworm | ok 2025-08-21 18:08:40.732181 | debian-bookworm | included: /var/lib/zuul/builds/5d95a79dcd1b452f928175778ac6c956/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-21 18:08:40.740675 | 2025-08-21 18:08:40.740756 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-21 18:08:40.753153 | debian-bookworm | skipping: Conditional result was False 2025-08-21 18:08:40.758528 | 2025-08-21 18:08:40.758612 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-21 18:08:41.376668 | debian-bookworm | changed 2025-08-21 18:08:41.383256 | 2025-08-21 18:08:41.383362 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-21 18:08:41.658259 | debian-bookworm | ok 2025-08-21 18:08:41.668834 | 2025-08-21 18:08:41.669019 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-21 18:08:42.361069 | debian-bookworm | changed 2025-08-21 18:08:42.368564 | 2025-08-21 18:08:42.368701 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-21 18:08:43.019067 | debian-bookworm | changed 2025-08-21 18:08:43.030325 | 2025-08-21 18:08:43.030455 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-21 18:08:43.065106 | debian-bookworm | skipping: Conditional result was False 2025-08-21 18:08:43.071794 | 2025-08-21 18:08:43.071926 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-21 18:08:43.683747 | debian-bookworm -> localhost | changed 2025-08-21 18:08:43.699871 | 2025-08-21 18:08:43.700085 | TASK [add-build-sshkey : Add back temp key] 2025-08-21 18:08:44.139987 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5d95a79dcd1b452f928175778ac6c956/work/5d95a79dcd1b452f928175778ac6c956_id_rsa (zuul-build-sshkey) 2025-08-21 18:08:44.140230 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010937 2025-08-21 18:08:44.148591 | 2025-08-21 18:08:44.148718 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-21 18:08:44.551620 | debian-bookworm | ok 2025-08-21 18:08:44.557520 | 2025-08-21 18:08:44.557640 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-21 18:08:44.593251 | debian-bookworm | skipping: Conditional result was False 2025-08-21 18:08:44.604243 | 2025-08-21 18:08:44.604374 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-21 18:08:45.095475 | debian-bookworm | ok 2025-08-21 18:08:45.106382 | 2025-08-21 18:08:45.106513 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-21 18:08:45.135090 | debian-bookworm | ok 2025-08-21 18:08:45.140394 | 2025-08-21 18:08:45.140511 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-21 18:08:45.407087 | debian-bookworm -> localhost | ok 2025-08-21 18:08:45.413776 | 2025-08-21 18:08:45.413901 | TASK [validate-host : Collect information about the host] 2025-08-21 18:08:46.519585 | debian-bookworm | ok 2025-08-21 18:08:46.532638 | 2025-08-21 18:08:46.532752 | TASK [validate-host : Sanitize hostname] 2025-08-21 18:08:46.644966 | debian-bookworm | ok 2025-08-21 18:08:46.675757 | 2025-08-21 18:08:46.675859 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-21 18:08:47.193804 | debian-bookworm -> localhost | changed 2025-08-21 18:08:47.199222 | 2025-08-21 18:08:47.199327 | TASK [validate-host : Collect information about zuul worker] 2025-08-21 18:08:47.596525 | debian-bookworm | ok 2025-08-21 18:08:47.600934 | 2025-08-21 18:08:47.601070 | TASK [validate-host : Write out all zuul information for each host] 2025-08-21 18:08:48.143394 | debian-bookworm -> localhost | changed 2025-08-21 18:08:48.153657 | 2025-08-21 18:08:48.153773 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-21 18:08:48.424923 | debian-bookworm | ok 2025-08-21 18:08:48.430501 | 2025-08-21 18:08:48.430592 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-21 18:08:49.283434 | debian-bookworm | changed: 2025-08-21 18:08:49.283620 | debian-bookworm | cd+++++++++ src/ 2025-08-21 18:08:49.283655 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-21 18:08:49.283680 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-21 18:08:49.283702 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-08-21 18:08:49.283722 | debian-bookworm | Containerfile 2025-08-21 18:08:49.283852 | debian-bookworm | 3.5.2-r0) 2025-08-21 18:09:50.407291 | debian-bookworm | #9 5.863 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-08-21 18:09:50.522666 | debian-bookworm | #9 5.894 (26/34) Installing openssl-dev (3.5.2-r0) 2025-08-21 18:09:50.522803 | debian-bookworm | #9 5.955 (27/34) Installing mpdecimal (4.0.1-r0) 2025-08-21 18:09:50.523212 | debian-bookworm | #9 5.979 (28/34) Installing python3 (3.12.11-r0) 2025-08-21 18:09:50.794687 | debian-bookworm | #9 6.251 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-08-21 18:09:50.953161 | debian-bookworm | #9 6.409 (30/34) Installing pyc (3.12.11-r0) 2025-08-21 18:09:50.953264 | debian-bookworm | #9 6.409 (31/34) Installing python3-pyc (3.12.11-r0) 2025-08-21 18:09:50.953280 | debian-bookworm | #9 6.409 (32/34) Installing python3-dev (3.12.11-r0) 2025-08-21 18:09:51.400522 | debian-bookworm | #9 6.857 (33/34) Installing yq-go (4.46.1-r1) 2025-08-21 18:09:51.562487 | debian-bookworm | #9 7.019 (34/34) Installing .build-deps (20250821.180947) 2025-08-21 18:09:51.773582 | debian-bookworm | #9 7.020 Executing busybox-1.37.0-r18.trigger 2025-08-21 18:09:51.773752 | debian-bookworm | #9 7.025 Executing ca-certificates-20250619-r0.trigger 2025-08-21 18:09:51.773806 | debian-bookworm | #9 7.078 OK: 359 MiB in 82 packages 2025-08-21 18:09:52.014683 | debian-bookworm | #9 DONE 7.5s 2025-08-21 18:09:52.173412 | debian-bookworm | 2025-08-21 18:09:52.173502 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-08-21 18:09:52.173527 | debian-bookworm | #10 DONE 0.1s 2025-08-21 18:09:52.173542 | debian-bookworm | 2025-08-21 18:09:52.173554 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-08-21 18:09:52.173569 | debian-bookworm | #11 DONE 0.1s 2025-08-21 18:09:52.397129 | debian-bookworm | 2025-08-21 18:09:52.397268 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-08-21 18:09:52.397317 | debian-bookworm | #12 DONE 0.1s 2025-08-21 18:09:52.397353 | debian-bookworm | 2025-08-21 18:09:52.397381 | 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-21 18:09:53.072844 | debian-bookworm | #13 0.676 Using Python 3.13.7 environment at: /usr/local 2025-08-21 18:09:53.248224 | debian-bookworm | #13 1.002 Resolved 17 packages in 323ms 2025-08-21 18:09:53.431040 | debian-bookworm | #13 1.032 Downloading ansible-core (2.3MiB) 2025-08-21 18:09:53.431090 | debian-bookworm | #13 1.033 Downloading netaddr (2.2MiB) 2025-08-21 18:09:53.431110 | debian-bookworm | #13 1.034 Downloading cryptography (4.4MiB) 2025-08-21 18:09:53.639371 | debian-bookworm | #13 1.393 Downloading netaddr 2025-08-21 18:09:53.765292 | debian-bookworm | #13 1.395 Downloading ansible-core 2025-08-21 18:09:53.765331 | debian-bookworm | #13 1.479 Downloading cryptography 2025-08-21 18:09:53.765337 | debian-bookworm | #13 1.480 Prepared 17 packages in 475ms 2025-08-21 18:09:53.765342 | debian-bookworm | #13 1.519 Installed 17 packages in 39ms 2025-08-21 18:09:53.765348 | debian-bookworm | #13 1.519 + ansible-core==2.19.0 2025-08-21 18:09:53.765353 | debian-bookworm | #13 1.519 + certifi==2025.8.3 2025-08-21 18:09:53.765357 | debian-bookworm | #13 1.519 + cffi==1.17.1 2025-08-21 18:09:53.765361 | debian-bookworm | #13 1.519 + charset-normalizer==3.4.3 2025-08-21 18:09:53.916528 | debian-bookworm | #13 1.519 + cryptography==45.0.6 2025-08-21 18:09:53.916608 | debian-bookworm | #13 1.520 + dynaconf==3.2.11 2025-08-21 18:09:53.916622 | debian-bookworm | #13 1.520 + idna==3.10 2025-08-21 18:09:53.916631 | debian-bookworm | #13 1.520 + jinja2==3.1.6 2025-08-21 18:09:53.916640 | debian-bookworm | #13 1.520 + markupsafe==3.0.2 2025-08-21 18:09:53.916649 | debian-bookworm | #13 1.520 + netaddr==1.3.0 2025-08-21 18:09:53.916658 | debian-bookworm | #13 1.520 + packaging==25.0 2025-08-21 18:09:53.916666 | debian-bookworm | #13 1.520 + pycparser==2.22 2025-08-21 18:09:53.916675 | debian-bookworm | #13 1.520 + pynetbox==7.5.0 2025-08-21 18:09:53.916684 | debian-bookworm | #13 1.520 + pyyaml==6.0.2 2025-08-21 18:09:53.916692 | debian-bookworm | #13 1.520 + requests==2.32.5 2025-08-21 18:09:53.916701 | debian-bookworm | #13 1.520 + resolvelib==1.2.0 2025-08-21 18:09:53.916710 | debian-bookworm | #13 1.520 + urllib3==2.5.0 2025-08-21 18:09:53.920223 | debian-bookworm | #13 DONE 1.7s 2025-08-21 18:09:54.078110 | debian-bookworm | 2025-08-21 18:09:54.078161 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007831 2025-08-21 18:10:22.084887 | 2025-08-21 18:10:22.085152 | TASK [upload-logs : Upload logs to log server] 2025-08-21 18:10:22.639440 | localhost | Output suppressed because no_log was given 2025-08-21 18:10:22.642550 | 2025-08-21 18:10:22.642689 | LOOP [upload-logs : Compress console log and json output] 2025-08-21 18:10:22.707517 | localhost | skipping: Conditional result was False 2025-08-21 18:10:22.712390 | localhost | skipping: Conditional result was False 2025-08-21 18:10:22.725793 | 2025-08-21 18:10:22.726063 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-21 18:10:22.772341 | localhost | skipping: Conditional result was False 2025-08-21 18:10:22.773160 | 2025-08-21 18:10:22.776430 | localhost | skipping: Conditional result was False 2025-08-21 18:10:22.790718 | 2025-08-21 18:10:22.791007 | LOOP [upload-logs : Upload console log and json output]