2026-10-01 13:13:55.481331 | Job console starting 2026-10-01 13:13:55.526007 | Updating git repos 2026-10-01 13:13:55.582182 | Cloning repos into workspace 2026-10-01 13:13:55.652801 | Restoring repo states 2026-10-01 13:13:55.674346 | Merging changes 2026-10-01 13:13:56.435366 | Checking out repos 2026-10-01 13:13:56.526181 | Preparing playbooks 2026-10-01 13:13:57.818511 | Running Ansible setup 2026-10-01 13:14:02.283295 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-01 13:14:03.126927 | 2026-10-01 13:14:03.127034 | PLAY [Base pre] 2026-10-01 13:14:03.143806 | 2026-10-01 13:14:03.143900 | TASK [Setup log path fact] 2026-10-01 13:14:03.182984 | debian-bookworm | ok 2026-10-01 13:14:03.206203 | 2026-10-01 13:14:03.206321 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 13:14:03.243451 | debian-bookworm | ok 2026-10-01 13:14:03.275473 | 2026-10-01 13:14:03.275618 | TASK [emit-job-header : Print job information] 2026-10-01 13:14:03.302347 | # Job Information 2026-10-01 13:14:03.302483 | Ansible Version: 2.16.14 2026-10-01 13:14:03.302512 | Job: container-image-inventory-reconciler-build 2026-10-01 13:14:03.302539 | Pipeline: check 2026-10-01 13:14:03.302583 | Executor: 521e9411259a 2026-10-01 13:14:03.302601 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/575 2026-10-01 13:14:03.302619 | Event ID: ed5f7ca0-bd99-11f1-8720-5491d9fed836 2026-10-01 13:14:03.306638 | 2026-10-01 13:14:03.306730 | LOOP [emit-job-header : Print node information] 2026-10-01 13:14:03.432634 | debian-bookworm | ok: 2026-10-01 13:14:03.432893 | debian-bookworm | # Node Information 2026-10-01 13:14:03.432930 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-01 13:14:03.432951 | debian-bookworm | Hostname: debian 2026-10-01 13:14:03.432970 | debian-bookworm | Username: zuul 2026-10-01 13:14:03.432988 | debian-bookworm | Distro: Debian 12.15 2026-10-01 13:14:03.433014 | debian-bookworm | Provider: regiocloud-a 2026-10-01 13:14:03.433034 | debian-bookworm | Region: 2026-10-01 13:14:03.433052 | debian-bookworm | Label: debian-bookworm 2026-10-01 13:14:03.433069 | debian-bookworm | Product Name: OpenStack Nova 2026-10-01 13:14:03.433085 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8f:c9ed 2026-10-01 13:14:03.451994 | 2026-10-01 13:14:03.452110 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-01 13:14:04.042209 | debian-bookworm -> localhost | changed 2026-10-01 13:14:04.048962 | 2026-10-01 13:14:04.049059 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-01 13:14:05.458596 | debian-bookworm -> localhost | changed 2026-10-01 13:14:05.475064 | 2026-10-01 13:14:05.475175 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-01 13:14:05.914802 | debian-bookworm -> localhost | ok 2026-10-01 13:14:05.919654 | 2026-10-01 13:14:05.919740 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-01 13:14:05.967255 | debian-bookworm | ok 2026-10-01 13:14:05.992379 | debian-bookworm | included: /var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-01 13:14:06.002878 | 2026-10-01 13:14:06.002960 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-01 13:14:08.128563 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-01 13:14:08.128917 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/work/04f6fc8da9c148489332871d47d56840_id_rsa 2026-10-01 13:14:08.128995 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/work/04f6fc8da9c148489332871d47d56840_id_rsa.pub 2026-10-01 13:14:08.129051 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-01 13:14:08.129104 | debian-bookworm -> localhost | SHA256:OIau623fDcG0fPSfqncUgdqlAISSUV85KCznEErE44E zuul-build-sshkey 2026-10-01 13:14:08.129170 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-01 13:14:08.129217 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-01 13:14:08.129261 | debian-bookworm -> localhost | | +o o*.ooo.. . | 2026-10-01 13:14:08.129308 | debian-bookworm -> localhost | |E.+.= =...+ . o | 2026-10-01 13:14:08.129353 | debian-bookworm -> localhost | | ..o * .o .= o . | 2026-10-01 13:14:08.129396 | debian-bookworm -> localhost | | . ..= o..o . | 2026-10-01 13:14:08.129459 | debian-bookworm -> localhost | | . + S . . . | 2026-10-01 13:14:08.129516 | debian-bookworm -> localhost | | . . . o ... | 2026-10-01 13:14:08.129763 | debian-bookworm -> localhost | | . . .o | 2026-10-01 13:14:08.129825 | debian-bookworm -> localhost | | o. . o ... | 2026-10-01 13:14:08.129875 | debian-bookworm -> localhost | | .+o... . oo.. | 2026-10-01 13:14:08.129921 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-01 13:14:08.130036 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.427088 2026-10-01 13:14:08.150910 | 2026-10-01 13:14:08.151015 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-01 13:14:08.182441 | debian-bookworm | ok 2026-10-01 13:14:08.198798 | debian-bookworm | included: /var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-01 13:14:08.215250 | 2026-10-01 13:14:08.215356 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-01 13:14:08.228978 | debian-bookworm | skipping: Conditional result was False 2026-10-01 13:14:08.235050 | 2026-10-01 13:14:08.235141 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-01 13:14:08.869063 | debian-bookworm | changed 2026-10-01 13:14:08.874653 | 2026-10-01 13:14:08.874742 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-01 13:14:09.156815 | debian-bookworm | ok 2026-10-01 13:14:09.162084 | 2026-10-01 13:14:09.162215 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-01 13:14:09.902301 | debian-bookworm | changed 2026-10-01 13:14:09.907979 | 2026-10-01 13:14:09.908076 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-01 13:14:10.651622 | debian-bookworm | changed 2026-10-01 13:14:10.659419 | 2026-10-01 13:14:10.659513 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-01 13:14:10.682193 | debian-bookworm | skipping: Conditional result was False 2026-10-01 13:14:10.687816 | 2026-10-01 13:14:10.687907 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-01 13:14:11.179727 | debian-bookworm -> localhost | changed 2026-10-01 13:14:11.199290 | 2026-10-01 13:14:11.199393 | TASK [add-build-sshkey : Add back temp key] 2026-10-01 13:14:11.577245 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/work/04f6fc8da9c148489332871d47d56840_id_rsa (zuul-build-sshkey) 2026-10-01 13:14:11.577459 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008087 2026-10-01 13:14:11.585187 | 2026-10-01 13:14:11.585286 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-01 13:14:12.022813 | debian-bookworm | ok 2026-10-01 13:14:12.027781 | 2026-10-01 13:14:12.027866 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-01 13:14:12.055355 | debian-bookworm | skipping: Conditional result was False 2026-10-01 13:14:12.065744 | 2026-10-01 13:14:12.065850 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-01 13:14:12.482502 | debian-bookworm | ok 2026-10-01 13:14:12.496313 | 2026-10-01 13:14:12.496397 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-01 13:14:12.522486 | debian-bookworm | ok 2026-10-01 13:14:12.526443 | 2026-10-01 13:14:12.526512 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-01 13:14:12.802196 | debian-bookworm -> localhost | ok 2026-10-01 13:14:12.808344 | 2026-10-01 13:14:12.808439 | TASK [validate-host : Collect information about the host] 2026-10-01 13:14:13.980976 | debian-bookworm | ok 2026-10-01 13:14:13.992026 | 2026-10-01 13:14:13.992116 | TASK [validate-host : Sanitize hostname] 2026-10-01 13:14:14.114120 | debian-bookworm | ok 2026-10-01 13:14:14.117692 | 2026-10-01 13:14:14.117766 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-01 13:14:14.545016 | debian-bookworm -> localhost | changed 2026-10-01 13:14:14.549468 | 2026-10-01 13:14:14.549596 | TASK [validate-host : Collect information about zuul worker] 2026-10-01 13:14:14.934279 | debian-bookworm | ok 2026-10-01 13:14:14.941756 | 2026-10-01 13:14:14.941888 | TASK [validate-host : Write out all zuul information for each host] 2026-10-01 13:14:15.404352 | debian-bookworm -> localhost | changed 2026-10-01 13:14:15.419046 | 2026-10-01 13:14:15.419145 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-01 13:14:15.710193 | debian-bookworm | ok 2026-10-01 13:14:15.718150 | 2026-10-01 13:14:15.718259 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-01 13:14:16.549265 | debian-bookworm | changed: 2026-10-01 13:14:16.549471 | debian-bookworm | cd+++++++++ src/ 2026-10-01 13:14:16.549508 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-01 13:14:16.549534 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-01 13:14:16.549625 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-10-01 13:14:16.549650 | debian-bookworm | Containerfile 2026-10-01 13:14:16.549815 | debian-bookworm | 3.5.9-r0) 2026-10-01 13:15:38.886003 | debian-bookworm | #9 5.911 (26/35) Upgrading libssl3 (3.5.8-r0 -> 3.5.9-r0) 2026-10-01 13:15:39.125501 | debian-bookworm | #9 5.935 (27/35) Installing openssl-dev (3.5.9-r0) 2026-10-01 13:15:39.125588 | debian-bookworm | #9 5.984 (28/35) Installing mpdecimal (4.0.1-r0) 2026-10-01 13:15:39.125619 | debian-bookworm | #9 5.999 (29/35) Installing python3 (3.14.8-r0) 2026-10-01 13:15:39.250046 | debian-bookworm | #9 6.274 (30/35) Installing python3-pycache-pyc0 (3.14.8-r0) 2026-10-01 13:15:39.426464 | debian-bookworm | #9 6.451 (31/35) Installing pyc (3.14.8-r0) 2026-10-01 13:15:39.426519 | debian-bookworm | #9 6.451 (32/35) Installing python3-pyc (3.14.8-r0) 2026-10-01 13:15:39.426525 | debian-bookworm | #9 6.451 (33/35) Installing python3-dev (3.14.8-r0) 2026-10-01 13:15:41.122122 | debian-bookworm | #9 8.147 (34/35) Installing yq-go (4.53.3-r1) 2026-10-01 13:15:41.317364 | debian-bookworm | #9 8.342 (35/35) Installing .build-deps (20261001.131535) 2026-10-01 13:15:41.538715 | debian-bookworm | #9 8.343 Executing busybox-1.37.0-r31.trigger 2026-10-01 13:15:41.547229 | debian-bookworm | #9 8.350 Executing ca-certificates-20260909-r0.trigger 2026-10-01 13:15:41.547274 | debian-bookworm | #9 8.412 OK: 451.4 MiB in 83 packages 2026-10-01 13:15:41.828670 | debian-bookworm | #9 DONE 8.9s 2026-10-01 13:15:41.968949 | debian-bookworm | 2026-10-01 13:15:41.969051 | debian-bookworm | #10 [builder 4/23] COPY --link files/requirements.txt /requirements.txt 2026-10-01 13:15:41.969069 | debian-bookworm | #10 DONE 0.8s 2026-10-01 13:15:42.076186 | debian-bookworm | 2026-10-01 13:15:42.076297 | debian-bookworm | #11 [builder 5/23] COPY --link files/render-python-requirements.py /render-python-requirements.py 2026-10-01 13:15:42.076322 | debian-bookworm | #11 DONE 0.6s 2026-10-01 13:15:42.076333 | debian-bookworm | 2026-10-01 13:15:42.076344 | debian-bookworm | #12 [builder 6/23] COPY --link files/templates /templates 2026-10-01 13:15:42.076352 | debian-bookworm | #12 merging 2026-10-01 13:15:42.176151 | debian-bookworm | #12 merging 0.1s done 2026-10-01 13:15:42.176396 | debian-bookworm | #12 DONE 0.7s 2026-10-01 13:15:42.176420 | debian-bookworm | 2026-10-01 13:15:42.176432 | 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 13:15:43.087837 | debian-bookworm | #13 0.912 Using Python 3.14.7 environment at: /usr/local 2026-10-01 13:15:43.457417 | debian-bookworm | #13 1.281 Resolved 17 packages in 366ms 2026-10-01 13:15:43.667818 | debian-bookworm | #13 1.321 Downloading cryptography (4.8MiB) 2026-10-01 13:15:43.667886 | debian-bookworm | #13 1.324 Downloading netaddr (2.2MiB) 2026-10-01 13:15:43.667904 | debian-bookworm | #13 1.341 Downloading ansible-core (2.3MiB) 2026-10-01 13:15:43.766371 | debian-bookworm | #13 1.591 Downloaded netaddr 2026-10-01 13:15:44.000941 | debian-bookworm | #13 1.675 Downloaded cryptography 2026-10-01 13:15:44.017485 | debian-bookworm | #13 1.842 Downloaded ansible-core 2026-10-01 13:15:44.227125 | debian-bookworm | #13 1.843 Prepared 17 packages in 559ms 2026-10-01 13:15:44.227223 | debian-bookworm | #13 1.895 Installed 17 packages in 51ms 2026-10-01 13:15:44.227255 | debian-bookworm | #13 1.896 + ansible-core==2.19.11 2026-10-01 13:15:44.227285 | debian-bookworm | #13 1.896 + certifi==2026.7.22 2026-10-01 13:15:44.227310 | debian-bookworm | #13 1.896 + cffi==2.1.1 2026-10-01 13:15:44.227329 | debian-bookworm | #13 1.897 + charset-normalizer==3.5.2 2026-10-01 13:15:44.227346 | debian-bookworm | #13 1.898 + cryptography==50.0.2 2026-10-01 13:15:44.227364 | debian-bookworm | #13 1.898 + dynaconf==3.3.5 2026-10-01 13:15:44.227382 | debian-bookworm | #13 1.898 + idna==3.20 2026-10-01 13:15:44.227400 | debian-bookworm | #13 1.899 + jinja2==3.1.6 2026-10-01 13:15:44.227417 | debian-bookworm | #13 1.899 + markupsafe==3.0.3 2026-10-01 13:15:44.227435 | debian-bookworm | #13 1.899 + netaddr==1.3.0 2026-10-01 13:15:44.227454 | debian-bookworm | #13 1.900 + packaging==26.3 2026-10-01 13:15:44.227471 | debian-bookworm | #13 1.900 + pycparser==3.0 2026-10-01 13:15:44.227487 | debian-bookworm | #13 1.900 + pynetbox==7.8.0 2026-10-01 13:15:44.227504 | debian-bookworm | #13 1.901 + pyyaml==6.0.3 2026-10-01 13:15:44.227522 | debian-bookworm | #13 1.901 + requests==2.34.2 2026-10-01 13:15:44.227540 | debian-bookworm | #13 1.901 + resolvelib==1.2.1 2026-10-01 13:15:44.227557 | debian-bookworm | #13 1.901 + urllib3==2.8.0 2026-10-01 13:15:44.248084 | debian-bookworm | #13 DONE 2.1s 2026-10-01 13:15:44.402647 | debian-bookworm | 2026-10-01 13:15:44.402772 | debian-bookworm | #14 [builder 8/23] RUN < localhost | ok: "/var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/work/logs" 2026-10-01 13:17:13.466536 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/work/artifacts" 2026-10-01 13:17:13.728015 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/04f6fc8da9c148489332871d47d56840/work/docs" 2026-10-01 13:17:13.746226 | 2026-10-01 13:17:13.746368 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-01 13:17:14.505024 | debian-bookworm | changed: 2026-10-01 13:17:14.505272 | debian-bookworm | .d..t...... ./ 2026-10-01 13:17:14.505321 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-01 13:17:14.505354 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-01 13:17:14.505377 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-01 13:17:14.505516 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-01 13:17:14.505585 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-01 13:17:14.505637 | debian-bookworm | changed: All items complete 2026-10-01 13:17:14.505667 | 2026-10-01 13:17:15.078899 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 13:17:15.639228 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 13:17:15.669376 | 2026-10-01 13:17:15.669535 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-01 13:17:15.999663 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008625 2026-10-01 13:17:16.288359 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008499 2026-10-01 13:17:16.298835 | 2026-10-01 13:17:16.298937 | PLAY RECAP 2026-10-01 13:17:16.298991 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-01 13:17:16.299018 | 2026-10-01 13:17:16.421331 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-01 13:17:16.423599 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 13:17:17.157833 | 2026-10-01 13:17:17.157975 | PLAY [Base post] 2026-10-01 13:17:17.171266 | 2026-10-01 13:17:17.171389 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-01 13:17:18.027941 | debian-bookworm | changed 2026-10-01 13:17:18.038284 | 2026-10-01 13:17:18.038434 | PLAY RECAP 2026-10-01 13:17:18.038524 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-01 13:17:18.038633 | 2026-10-01 13:17:18.154667 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 13:17:18.156862 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-01 13:17:18.921791 | 2026-10-01 13:17:18.921947 | PLAY [Base post-logs] 2026-10-01 13:17:18.932120 | 2026-10-01 13:17:18.932253 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-01 13:17:19.356817 | localhost | changed 2026-10-01 13:17:19.366920 | 2026-10-01 13:17:19.367074 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-01 13:17:19.402640 | localhost | ok 2026-10-01 13:17:19.406649 | 2026-10-01 13:17:19.406774 | TASK [Set zuul-log-path fact] 2026-10-01 13:17:19.422017 | localhost | ok 2026-10-01 13:17:19.430928 | 2026-10-01 13:17:19.431040 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 13:17:19.456462 | localhost | ok 2026-10-01 13:17:19.462023 | 2026-10-01 13:17:19.462193 | TASK [upload-logs : Create log directories] 2026-10-01 13:17:19.949655 | localhost | changed 2026-10-01 13:17:19.954438 | 2026-10-01 13:17:19.954620 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-01 13:17:20.445894 | localhost -> localhost | ok: Runtime: 0:00:00.007089 2026-10-01 13:17:20.454959 | 2026-10-01 13:17:20.455183 | TASK [upload-logs : Upload logs to log server] 2026-10-01 13:17:21.001134 | localhost | Output suppressed because no_log was given 2026-10-01 13:17:21.005654 | 2026-10-01 13:17:21.005880 | LOOP [upload-logs : Compress console log and json output] 2026-10-01 13:17:21.064674 | localhost | skipping: Conditional result was False 2026-10-01 13:17:21.069921 | localhost | skipping: Conditional result was False 2026-10-01 13:17:21.074502 | 2026-10-01 13:17:21.074657 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-01 13:17:21.131167 | localhost | skipping: Conditional result was False 2026-10-01 13:17:21.131739 | 2026-10-01 13:17:21.135269 | localhost | skipping: Conditional result was False 2026-10-01 13:17:21.144646 | 2026-10-01 13:17:21.144878 | LOOP [upload-logs : Upload console log and json output]