2026-06-26 13:24:16.989074 | Job console starting 2026-06-26 13:24:17.001840 | Updating git repos 2026-06-26 13:24:17.037950 | Cloning repos into workspace 2026-06-26 13:24:17.108601 | Restoring repo states 2026-06-26 13:24:17.122311 | Merging changes 2026-06-26 13:24:17.122391 | Checking out repos 2026-06-26 13:24:17.186724 | Preparing playbooks 2026-06-26 13:24:18.213869 | Running Ansible setup 2026-06-26 13:24:22.769459 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-26 13:24:23.607880 | 2026-06-26 13:24:23.608087 | PLAY [Base pre] 2026-06-26 13:24:23.630304 | 2026-06-26 13:24:23.630959 | TASK [Setup log path fact] 2026-06-26 13:24:23.664926 | debian-bookworm | ok 2026-06-26 13:24:23.682805 | 2026-06-26 13:24:23.683056 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-26 13:24:23.730728 | debian-bookworm | ok 2026-06-26 13:24:23.745762 | 2026-06-26 13:24:23.745930 | TASK [emit-job-header : Print job information] 2026-06-26 13:24:23.813730 | # Job Information 2026-06-26 13:24:23.814025 | Ansible Version: 2.16.14 2026-06-26 13:24:23.814291 | Job: container-image-inventory-reconciler-push 2026-06-26 13:24:23.814394 | Pipeline: post 2026-06-26 13:24:23.814441 | Executor: 521e9411259a 2026-06-26 13:24:23.814480 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/commit/057aabbf4f1c9c12d7184306d96bcc46e687c4ef 2026-06-26 13:24:23.814521 | Event ID: 4e25859a-7162-11f1-88a1-c182d1ff5267 2026-06-26 13:24:23.823499 | 2026-06-26 13:24:23.823659 | LOOP [emit-job-header : Print node information] 2026-06-26 13:24:23.955895 | debian-bookworm | ok: 2026-06-26 13:24:23.956353 | debian-bookworm | # Node Information 2026-06-26 13:24:23.956445 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-26 13:24:23.956514 | debian-bookworm | Hostname: debian 2026-06-26 13:24:23.956570 | debian-bookworm | Username: zuul 2026-06-26 13:24:23.956624 | debian-bookworm | Distro: Debian 12.14 2026-06-26 13:24:23.956685 | debian-bookworm | Provider: regiocloud-a 2026-06-26 13:24:23.956741 | debian-bookworm | Region: 2026-06-26 13:24:23.956796 | debian-bookworm | Label: debian-bookworm 2026-06-26 13:24:23.956849 | debian-bookworm | Product Name: OpenStack Nova 2026-06-26 13:24:23.956901 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee9:beb0 2026-06-26 13:24:23.978031 | 2026-06-26 13:24:23.978240 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-26 13:24:24.500313 | debian-bookworm -> localhost | changed 2026-06-26 13:24:24.508671 | 2026-06-26 13:24:24.508812 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-26 13:24:25.710540 | debian-bookworm -> localhost | changed 2026-06-26 13:24:25.722957 | 2026-06-26 13:24:25.723114 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-26 13:24:26.033949 | debian-bookworm -> localhost | ok 2026-06-26 13:24:26.048930 | 2026-06-26 13:24:26.049168 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-26 13:24:26.093234 | debian-bookworm | ok 2026-06-26 13:24:26.114204 | debian-bookworm | included: /var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-26 13:24:26.120615 | 2026-06-26 13:24:26.120735 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-26 13:24:27.273673 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-26 13:24:27.274024 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/work/1a042407988f442ca3fd511909d23b18_id_rsa 2026-06-26 13:24:27.274076 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/work/1a042407988f442ca3fd511909d23b18_id_rsa.pub 2026-06-26 13:24:27.274112 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-26 13:24:27.274144 | debian-bookworm -> localhost | SHA256:Nsvdm/Vtk/xnDPJBDZ4/cX7Tm0eaja3TRpUpun4MNRk zuul-build-sshkey 2026-06-26 13:24:27.274191 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-26 13:24:27.274222 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-26 13:24:27.274251 | debian-bookworm -> localhost | | | 2026-06-26 13:24:27.274280 | debian-bookworm -> localhost | | E. | 2026-06-26 13:24:27.274306 | debian-bookworm -> localhost | | .o+o| 2026-06-26 13:24:27.274352 | debian-bookworm -> localhost | | =+++| 2026-06-26 13:24:27.274382 | debian-bookworm -> localhost | | S o.oo=| 2026-06-26 13:24:27.274422 | debian-bookworm -> localhost | | o + +. ooB| 2026-06-26 13:24:27.274450 | debian-bookworm -> localhost | | o . =oo&B| 2026-06-26 13:24:27.274477 | debian-bookworm -> localhost | | . **B%| 2026-06-26 13:24:27.274505 | debian-bookworm -> localhost | | ..+ .*O| 2026-06-26 13:24:27.274532 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-26 13:24:27.274613 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.616481 2026-06-26 13:24:27.283798 | 2026-06-26 13:24:27.283935 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-26 13:24:27.320508 | debian-bookworm | ok 2026-06-26 13:24:27.337300 | debian-bookworm | included: /var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-26 13:24:27.346128 | 2026-06-26 13:24:27.346232 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-26 13:24:27.372562 | debian-bookworm | skipping: Conditional result was False 2026-06-26 13:24:27.388466 | 2026-06-26 13:24:27.388661 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-26 13:24:28.077523 | debian-bookworm | changed 2026-06-26 13:24:28.086142 | 2026-06-26 13:24:28.086284 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-26 13:24:28.363893 | debian-bookworm | ok 2026-06-26 13:24:28.372466 | 2026-06-26 13:24:28.372623 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-26 13:24:29.107645 | debian-bookworm | changed 2026-06-26 13:24:29.116421 | 2026-06-26 13:24:29.116603 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-26 13:24:29.815246 | debian-bookworm | changed 2026-06-26 13:24:29.824644 | 2026-06-26 13:24:29.824837 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-26 13:24:29.860489 | debian-bookworm | skipping: Conditional result was False 2026-06-26 13:24:29.874575 | 2026-06-26 13:24:29.874822 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-26 13:24:30.367807 | debian-bookworm -> localhost | changed 2026-06-26 13:24:30.384549 | 2026-06-26 13:24:30.384732 | TASK [add-build-sshkey : Add back temp key] 2026-06-26 13:24:30.796783 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/work/1a042407988f442ca3fd511909d23b18_id_rsa (zuul-build-sshkey) 2026-06-26 13:24:30.797381 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022241 2026-06-26 13:24:30.814067 | 2026-06-26 13:24:30.814233 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-26 13:24:31.256592 | debian-bookworm | ok 2026-06-26 13:24:31.264258 | 2026-06-26 13:24:31.264440 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-26 13:24:31.300444 | debian-bookworm | skipping: Conditional result was False 2026-06-26 13:24:31.319854 | 2026-06-26 13:24:31.320009 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-26 13:24:31.805377 | debian-bookworm | ok 2026-06-26 13:24:31.824031 | 2026-06-26 13:24:31.824231 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-26 13:24:31.874650 | debian-bookworm | ok 2026-06-26 13:24:31.886427 | 2026-06-26 13:24:31.886643 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-26 13:24:32.249545 | debian-bookworm -> localhost | ok 2026-06-26 13:24:32.263858 | 2026-06-26 13:24:32.264028 | TASK [validate-host : Collect information about the host] 2026-06-26 13:24:33.473370 | debian-bookworm | ok 2026-06-26 13:24:33.490519 | 2026-06-26 13:24:33.490651 | TASK [validate-host : Sanitize hostname] 2026-06-26 13:24:33.649143 | debian-bookworm | ok 2026-06-26 13:24:33.655097 | 2026-06-26 13:24:33.655228 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-26 13:24:34.239713 | debian-bookworm -> localhost | changed 2026-06-26 13:24:34.252111 | 2026-06-26 13:24:34.252279 | TASK [validate-host : Collect information about zuul worker] 2026-06-26 13:24:34.738126 | debian-bookworm | ok 2026-06-26 13:24:34.746949 | 2026-06-26 13:24:34.747140 | TASK [validate-host : Write out all zuul information for each host] 2026-06-26 13:24:35.388992 | debian-bookworm -> localhost | changed 2026-06-26 13:24:35.409297 | 2026-06-26 13:24:35.409514 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-26 13:24:35.718888 | debian-bookworm | ok 2026-06-26 13:24:35.727539 | 2026-06-26 13:24:35.727688 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-26 13:24:36.828930 | debian-bookworm | changed: 2026-06-26 13:24:36.829272 | debian-bookworm | cd+++++++++ src/ 2026-06-26 13:24:36.829323 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-26 13:24:36.829378 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-26 13:24:36.829411 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-06-26 13:24:36.829443 | debian-bookworm | Containerfile 2026-06-26 13:24:36.829782 | debian-bookworm | {"status":400,"title":"The uploaded BOM is invalid","detail":"Unrecognized specVersion 1.7"} 2026-06-26 13:27:47.310085 | debian-bookworm | ERROR 2026-06-26 13:27:47.310668 | debian-bookworm | { 2026-06-26 13:27:47.310782 | debian-bookworm | "delta": "0:00:21.040799", 2026-06-26 13:27:47.310901 | debian-bookworm | "end": "2026-06-26 13:27:47.110808", 2026-06-26 13:27:47.310982 | debian-bookworm | "msg": "non-zero return code", 2026-06-26 13:27:47.311048 | debian-bookworm | "rc": 1, 2026-06-26 13:27:47.311105 | debian-bookworm | "start": "2026-06-26 13:27:26.070009" 2026-06-26 13:27:47.311159 | debian-bookworm | } failure 2026-06-26 13:27:47.322729 | 2026-06-26 13:27:47.322973 | PLAY RECAP 2026-06-26 13:27:47.323360 | debian-bookworm | ok: 4 changed: 4 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-26 13:27:47.323662 | 2026-06-26 13:27:47.588988 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-inventory-reconciler/playbooks/build.yml@main] 2026-06-26 13:27:47.590429 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-26 13:27:48.417837 | 2026-06-26 13:27:48.418069 | PLAY [Base post-fetch] 2026-06-26 13:27:48.436618 | 2026-06-26 13:27:48.436817 | TASK [diagnose-network : Ensure log directory exists] 2026-06-26 13:27:49.145983 | debian-bookworm | ok 2026-06-26 13:27:49.155813 | 2026-06-26 13:27:49.156012 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-06-26 13:28:00.986637 | debian-bookworm | ok: Runtime: 0:00:06.128599 2026-06-26 13:28:00.998029 | 2026-06-26 13:28:00.998226 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-06-26 13:28:07.061519 | debian-bookworm | ok: Runtime: 0:00:00.287132 2026-06-26 13:28:07.071402 | 2026-06-26 13:28:07.071593 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-06-26 13:28:13.179765 | debian-bookworm | ok: Runtime: 0:00:01.008206 2026-06-26 13:28:13.197249 | 2026-06-26 13:28:13.197469 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-26 13:28:13.263743 | debian-bookworm | skipping: Conditional result was False 2026-06-26 13:28:13.270964 | 2026-06-26 13:28:13.271122 | TASK [fetch-output : Set log path for single node] 2026-06-26 13:28:13.314525 | debian-bookworm | ok 2026-06-26 13:28:13.320594 | 2026-06-26 13:28:13.320738 | LOOP [fetch-output : Ensure local output dirs] 2026-06-26 13:28:13.726860 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/work/logs" 2026-06-26 13:28:14.031386 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/work/artifacts" 2026-06-26 13:28:14.308983 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1a042407988f442ca3fd511909d23b18/work/docs" 2026-06-26 13:28:14.324425 | 2026-06-26 13:28:14.324579 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-26 13:28:15.112044 | debian-bookworm | changed: 2026-06-26 13:28:15.112295 | debian-bookworm | .d..t...... ./ 2026-06-26 13:28:15.112349 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-06-26 13:28:15.112375 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-06-26 13:28:15.112397 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-26 13:28:15.112418 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-26 13:28:15.112439 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-26 13:28:15.112487 | debian-bookworm | changed: All items complete 2026-06-26 13:28:15.112514 | 2026-06-26 13:28:15.673837 | debian-bookworm | changed: .d..t...... ./ 2026-06-26 13:28:16.279796 | debian-bookworm | changed: .d..t...... ./ 2026-06-26 13:28:16.298156 | 2026-06-26 13:28:16.298314 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-26 13:28:16.357023 | debian-bookworm | skipping: Conditional result was False 2026-06-26 13:28:16.364003 | debian-bookworm | skipping: Conditional result was False 2026-06-26 13:28:16.383950 | 2026-06-26 13:28:16.384070 | PLAY RECAP 2026-06-26 13:28:16.384126 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-26 13:28:16.384150 | 2026-06-26 13:28:16.573094 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-26 13:28:16.574213 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-26 13:28:17.507690 | 2026-06-26 13:28:17.507869 | PLAY [Base post] 2026-06-26 13:28:17.523520 | 2026-06-26 13:28:17.523712 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-26 13:28:18.343007 | debian-bookworm | changed 2026-06-26 13:28:18.356090 | 2026-06-26 13:28:18.356228 | PLAY RECAP 2026-06-26 13:28:18.356290 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-26 13:28:18.356398 | 2026-06-26 13:28:18.518120 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-26 13:28:18.519310 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-26 13:28:19.454701 | 2026-06-26 13:28:19.454968 | PLAY [Base post-logs] 2026-06-26 13:28:19.467763 | 2026-06-26 13:28:19.467953 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-26 13:28:19.981129 | localhost | changed 2026-06-26 13:28:19.991930 | 2026-06-26 13:28:19.992082 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-26 13:28:20.029921 | localhost | ok 2026-06-26 13:28:20.036037 | 2026-06-26 13:28:20.036371 | TASK [Set zuul-log-path fact] 2026-06-26 13:28:20.058480 | localhost | ok 2026-06-26 13:28:20.072224 | 2026-06-26 13:28:20.072395 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-26 13:28:20.100875 | localhost | ok 2026-06-26 13:28:20.106796 | 2026-06-26 13:28:20.106991 | TASK [upload-logs : Create log directories] 2026-06-26 13:28:20.678949 | localhost | changed 2026-06-26 13:28:20.683781 | 2026-06-26 13:28:20.683961 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-26 13:28:21.248137 | localhost -> localhost | ok: Runtime: 0:00:00.007833 2026-06-26 13:28:21.263534 | 2026-06-26 13:28:21.263829 | TASK [upload-logs : Upload logs to log server] 2026-06-26 13:28:21.888022 | localhost | Output suppressed because no_log was given 2026-06-26 13:28:21.890159 | 2026-06-26 13:28:21.890277 | LOOP [upload-logs : Compress console log and json output] 2026-06-26 13:28:21.958180 | localhost | skipping: Conditional result was False 2026-06-26 13:28:21.964602 | localhost | skipping: Conditional result was False 2026-06-26 13:28:21.970589 | 2026-06-26 13:28:21.970771 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-26 13:28:22.041749 | localhost | skipping: Conditional result was False 2026-06-26 13:28:22.042282 | 2026-06-26 13:28:22.044899 | localhost | skipping: Conditional result was False 2026-06-26 13:28:22.059815 | 2026-06-26 13:28:22.060036 | LOOP [upload-logs : Upload console log and json output]