2026-05-05 20:54:42.977299 | Job console starting 2026-05-05 20:54:43.007069 | Updating git repos 2026-05-05 20:54:43.085673 | Cloning repos into workspace 2026-05-05 20:54:43.191792 | Restoring repo states 2026-05-05 20:54:43.225890 | Merging changes 2026-05-05 20:54:43.837023 | Checking out repos 2026-05-05 20:54:43.977129 | Preparing playbooks 2026-05-05 20:54:45.135098 | Running Ansible setup 2026-05-05 20:54:50.239997 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-05 20:54:51.302956 | 2026-05-05 20:54:51.303177 | PLAY [Base pre] 2026-05-05 20:54:51.321031 | 2026-05-05 20:54:51.321191 | TASK [Setup log path fact] 2026-05-05 20:54:51.340842 | debian-bookworm | ok 2026-05-05 20:54:51.358013 | 2026-05-05 20:54:51.358193 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 20:54:51.397795 | debian-bookworm | ok 2026-05-05 20:54:51.408375 | 2026-05-05 20:54:51.408509 | TASK [emit-job-header : Print job information] 2026-05-05 20:54:51.448140 | # Job Information 2026-05-05 20:54:51.448416 | Ansible Version: 2.16.14 2026-05-05 20:54:51.448454 | Job: container-image-inventory-reconciler-build 2026-05-05 20:54:51.448488 | Pipeline: check 2026-05-05 20:54:51.448512 | Executor: 521e9411259a 2026-05-05 20:54:51.448534 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/533 2026-05-05 20:54:51.448555 | Event ID: 9ded71d0-48c4-11f1-95c2-a1a64912207f 2026-05-05 20:54:51.463379 | 2026-05-05 20:54:51.463546 | LOOP [emit-job-header : Print node information] 2026-05-05 20:54:51.633133 | debian-bookworm | ok: 2026-05-05 20:54:51.633354 | debian-bookworm | # Node Information 2026-05-05 20:54:51.633389 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-05 20:54:51.633414 | debian-bookworm | Hostname: debian 2026-05-05 20:54:51.633436 | debian-bookworm | Username: zuul 2026-05-05 20:54:51.633457 | debian-bookworm | Distro: Debian 12.13 2026-05-05 20:54:51.633482 | debian-bookworm | Provider: regiocloud-a 2026-05-05 20:54:51.633502 | debian-bookworm | Region: 2026-05-05 20:54:51.633523 | debian-bookworm | Label: debian-bookworm 2026-05-05 20:54:51.633543 | debian-bookworm | Product Name: OpenStack Nova 2026-05-05 20:54:51.633562 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe10:ae0 2026-05-05 20:54:51.652758 | 2026-05-05 20:54:51.652915 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-05 20:54:52.429495 | debian-bookworm -> localhost | changed 2026-05-05 20:54:52.437570 | 2026-05-05 20:54:52.437715 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-05 20:54:53.960628 | debian-bookworm -> localhost | changed 2026-05-05 20:54:53.972101 | 2026-05-05 20:54:53.972250 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-05 20:54:54.563577 | debian-bookworm -> localhost | ok 2026-05-05 20:54:54.570060 | 2026-05-05 20:54:54.570201 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-05 20:54:54.601649 | debian-bookworm | ok 2026-05-05 20:54:54.619027 | debian-bookworm | included: /var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-05 20:54:54.625540 | 2026-05-05 20:54:54.625663 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-05 20:54:56.291910 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-05 20:54:56.292225 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/work/7addfeef2e7a4259b56eb77a0ba9c9c2_id_rsa 2026-05-05 20:54:56.292267 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/work/7addfeef2e7a4259b56eb77a0ba9c9c2_id_rsa.pub 2026-05-05 20:54:56.292295 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-05 20:54:56.292320 | debian-bookworm -> localhost | SHA256:fQaZvy4iHprGuG+EZls+vSPWNcZPGCg2hcqR1OFEp3g zuul-build-sshkey 2026-05-05 20:54:56.292358 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-05 20:54:56.292380 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-05 20:54:56.292401 | debian-bookworm -> localhost | | ..++o. | 2026-05-05 20:54:56.292422 | debian-bookworm -> localhost | | o+oo. o | 2026-05-05 20:54:56.292444 | debian-bookworm -> localhost | | ..oE. . + | 2026-05-05 20:54:56.292464 | debian-bookworm -> localhost | | o.+ . .. o | 2026-05-05 20:54:56.292485 | debian-bookworm -> localhost | | o o .So. + | 2026-05-05 20:54:56.292510 | debian-bookworm -> localhost | | + o * .o . | 2026-05-05 20:54:56.292531 | debian-bookworm -> localhost | | o B o.o + . | 2026-05-05 20:54:56.292551 | debian-bookworm -> localhost | | o Oo=.. o. | 2026-05-05 20:54:56.292572 | debian-bookworm -> localhost | | .*++o+ . .. | 2026-05-05 20:54:56.292593 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-05 20:54:56.292649 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.913358 2026-05-05 20:54:56.299665 | 2026-05-05 20:54:56.299873 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-05 20:54:56.342967 | debian-bookworm | ok 2026-05-05 20:54:56.363216 | debian-bookworm | included: /var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-05 20:54:56.378004 | 2026-05-05 20:54:56.378141 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-05 20:54:56.434432 | debian-bookworm | skipping: Conditional result was False 2026-05-05 20:54:56.441034 | 2026-05-05 20:54:56.441156 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-05 20:54:57.345489 | debian-bookworm | changed 2026-05-05 20:54:57.373962 | 2026-05-05 20:54:57.374176 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-05 20:54:57.687169 | debian-bookworm | ok 2026-05-05 20:54:57.693405 | 2026-05-05 20:54:57.693549 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-05 20:54:58.392631 | debian-bookworm | changed 2026-05-05 20:54:58.404403 | 2026-05-05 20:54:58.404624 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-05 20:54:59.105926 | debian-bookworm | changed 2026-05-05 20:54:59.115382 | 2026-05-05 20:54:59.115547 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-05 20:54:59.162247 | debian-bookworm | skipping: Conditional result was False 2026-05-05 20:54:59.174503 | 2026-05-05 20:54:59.174722 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-05 20:54:59.705096 | debian-bookworm -> localhost | changed 2026-05-05 20:54:59.720620 | 2026-05-05 20:54:59.720802 | TASK [add-build-sshkey : Add back temp key] 2026-05-05 20:55:00.100571 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/work/7addfeef2e7a4259b56eb77a0ba9c9c2_id_rsa (zuul-build-sshkey) 2026-05-05 20:55:00.100939 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019598 2026-05-05 20:55:00.110054 | 2026-05-05 20:55:00.110201 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-05 20:55:00.562574 | debian-bookworm | ok 2026-05-05 20:55:00.571581 | 2026-05-05 20:55:00.571903 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-05 20:55:00.600006 | debian-bookworm | skipping: Conditional result was False 2026-05-05 20:55:00.630403 | 2026-05-05 20:55:00.630619 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-05 20:55:01.109480 | debian-bookworm | ok 2026-05-05 20:55:01.122531 | 2026-05-05 20:55:01.122696 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-05 20:55:01.153037 | debian-bookworm | ok 2026-05-05 20:55:01.159094 | 2026-05-05 20:55:01.159247 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-05 20:55:01.613760 | debian-bookworm -> localhost | ok 2026-05-05 20:55:01.623240 | 2026-05-05 20:55:01.623407 | TASK [validate-host : Collect information about the host] 2026-05-05 20:55:03.092309 | debian-bookworm | ok 2026-05-05 20:55:03.124108 | 2026-05-05 20:55:03.124376 | TASK [validate-host : Sanitize hostname] 2026-05-05 20:55:03.353336 | debian-bookworm | ok 2026-05-05 20:55:03.417843 | 2026-05-05 20:55:03.418051 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-05 20:55:04.130099 | debian-bookworm -> localhost | changed 2026-05-05 20:55:04.151264 | 2026-05-05 20:55:04.151445 | TASK [validate-host : Collect information about zuul worker] 2026-05-05 20:55:04.675966 | debian-bookworm | ok 2026-05-05 20:55:04.682012 | 2026-05-05 20:55:04.682151 | TASK [validate-host : Write out all zuul information for each host] 2026-05-05 20:55:05.326029 | debian-bookworm -> localhost | changed 2026-05-05 20:55:05.338568 | 2026-05-05 20:55:05.338727 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-05 20:55:05.624957 | debian-bookworm | ok 2026-05-05 20:55:05.631690 | 2026-05-05 20:55:05.631842 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-05 20:55:07.262167 | debian-bookworm | changed: 2026-05-05 20:55:07.262409 | debian-bookworm | cd+++++++++ src/ 2026-05-05 20:55:07.262446 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-05 20:55:07.262469 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-05 20:55:07.262490 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-05-05 20:55:07.262511 | debian-bookworm | Containerfile 2026-05-05 20:55:07.262665 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/work/logs" 2026-05-05 20:57:20.986638 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/work/artifacts" 2026-05-05 20:57:21.281950 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7addfeef2e7a4259b56eb77a0ba9c9c2/work/docs" 2026-05-05 20:57:21.311910 | 2026-05-05 20:57:21.312102 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-05 20:57:22.149298 | debian-bookworm | changed: 2026-05-05 20:57:22.149761 | debian-bookworm | .d..t...... ./ 2026-05-05 20:57:22.149832 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-05 20:57:22.149888 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-05 20:57:22.149936 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-05 20:57:22.150001 | debian-bookworm | changed: All items complete 2026-05-05 20:57:22.150042 | 2026-05-05 20:57:22.784082 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 20:57:23.410272 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 20:57:23.443601 | 2026-05-05 20:57:23.443819 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-05 20:57:23.970452 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009870 2026-05-05 20:57:24.246753 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010194 2026-05-05 20:57:24.274756 | 2026-05-05 20:57:24.274988 | PLAY RECAP 2026-05-05 20:57:24.275095 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-05 20:57:24.275148 | 2026-05-05 20:57:24.464589 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-05 20:57:24.466640 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 20:57:25.258999 | 2026-05-05 20:57:25.259235 | PLAY [Base post] 2026-05-05 20:57:25.274962 | 2026-05-05 20:57:25.275246 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-05 20:57:26.144888 | debian-bookworm | changed 2026-05-05 20:57:26.154545 | 2026-05-05 20:57:26.154709 | PLAY RECAP 2026-05-05 20:57:26.154790 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-05 20:57:26.154911 | 2026-05-05 20:57:26.294504 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 20:57:26.296944 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-05 20:57:27.088307 | 2026-05-05 20:57:27.088497 | PLAY [Base post-logs] 2026-05-05 20:57:27.100039 | 2026-05-05 20:57:27.100186 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-05 20:57:27.597086 | localhost | changed 2026-05-05 20:57:27.615507 | 2026-05-05 20:57:27.615802 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-05 20:57:27.654111 | localhost | ok 2026-05-05 20:57:27.659047 | 2026-05-05 20:57:27.659201 | TASK [Set zuul-log-path fact] 2026-05-05 20:57:27.676359 | localhost | ok 2026-05-05 20:57:27.688817 | 2026-05-05 20:57:27.688976 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 20:57:27.716258 | localhost | ok 2026-05-05 20:57:27.721104 | 2026-05-05 20:57:27.721260 | TASK [upload-logs : Create log directories] 2026-05-05 20:57:28.281432 | localhost | changed 2026-05-05 20:57:28.286859 | 2026-05-05 20:57:28.287032 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-05 20:57:28.829390 | localhost -> localhost | ok: Runtime: 0:00:00.007838 2026-05-05 20:57:28.833853 | 2026-05-05 20:57:28.833975 | TASK [upload-logs : Upload logs to log server] 2026-05-05 20:57:29.437431 | localhost | Output suppressed because no_log was given 2026-05-05 20:57:29.441604 | 2026-05-05 20:57:29.441814 | LOOP [upload-logs : Compress console log and json output] 2026-05-05 20:57:29.497479 | localhost | skipping: Conditional result was False 2026-05-05 20:57:29.500901 | localhost | skipping: Conditional result was False 2026-05-05 20:57:29.511629 | 2026-05-05 20:57:29.511787 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-05 20:57:29.557000 | localhost | skipping: Conditional result was False 2026-05-05 20:57:29.557754 | 2026-05-05 20:57:29.560865 | localhost | skipping: Conditional result was False 2026-05-05 20:57:29.574246 | 2026-05-05 20:57:29.574554 | LOOP [upload-logs : Upload console log and json output]