2025-10-25 18:29:52.818168 | Job console starting 2025-10-25 18:29:52.842849 | Updating git repos 2025-10-25 18:29:52.870619 | Cloning repos into workspace 2025-10-25 18:29:52.901986 | Restoring repo states 2025-10-25 18:29:52.908883 | Merging changes 2025-10-25 18:29:53.387815 | Checking out repos 2025-10-25 18:29:53.450019 | Preparing playbooks 2025-10-25 18:29:54.352122 | Running Ansible setup 2025-10-25 18:29:59.496990 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-25 18:30:00.418548 | 2025-10-25 18:30:00.418719 | PLAY [Base pre] 2025-10-25 18:30:00.435357 | 2025-10-25 18:30:00.435499 | TASK [Setup log path fact] 2025-10-25 18:30:00.454785 | debian-bookworm | ok 2025-10-25 18:30:00.471380 | 2025-10-25 18:30:00.471537 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-25 18:30:00.501528 | debian-bookworm | ok 2025-10-25 18:30:00.512168 | 2025-10-25 18:30:00.512334 | TASK [emit-job-header : Print job information] 2025-10-25 18:30:00.561646 | # Job Information 2025-10-25 18:30:00.561835 | Ansible Version: 2.16.14 2025-10-25 18:30:00.561872 | Job: container-image-inventory-reconciler-build 2025-10-25 18:30:00.561904 | Pipeline: check 2025-10-25 18:30:00.561927 | Executor: 521e9411259a 2025-10-25 18:30:00.561948 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/441 2025-10-25 18:30:00.561969 | Event ID: 93ba9d40-b1d0-11f0-8089-450b874cd711 2025-10-25 18:30:00.567089 | 2025-10-25 18:30:00.567242 | LOOP [emit-job-header : Print node information] 2025-10-25 18:30:00.783775 | debian-bookworm | ok: 2025-10-25 18:30:00.784121 | debian-bookworm | # Node Information 2025-10-25 18:30:00.784163 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-25 18:30:00.784190 | debian-bookworm | Hostname: debian 2025-10-25 18:30:00.784236 | debian-bookworm | Username: zuul 2025-10-25 18:30:00.784259 | debian-bookworm | Distro: Debian 12.12 2025-10-25 18:30:00.784284 | debian-bookworm | Provider: regiocloud-a 2025-10-25 18:30:00.784306 | debian-bookworm | Region: 2025-10-25 18:30:00.784328 | debian-bookworm | Label: debian-bookworm 2025-10-25 18:30:00.784347 | debian-bookworm | Product Name: OpenStack Nova 2025-10-25 18:30:00.784366 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7f:dcd3 2025-10-25 18:30:00.804970 | 2025-10-25 18:30:00.805107 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-25 18:30:01.464940 | debian-bookworm -> localhost | changed 2025-10-25 18:30:01.471656 | 2025-10-25 18:30:01.471761 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-25 18:30:02.925972 | debian-bookworm -> localhost | changed 2025-10-25 18:30:02.954916 | 2025-10-25 18:30:02.955030 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-25 18:30:03.469627 | debian-bookworm -> localhost | ok 2025-10-25 18:30:03.476558 | 2025-10-25 18:30:03.476751 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-25 18:30:03.506879 | debian-bookworm | ok 2025-10-25 18:30:03.539661 | debian-bookworm | included: /var/lib/zuul/builds/466b5463d39e4a5a925ab6c5018e20cc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-25 18:30:03.551259 | 2025-10-25 18:30:03.551360 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-25 18:30:04.987479 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-25 18:30:04.987667 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/466b5463d39e4a5a925ab6c5018e20cc/work/466b5463d39e4a5a925ab6c5018e20cc_id_rsa 2025-10-25 18:30:04.987704 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/466b5463d39e4a5a925ab6c5018e20cc/work/466b5463d39e4a5a925ab6c5018e20cc_id_rsa.pub 2025-10-25 18:30:04.987731 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-25 18:30:04.987757 | debian-bookworm -> localhost | SHA256:tBQgdJnMu1R75ypZQH9m9hxf/J/FnECUK2ePsTdJU1k zuul-build-sshkey 2025-10-25 18:30:04.987787 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-25 18:30:04.987810 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-25 18:30:04.987831 | debian-bookworm -> localhost | | .ooo+. ... E| 2025-10-25 18:30:04.987853 | debian-bookworm -> localhost | | o= o. o ..| 2025-10-25 18:30:04.987873 | debian-bookworm -> localhost | | +oo . ...| 2025-10-25 18:30:04.987893 | debian-bookworm -> localhost | | ooo.o.**.oo| 2025-10-25 18:30:04.987926 | debian-bookworm -> localhost | | . .So B+oB=*| 2025-10-25 18:30:04.987950 | debian-bookworm -> localhost | | . . .oo**| 2025-10-25 18:30:04.987970 | debian-bookworm -> localhost | | o . . =| 2025-10-25 18:30:04.987990 | debian-bookworm -> localhost | | o . ..| 2025-10-25 18:30:04.988010 | debian-bookworm -> localhost | | . | 2025-10-25 18:30:04.988030 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-25 18:30:04.988080 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.602548 2025-10-25 18:30:04.994121 | 2025-10-25 18:30:04.994231 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-25 18:30:05.011069 | debian-bookworm | ok 2025-10-25 18:30:05.019903 | debian-bookworm | included: /var/lib/zuul/builds/466b5463d39e4a5a925ab6c5018e20cc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-25 18:30:05.028377 | 2025-10-25 18:30:05.028454 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-25 18:30:05.051468 | debian-bookworm | skipping: Conditional result was False 2025-10-25 18:30:05.063986 | 2025-10-25 18:30:05.064124 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-25 18:30:05.705544 | debian-bookworm | changed 2025-10-25 18:30:05.710643 | 2025-10-25 18:30:05.710727 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-25 18:30:05.970523 | debian-bookworm | ok 2025-10-25 18:30:05.976480 | 2025-10-25 18:30:05.976569 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-25 18:30:06.730375 | debian-bookworm | changed 2025-10-25 18:30:06.739005 | 2025-10-25 18:30:06.739118 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-25 18:30:07.373887 | debian-bookworm | changed 2025-10-25 18:30:07.379666 | 2025-10-25 18:30:07.379752 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-25 18:30:07.402512 | debian-bookworm | skipping: Conditional result was False 2025-10-25 18:30:07.408016 | 2025-10-25 18:30:07.408099 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-25 18:30:07.837805 | debian-bookworm -> localhost | changed 2025-10-25 18:30:07.848140 | 2025-10-25 18:30:07.848257 | TASK [add-build-sshkey : Add back temp key] 2025-10-25 18:30:08.254579 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/466b5463d39e4a5a925ab6c5018e20cc/work/466b5463d39e4a5a925ab6c5018e20cc_id_rsa (zuul-build-sshkey) 2025-10-25 18:30:08.254812 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017600 2025-10-25 18:30:08.262825 | 2025-10-25 18:30:08.262931 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-25 18:30:08.659352 | debian-bookworm | ok 2025-10-25 18:30:08.667759 | 2025-10-25 18:30:08.667896 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-25 18:30:08.699626 | debian-bookworm | skipping: Conditional result was False 2025-10-25 18:30:08.712674 | 2025-10-25 18:30:08.712783 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-25 18:30:09.115611 | debian-bookworm | ok 2025-10-25 18:30:09.125892 | 2025-10-25 18:30:09.125987 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-25 18:30:09.184178 | debian-bookworm | ok 2025-10-25 18:30:09.208099 | 2025-10-25 18:30:09.208266 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-25 18:30:09.535331 | debian-bookworm -> localhost | ok 2025-10-25 18:30:09.547051 | 2025-10-25 18:30:09.547213 | TASK [validate-host : Collect information about the host] 2025-10-25 18:30:10.599475 | debian-bookworm | ok 2025-10-25 18:30:10.620303 | 2025-10-25 18:30:10.620436 | TASK [validate-host : Sanitize hostname] 2025-10-25 18:30:10.772688 | debian-bookworm | ok 2025-10-25 18:30:10.826474 | 2025-10-25 18:30:10.826676 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-25 18:30:11.454859 | debian-bookworm -> localhost | changed 2025-10-25 18:30:11.464584 | 2025-10-25 18:30:11.464731 | TASK [validate-host : Collect information about zuul worker] 2025-10-25 18:30:11.897090 | debian-bookworm | ok 2025-10-25 18:30:11.902056 | 2025-10-25 18:30:11.902171 | TASK [validate-host : Write out all zuul information for each host] 2025-10-25 18:30:12.486564 | debian-bookworm -> localhost | changed 2025-10-25 18:30:12.506573 | 2025-10-25 18:30:12.506750 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-25 18:30:12.761275 | debian-bookworm | ok 2025-10-25 18:30:12.768932 | 2025-10-25 18:30:12.769055 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-25 18:30:13.626642 | debian-bookworm | changed: 2025-10-25 18:30:13.626889 | debian-bookworm | cd+++++++++ src/ 2025-10-25 18:30:13.626929 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-25 18:30:13.626954 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-25 18:30:13.626976 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-10-25 18:30:13.626996 | debian-bookworm | Containerfile 2025-10-25 18:30:13.627128 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006524 2025-10-25 18:31:51.067969 | 2025-10-25 18:31:51.068082 | TASK [upload-logs : Upload logs to log server] 2025-10-25 18:31:51.624617 | localhost | Output suppressed because no_log was given 2025-10-25 18:31:51.628148 | 2025-10-25 18:31:51.628389 | LOOP [upload-logs : Compress console log and json output] 2025-10-25 18:31:51.681331 | localhost | skipping: Conditional result was False 2025-10-25 18:31:51.686039 | localhost | skipping: Conditional result was False 2025-10-25 18:31:51.699674 | 2025-10-25 18:31:51.699920 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-25 18:31:51.745158 | localhost | skipping: Conditional result was False 2025-10-25 18:31:51.745854 | 2025-10-25 18:31:51.749099 | localhost | skipping: Conditional result was False 2025-10-25 18:31:51.762615 | 2025-10-25 18:31:51.762894 | LOOP [upload-logs : Upload console log and json output]