2026-05-05 08:22:48.383190 | Job console starting 2026-05-05 08:22:48.396080 | Updating git repos 2026-05-05 08:22:48.550024 | Cloning repos into workspace 2026-05-05 08:22:48.720208 | Restoring repo states 2026-05-05 08:22:48.741663 | Merging changes 2026-05-05 08:22:49.613326 | Checking out repos 2026-05-05 08:22:49.671596 | Preparing playbooks 2026-05-05 08:22:51.022243 | Running Ansible setup 2026-05-05 08:22:57.746226 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-05 08:22:59.460510 | 2026-05-05 08:22:59.463104 | PLAY [Base pre] 2026-05-05 08:22:59.536791 | 2026-05-05 08:22:59.536955 | TASK [Setup log path fact] 2026-05-05 08:22:59.590936 | debian-bookworm | ok 2026-05-05 08:22:59.633492 | 2026-05-05 08:22:59.634046 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 08:22:59.739197 | debian-bookworm | ok 2026-05-05 08:22:59.760886 | 2026-05-05 08:22:59.761005 | TASK [emit-job-header : Print job information] 2026-05-05 08:22:59.865877 | # Job Information 2026-05-05 08:22:59.866077 | Ansible Version: 2.16.14 2026-05-05 08:22:59.866116 | Job: container-image-inventory-reconciler-build 2026-05-05 08:22:59.866150 | Pipeline: check 2026-05-05 08:22:59.866172 | Executor: 521e9411259a 2026-05-05 08:22:59.866194 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/533 2026-05-05 08:22:59.866215 | Event ID: 550c4f90-485b-11f1-8eea-dc5eb61796cf 2026-05-05 08:22:59.877436 | 2026-05-05 08:22:59.878207 | LOOP [emit-job-header : Print node information] 2026-05-05 08:23:00.129789 | debian-bookworm | ok: 2026-05-05 08:23:00.129960 | debian-bookworm | # Node Information 2026-05-05 08:23:00.129990 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-05 08:23:00.130011 | debian-bookworm | Hostname: debian 2026-05-05 08:23:00.130029 | debian-bookworm | Username: zuul 2026-05-05 08:23:00.130046 | debian-bookworm | Distro: Debian 12.13 2026-05-05 08:23:00.130066 | debian-bookworm | Provider: regiocloud-a 2026-05-05 08:23:00.130083 | debian-bookworm | Region: 2026-05-05 08:23:00.130100 | debian-bookworm | Label: debian-bookworm 2026-05-05 08:23:00.130116 | debian-bookworm | Product Name: OpenStack Nova 2026-05-05 08:23:00.130131 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefb:aad4 2026-05-05 08:23:00.143698 | 2026-05-05 08:23:00.143814 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-05 08:23:01.397360 | debian-bookworm -> localhost | changed 2026-05-05 08:23:01.404401 | 2026-05-05 08:23:01.404506 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-05 08:23:04.013342 | debian-bookworm -> localhost | changed 2026-05-05 08:23:04.024572 | 2026-05-05 08:23:04.024664 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-05 08:23:04.572616 | debian-bookworm -> localhost | ok 2026-05-05 08:23:04.577734 | 2026-05-05 08:23:04.577833 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-05 08:23:04.617712 | debian-bookworm | ok 2026-05-05 08:23:04.641607 | debian-bookworm | included: /var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-05 08:23:04.656708 | 2026-05-05 08:23:04.656812 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-05 08:23:06.999012 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-05 08:23:06.999209 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/work/ca733cbb653d4abcbb2a2b5359f27d00_id_rsa 2026-05-05 08:23:06.999243 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/work/ca733cbb653d4abcbb2a2b5359f27d00_id_rsa.pub 2026-05-05 08:23:06.999265 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-05 08:23:06.999284 | debian-bookworm -> localhost | SHA256:B0FdkcLQhA4w3sPJquwdIzPMddn8qWX7BzLRtUDmYAI zuul-build-sshkey 2026-05-05 08:23:06.999309 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-05 08:23:06.999327 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-05 08:23:06.999344 | debian-bookworm -> localhost | | o.Eo=B+o=o | 2026-05-05 08:23:06.999362 | debian-bookworm -> localhost | | . =...+=+o . | 2026-05-05 08:23:06.999378 | debian-bookworm -> localhost | | . *o. o.o . | 2026-05-05 08:23:06.999394 | debian-bookworm -> localhost | | . =... . . | 2026-05-05 08:23:06.999410 | debian-bookworm -> localhost | | o o S .. | 2026-05-05 08:23:06.999433 | debian-bookworm -> localhost | | + o . oo.. | 2026-05-05 08:23:06.999450 | debian-bookworm -> localhost | | O o =o . | 2026-05-05 08:23:06.999466 | debian-bookworm -> localhost | | . = o + . . | 2026-05-05 08:23:06.999482 | debian-bookworm -> localhost | | . . . .... | 2026-05-05 08:23:06.999498 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-05 08:23:06.999542 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.857012 2026-05-05 08:23:07.005144 | 2026-05-05 08:23:07.005225 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-05 08:23:07.043422 | debian-bookworm | ok 2026-05-05 08:23:07.086750 | debian-bookworm | included: /var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-05 08:23:07.127478 | 2026-05-05 08:23:07.127579 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-05 08:23:07.207364 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:23:07.212998 | 2026-05-05 08:23:07.213085 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-05 08:23:08.299945 | debian-bookworm | changed 2026-05-05 08:23:08.315494 | 2026-05-05 08:23:08.315887 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-05 08:23:08.612021 | debian-bookworm | ok 2026-05-05 08:23:08.625383 | 2026-05-05 08:23:08.625481 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-05 08:23:09.425726 | debian-bookworm | changed 2026-05-05 08:23:09.431279 | 2026-05-05 08:23:09.431367 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-05 08:23:10.160452 | debian-bookworm | changed 2026-05-05 08:23:10.171822 | 2026-05-05 08:23:10.171958 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-05 08:23:10.235884 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:23:10.243297 | 2026-05-05 08:23:10.243408 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-05 08:23:10.958152 | debian-bookworm -> localhost | changed 2026-05-05 08:23:10.967635 | 2026-05-05 08:23:10.967737 | TASK [add-build-sshkey : Add back temp key] 2026-05-05 08:23:11.544579 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/work/ca733cbb653d4abcbb2a2b5359f27d00_id_rsa (zuul-build-sshkey) 2026-05-05 08:23:11.544790 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.027618 2026-05-05 08:23:11.554640 | 2026-05-05 08:23:11.554743 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-05 08:23:11.931345 | debian-bookworm | ok 2026-05-05 08:23:11.936255 | 2026-05-05 08:23:11.936348 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-05 08:23:11.960217 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:23:11.978659 | 2026-05-05 08:23:11.978771 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-05 08:23:12.433196 | debian-bookworm | ok 2026-05-05 08:23:12.443514 | 2026-05-05 08:23:12.450202 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-05 08:23:12.479447 | debian-bookworm | ok 2026-05-05 08:23:12.483672 | 2026-05-05 08:23:12.483754 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-05 08:23:12.998667 | debian-bookworm -> localhost | ok 2026-05-05 08:23:13.003961 | 2026-05-05 08:23:13.004049 | TASK [validate-host : Collect information about the host] 2026-05-05 08:23:14.300006 | debian-bookworm | ok 2026-05-05 08:23:14.322884 | 2026-05-05 08:23:14.323009 | TASK [validate-host : Sanitize hostname] 2026-05-05 08:23:14.574961 | debian-bookworm | ok 2026-05-05 08:23:14.696056 | 2026-05-05 08:23:14.696174 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-05 08:23:15.674207 | debian-bookworm -> localhost | changed 2026-05-05 08:23:15.681564 | 2026-05-05 08:23:15.681683 | TASK [validate-host : Collect information about zuul worker] 2026-05-05 08:23:16.124911 | debian-bookworm | ok 2026-05-05 08:23:16.132240 | 2026-05-05 08:23:16.132368 | TASK [validate-host : Write out all zuul information for each host] 2026-05-05 08:23:16.787565 | debian-bookworm -> localhost | changed 2026-05-05 08:23:16.796899 | 2026-05-05 08:23:16.797006 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-05 08:23:17.082421 | debian-bookworm | ok 2026-05-05 08:23:17.089223 | 2026-05-05 08:23:17.089341 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-05 08:23:18.020986 | debian-bookworm | changed: 2026-05-05 08:23:18.021168 | debian-bookworm | cd+++++++++ src/ 2026-05-05 08:23:18.021203 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-05 08:23:18.021227 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-05 08:23:18.021249 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-05-05 08:23:18.021270 | debian-bookworm | Containerfile 2026-05-05 08:23:18.021422 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/work/logs" 2026-05-05 08:25:33.450517 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/work/artifacts" 2026-05-05 08:25:34.469106 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ca733cbb653d4abcbb2a2b5359f27d00/work/docs" 2026-05-05 08:25:34.504369 | 2026-05-05 08:25:34.504482 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-05 08:25:36.014789 | debian-bookworm | changed: 2026-05-05 08:25:36.015038 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-05 08:25:36.015075 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-05 08:25:36.015104 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-05 08:25:36.015149 | debian-bookworm | changed: All items complete 2026-05-05 08:25:36.015176 | 2026-05-05 08:25:36.967657 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 08:25:37.964471 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 08:25:37.982534 | 2026-05-05 08:25:37.982634 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-05 08:25:38.983269 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.025252 2026-05-05 08:25:39.343913 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007089 2026-05-05 08:25:39.355742 | 2026-05-05 08:25:39.355819 | PLAY RECAP 2026-05-05 08:25:39.355859 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-05 08:25:39.355880 | 2026-05-05 08:25:39.699737 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-05 08:25:39.700410 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 08:25:40.597998 | 2026-05-05 08:25:40.598122 | PLAY [Base post] 2026-05-05 08:25:40.618299 | 2026-05-05 08:25:40.618422 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-05 08:25:41.403148 | debian-bookworm | changed 2026-05-05 08:25:41.408057 | 2026-05-05 08:25:41.408153 | PLAY RECAP 2026-05-05 08:25:41.411019 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-05 08:25:41.411084 | 2026-05-05 08:25:41.702549 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 08:25:41.703290 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-05 08:25:43.384078 | 2026-05-05 08:25:43.384210 | PLAY [Base post-logs] 2026-05-05 08:25:43.410119 | 2026-05-05 08:25:43.410245 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-05 08:25:44.361278 | localhost | changed 2026-05-05 08:25:44.369038 | 2026-05-05 08:25:44.369171 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-05 08:25:44.405863 | localhost | ok 2026-05-05 08:25:44.408249 | 2026-05-05 08:25:44.408322 | TASK [Set zuul-log-path fact] 2026-05-05 08:25:44.447455 | localhost | ok 2026-05-05 08:25:44.455176 | 2026-05-05 08:25:44.455272 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 08:25:44.489562 | localhost | ok 2026-05-05 08:25:44.492036 | 2026-05-05 08:25:44.492110 | TASK [upload-logs : Create log directories] 2026-05-05 08:25:45.707449 | localhost | changed 2026-05-05 08:25:45.709662 | 2026-05-05 08:25:45.709757 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-05 08:25:46.654866 | localhost -> localhost | ok: Runtime: 0:00:00.028434 2026-05-05 08:25:46.658242 | 2026-05-05 08:25:46.658327 | TASK [upload-logs : Upload logs to log server] 2026-05-05 08:25:47.800575 | localhost | Output suppressed because no_log was given 2026-05-05 08:25:47.801990 | 2026-05-05 08:25:47.802064 | LOOP [upload-logs : Compress console log and json output] 2026-05-05 08:25:47.914489 | localhost | skipping: Conditional result was False 2026-05-05 08:25:47.932648 | localhost | skipping: Conditional result was False 2026-05-05 08:25:47.941433 | 2026-05-05 08:25:47.941535 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-05 08:25:48.057583 | localhost | skipping: Conditional result was False 2026-05-05 08:25:48.057819 | 2026-05-05 08:25:48.070750 | localhost | skipping: Conditional result was False 2026-05-05 08:25:48.074011 | 2026-05-05 08:25:48.074116 | LOOP [upload-logs : Upload console log and json output]