2025-08-27 14:51:14.851240 | Job console starting 2025-08-27 14:51:14.866912 | Updating git repos 2025-08-27 14:51:14.885891 | Cloning repos into workspace 2025-08-27 14:51:14.931424 | Restoring repo states 2025-08-27 14:51:14.941433 | Merging changes 2025-08-27 14:51:15.442093 | Checking out repos 2025-08-27 14:51:15.514076 | Preparing playbooks 2025-08-27 14:51:16.504008 | Running Ansible setup 2025-08-27 14:51:21.701007 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-27 14:51:23.111950 | 2025-08-27 14:51:23.112125 | PLAY [Base pre] 2025-08-27 14:51:23.150501 | 2025-08-27 14:51:23.150661 | TASK [Setup log path fact] 2025-08-27 14:51:23.180824 | debian-bookworm | ok 2025-08-27 14:51:23.219988 | 2025-08-27 14:51:23.220189 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 14:51:23.269004 | debian-bookworm | ok 2025-08-27 14:51:23.283667 | 2025-08-27 14:51:23.283831 | TASK [emit-job-header : Print job information] 2025-08-27 14:51:23.333658 | # Job Information 2025-08-27 14:51:23.333880 | Ansible Version: 2.16.14 2025-08-27 14:51:23.333917 | Job: container-image-inventory-reconciler-build 2025-08-27 14:51:23.333950 | Pipeline: check 2025-08-27 14:51:23.333973 | Executor: 521e9411259a 2025-08-27 14:51:23.333994 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/422 2025-08-27 14:51:23.334016 | Event ID: 42b8c6f0-8355-11f0-8396-06abac2ea529 2025-08-27 14:51:23.342131 | 2025-08-27 14:51:23.342266 | LOOP [emit-job-header : Print node information] 2025-08-27 14:51:23.452299 | debian-bookworm | ok: 2025-08-27 14:51:23.452488 | debian-bookworm | # Node Information 2025-08-27 14:51:23.452522 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-27 14:51:23.452547 | debian-bookworm | Hostname: debian 2025-08-27 14:51:23.452570 | debian-bookworm | Username: zuul 2025-08-27 14:51:23.452591 | debian-bookworm | Distro: Debian 12.11 2025-08-27 14:51:23.452615 | debian-bookworm | Provider: regiocloud-a 2025-08-27 14:51:23.452636 | debian-bookworm | Region: 2025-08-27 14:51:23.452657 | debian-bookworm | Label: debian-bookworm 2025-08-27 14:51:23.452676 | debian-bookworm | Product Name: OpenStack Nova 2025-08-27 14:51:23.452696 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feef:4efe 2025-08-27 14:51:23.469195 | 2025-08-27 14:51:23.469336 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-27 14:51:24.048597 | debian-bookworm -> localhost | changed 2025-08-27 14:51:24.055900 | 2025-08-27 14:51:24.056011 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-27 14:51:25.290107 | debian-bookworm -> localhost | changed 2025-08-27 14:51:25.303652 | 2025-08-27 14:51:25.303782 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-27 14:51:25.632211 | debian-bookworm -> localhost | ok 2025-08-27 14:51:25.637041 | 2025-08-27 14:51:25.637128 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-27 14:51:25.664035 | debian-bookworm | ok 2025-08-27 14:51:25.689747 | debian-bookworm | included: /var/lib/zuul/builds/5acf13d2113047dd8fe879447b11dc3f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-27 14:51:25.694765 | 2025-08-27 14:51:25.694857 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-27 14:51:26.937821 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-27 14:51:26.937978 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5acf13d2113047dd8fe879447b11dc3f/work/5acf13d2113047dd8fe879447b11dc3f_id_rsa 2025-08-27 14:51:26.938009 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5acf13d2113047dd8fe879447b11dc3f/work/5acf13d2113047dd8fe879447b11dc3f_id_rsa.pub 2025-08-27 14:51:26.938031 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-27 14:51:26.938052 | debian-bookworm -> localhost | SHA256:p/x45jGcdduucL1++6Vo9755ZqzPT/ccLt2yQhS2LMk zuul-build-sshkey 2025-08-27 14:51:26.938078 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-27 14:51:26.938096 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-27 14:51:26.938113 | debian-bookworm -> localhost | | | 2025-08-27 14:51:26.938130 | debian-bookworm -> localhost | | o | 2025-08-27 14:51:26.938147 | debian-bookworm -> localhost | | . + o | 2025-08-27 14:51:26.938163 | debian-bookworm -> localhost | | E + | 2025-08-27 14:51:26.938179 | debian-bookworm -> localhost | | S .o. . | 2025-08-27 14:51:26.938198 | debian-bookworm -> localhost | | . + o.. + | 2025-08-27 14:51:26.938215 | debian-bookworm -> localhost | | o =.. +oB| 2025-08-27 14:51:26.938231 | debian-bookworm -> localhost | | oooo=+B#| 2025-08-27 14:51:26.938248 | debian-bookworm -> localhost | | .+o..oB^^| 2025-08-27 14:51:26.938264 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-27 14:51:26.938308 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.603871 2025-08-27 14:51:26.943331 | 2025-08-27 14:51:26.943409 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-27 14:51:26.979559 | debian-bookworm | ok 2025-08-27 14:51:26.987000 | debian-bookworm | included: /var/lib/zuul/builds/5acf13d2113047dd8fe879447b11dc3f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-27 14:51:26.994187 | 2025-08-27 14:51:26.994260 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-27 14:51:27.006782 | debian-bookworm | skipping: Conditional result was False 2025-08-27 14:51:27.011429 | 2025-08-27 14:51:27.011500 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-27 14:51:27.585628 | debian-bookworm | changed 2025-08-27 14:51:27.590766 | 2025-08-27 14:51:27.590907 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-27 14:51:27.835062 | debian-bookworm | ok 2025-08-27 14:51:27.840367 | 2025-08-27 14:51:27.840457 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-27 14:51:28.494093 | debian-bookworm | changed 2025-08-27 14:51:28.504244 | 2025-08-27 14:51:28.504346 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-27 14:51:29.113050 | debian-bookworm | changed 2025-08-27 14:51:29.118812 | 2025-08-27 14:51:29.118944 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-27 14:51:29.142242 | debian-bookworm | skipping: Conditional result was False 2025-08-27 14:51:29.148500 | 2025-08-27 14:51:29.148613 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-27 14:51:29.618741 | debian-bookworm -> localhost | changed 2025-08-27 14:51:29.630108 | 2025-08-27 14:51:29.630254 | TASK [add-build-sshkey : Add back temp key] 2025-08-27 14:51:30.067325 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5acf13d2113047dd8fe879447b11dc3f/work/5acf13d2113047dd8fe879447b11dc3f_id_rsa (zuul-build-sshkey) 2025-08-27 14:51:30.067533 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022619 2025-08-27 14:51:30.075377 | 2025-08-27 14:51:30.075481 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-27 14:51:30.501428 | debian-bookworm | ok 2025-08-27 14:51:30.506222 | 2025-08-27 14:51:30.506310 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-27 14:51:30.548070 | debian-bookworm | skipping: Conditional result was False 2025-08-27 14:51:30.563533 | 2025-08-27 14:51:30.563650 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-27 14:51:30.975064 | debian-bookworm | ok 2025-08-27 14:51:30.986051 | 2025-08-27 14:51:30.986159 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-27 14:51:31.039381 | debian-bookworm | ok 2025-08-27 14:51:31.044344 | 2025-08-27 14:51:31.044432 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-27 14:51:31.327310 | debian-bookworm -> localhost | ok 2025-08-27 14:51:31.333376 | 2025-08-27 14:51:31.333470 | TASK [validate-host : Collect information about the host] 2025-08-27 14:51:32.615574 | debian-bookworm | ok 2025-08-27 14:51:32.633831 | 2025-08-27 14:51:32.633975 | TASK [validate-host : Sanitize hostname] 2025-08-27 14:51:32.744035 | debian-bookworm | ok 2025-08-27 14:51:32.790117 | 2025-08-27 14:51:32.790214 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-27 14:51:33.355484 | debian-bookworm -> localhost | changed 2025-08-27 14:51:33.361405 | 2025-08-27 14:51:33.361494 | TASK [validate-host : Collect information about zuul worker] 2025-08-27 14:51:33.755865 | debian-bookworm | ok 2025-08-27 14:51:33.760197 | 2025-08-27 14:51:33.760283 | TASK [validate-host : Write out all zuul information for each host] 2025-08-27 14:51:34.659045 | debian-bookworm -> localhost | changed 2025-08-27 14:51:34.686958 | 2025-08-27 14:51:34.687059 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-27 14:51:34.989104 | debian-bookworm | ok 2025-08-27 14:51:34.994298 | 2025-08-27 14:51:34.994390 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-27 14:51:35.918320 | debian-bookworm | changed: 2025-08-27 14:51:35.918860 | debian-bookworm | cd+++++++++ src/ 2025-08-27 14:51:35.918916 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-27 14:51:35.918938 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-27 14:51:35.918957 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-08-27 14:51:35.918974 | debian-bookworm | Containerfile 2025-08-27 14:51:35.919086 | debian-bookworm | 3.5.2-r0) 2025-08-27 14:52:40.226113 | debian-bookworm | #9 5.757 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-08-27 14:52:40.336547 | debian-bookworm | #9 5.788 (26/34) Installing openssl-dev (3.5.2-r0) 2025-08-27 14:52:40.336624 | debian-bookworm | #9 5.843 (27/34) Installing mpdecimal (4.0.1-r0) 2025-08-27 14:52:40.336646 | debian-bookworm | #9 5.868 (28/34) Installing python3 (3.12.11-r0) 2025-08-27 14:52:40.642379 | debian-bookworm | #9 6.173 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-08-27 14:52:40.811855 | debian-bookworm | #9 6.343 (30/34) Installing pyc (3.12.11-r0) 2025-08-27 14:52:40.811944 | debian-bookworm | #9 6.343 (31/34) Installing python3-pyc (3.12.11-r0) 2025-08-27 14:52:40.811976 | debian-bookworm | #9 6.343 (32/34) Installing python3-dev (3.12.11-r0) 2025-08-27 14:52:41.329915 | debian-bookworm | #9 6.861 (33/34) Installing yq-go (4.46.1-r1) 2025-08-27 14:52:41.487415 | debian-bookworm | #9 7.018 (34/34) Installing .build-deps (20250827.145237) 2025-08-27 14:52:41.703630 | debian-bookworm | #9 7.020 Executing busybox-1.37.0-r18.trigger 2025-08-27 14:52:41.703720 | debian-bookworm | #9 7.029 Executing ca-certificates-20250619-r0.trigger 2025-08-27 14:52:41.703746 | debian-bookworm | #9 7.084 OK: 359 MiB in 82 packages 2025-08-27 14:52:41.980555 | debian-bookworm | #9 DONE 7.5s 2025-08-27 14:52:42.126255 | debian-bookworm | 2025-08-27 14:52:42.126454 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-08-27 14:52:42.126516 | debian-bookworm | #10 DONE 0.1s 2025-08-27 14:52:42.126551 | debian-bookworm | 2025-08-27 14:52:42.126583 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-08-27 14:52:42.126620 | debian-bookworm | #11 DONE 0.1s 2025-08-27 14:52:42.349870 | debian-bookworm | 2025-08-27 14:52:42.349961 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-08-27 14:52:42.349978 | debian-bookworm | #12 DONE 0.1s 2025-08-27 14:52:42.349990 | debian-bookworm | 2025-08-27 14:52:42.350003 | 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-27 14:52:43.083405 | debian-bookworm | #13 0.734 Using Python 3.13.7 environment at: /usr/local 2025-08-27 14:52:43.211838 | debian-bookworm | #13 1.013 Resolved 17 packages in 276ms 2025-08-27 14:52:43.402093 | debian-bookworm | #13 1.051 Downloading ansible-core (2.3MiB) 2025-08-27 14:52:43.402220 | debian-bookworm | #13 1.052 Downloading cryptography (4.4MiB) 2025-08-27 14:52:43.402235 | debian-bookworm | #13 1.053 Downloading netaddr (2.2MiB) 2025-08-27 14:52:43.619153 | debian-bookworm | #13 1.420 Downloading netaddr 2025-08-27 14:52:43.756599 | debian-bookworm | #13 1.504 Downloading cryptography 2025-08-27 14:52:43.756715 | debian-bookworm | #13 1.558 Downloading ansible-core 2025-08-27 14:52:43.957129 | debian-bookworm | #13 1.559 Prepared 17 packages in 545ms 2025-08-27 14:52:43.957250 | debian-bookworm | #13 1.607 Installed 17 packages in 47ms 2025-08-27 14:52:43.957313 | debian-bookworm | #13 1.607 + ansible-core==2.19.1 2025-08-27 14:52:43.957356 | debian-bookworm | #13 1.607 + certifi==2025.8.3 2025-08-27 14:52:43.957398 | debian-bookworm | #13 1.608 + cffi==1.17.1 2025-08-27 14:52:43.957438 | debian-bookworm | #13 1.608 + charset-normalizer==3.4.3 2025-08-27 14:52:43.957477 | debian-bookworm | #13 1.608 + cryptography==45.0.6 2025-08-27 14:52:43.957515 | debian-bookworm | #13 1.608 + dynaconf==3.2.11 2025-08-27 14:52:43.957554 | debian-bookworm | #13 1.608 + idna==3.10 2025-08-27 14:52:43.957593 | debian-bookworm | #13 1.608 + jinja2==3.1.6 2025-08-27 14:52:43.957633 | debian-bookworm | #13 1.608 + markupsafe==3.0.2 2025-08-27 14:52:43.957672 | debian-bookworm | #13 1.608 + netaddr==1.3.0 2025-08-27 14:52:43.957711 | debian-bookworm | #13 1.608 + packaging==25.0 2025-08-27 14:52:43.957750 | debian-bookworm | #13 1.608 + pycparser==2.22 2025-08-27 14:52:43.957788 | debian-bookworm | #13 1.608 + pynetbox==7.5.0 2025-08-27 14:52:43.957827 | debian-bookworm | #13 1.608 + pyyaml==6.0.2 2025-08-27 14:52:43.957865 | debian-bookworm | #13 1.608 + requests==2.32.5 2025-08-27 14:52:43.957904 | debian-bookworm | #13 1.608 + resolvelib==1.2.0 2025-08-27 14:52:43.957943 | debian-bookworm | #13 1.608 + urllib3==2.5.0 2025-08-27 14:52:43.970308 | debian-bookworm | #13 DONE 1.8s 2025-08-27 14:52:44.128047 | debian-bookworm | 2025-08-27 14:52:44.128135 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.011170 2025-08-27 14:53:13.075385 | 2025-08-27 14:53:13.075501 | TASK [upload-logs : Upload logs to log server] 2025-08-27 14:53:13.606696 | localhost | Output suppressed because no_log was given 2025-08-27 14:53:13.608546 | 2025-08-27 14:53:13.608694 | LOOP [upload-logs : Compress console log and json output] 2025-08-27 14:53:13.664265 | localhost | skipping: Conditional result was False 2025-08-27 14:53:13.670190 | localhost | skipping: Conditional result was False 2025-08-27 14:53:13.678117 | 2025-08-27 14:53:13.678342 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-27 14:53:13.722725 | localhost | skipping: Conditional result was False 2025-08-27 14:53:13.723025 | 2025-08-27 14:53:13.728345 | localhost | skipping: Conditional result was False 2025-08-27 14:53:13.739427 | 2025-08-27 14:53:13.739624 | LOOP [upload-logs : Upload console log and json output]