2026-08-31 00:00:10.874396 | Job console starting 2026-08-31 00:00:10.928844 | Updating git repos 2026-08-31 00:00:10.986357 | Cloning repos into workspace 2026-08-31 00:00:11.085562 | Restoring repo states 2026-08-31 00:00:11.100366 | Merging changes 2026-08-31 00:00:11.100388 | Checking out repos 2026-08-31 00:00:11.251423 | Preparing playbooks 2026-08-31 00:00:12.807128 | Running Ansible setup 2026-08-31 00:00:19.793528 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-31 00:00:21.187463 | 2026-08-31 00:00:21.187607 | PLAY [Base pre] 2026-08-31 00:00:21.284280 | 2026-08-31 00:00:21.284913 | TASK [Setup log path fact] 2026-08-31 00:00:21.327490 | debian-bookworm | ok 2026-08-31 00:00:21.392798 | 2026-08-31 00:00:21.392943 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-31 00:00:21.475330 | debian-bookworm | ok 2026-08-31 00:00:21.495158 | 2026-08-31 00:00:21.495282 | TASK [emit-job-header : Print job information] 2026-08-31 00:00:21.589486 | # Job Information 2026-08-31 00:00:21.589636 | Ansible Version: 2.16.14 2026-08-31 00:00:21.589694 | Job: container-image-inventory-reconciler-push 2026-08-31 00:00:21.589725 | Pipeline: periodic-midnight 2026-08-31 00:00:21.589746 | Executor: 521e9411259a 2026-08-31 00:00:21.589764 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2026-08-31 00:00:21.589782 | Event ID: d9c99af7b9124272a57f76389024ceef 2026-08-31 00:00:21.594581 | 2026-08-31 00:00:21.594666 | LOOP [emit-job-header : Print node information] 2026-08-31 00:00:21.805554 | debian-bookworm | ok: 2026-08-31 00:00:21.805711 | debian-bookworm | # Node Information 2026-08-31 00:00:21.805739 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-31 00:00:21.805760 | debian-bookworm | Hostname: debian 2026-08-31 00:00:21.805778 | debian-bookworm | Username: zuul 2026-08-31 00:00:21.805795 | debian-bookworm | Distro: Debian 12.15 2026-08-31 00:00:21.805815 | debian-bookworm | Provider: regiocloud-a 2026-08-31 00:00:21.805832 | debian-bookworm | Region: 2026-08-31 00:00:21.805849 | debian-bookworm | Label: debian-bookworm 2026-08-31 00:00:21.805866 | debian-bookworm | Product Name: OpenStack Nova 2026-08-31 00:00:21.805882 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe04:c43f 2026-08-31 00:00:21.820965 | 2026-08-31 00:00:21.821070 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-31 00:00:23.550086 | debian-bookworm -> localhost | changed 2026-08-31 00:00:23.556501 | 2026-08-31 00:00:23.556593 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-31 00:00:27.169448 | debian-bookworm -> localhost | changed 2026-08-31 00:00:27.180490 | 2026-08-31 00:00:27.180586 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-31 00:00:27.867423 | debian-bookworm -> localhost | ok 2026-08-31 00:00:27.874397 | 2026-08-31 00:00:27.874503 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-31 00:00:27.913701 | debian-bookworm | ok 2026-08-31 00:00:27.954411 | debian-bookworm | included: /var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-31 00:00:27.973247 | 2026-08-31 00:00:27.973347 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-31 00:00:31.429953 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-31 00:00:31.430223 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/work/e01e0980dc0644be9145f031233e461a_id_rsa 2026-08-31 00:00:31.430257 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/work/e01e0980dc0644be9145f031233e461a_id_rsa.pub 2026-08-31 00:00:31.430280 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-31 00:00:31.430300 | debian-bookworm -> localhost | SHA256:CXWjQAnJ7adKNeC2hNA31SEa6K2Pfkq216uFZIqz84s zuul-build-sshkey 2026-08-31 00:00:31.430329 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-31 00:00:31.430347 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-31 00:00:31.430364 | debian-bookworm -> localhost | | . oo*=oo.o | 2026-08-31 00:00:31.430382 | debian-bookworm -> localhost | |. o *oo+.o . | 2026-08-31 00:00:31.430409 | debian-bookworm -> localhost | | o =.+. . | 2026-08-31 00:00:31.430452 | debian-bookworm -> localhost | | o = +... | 2026-08-31 00:00:31.430473 | debian-bookworm -> localhost | | +oo +S | 2026-08-31 00:00:31.430497 | debian-bookworm -> localhost | | ..+o.. | 2026-08-31 00:00:31.430514 | debian-bookworm -> localhost | |o ++.o. | 2026-08-31 00:00:31.430531 | debian-bookworm -> localhost | |.*..=.. | 2026-08-31 00:00:31.430549 | debian-bookworm -> localhost | |E+*=.... | 2026-08-31 00:00:31.430566 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-31 00:00:31.430613 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.155507 2026-08-31 00:00:31.436670 | 2026-08-31 00:00:31.436755 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-31 00:00:31.496953 | debian-bookworm | ok 2026-08-31 00:00:31.515562 | debian-bookworm | included: /var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-31 00:00:31.532718 | 2026-08-31 00:00:31.532819 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-31 00:00:31.590096 | debian-bookworm | skipping: Conditional result was False 2026-08-31 00:00:31.595355 | 2026-08-31 00:00:31.595467 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-31 00:00:33.061386 | debian-bookworm | changed 2026-08-31 00:00:33.065937 | 2026-08-31 00:00:33.066022 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-31 00:00:33.387500 | debian-bookworm | ok 2026-08-31 00:00:33.392916 | 2026-08-31 00:00:33.392998 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-31 00:00:34.354813 | debian-bookworm | changed 2026-08-31 00:00:34.367281 | 2026-08-31 00:00:34.367381 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-31 00:00:35.234997 | debian-bookworm | changed 2026-08-31 00:00:35.240426 | 2026-08-31 00:00:35.240512 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-31 00:00:35.297478 | debian-bookworm | skipping: Conditional result was False 2026-08-31 00:00:35.305213 | 2026-08-31 00:00:35.305485 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-31 00:00:36.756506 | debian-bookworm -> localhost | changed 2026-08-31 00:00:36.765189 | 2026-08-31 00:00:36.765274 | TASK [add-build-sshkey : Add back temp key] 2026-08-31 00:00:37.855090 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/work/e01e0980dc0644be9145f031233e461a_id_rsa (zuul-build-sshkey) 2026-08-31 00:00:37.855291 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029035 2026-08-31 00:00:37.862659 | 2026-08-31 00:00:37.862743 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-31 00:00:38.679889 | debian-bookworm | ok 2026-08-31 00:00:38.684176 | 2026-08-31 00:00:38.684256 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-31 00:00:38.751484 | debian-bookworm | skipping: Conditional result was False 2026-08-31 00:00:38.760514 | 2026-08-31 00:00:38.760605 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-31 00:00:39.427536 | debian-bookworm | ok 2026-08-31 00:00:39.456764 | 2026-08-31 00:00:39.456883 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-31 00:00:39.533709 | debian-bookworm | ok 2026-08-31 00:00:39.552516 | 2026-08-31 00:00:39.552639 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-31 00:00:40.159325 | debian-bookworm -> localhost | ok 2026-08-31 00:00:40.165843 | 2026-08-31 00:00:40.165940 | TASK [validate-host : Collect information about the host] 2026-08-31 00:00:41.603958 | debian-bookworm | ok 2026-08-31 00:00:41.646566 | 2026-08-31 00:00:41.646684 | TASK [validate-host : Sanitize hostname] 2026-08-31 00:00:41.993513 | debian-bookworm | ok 2026-08-31 00:00:42.002932 | 2026-08-31 00:00:42.003031 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-31 00:00:43.789941 | debian-bookworm -> localhost | changed 2026-08-31 00:00:43.798496 | 2026-08-31 00:00:43.798629 | TASK [validate-host : Collect information about zuul worker] 2026-08-31 00:00:44.385468 | debian-bookworm | ok 2026-08-31 00:00:44.389437 | 2026-08-31 00:00:44.389521 | TASK [validate-host : Write out all zuul information for each host] 2026-08-31 00:00:45.880215 | debian-bookworm -> localhost | changed 2026-08-31 00:00:45.888942 | 2026-08-31 00:00:45.889033 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-31 00:00:46.222247 | debian-bookworm | ok 2026-08-31 00:00:46.228969 | 2026-08-31 00:00:46.229059 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-31 00:00:47.713350 | debian-bookworm | changed: 2026-08-31 00:00:47.713515 | debian-bookworm | cd+++++++++ src/ 2026-08-31 00:00:47.713542 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-31 00:00:47.713562 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-31 00:00:47.713579 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-08-31 00:00:47.713596 | debian-bookworm | Containerfile 2026-08-31 00:00:47.713720 | debian-bookworm | 3.5.8-r0) 2026-08-31 00:02:23.386007 | debian-bookworm | #9 6.381 (26/35) Upgrading libssl3 (3.5.7-r0 -> 3.5.8-r0) 2026-08-31 00:02:23.492198 | debian-bookworm | #9 6.411 (27/35) Installing openssl-dev (3.5.8-r0) 2026-08-31 00:02:23.492312 | debian-bookworm | #9 6.469 (28/35) Installing mpdecimal (4.0.1-r0) 2026-08-31 00:02:23.492364 | debian-bookworm | #9 6.488 (29/35) Installing python3 (3.14.7-r1) 2026-08-31 00:02:23.799718 | debian-bookworm | #9 6.795 (30/35) Installing python3-pycache-pyc0 (3.14.7-r1) 2026-08-31 00:02:23.974529 | debian-bookworm | #9 6.970 (31/35) Installing pyc (3.14.7-r1) 2026-08-31 00:02:23.974597 | debian-bookworm | #9 6.970 (32/35) Installing python3-pyc (3.14.7-r1) 2026-08-31 00:02:23.974625 | debian-bookworm | #9 6.970 (33/35) Installing python3-dev (3.14.7-r1) 2026-08-31 00:02:25.935271 | debian-bookworm | #9 8.931 (34/35) Installing yq-go (4.53.3-r0) 2026-08-31 00:02:26.146894 | debian-bookworm | #9 9.142 (35/35) Installing .build-deps (20260831.000219) 2026-08-31 00:02:26.372372 | debian-bookworm | #9 9.144 Executing busybox-1.37.0-r31.trigger 2026-08-31 00:02:26.372853 | debian-bookworm | #9 9.150 Executing ca-certificates-20260611-r0.trigger 2026-08-31 00:02:26.372878 | debian-bookworm | #9 9.192 OK: 451.2 MiB in 83 packages 2026-08-31 00:02:26.665548 | debian-bookworm | #9 DONE 9.7s 2026-08-31 00:02:26.790280 | debian-bookworm | 2026-08-31 00:02:26.790365 | debian-bookworm | #10 [builder 4/23] COPY --link files/requirements.txt /requirements.txt 2026-08-31 00:02:26.790413 | debian-bookworm | #10 DONE 1.2s 2026-08-31 00:02:26.906825 | debian-bookworm | 2026-08-31 00:02:26.906915 | debian-bookworm | #11 [builder 5/23] COPY --link files/render-python-requirements.py /render-python-requirements.py 2026-08-31 00:02:26.906941 | debian-bookworm | #11 DONE 1.1s 2026-08-31 00:02:26.906951 | debian-bookworm | 2026-08-31 00:02:26.906961 | debian-bookworm | #12 [builder 6/23] COPY --link files/templates /templates 2026-08-31 00:02:26.906969 | debian-bookworm | #12 DONE 1.0s 2026-08-31 00:02:27.034167 | debian-bookworm | 2026-08-31 00:02:27.034293 | debian-bookworm | #12 [builder 6/23] COPY --link files/templates /templates 2026-08-31 00:02:27.034314 | debian-bookworm | #12 merging 0.1s done 2026-08-31 00:02:27.034327 | debian-bookworm | #12 DONE 1.2s 2026-08-31 00:02:27.185096 | debian-bookworm | 2026-08-31 00:02:27.185185 | debian-bookworm | #13 [builder 7/23] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2026-08-31 00:02:28.009780 | debian-bookworm | #13 0.976 Using Python 3.14.7 environment at: /usr/local 2026-08-31 00:02:28.346909 | debian-bookworm | #13 1.313 Resolved 17 packages in 335ms 2026-08-31 00:02:28.537650 | debian-bookworm | #13 1.350 Downloading ansible-core (2.3MiB) 2026-08-31 00:02:28.537721 | debian-bookworm | #13 1.352 Downloading cryptography (4.8MiB) 2026-08-31 00:02:28.537747 | debian-bookworm | #13 1.353 Downloading netaddr (2.2MiB) 2026-08-31 00:02:28.881135 | debian-bookworm | #13 1.646 Downloaded netaddr 2026-08-31 00:02:28.881201 | debian-bookworm | #13 1.696 Downloaded cryptography 2026-08-31 00:02:29.127214 | debian-bookworm | #13 1.883 Downloaded ansible-core 2026-08-31 00:02:29.127410 | debian-bookworm | #13 1.884 Prepared 17 packages in 570ms 2026-08-31 00:02:29.127427 | debian-bookworm | #13 1.942 Installed 17 packages in 56ms 2026-08-31 00:02:29.127435 | debian-bookworm | #13 1.943 + ansible-core==2.19.11 2026-08-31 00:02:29.127442 | debian-bookworm | #13 1.944 + certifi==2026.7.22 2026-08-31 00:02:29.127450 | debian-bookworm | #13 1.945 + cffi==2.1.1 2026-08-31 00:02:29.127457 | debian-bookworm | #13 1.945 + charset-normalizer==3.5.1 2026-08-31 00:02:29.127464 | debian-bookworm | #13 1.946 + cryptography==50.0.1 2026-08-31 00:02:29.127470 | debian-bookworm | #13 1.947 + dynaconf==3.3.5 2026-08-31 00:02:29.127478 | debian-bookworm | #13 1.948 + idna==3.19 2026-08-31 00:02:29.127500 | debian-bookworm | #13 1.948 + jinja2==3.1.6 2026-08-31 00:02:29.127510 | debian-bookworm | #13 1.948 + markupsafe==3.0.3 2026-08-31 00:02:29.127516 | debian-bookworm | #13 1.949 + netaddr==1.3.0 2026-08-31 00:02:29.127523 | debian-bookworm | #13 1.949 + packaging==26.3 2026-08-31 00:02:29.127530 | debian-bookworm | #13 1.949 + pycparser==3.0 2026-08-31 00:02:29.127539 | debian-bookworm | #13 1.949 + pynetbox==7.8.0 2026-08-31 00:02:29.127548 | debian-bookworm | #13 1.952 + pyyaml==6.0.3 2026-08-31 00:02:29.127554 | debian-bookworm | #13 1.952 + requests==2.34.2 2026-08-31 00:02:29.127561 | debian-bookworm | #13 1.952 + resolvelib==1.2.1 2026-08-31 00:02:29.127571 | debian-bookworm | #13 1.952 + urllib3==2.7.0 2026-08-31 00:02:29.127579 | debian-bookworm | #13 DONE 2.1s 2026-08-31 00:02:29.283534 | debian-bookworm | 2026-08-31 00:02:29.283651 | debian-bookworm | #14 [builder 8/23] RUN < localhost | ok: "/var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/work/logs" 2026-08-31 00:04:49.220624 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/work/artifacts" 2026-08-31 00:04:49.529068 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e01e0980dc0644be9145f031233e461a/work/docs" 2026-08-31 00:04:49.553532 | 2026-08-31 00:04:49.553734 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-31 00:04:50.446080 | debian-bookworm | changed: 2026-08-31 00:04:50.446345 | debian-bookworm | .d..t...... ./ 2026-08-31 00:04:50.446384 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-31 00:04:50.446409 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-31 00:04:50.446430 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-31 00:04:50.446451 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-31 00:04:50.446472 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-31 00:04:50.446512 | debian-bookworm | changed: All items complete 2026-08-31 00:04:50.446535 | 2026-08-31 00:04:51.063775 | debian-bookworm | changed: .d..t...... ./ 2026-08-31 00:04:51.720703 | debian-bookworm | changed: .d..t...... ./ 2026-08-31 00:04:51.750820 | 2026-08-31 00:04:51.751042 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-31 00:04:51.783881 | debian-bookworm | skipping: Conditional result was False 2026-08-31 00:04:51.786781 | debian-bookworm | skipping: Conditional result was False 2026-08-31 00:04:51.804880 | 2026-08-31 00:04:51.805046 | PLAY RECAP 2026-08-31 00:04:51.805137 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-31 00:04:51.805179 | 2026-08-31 00:04:51.979804 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-31 00:04:51.984493 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-31 00:04:52.834002 | 2026-08-31 00:04:52.834345 | PLAY [Base post] 2026-08-31 00:04:52.850234 | 2026-08-31 00:04:52.850413 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-31 00:04:53.910816 | debian-bookworm | changed 2026-08-31 00:04:53.920948 | 2026-08-31 00:04:53.921067 | PLAY RECAP 2026-08-31 00:04:53.921137 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-31 00:04:53.921203 | 2026-08-31 00:04:54.088691 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-31 00:04:54.089830 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-31 00:04:54.948082 | 2026-08-31 00:04:54.948288 | PLAY [Base post-logs] 2026-08-31 00:04:54.960327 | 2026-08-31 00:04:54.960488 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-31 00:04:55.482069 | localhost | changed 2026-08-31 00:04:55.504946 | 2026-08-31 00:04:55.505175 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-31 00:04:55.534310 | localhost | ok 2026-08-31 00:04:55.540728 | 2026-08-31 00:04:55.540883 | TASK [Set zuul-log-path fact] 2026-08-31 00:04:55.558695 | localhost | ok 2026-08-31 00:04:55.568772 | 2026-08-31 00:04:55.568891 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-31 00:04:55.594686 | localhost | ok 2026-08-31 00:04:55.598969 | 2026-08-31 00:04:55.599077 | TASK [upload-logs : Create log directories] 2026-08-31 00:04:56.097441 | localhost | changed 2026-08-31 00:04:56.102455 | 2026-08-31 00:04:56.102613 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-31 00:04:56.632874 | localhost -> localhost | ok: Runtime: 0:00:00.007805 2026-08-31 00:04:56.638786 | 2026-08-31 00:04:56.638961 | TASK [upload-logs : Upload logs to log server] 2026-08-31 00:04:57.272273 | localhost | Output suppressed because no_log was given 2026-08-31 00:04:57.276322 | 2026-08-31 00:04:57.276508 | LOOP [upload-logs : Compress console log and json output] 2026-08-31 00:04:57.356793 | localhost | skipping: Conditional result was False 2026-08-31 00:04:57.364693 | localhost | skipping: Conditional result was False 2026-08-31 00:04:57.382694 | 2026-08-31 00:04:57.382874 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-31 00:04:57.455995 | localhost | skipping: Conditional result was False 2026-08-31 00:04:57.456348 | 2026-08-31 00:04:57.463794 | localhost | skipping: Conditional result was False 2026-08-31 00:04:57.473871 | 2026-08-31 00:04:57.474150 | LOOP [upload-logs : Upload console log and json output]