2025-09-24 00:00:16.475791 | Job console starting 2025-09-24 00:00:16.492636 | Updating git repos 2025-09-24 00:00:16.641088 | Cloning repos into workspace 2025-09-24 00:00:16.772723 | Restoring repo states 2025-09-24 00:00:16.795622 | Merging changes 2025-09-24 00:00:16.795637 | Checking out repos 2025-09-24 00:00:16.907218 | Preparing playbooks 2025-09-24 00:00:18.136552 | Running Ansible setup 2025-09-24 00:00:25.498917 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-24 00:00:26.726676 | 2025-09-24 00:00:26.726793 | PLAY [Base pre] 2025-09-24 00:00:26.748979 | 2025-09-24 00:00:26.749090 | TASK [Setup log path fact] 2025-09-24 00:00:26.777499 | debian-bookworm | ok 2025-09-24 00:00:26.794749 | 2025-09-24 00:00:26.795808 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-24 00:00:26.854350 | debian-bookworm | ok 2025-09-24 00:00:26.862294 | 2025-09-24 00:00:26.862410 | TASK [emit-job-header : Print job information] 2025-09-24 00:00:26.927022 | # Job Information 2025-09-24 00:00:26.927154 | Ansible Version: 2.16.14 2025-09-24 00:00:26.927182 | Job: container-image-inventory-reconciler-push 2025-09-24 00:00:26.927208 | Pipeline: periodic-midnight 2025-09-24 00:00:26.927227 | Executor: 521e9411259a 2025-09-24 00:00:26.927244 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-24 00:00:26.927263 | Event ID: a4c04c3eb1644ef3995d01cc89792f00 2025-09-24 00:00:26.931282 | 2025-09-24 00:00:26.931361 | LOOP [emit-job-header : Print node information] 2025-09-24 00:00:27.143501 | debian-bookworm | ok: 2025-09-24 00:00:27.143681 | debian-bookworm | # Node Information 2025-09-24 00:00:27.143712 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-24 00:00:27.143732 | debian-bookworm | Hostname: debian 2025-09-24 00:00:27.143750 | debian-bookworm | Username: zuul 2025-09-24 00:00:27.143767 | debian-bookworm | Distro: Debian 12.12 2025-09-24 00:00:27.143792 | debian-bookworm | Provider: regiocloud-a 2025-09-24 00:00:27.143827 | debian-bookworm | Region: 2025-09-24 00:00:27.143845 | debian-bookworm | Label: debian-bookworm 2025-09-24 00:00:27.143862 | debian-bookworm | Product Name: OpenStack Nova 2025-09-24 00:00:27.143877 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febf:dc18 2025-09-24 00:00:27.155355 | 2025-09-24 00:00:27.155455 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-24 00:00:28.131289 | debian-bookworm -> localhost | changed 2025-09-24 00:00:28.137411 | 2025-09-24 00:00:28.137500 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-24 00:00:30.342116 | debian-bookworm -> localhost | changed 2025-09-24 00:00:30.350739 | 2025-09-24 00:00:30.350869 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-24 00:00:31.108282 | debian-bookworm -> localhost | ok 2025-09-24 00:00:31.114128 | 2025-09-24 00:00:31.114212 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-24 00:00:31.162862 | debian-bookworm | ok 2025-09-24 00:00:31.194669 | debian-bookworm | included: /var/lib/zuul/builds/bd14367d37fb4197a05c3b74ee0305ca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-24 00:00:31.205783 | 2025-09-24 00:00:31.205879 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-24 00:00:33.450060 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-24 00:00:33.450215 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bd14367d37fb4197a05c3b74ee0305ca/work/bd14367d37fb4197a05c3b74ee0305ca_id_rsa 2025-09-24 00:00:33.450244 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bd14367d37fb4197a05c3b74ee0305ca/work/bd14367d37fb4197a05c3b74ee0305ca_id_rsa.pub 2025-09-24 00:00:33.450264 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-24 00:00:33.450283 | debian-bookworm -> localhost | SHA256:X85s60ifY7ZI8uqe9Btt/s81TIr0iPB5cSj7A+neOIs zuul-build-sshkey 2025-09-24 00:00:33.450306 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-24 00:00:33.450324 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-24 00:00:33.450341 | debian-bookworm -> localhost | | | 2025-09-24 00:00:33.450357 | debian-bookworm -> localhost | | | 2025-09-24 00:00:33.450374 | debian-bookworm -> localhost | | | 2025-09-24 00:00:33.450390 | debian-bookworm -> localhost | | . | 2025-09-24 00:00:33.450406 | debian-bookworm -> localhost | | .S..+.. . | 2025-09-24 00:00:33.450426 | debian-bookworm -> localhost | | o+*** + | 2025-09-24 00:00:33.450442 | debian-bookworm -> localhost | | +=*+Bo o.| 2025-09-24 00:00:33.450458 | debian-bookworm -> localhost | | ..X=X+o .o| 2025-09-24 00:00:33.450474 | debian-bookworm -> localhost | | E*=OBO+..o| 2025-09-24 00:00:33.450491 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-24 00:00:33.450527 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.333215 2025-09-24 00:00:33.455644 | 2025-09-24 00:00:33.455719 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-24 00:00:33.492689 | debian-bookworm | ok 2025-09-24 00:00:33.500839 | debian-bookworm | included: /var/lib/zuul/builds/bd14367d37fb4197a05c3b74ee0305ca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-24 00:00:33.545098 | 2025-09-24 00:00:33.545188 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-24 00:00:33.578678 | debian-bookworm | skipping: Conditional result was False 2025-09-24 00:00:33.584155 | 2025-09-24 00:00:33.584252 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-24 00:00:34.333827 | debian-bookworm | changed 2025-09-24 00:00:34.338245 | 2025-09-24 00:00:34.338327 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-24 00:00:34.607714 | debian-bookworm | ok 2025-09-24 00:00:34.615730 | 2025-09-24 00:00:34.615845 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-24 00:00:35.364160 | debian-bookworm | changed 2025-09-24 00:00:35.369559 | 2025-09-24 00:00:35.369639 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-24 00:00:36.109247 | debian-bookworm | changed 2025-09-24 00:00:36.113652 | 2025-09-24 00:00:36.113729 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-24 00:00:36.147043 | debian-bookworm | skipping: Conditional result was False 2025-09-24 00:00:36.151991 | 2025-09-24 00:00:36.152082 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-24 00:00:36.930566 | debian-bookworm -> localhost | changed 2025-09-24 00:00:36.940034 | 2025-09-24 00:00:36.940129 | TASK [add-build-sshkey : Add back temp key] 2025-09-24 00:00:37.469696 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bd14367d37fb4197a05c3b74ee0305ca/work/bd14367d37fb4197a05c3b74ee0305ca_id_rsa (zuul-build-sshkey) 2025-09-24 00:00:37.469994 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012270 2025-09-24 00:00:37.478528 | 2025-09-24 00:00:37.478620 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-24 00:00:38.043273 | debian-bookworm | ok 2025-09-24 00:00:38.048610 | 2025-09-24 00:00:38.048702 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-24 00:00:38.091682 | debian-bookworm | skipping: Conditional result was False 2025-09-24 00:00:38.101939 | 2025-09-24 00:00:38.102048 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-24 00:00:38.524875 | debian-bookworm | ok 2025-09-24 00:00:38.555406 | 2025-09-24 00:00:38.555518 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-24 00:00:38.613434 | debian-bookworm | ok 2025-09-24 00:00:38.618518 | 2025-09-24 00:00:38.618612 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-24 00:00:39.457028 | debian-bookworm -> localhost | ok 2025-09-24 00:00:39.477726 | 2025-09-24 00:00:39.477853 | TASK [validate-host : Collect information about the host] 2025-09-24 00:00:40.830738 | debian-bookworm | ok 2025-09-24 00:00:40.846658 | 2025-09-24 00:00:40.846746 | TASK [validate-host : Sanitize hostname] 2025-09-24 00:00:41.101185 | debian-bookworm | ok 2025-09-24 00:00:41.156442 | 2025-09-24 00:00:41.156533 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-24 00:00:42.024070 | debian-bookworm -> localhost | changed 2025-09-24 00:00:42.031456 | 2025-09-24 00:00:42.031573 | TASK [validate-host : Collect information about zuul worker] 2025-09-24 00:00:42.430932 | debian-bookworm | ok 2025-09-24 00:00:42.437632 | 2025-09-24 00:00:42.437740 | TASK [validate-host : Write out all zuul information for each host] 2025-09-24 00:00:43.087783 | debian-bookworm -> localhost | changed 2025-09-24 00:00:43.096663 | 2025-09-24 00:00:43.096747 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-24 00:00:43.391331 | debian-bookworm | ok 2025-09-24 00:00:43.397140 | 2025-09-24 00:00:43.397285 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-24 00:00:44.160855 | debian-bookworm | changed: 2025-09-24 00:00:44.160997 | debian-bookworm | cd+++++++++ src/ 2025-09-24 00:00:44.161071 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-24 00:00:44.161094 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-24 00:00:44.161113 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-24 00:00:44.161131 | debian-bookworm | Containerfile 2025-09-24 00:00:44.161244 | debian-bookworm | 3.5.2-r0) 2025-09-24 00:01:48.604694 | debian-bookworm | #9 6.113 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-24 00:01:48.853145 | debian-bookworm | #9 6.142 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-24 00:01:48.853278 | debian-bookworm | #9 6.190 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-24 00:01:48.853327 | debian-bookworm | #9 6.211 (28/34) Installing python3 (3.12.11-r0) 2025-09-24 00:01:48.993957 | debian-bookworm | #9 6.503 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-24 00:01:49.163424 | debian-bookworm | #9 6.672 (30/34) Installing pyc (3.12.11-r0) 2025-09-24 00:01:49.314707 | debian-bookworm | #9 6.673 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-24 00:01:49.314845 | debian-bookworm | #9 6.673 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-24 00:01:49.680031 | debian-bookworm | #9 7.189 (33/34) Installing yq-go (4.47.2-r0) 2025-09-24 00:01:49.833674 | debian-bookworm | #9 7.343 (34/34) Installing .build-deps (20250924.000145) 2025-09-24 00:01:50.050889 | debian-bookworm | #9 7.344 Executing busybox-1.37.0-r18.trigger 2025-09-24 00:01:50.051031 | debian-bookworm | #9 7.351 Executing ca-certificates-20250619-r0.trigger 2025-09-24 00:01:50.051121 | debian-bookworm | #9 7.409 OK: 359 MiB in 82 packages 2025-09-24 00:01:50.406002 | debian-bookworm | #9 DONE 7.9s 2025-09-24 00:01:50.519981 | debian-bookworm | 2025-09-24 00:01:50.520147 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-24 00:01:50.520201 | debian-bookworm | #10 DONE 0.1s 2025-09-24 00:01:50.682302 | debian-bookworm | 2025-09-24 00:01:50.682394 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-24 00:01:50.682406 | debian-bookworm | #11 DONE 0.1s 2025-09-24 00:01:50.682416 | debian-bookworm | 2025-09-24 00:01:50.682425 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-24 00:01:50.682433 | debian-bookworm | #12 DONE 0.1s 2025-09-24 00:01:50.837084 | debian-bookworm | 2025-09-24 00:01:50.837171 | 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-24 00:01:51.445414 | debian-bookworm | #13 0.759 Using Python 3.13.7 environment at: /usr/local 2025-09-24 00:01:51.925004 | debian-bookworm | #13 1.056 Resolved 17 packages in 294ms 2025-09-24 00:01:51.925133 | debian-bookworm | #13 1.072 Downloading netaddr (2.2MiB) 2025-09-24 00:01:51.925146 | debian-bookworm | #13 1.085 Downloading ansible-core (2.3MiB) 2025-09-24 00:01:51.925151 | debian-bookworm | #13 1.088 Downloading cryptography (4.5MiB) 2025-09-24 00:01:52.068886 | debian-bookworm | #13 1.383 Downloading netaddr 2025-09-24 00:01:52.194352 | debian-bookworm | #13 1.508 Downloading cryptography 2025-09-24 00:01:52.329590 | debian-bookworm | #13 1.644 Downloading ansible-core 2025-09-24 00:01:52.565977 | debian-bookworm | #13 1.645 Prepared 17 packages in 586ms 2025-09-24 00:01:52.566184 | debian-bookworm | #13 1.729 Installed 17 packages in 83ms 2025-09-24 00:01:52.566266 | debian-bookworm | #13 1.729 + ansible-core==2.19.2 2025-09-24 00:01:52.566312 | debian-bookworm | #13 1.729 + certifi==2025.8.3 2025-09-24 00:01:52.566387 | debian-bookworm | #13 1.729 + cffi==2.0.0 2025-09-24 00:01:52.566449 | debian-bookworm | #13 1.729 + charset-normalizer==3.4.3 2025-09-24 00:01:52.566503 | debian-bookworm | #13 1.729 + cryptography==46.0.1 2025-09-24 00:01:52.566564 | debian-bookworm | #13 1.729 + dynaconf==3.2.11 2025-09-24 00:01:52.566640 | debian-bookworm | #13 1.729 + idna==3.10 2025-09-24 00:01:52.566714 | debian-bookworm | #13 1.729 + jinja2==3.1.6 2025-09-24 00:01:52.566774 | debian-bookworm | #13 1.729 + markupsafe==3.0.2 2025-09-24 00:01:52.566829 | debian-bookworm | #13 1.729 + netaddr==1.3.0 2025-09-24 00:01:52.566889 | debian-bookworm | #13 1.729 + packaging==25.0 2025-09-24 00:01:52.566942 | debian-bookworm | #13 1.729 + pycparser==2.23 2025-09-24 00:01:52.567001 | debian-bookworm | #13 1.729 + pynetbox==7.5.0 2025-09-24 00:01:52.567071 | debian-bookworm | #13 1.729 + pyyaml==6.0.2 2025-09-24 00:01:52.567147 | debian-bookworm | #13 1.729 + requests==2.32.5 2025-09-24 00:01:52.567188 | debian-bookworm | #13 1.729 + resolvelib==1.2.0 2025-09-24 00:01:52.567259 | debian-bookworm | #13 1.729 + urllib3==2.5.0 2025-09-24 00:01:52.589268 | debian-bookworm | #13 DONE 1.9s 2025-09-24 00:01:52.765715 | debian-bookworm | 2025-09-24 00:01:52.765792 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007587 2025-09-24 00:03:32.806461 | 2025-09-24 00:03:32.806638 | TASK [upload-logs : Upload logs to log server] 2025-09-24 00:03:33.419548 | localhost | Output suppressed because no_log was given 2025-09-24 00:03:33.424076 | 2025-09-24 00:03:33.424256 | LOOP [upload-logs : Compress console log and json output] 2025-09-24 00:03:33.480940 | localhost | skipping: Conditional result was False 2025-09-24 00:03:33.487626 | localhost | skipping: Conditional result was False 2025-09-24 00:03:33.498191 | 2025-09-24 00:03:33.498722 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-24 00:03:33.550389 | localhost | skipping: Conditional result was False 2025-09-24 00:03:33.551037 | 2025-09-24 00:03:33.554812 | localhost | skipping: Conditional result was False 2025-09-24 00:03:33.563315 | 2025-09-24 00:03:33.563564 | LOOP [upload-logs : Upload console log and json output]