2026-06-01 09:05:00.240956 | Job console starting 2026-06-01 09:05:00.255044 | Updating git repos 2026-06-01 09:05:00.321981 | Cloning repos into workspace 2026-06-01 09:05:00.548700 | Restoring repo states 2026-06-01 09:05:00.631368 | Merging changes 2026-06-01 09:05:01.692876 | Checking out repos 2026-06-01 09:05:01.748449 | Preparing playbooks 2026-06-01 09:05:02.639911 | Running Ansible setup 2026-06-01 09:05:10.005322 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-01 09:05:11.473773 | 2026-06-01 09:05:11.473902 | PLAY [Base pre] 2026-06-01 09:05:11.497716 | 2026-06-01 09:05:11.497847 | TASK [Setup log path fact] 2026-06-01 09:05:11.528680 | debian-bookworm | ok 2026-06-01 09:05:11.563391 | 2026-06-01 09:05:11.563520 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-01 09:05:11.617320 | debian-bookworm | ok 2026-06-01 09:05:11.626937 | 2026-06-01 09:05:11.627054 | TASK [emit-job-header : Print job information] 2026-06-01 09:05:11.698949 | # Job Information 2026-06-01 09:05:11.699090 | Ansible Version: 2.16.14 2026-06-01 09:05:11.699119 | Job: python-black 2026-06-01 09:05:11.699145 | Pipeline: check 2026-06-01 09:05:11.699164 | Executor: 521e9411259a 2026-06-01 09:05:11.699181 | Triggered by: https://github.com/osism/ansible-collection-services/pull/2089 2026-06-01 09:05:11.699202 | Event ID: d9aa1276-5d98-11f1-9b6d-8fcb69ac7433 2026-06-01 09:05:11.722675 | 2026-06-01 09:05:11.722786 | LOOP [emit-job-header : Print node information] 2026-06-01 09:05:11.981576 | debian-bookworm | ok: 2026-06-01 09:05:11.981730 | debian-bookworm | # Node Information 2026-06-01 09:05:11.981759 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-01 09:05:11.981780 | debian-bookworm | Hostname: debian 2026-06-01 09:05:11.981798 | debian-bookworm | Username: zuul 2026-06-01 09:05:11.981815 | debian-bookworm | Distro: Debian 12.14 2026-06-01 09:05:11.981859 | debian-bookworm | Provider: regiocloud-a 2026-06-01 09:05:11.981879 | debian-bookworm | Region: 2026-06-01 09:05:11.981896 | debian-bookworm | Label: debian-bookworm 2026-06-01 09:05:11.981913 | debian-bookworm | Product Name: OpenStack Nova 2026-06-01 09:05:11.981928 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe21:6cd6 2026-06-01 09:05:12.001045 | 2026-06-01 09:05:12.001150 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-01 09:05:13.208209 | debian-bookworm -> localhost | changed 2026-06-01 09:05:13.218816 | 2026-06-01 09:05:13.218971 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-01 09:05:14.839660 | debian-bookworm -> localhost | changed 2026-06-01 09:05:14.848756 | 2026-06-01 09:05:14.853540 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-01 09:05:15.253713 | debian-bookworm -> localhost | ok 2026-06-01 09:05:15.259133 | 2026-06-01 09:05:15.259220 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-01 09:05:15.297905 | debian-bookworm | ok 2026-06-01 09:05:15.312213 | debian-bookworm | included: /var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-01 09:05:15.317291 | 2026-06-01 09:05:15.317374 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-01 09:05:21.114015 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-01 09:05:21.114184 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/work/2b8d350048db4b9eb18e201e2e4833fe_id_rsa 2026-06-01 09:05:21.114215 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/work/2b8d350048db4b9eb18e201e2e4833fe_id_rsa.pub 2026-06-01 09:05:21.114237 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-01 09:05:21.114257 | debian-bookworm -> localhost | SHA256:ops9C/B8EOBXKNwfeIonwTtE0cHR5liWFHz1hLCd+G0 zuul-build-sshkey 2026-06-01 09:05:21.114275 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-01 09:05:21.114302 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-01 09:05:21.114319 | debian-bookworm -> localhost | | ++=oO+o.o... | 2026-06-01 09:05:21.114337 | debian-bookworm -> localhost | | .*.*.X .+.+ | 2026-06-01 09:05:21.114354 | debian-bookworm -> localhost | | ..=oX oo o . | 2026-06-01 09:05:21.114371 | debian-bookworm -> localhost | | =.+.o . . | 2026-06-01 09:05:21.114388 | debian-bookworm -> localhost | | .+. . S . E | 2026-06-01 09:05:21.114408 | debian-bookworm -> localhost | | + o . . | 2026-06-01 09:05:21.114426 | debian-bookworm -> localhost | | = . | 2026-06-01 09:05:21.114443 | debian-bookworm -> localhost | | *. | 2026-06-01 09:05:21.114461 | debian-bookworm -> localhost | | o oo | 2026-06-01 09:05:21.114477 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-01 09:05:21.114518 | debian-bookworm -> localhost | ok: Runtime: 0:00:05.148622 2026-06-01 09:05:21.120023 | 2026-06-01 09:05:21.120116 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-01 09:05:21.156768 | debian-bookworm | ok 2026-06-01 09:05:21.164336 | debian-bookworm | included: /var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-01 09:05:21.178124 | 2026-06-01 09:05:21.178224 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-01 09:05:21.211230 | debian-bookworm | skipping: Conditional result was False 2026-06-01 09:05:21.216444 | 2026-06-01 09:05:21.216536 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-01 09:05:21.922771 | debian-bookworm | changed 2026-06-01 09:05:21.927363 | 2026-06-01 09:05:21.927443 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-01 09:05:22.220413 | debian-bookworm | ok 2026-06-01 09:05:22.225095 | 2026-06-01 09:05:22.225187 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-01 09:05:22.993320 | debian-bookworm | changed 2026-06-01 09:05:23.005558 | 2026-06-01 09:05:23.005667 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-01 09:05:23.742427 | debian-bookworm | changed 2026-06-01 09:05:23.749630 | 2026-06-01 09:05:23.749725 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-01 09:05:23.787167 | debian-bookworm | skipping: Conditional result was False 2026-06-01 09:05:23.794222 | 2026-06-01 09:05:23.794387 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-01 09:05:24.315275 | debian-bookworm -> localhost | changed 2026-06-01 09:05:24.326222 | 2026-06-01 09:05:24.326336 | TASK [add-build-sshkey : Add back temp key] 2026-06-01 09:05:25.060900 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/work/2b8d350048db4b9eb18e201e2e4833fe_id_rsa (zuul-build-sshkey) 2026-06-01 09:05:25.061114 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.032100 2026-06-01 09:05:25.070463 | 2026-06-01 09:05:25.070569 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-01 09:05:25.548096 | debian-bookworm | ok 2026-06-01 09:05:25.553269 | 2026-06-01 09:05:25.553378 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-01 09:05:25.601697 | debian-bookworm | skipping: Conditional result was False 2026-06-01 09:05:25.613997 | 2026-06-01 09:05:25.614111 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-01 09:05:26.183595 | debian-bookworm | ok 2026-06-01 09:05:26.199348 | 2026-06-01 09:05:26.199455 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-01 09:05:26.231700 | debian-bookworm | ok 2026-06-01 09:05:26.237275 | 2026-06-01 09:05:26.237364 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-01 09:05:26.884445 | debian-bookworm -> localhost | ok 2026-06-01 09:05:26.900058 | 2026-06-01 09:05:26.900164 | TASK [validate-host : Collect information about the host] 2026-06-01 09:05:28.255087 | debian-bookworm | ok 2026-06-01 09:05:28.318510 | 2026-06-01 09:05:28.318666 | TASK [validate-host : Sanitize hostname] 2026-06-01 09:05:28.457538 | debian-bookworm | ok 2026-06-01 09:05:28.566879 | 2026-06-01 09:05:28.567027 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-01 09:05:29.237354 | debian-bookworm -> localhost | changed 2026-06-01 09:05:29.251787 | 2026-06-01 09:05:29.251959 | TASK [validate-host : Collect information about zuul worker] 2026-06-01 09:05:29.764059 | debian-bookworm | ok 2026-06-01 09:05:29.770220 | 2026-06-01 09:05:29.770325 | TASK [validate-host : Write out all zuul information for each host] 2026-06-01 09:05:30.734942 | debian-bookworm -> localhost | changed 2026-06-01 09:05:30.744473 | 2026-06-01 09:05:30.744571 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-01 09:05:31.012325 | debian-bookworm | ok 2026-06-01 09:05:31.023019 | 2026-06-01 09:05:31.023158 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-01 09:05:32.181418 | debian-bookworm | changed: 2026-06-01 09:05:32.181676 | debian-bookworm | cd+++++++++ src/ 2026-06-01 09:05:32.181708 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-01 09:05:32.181730 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-01 09:05:32.181749 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2026-06-01 09:05:32.181767 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/work/logs" 2026-06-01 09:05:57.615499 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/work/artifacts" 2026-06-01 09:05:57.917638 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2b8d350048db4b9eb18e201e2e4833fe/work/docs" 2026-06-01 09:05:57.933123 | 2026-06-01 09:05:57.933278 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-01 09:05:58.806478 | debian-bookworm | changed: 2026-06-01 09:05:58.806818 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-01 09:05:58.806930 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-01 09:05:58.806983 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-01 09:05:58.807063 | debian-bookworm | changed: All items complete 2026-06-01 09:05:58.807113 | 2026-06-01 09:05:59.440249 | debian-bookworm | changed: .d..t...... ./ 2026-06-01 09:06:00.022470 | debian-bookworm | changed: .d..t...... ./ 2026-06-01 09:06:00.037624 | 2026-06-01 09:06:00.037765 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-01 09:06:00.622444 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010354 2026-06-01 09:06:00.905395 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011761 2026-06-01 09:06:00.931512 | 2026-06-01 09:06:00.931654 | PLAY RECAP 2026-06-01 09:06:00.931732 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-01 09:06:00.931770 | 2026-06-01 09:06:01.095333 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-01 09:06:01.096736 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-01 09:06:01.914701 | 2026-06-01 09:06:01.914936 | PLAY [Base post] 2026-06-01 09:06:01.929221 | 2026-06-01 09:06:01.929380 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-01 09:06:02.522193 | debian-bookworm | changed 2026-06-01 09:06:02.535262 | 2026-06-01 09:06:02.535397 | PLAY RECAP 2026-06-01 09:06:02.535462 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-01 09:06:02.535526 | 2026-06-01 09:06:02.673698 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-01 09:06:02.675776 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-01 09:06:03.483844 | 2026-06-01 09:06:03.484041 | PLAY [Base post-logs] 2026-06-01 09:06:03.495001 | 2026-06-01 09:06:03.495152 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-01 09:06:04.004097 | localhost | changed 2026-06-01 09:06:04.020731 | 2026-06-01 09:06:04.021011 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-01 09:06:04.062822 | localhost | ok 2026-06-01 09:06:04.068531 | 2026-06-01 09:06:04.068716 | TASK [Set zuul-log-path fact] 2026-06-01 09:06:04.087519 | localhost | ok 2026-06-01 09:06:04.100738 | 2026-06-01 09:06:04.100972 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-01 09:06:04.140165 | localhost | ok 2026-06-01 09:06:04.146991 | 2026-06-01 09:06:04.147190 | TASK [upload-logs : Create log directories] 2026-06-01 09:06:04.720777 | localhost | changed 2026-06-01 09:06:04.725490 | 2026-06-01 09:06:04.725660 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-01 09:06:05.279184 | localhost -> localhost | ok: Runtime: 0:00:00.009313 2026-06-01 09:06:05.283401 | 2026-06-01 09:06:05.283519 | TASK [upload-logs : Upload logs to log server] 2026-06-01 09:06:05.848950 | localhost | Output suppressed because no_log was given 2026-06-01 09:06:05.850993 | 2026-06-01 09:06:05.851102 | LOOP [upload-logs : Compress console log and json output] 2026-06-01 09:06:05.899816 | localhost | skipping: Conditional result was False 2026-06-01 09:06:05.904937 | localhost | skipping: Conditional result was False 2026-06-01 09:06:05.912031 | 2026-06-01 09:06:05.912210 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-01 09:06:05.960720 | localhost | skipping: Conditional result was False 2026-06-01 09:06:05.961101 | 2026-06-01 09:06:05.964731 | localhost | skipping: Conditional result was False 2026-06-01 09:06:05.975717 | 2026-06-01 09:06:05.975982 | LOOP [upload-logs : Upload console log and json output]