2025-09-26 00:00:16.927632 | Job console starting 2025-09-26 00:00:16.945964 | Updating git repos 2025-09-26 00:00:17.021606 | Cloning repos into workspace 2025-09-26 00:00:17.117528 | Restoring repo states 2025-09-26 00:00:17.141137 | Merging changes 2025-09-26 00:00:17.141152 | Checking out repos 2025-09-26 00:00:17.215154 | Preparing playbooks 2025-09-26 00:00:18.537650 | Running Ansible setup 2025-09-26 00:00:24.945589 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-26 00:00:26.310020 | 2025-09-26 00:00:26.310128 | PLAY [Base pre] 2025-09-26 00:00:26.324215 | 2025-09-26 00:00:26.324314 | TASK [Setup log path fact] 2025-09-26 00:00:26.351361 | debian-bookworm | ok 2025-09-26 00:00:26.381174 | 2025-09-26 00:00:26.381298 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-26 00:00:26.445081 | debian-bookworm | ok 2025-09-26 00:00:26.461393 | 2025-09-26 00:00:26.461488 | TASK [emit-job-header : Print job information] 2025-09-26 00:00:26.543156 | # Job Information 2025-09-26 00:00:26.543291 | Ansible Version: 2.16.14 2025-09-26 00:00:26.543320 | Job: container-image-inventory-reconciler-push 2025-09-26 00:00:26.543347 | Pipeline: periodic-midnight 2025-09-26 00:00:26.543366 | Executor: 521e9411259a 2025-09-26 00:00:26.543383 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-26 00:00:26.543401 | Event ID: 2267531b2e7e4699acc3ccbf1a06b101 2025-09-26 00:00:26.547351 | 2025-09-26 00:00:26.547430 | LOOP [emit-job-header : Print node information] 2025-09-26 00:00:26.735977 | debian-bookworm | ok: 2025-09-26 00:00:26.736200 | debian-bookworm | # Node Information 2025-09-26 00:00:26.736234 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-26 00:00:26.736256 | debian-bookworm | Hostname: debian 2025-09-26 00:00:26.736274 | debian-bookworm | Username: zuul 2025-09-26 00:00:26.736291 | debian-bookworm | Distro: Debian 12.12 2025-09-26 00:00:26.736315 | debian-bookworm | Provider: regiocloud-a 2025-09-26 00:00:26.736335 | debian-bookworm | Region: 2025-09-26 00:00:26.736353 | debian-bookworm | Label: debian-bookworm 2025-09-26 00:00:26.736370 | debian-bookworm | Product Name: OpenStack Nova 2025-09-26 00:00:26.736395 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe75:e240 2025-09-26 00:00:26.754402 | 2025-09-26 00:00:26.754496 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-26 00:00:27.884732 | debian-bookworm -> localhost | changed 2025-09-26 00:00:27.890357 | 2025-09-26 00:00:27.890582 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-26 00:00:29.936658 | debian-bookworm -> localhost | changed 2025-09-26 00:00:29.945298 | 2025-09-26 00:00:29.945383 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-26 00:00:30.593215 | debian-bookworm -> localhost | ok 2025-09-26 00:00:30.598559 | 2025-09-26 00:00:30.598661 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-26 00:00:30.646096 | debian-bookworm | ok 2025-09-26 00:00:30.682788 | debian-bookworm | included: /var/lib/zuul/builds/64830e358f1843dca9b737ab8d9db699/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-26 00:00:30.699622 | 2025-09-26 00:00:30.699722 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-26 00:00:33.017247 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-26 00:00:33.017449 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/64830e358f1843dca9b737ab8d9db699/work/64830e358f1843dca9b737ab8d9db699_id_rsa 2025-09-26 00:00:33.017485 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/64830e358f1843dca9b737ab8d9db699/work/64830e358f1843dca9b737ab8d9db699_id_rsa.pub 2025-09-26 00:00:33.017507 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-26 00:00:33.017526 | debian-bookworm -> localhost | SHA256:mp5v9N9X19fz6zMlX/KL80GmhJOt28CoO1UP/KBGorM zuul-build-sshkey 2025-09-26 00:00:33.017552 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-26 00:00:33.017571 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-26 00:00:33.017588 | debian-bookworm -> localhost | | | 2025-09-26 00:00:33.017606 | debian-bookworm -> localhost | | | 2025-09-26 00:00:33.017622 | debian-bookworm -> localhost | | . | 2025-09-26 00:00:33.017639 | debian-bookworm -> localhost | | . . =+ | 2025-09-26 00:00:33.017655 | debian-bookworm -> localhost | | . S o+=o oo| 2025-09-26 00:00:33.017678 | debian-bookworm -> localhost | | o o.+o +o*.O| 2025-09-26 00:00:33.017695 | debian-bookworm -> localhost | | =.oo + . *O| 2025-09-26 00:00:33.017711 | debian-bookworm -> localhost | | E oo . +o.o*| 2025-09-26 00:00:33.017729 | debian-bookworm -> localhost | | o=+ o.o=*=| 2025-09-26 00:00:33.017745 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-26 00:00:33.017787 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.370402 2025-09-26 00:00:33.023038 | 2025-09-26 00:00:33.023129 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-26 00:00:33.067593 | debian-bookworm | ok 2025-09-26 00:00:33.078295 | debian-bookworm | included: /var/lib/zuul/builds/64830e358f1843dca9b737ab8d9db699/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-26 00:00:33.107736 | 2025-09-26 00:00:33.107840 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-26 00:00:33.141156 | debian-bookworm | skipping: Conditional result was False 2025-09-26 00:00:33.146034 | 2025-09-26 00:00:33.146119 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-26 00:00:33.935805 | debian-bookworm | changed 2025-09-26 00:00:33.940785 | 2025-09-26 00:00:33.940873 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-26 00:00:34.224603 | debian-bookworm | ok 2025-09-26 00:00:34.230807 | 2025-09-26 00:00:34.230965 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-26 00:00:34.979981 | debian-bookworm | changed 2025-09-26 00:00:34.987379 | 2025-09-26 00:00:34.987468 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-26 00:00:35.701262 | debian-bookworm | changed 2025-09-26 00:00:35.706205 | 2025-09-26 00:00:35.706291 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-26 00:00:35.730471 | debian-bookworm | skipping: Conditional result was False 2025-09-26 00:00:35.735443 | 2025-09-26 00:00:35.735536 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-26 00:00:36.501033 | debian-bookworm -> localhost | changed 2025-09-26 00:00:36.509901 | 2025-09-26 00:00:36.510024 | TASK [add-build-sshkey : Add back temp key] 2025-09-26 00:00:37.119593 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/64830e358f1843dca9b737ab8d9db699/work/64830e358f1843dca9b737ab8d9db699_id_rsa (zuul-build-sshkey) 2025-09-26 00:00:37.119778 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025320 2025-09-26 00:00:37.125935 | 2025-09-26 00:00:37.126044 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-26 00:00:37.594619 | debian-bookworm | ok 2025-09-26 00:00:37.598723 | 2025-09-26 00:00:37.598805 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-26 00:00:37.632134 | debian-bookworm | skipping: Conditional result was False 2025-09-26 00:00:37.641845 | 2025-09-26 00:00:37.642040 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-26 00:00:38.098626 | debian-bookworm | ok 2025-09-26 00:00:38.107737 | 2025-09-26 00:00:38.107826 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-26 00:00:38.141393 | debian-bookworm | ok 2025-09-26 00:00:38.145409 | 2025-09-26 00:00:38.145484 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-26 00:00:38.493774 | debian-bookworm -> localhost | ok 2025-09-26 00:00:38.498961 | 2025-09-26 00:00:38.499056 | TASK [validate-host : Collect information about the host] 2025-09-26 00:00:39.837463 | debian-bookworm | ok 2025-09-26 00:00:39.855910 | 2025-09-26 00:00:39.856025 | TASK [validate-host : Sanitize hostname] 2025-09-26 00:00:40.025442 | debian-bookworm | ok 2025-09-26 00:00:40.083808 | 2025-09-26 00:00:40.083918 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-26 00:00:41.084417 | debian-bookworm -> localhost | changed 2025-09-26 00:00:41.089725 | 2025-09-26 00:00:41.089815 | TASK [validate-host : Collect information about zuul worker] 2025-09-26 00:00:41.623198 | debian-bookworm | ok 2025-09-26 00:00:41.628303 | 2025-09-26 00:00:41.628402 | TASK [validate-host : Write out all zuul information for each host] 2025-09-26 00:00:42.635470 | debian-bookworm -> localhost | changed 2025-09-26 00:00:42.644368 | 2025-09-26 00:00:42.644465 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-26 00:00:42.954387 | debian-bookworm | ok 2025-09-26 00:00:42.959728 | 2025-09-26 00:00:42.959819 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-26 00:00:44.013338 | debian-bookworm | changed: 2025-09-26 00:00:44.013482 | debian-bookworm | cd+++++++++ src/ 2025-09-26 00:00:44.013510 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-26 00:00:44.013530 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-26 00:00:44.013548 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-26 00:00:44.013566 | debian-bookworm | Containerfile 2025-09-26 00:00:44.013676 | debian-bookworm | 3.5.3-r1) 2025-09-26 00:01:45.982836 | debian-bookworm | #9 5.780 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.3-r1) 2025-09-26 00:01:46.091934 | debian-bookworm | #9 5.807 (26/34) Installing openssl-dev (3.5.3-r1) 2025-09-26 00:01:46.092012 | debian-bookworm | #9 5.870 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-26 00:01:46.092027 | debian-bookworm | #9 5.890 (28/34) Installing python3 (3.12.11-r0) 2025-09-26 00:01:46.551279 | debian-bookworm | #9 6.349 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-26 00:01:46.790106 | debian-bookworm | #9 6.588 (30/34) Installing pyc (3.12.11-r0) 2025-09-26 00:01:46.940971 | debian-bookworm | #9 6.589 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-26 00:01:46.941099 | debian-bookworm | #9 6.589 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-26 00:01:47.556691 | debian-bookworm | #9 7.355 (33/34) Installing yq-go (4.47.2-r0) 2025-09-26 00:01:47.787563 | debian-bookworm | #9 7.585 (34/34) Installing .build-deps (20250926.000142) 2025-09-26 00:01:47.997499 | debian-bookworm | #9 7.587 Executing busybox-1.37.0-r18.trigger 2025-09-26 00:01:47.997587 | debian-bookworm | #9 7.594 Executing ca-certificates-20250619-r0.trigger 2025-09-26 00:01:47.997602 | debian-bookworm | #9 7.645 OK: 359 MiB in 82 packages 2025-09-26 00:01:48.354914 | debian-bookworm | #9 DONE 8.2s 2025-09-26 00:01:48.476197 | debian-bookworm | 2025-09-26 00:01:48.476362 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-26 00:01:48.476419 | debian-bookworm | #10 DONE 0.1s 2025-09-26 00:01:48.476459 | debian-bookworm | 2025-09-26 00:01:48.476491 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-26 00:01:48.625799 | debian-bookworm | #11 DONE 0.1s 2025-09-26 00:01:48.625971 | debian-bookworm | 2025-09-26 00:01:48.626017 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-26 00:01:48.626054 | debian-bookworm | #12 DONE 0.1s 2025-09-26 00:01:48.781935 | debian-bookworm | 2025-09-26 00:01:48.782067 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-09-26 00:01:49.540854 | debian-bookworm | #13 0.759 Using Python 3.13.7 environment at: /usr/local 2025-09-26 00:01:49.968102 | debian-bookworm | #13 1.142 Resolved 17 packages in 380ms 2025-09-26 00:01:49.968158 | debian-bookworm | #13 1.184 Downloading cryptography (4.5MiB) 2025-09-26 00:01:49.968164 | debian-bookworm | #13 1.186 Downloading ansible-core (2.3MiB) 2025-09-26 00:01:49.968168 | debian-bookworm | #13 1.186 Downloading netaddr (2.2MiB) 2025-09-26 00:01:50.060099 | debian-bookworm | #13 1.429 Downloading netaddr 2025-09-26 00:01:50.295485 | debian-bookworm | #13 1.513 Downloading cryptography 2025-09-26 00:01:50.359529 | debian-bookworm | #13 1.729 Downloading ansible-core 2025-09-26 00:01:50.556794 | debian-bookworm | #13 1.730 Prepared 17 packages in 583ms 2025-09-26 00:01:50.556908 | debian-bookworm | #13 1.775 Installed 17 packages in 44ms 2025-09-26 00:01:50.556926 | debian-bookworm | #13 1.775 + ansible-core==2.19.2 2025-09-26 00:01:50.556939 | debian-bookworm | #13 1.775 + certifi==2025.8.3 2025-09-26 00:01:50.556951 | debian-bookworm | #13 1.775 + cffi==2.0.0 2025-09-26 00:01:50.556965 | debian-bookworm | #13 1.775 + charset-normalizer==3.4.3 2025-09-26 00:01:50.556977 | debian-bookworm | #13 1.775 + cryptography==46.0.1 2025-09-26 00:01:50.556988 | debian-bookworm | #13 1.775 + dynaconf==3.2.11 2025-09-26 00:01:50.556999 | debian-bookworm | #13 1.775 + idna==3.10 2025-09-26 00:01:50.557010 | debian-bookworm | #13 1.775 + jinja2==3.1.6 2025-09-26 00:01:50.557021 | debian-bookworm | #13 1.775 + markupsafe==3.0.2 2025-09-26 00:01:50.557032 | debian-bookworm | #13 1.775 + netaddr==1.3.0 2025-09-26 00:01:50.557043 | debian-bookworm | #13 1.775 + packaging==25.0 2025-09-26 00:01:50.557053 | debian-bookworm | #13 1.775 + pycparser==2.23 2025-09-26 00:01:50.557064 | debian-bookworm | #13 1.775 + pynetbox==7.5.0 2025-09-26 00:01:50.557075 | debian-bookworm | #13 1.775 + pyyaml==6.0.3 2025-09-26 00:01:50.557086 | debian-bookworm | #13 1.775 + requests==2.32.5 2025-09-26 00:01:50.557096 | debian-bookworm | #13 1.775 + resolvelib==1.2.0 2025-09-26 00:01:50.557107 | debian-bookworm | #13 1.776 + urllib3==2.5.0 2025-09-26 00:01:50.586226 | debian-bookworm | #13 DONE 2.0s 2025-09-26 00:01:50.743028 | debian-bookworm | 2025-09-26 00:01:50.743104 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.006245 2025-09-26 00:03:22.709887 | 2025-09-26 00:03:22.710083 | TASK [upload-logs : Upload logs to log server] 2025-09-26 00:03:23.260417 | localhost | Output suppressed because no_log was given 2025-09-26 00:03:23.263824 | 2025-09-26 00:03:23.264011 | LOOP [upload-logs : Compress console log and json output] 2025-09-26 00:03:23.313896 | localhost | skipping: Conditional result was False 2025-09-26 00:03:23.319568 | localhost | skipping: Conditional result was False 2025-09-26 00:03:23.327896 | 2025-09-26 00:03:23.328168 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-26 00:03:23.376582 | localhost | skipping: Conditional result was False 2025-09-26 00:03:23.377238 | 2025-09-26 00:03:23.380451 | localhost | skipping: Conditional result was False 2025-09-26 00:03:23.394508 | 2025-09-26 00:03:23.394751 | LOOP [upload-logs : Upload console log and json output]