2026-10-01 15:29:48.220720 | Job console starting 2026-10-01 15:29:48.232599 | Updating git repos 2026-10-01 15:29:48.266451 | Cloning repos into workspace 2026-10-01 15:29:48.325821 | Restoring repo states 2026-10-01 15:29:48.340776 | Merging changes 2026-10-01 15:29:48.340809 | Checking out repos 2026-10-01 15:29:48.412472 | Preparing playbooks 2026-10-01 15:29:49.246202 | Running Ansible setup 2026-10-01 15:29:53.569616 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-01 15:29:54.332671 | 2026-10-01 15:29:54.332824 | PLAY [Base pre] 2026-10-01 15:29:54.351187 | 2026-10-01 15:29:54.351311 | TASK [Setup log path fact] 2026-10-01 15:29:54.375618 | debian-bookworm | ok 2026-10-01 15:29:54.391312 | 2026-10-01 15:29:54.391434 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 15:29:54.431283 | debian-bookworm | ok 2026-10-01 15:29:54.442214 | 2026-10-01 15:29:54.442321 | TASK [emit-job-header : Print job information] 2026-10-01 15:29:54.487521 | # Job Information 2026-10-01 15:29:54.487782 | Ansible Version: 2.16.14 2026-10-01 15:29:54.487874 | Job: container-image-inventory-reconciler-push 2026-10-01 15:29:54.487935 | Pipeline: post 2026-10-01 15:29:54.487977 | Executor: 521e9411259a 2026-10-01 15:29:54.488017 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/commit/bc3026fb1d0436659d273aee89874f40ed6717a8 2026-10-01 15:29:54.488079 | Event ID: ec70313c-bdac-11f1-8349-4699847e4db8 2026-10-01 15:29:54.495892 | 2026-10-01 15:29:54.496079 | LOOP [emit-job-header : Print node information] 2026-10-01 15:29:54.611323 | debian-bookworm | ok: 2026-10-01 15:29:54.611574 | debian-bookworm | # Node Information 2026-10-01 15:29:54.611630 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-01 15:29:54.611673 | debian-bookworm | Hostname: debian 2026-10-01 15:29:54.611710 | debian-bookworm | Username: zuul 2026-10-01 15:29:54.611744 | debian-bookworm | Distro: Debian 12.15 2026-10-01 15:29:54.611784 | debian-bookworm | Provider: regiocloud-a 2026-10-01 15:29:54.611820 | debian-bookworm | Region: 2026-10-01 15:29:54.611854 | debian-bookworm | Label: debian-bookworm 2026-10-01 15:29:54.611886 | debian-bookworm | Product Name: OpenStack Nova 2026-10-01 15:29:54.611917 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec1:2fbd 2026-10-01 15:29:54.640833 | 2026-10-01 15:29:54.641140 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-01 15:29:55.113498 | debian-bookworm -> localhost | changed 2026-10-01 15:29:55.122959 | 2026-10-01 15:29:55.123280 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-01 15:29:56.155843 | debian-bookworm -> localhost | changed 2026-10-01 15:29:56.175791 | 2026-10-01 15:29:56.175924 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-01 15:29:56.453792 | debian-bookworm -> localhost | ok 2026-10-01 15:29:56.467619 | 2026-10-01 15:29:56.467793 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-01 15:29:56.502710 | debian-bookworm | ok 2026-10-01 15:29:56.519298 | debian-bookworm | included: /var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-01 15:29:56.525342 | 2026-10-01 15:29:56.525435 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-01 15:29:57.592553 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-01 15:29:57.592985 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/work/80339b9cc38245c682566b861cfc7134_id_rsa 2026-10-01 15:29:57.593077 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/work/80339b9cc38245c682566b861cfc7134_id_rsa.pub 2026-10-01 15:29:57.593127 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-01 15:29:57.593170 | debian-bookworm -> localhost | SHA256:iKG4vSssF0JvuCnKwhugE1dlymq14M6he/FHG4iWAVA zuul-build-sshkey 2026-10-01 15:29:57.593233 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-01 15:29:57.593274 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-01 15:29:57.593313 | debian-bookworm -> localhost | |o.E o | 2026-10-01 15:29:57.593351 | debian-bookworm -> localhost | |. . + | 2026-10-01 15:29:57.593389 | debian-bookworm -> localhost | | .. * | 2026-10-01 15:29:57.593425 | debian-bookworm -> localhost | | +.* + . | 2026-10-01 15:29:57.593462 | debian-bookworm -> localhost | |= X+o.. S | 2026-10-01 15:29:57.593511 | debian-bookworm -> localhost | |+@*+. o | 2026-10-01 15:29:57.593576 | debian-bookworm -> localhost | |B+B+ . o | 2026-10-01 15:29:57.593617 | debian-bookworm -> localhost | |*B+.. o | 2026-10-01 15:29:57.593654 | debian-bookworm -> localhost | |B*+. . | 2026-10-01 15:29:57.593690 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-01 15:29:57.593782 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.563969 2026-10-01 15:29:57.603433 | 2026-10-01 15:29:57.603555 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-01 15:29:57.637756 | debian-bookworm | ok 2026-10-01 15:29:57.651814 | debian-bookworm | included: /var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-01 15:29:57.660304 | 2026-10-01 15:29:57.660401 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-01 15:29:57.685151 | debian-bookworm | skipping: Conditional result was False 2026-10-01 15:29:57.691338 | 2026-10-01 15:29:57.691437 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-01 15:29:58.364690 | debian-bookworm | changed 2026-10-01 15:29:58.374078 | 2026-10-01 15:29:58.374225 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-01 15:29:58.663418 | debian-bookworm | ok 2026-10-01 15:29:58.672229 | 2026-10-01 15:29:58.672360 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-01 15:29:59.377146 | debian-bookworm | changed 2026-10-01 15:29:59.385643 | 2026-10-01 15:29:59.385767 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-01 15:30:00.114267 | debian-bookworm | changed 2026-10-01 15:30:00.120082 | 2026-10-01 15:30:00.120188 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-01 15:30:00.154421 | debian-bookworm | skipping: Conditional result was False 2026-10-01 15:30:00.167652 | 2026-10-01 15:30:00.167811 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-01 15:30:00.649519 | debian-bookworm -> localhost | changed 2026-10-01 15:30:00.660763 | 2026-10-01 15:30:00.660874 | TASK [add-build-sshkey : Add back temp key] 2026-10-01 15:30:00.989946 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/work/80339b9cc38245c682566b861cfc7134_id_rsa (zuul-build-sshkey) 2026-10-01 15:30:00.990493 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022651 2026-10-01 15:30:01.003243 | 2026-10-01 15:30:01.003373 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-01 15:30:01.446725 | debian-bookworm | ok 2026-10-01 15:30:01.455010 | 2026-10-01 15:30:01.455167 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-01 15:30:01.479688 | debian-bookworm | skipping: Conditional result was False 2026-10-01 15:30:01.495889 | 2026-10-01 15:30:01.496002 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-01 15:30:01.920525 | debian-bookworm | ok 2026-10-01 15:30:01.931691 | 2026-10-01 15:30:01.931801 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-01 15:30:01.972054 | debian-bookworm | ok 2026-10-01 15:30:01.978138 | 2026-10-01 15:30:01.978249 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-01 15:30:02.256220 | debian-bookworm -> localhost | ok 2026-10-01 15:30:02.265055 | 2026-10-01 15:30:02.265213 | TASK [validate-host : Collect information about the host] 2026-10-01 15:30:03.432851 | debian-bookworm | ok 2026-10-01 15:30:03.449111 | 2026-10-01 15:30:03.449269 | TASK [validate-host : Sanitize hostname] 2026-10-01 15:30:03.623425 | debian-bookworm | ok 2026-10-01 15:30:03.631642 | 2026-10-01 15:30:03.631755 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-01 15:30:04.276875 | debian-bookworm -> localhost | changed 2026-10-01 15:30:04.289171 | 2026-10-01 15:30:04.289292 | TASK [validate-host : Collect information about zuul worker] 2026-10-01 15:30:04.775540 | debian-bookworm | ok 2026-10-01 15:30:04.784644 | 2026-10-01 15:30:04.784785 | TASK [validate-host : Write out all zuul information for each host] 2026-10-01 15:30:05.402490 | debian-bookworm -> localhost | changed 2026-10-01 15:30:05.412300 | 2026-10-01 15:30:05.412411 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-01 15:30:05.720642 | debian-bookworm | ok 2026-10-01 15:30:05.730927 | 2026-10-01 15:30:05.731104 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-01 15:30:06.679001 | debian-bookworm | changed: 2026-10-01 15:30:06.679341 | debian-bookworm | cd+++++++++ src/ 2026-10-01 15:30:06.679386 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-01 15:30:06.679412 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-01 15:30:06.679435 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-10-01 15:30:06.679458 | debian-bookworm | Containerfile 2026-10-01 15:30:06.679616 | debian-bookworm | 3.5.9-r0) 2026-10-01 15:31:31.914256 | debian-bookworm | #9 6.206 (26/35) Upgrading libssl3 (3.5.8-r0 -> 3.5.9-r0) 2026-10-01 15:31:32.154329 | debian-bookworm | #9 6.237 (27/35) Installing openssl-dev (3.5.9-r0) 2026-10-01 15:31:32.154506 | debian-bookworm | #9 6.282 (28/35) Installing mpdecimal (4.0.1-r0) 2026-10-01 15:31:32.154588 | debian-bookworm | #9 6.296 (29/35) Installing python3 (3.14.8-r0) 2026-10-01 15:31:32.315983 | debian-bookworm | #9 6.608 (30/35) Installing python3-pycache-pyc0 (3.14.8-r0) 2026-10-01 15:31:32.502568 | debian-bookworm | #9 6.794 (31/35) Installing pyc (3.14.8-r0) 2026-10-01 15:31:32.502632 | debian-bookworm | #9 6.794 (32/35) Installing python3-pyc (3.14.8-r0) 2026-10-01 15:31:32.502639 | debian-bookworm | #9 6.794 (33/35) Installing python3-dev (3.14.8-r0) 2026-10-01 15:31:34.220612 | debian-bookworm | #9 8.513 (34/35) Installing yq-go (4.53.3-r1) 2026-10-01 15:31:34.387024 | debian-bookworm | #9 8.679 (35/35) Installing .build-deps (20261001.153128) 2026-10-01 15:31:34.622216 | debian-bookworm | #9 8.679 Executing busybox-1.37.0-r31.trigger 2026-10-01 15:31:34.622388 | debian-bookworm | #9 8.687 Executing ca-certificates-20260909-r0.trigger 2026-10-01 15:31:34.622436 | debian-bookworm | #9 8.764 OK: 451.4 MiB in 83 packages 2026-10-01 15:31:35.009958 | debian-bookworm | #9 DONE 9.3s 2026-10-01 15:31:35.143852 | debian-bookworm | 2026-10-01 15:31:35.143960 | debian-bookworm | #10 [builder 4/23] COPY --link files/requirements.txt /requirements.txt 2026-10-01 15:31:35.144012 | debian-bookworm | #10 DONE 0.9s 2026-10-01 15:31:35.257313 | debian-bookworm | 2026-10-01 15:31:35.257438 | debian-bookworm | #11 [builder 5/23] COPY --link files/render-python-requirements.py /render-python-requirements.py 2026-10-01 15:31:35.257479 | debian-bookworm | #11 DONE 0.8s 2026-10-01 15:31:35.257505 | debian-bookworm | 2026-10-01 15:31:35.257532 | debian-bookworm | #12 [builder 6/23] COPY --link files/templates /templates 2026-10-01 15:31:35.257567 | debian-bookworm | #12 DONE 0.8s 2026-10-01 15:31:35.367142 | debian-bookworm | 2026-10-01 15:31:35.367244 | debian-bookworm | #12 [builder 6/23] COPY --link files/templates /templates 2026-10-01 15:31:35.367257 | debian-bookworm | #12 merging 0.1s done 2026-10-01 15:31:35.367279 | debian-bookworm | #12 DONE 0.9s 2026-10-01 15:31:35.517813 | debian-bookworm | 2026-10-01 15:31:35.517903 | debian-bookworm | #13 [builder 7/23] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2026-10-01 15:31:36.391966 | debian-bookworm | #13 1.025 Using Python 3.14.7 environment at: /usr/local 2026-10-01 15:31:36.825931 | debian-bookworm | #13 1.459 Resolved 17 packages in 431ms 2026-10-01 15:31:37.025523 | debian-bookworm | #13 1.491 Downloading ansible-core (2.3MiB) 2026-10-01 15:31:37.025802 | debian-bookworm | #13 1.506 Downloading cryptography (4.8MiB) 2026-10-01 15:31:37.025849 | debian-bookworm | #13 1.508 Downloading netaddr (2.2MiB) 2026-10-01 15:31:37.152589 | debian-bookworm | #13 1.785 Downloaded netaddr 2026-10-01 15:31:37.366930 | debian-bookworm | #13 1.849 Downloaded cryptography 2026-10-01 15:31:37.404588 | debian-bookworm | #13 2.038 Downloaded ansible-core 2026-10-01 15:31:37.619606 | debian-bookworm | #13 2.039 Prepared 17 packages in 578ms 2026-10-01 15:31:37.619697 | debian-bookworm | #13 2.098 Installed 17 packages in 57ms 2026-10-01 15:31:37.619708 | debian-bookworm | #13 2.098 + ansible-core==2.19.11 2026-10-01 15:31:37.619716 | debian-bookworm | #13 2.099 + certifi==2026.7.22 2026-10-01 15:31:37.619724 | debian-bookworm | #13 2.100 + cffi==2.1.1 2026-10-01 15:31:37.619733 | debian-bookworm | #13 2.100 + charset-normalizer==3.5.2 2026-10-01 15:31:37.619740 | debian-bookworm | #13 2.100 + cryptography==50.0.2 2026-10-01 15:31:37.619748 | debian-bookworm | #13 2.100 + dynaconf==3.3.5 2026-10-01 15:31:37.619755 | debian-bookworm | #13 2.100 + idna==3.20 2026-10-01 15:31:37.619773 | debian-bookworm | #13 2.100 + jinja2==3.1.6 2026-10-01 15:31:37.619781 | debian-bookworm | #13 2.101 + markupsafe==3.0.3 2026-10-01 15:31:37.619787 | debian-bookworm | #13 2.101 + netaddr==1.3.0 2026-10-01 15:31:37.619797 | debian-bookworm | #13 2.101 + packaging==26.3 2026-10-01 15:31:37.619804 | debian-bookworm | #13 2.101 + pycparser==3.0 2026-10-01 15:31:37.619811 | debian-bookworm | #13 2.101 + pynetbox==7.8.0 2026-10-01 15:31:37.619818 | debian-bookworm | #13 2.101 + pyyaml==6.0.3 2026-10-01 15:31:37.619826 | debian-bookworm | #13 2.101 + requests==2.34.2 2026-10-01 15:31:37.619833 | debian-bookworm | #13 2.102 + resolvelib==1.2.1 2026-10-01 15:31:37.619840 | debian-bookworm | #13 2.102 + urllib3==2.8.0 2026-10-01 15:31:37.636980 | debian-bookworm | #13 DONE 2.3s 2026-10-01 15:31:37.796536 | debian-bookworm | 2026-10-01 15:31:37.796634 | debian-bookworm | #14 [builder 8/23] RUN < localhost | ok: "/var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/work/logs" 2026-10-01 15:33:51.249364 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/work/artifacts" 2026-10-01 15:33:51.509891 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/80339b9cc38245c682566b861cfc7134/work/docs" 2026-10-01 15:33:51.527954 | 2026-10-01 15:33:51.528088 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-01 15:33:52.336092 | debian-bookworm | changed: 2026-10-01 15:33:52.336448 | debian-bookworm | .d..t...... ./ 2026-10-01 15:33:52.336502 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-01 15:33:52.336536 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-01 15:33:52.336565 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-01 15:33:52.336595 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-01 15:33:52.336622 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-01 15:33:52.336673 | debian-bookworm | changed: All items complete 2026-10-01 15:33:52.336710 | 2026-10-01 15:33:52.926123 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 15:33:53.525642 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 15:33:53.543496 | 2026-10-01 15:33:53.543628 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-01 15:33:53.573628 | debian-bookworm | skipping: Conditional result was False 2026-10-01 15:33:53.584488 | debian-bookworm | skipping: Conditional result was False 2026-10-01 15:33:53.602843 | 2026-10-01 15:33:53.602973 | PLAY RECAP 2026-10-01 15:33:53.603090 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-10-01 15:33:53.603166 | 2026-10-01 15:33:53.729588 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-01 15:33:53.732059 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 15:33:54.449425 | 2026-10-01 15:33:54.449622 | PLAY [Base post] 2026-10-01 15:33:54.463333 | 2026-10-01 15:33:54.463459 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-01 15:33:55.324314 | debian-bookworm | changed 2026-10-01 15:33:55.333927 | 2026-10-01 15:33:55.334044 | PLAY RECAP 2026-10-01 15:33:55.334121 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-01 15:33:55.334218 | 2026-10-01 15:33:55.442484 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 15:33:55.444863 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-01 15:33:56.208900 | 2026-10-01 15:33:56.209060 | PLAY [Base post-logs] 2026-10-01 15:33:56.219636 | 2026-10-01 15:33:56.219764 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-01 15:33:56.672423 | localhost | changed 2026-10-01 15:33:56.682515 | 2026-10-01 15:33:56.682658 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-01 15:33:56.718935 | localhost | ok 2026-10-01 15:33:56.723384 | 2026-10-01 15:33:56.723507 | TASK [Set zuul-log-path fact] 2026-10-01 15:33:56.739432 | localhost | ok 2026-10-01 15:33:56.748917 | 2026-10-01 15:33:56.749040 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 15:33:56.774451 | localhost | ok 2026-10-01 15:33:56.778409 | 2026-10-01 15:33:56.778536 | TASK [upload-logs : Create log directories] 2026-10-01 15:33:57.252387 | localhost | changed 2026-10-01 15:33:57.255302 | 2026-10-01 15:33:57.255413 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-01 15:33:57.739076 | localhost -> localhost | ok: Runtime: 0:00:00.003818 2026-10-01 15:33:57.748694 | 2026-10-01 15:33:57.748914 | TASK [upload-logs : Upload logs to log server] 2026-10-01 15:33:58.288510 | localhost | Output suppressed because no_log was given 2026-10-01 15:33:58.292700 | 2026-10-01 15:33:58.292911 | LOOP [upload-logs : Compress console log and json output] 2026-10-01 15:33:58.351707 | localhost | skipping: Conditional result was False 2026-10-01 15:33:58.356772 | localhost | skipping: Conditional result was False 2026-10-01 15:33:58.365829 | 2026-10-01 15:33:58.366040 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-01 15:33:58.412730 | localhost | skipping: Conditional result was False 2026-10-01 15:33:58.413397 | 2026-10-01 15:33:58.416668 | localhost | skipping: Conditional result was False 2026-10-01 15:33:58.430531 | 2026-10-01 15:33:58.430753 | LOOP [upload-logs : Upload console log and json output]