2026-05-19 14:49:27.681283 | Job console starting 2026-05-19 14:49:27.699949 | Updating git repos 2026-05-19 14:49:27.786292 | Cloning repos into workspace 2026-05-19 14:49:27.864042 | Restoring repo states 2026-05-19 14:49:27.951168 | Merging changes 2026-05-19 14:49:28.666979 | Checking out repos 2026-05-19 14:49:28.888358 | Preparing playbooks 2026-05-19 14:49:30.086122 | Running Ansible setup 2026-05-19 14:49:34.944833 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-19 14:49:35.781401 | 2026-05-19 14:49:35.781565 | PLAY [Base pre] 2026-05-19 14:49:35.799307 | 2026-05-19 14:49:35.799432 | TASK [Setup log path fact] 2026-05-19 14:49:35.817172 | debian-bookworm | ok 2026-05-19 14:49:35.833846 | 2026-05-19 14:49:35.833975 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-19 14:49:35.861553 | debian-bookworm | ok 2026-05-19 14:49:35.871323 | 2026-05-19 14:49:35.871426 | TASK [emit-job-header : Print job information] 2026-05-19 14:49:35.913287 | # Job Information 2026-05-19 14:49:35.913439 | Ansible Version: 2.16.14 2026-05-19 14:49:35.913474 | Job: python-black 2026-05-19 14:49:35.913529 | Pipeline: check 2026-05-19 14:49:35.913554 | Executor: 521e9411259a 2026-05-19 14:49:35.913575 | Triggered by: https://github.com/osism/python-osism/pull/2290 2026-05-19 14:49:35.913598 | Event ID: ca836c90-5391-11f1-8149-a045c914ef75 2026-05-19 14:49:35.918260 | 2026-05-19 14:49:35.918347 | LOOP [emit-job-header : Print node information] 2026-05-19 14:49:36.024219 | debian-bookworm | ok: 2026-05-19 14:49:36.024388 | debian-bookworm | # Node Information 2026-05-19 14:49:36.024422 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-19 14:49:36.024449 | debian-bookworm | Hostname: debian 2026-05-19 14:49:36.024471 | debian-bookworm | Username: zuul 2026-05-19 14:49:36.024492 | debian-bookworm | Distro: Debian 12.14 2026-05-19 14:49:36.024528 | debian-bookworm | Provider: regiocloud-a 2026-05-19 14:49:36.024549 | debian-bookworm | Region: 2026-05-19 14:49:36.024570 | debian-bookworm | Label: debian-bookworm 2026-05-19 14:49:36.024590 | debian-bookworm | Product Name: OpenStack Nova 2026-05-19 14:49:36.024610 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe35:8a26 2026-05-19 14:49:36.037011 | 2026-05-19 14:49:36.037130 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-19 14:49:36.680698 | debian-bookworm -> localhost | changed 2026-05-19 14:49:36.688993 | 2026-05-19 14:49:36.689108 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-19 14:49:38.635854 | debian-bookworm -> localhost | changed 2026-05-19 14:49:38.649217 | 2026-05-19 14:49:38.649368 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-19 14:49:39.252838 | debian-bookworm -> localhost | ok 2026-05-19 14:49:39.258929 | 2026-05-19 14:49:39.259037 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-19 14:49:39.299125 | debian-bookworm | ok 2026-05-19 14:49:39.327044 | debian-bookworm | included: /var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-19 14:49:39.340470 | 2026-05-19 14:49:39.340584 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-19 14:49:41.306129 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-19 14:49:41.306290 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/work/14d1a1ac66704c13816117690b76c58a_id_rsa 2026-05-19 14:49:41.306321 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/work/14d1a1ac66704c13816117690b76c58a_id_rsa.pub 2026-05-19 14:49:41.306344 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-19 14:49:41.306364 | debian-bookworm -> localhost | SHA256:SyDefk9V8bCHHR8a1Y9eE2jAPqKkJNSH4m6JGG6w0BE zuul-build-sshkey 2026-05-19 14:49:41.306383 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-19 14:49:41.306410 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-19 14:49:41.306429 | debian-bookworm -> localhost | | E.. . ....=+.| 2026-05-19 14:49:41.306447 | debian-bookworm -> localhost | | .o o . . ooO=| 2026-05-19 14:49:41.306464 | debian-bookworm -> localhost | | .o.o o . ..+.B| 2026-05-19 14:49:41.306480 | debian-bookworm -> localhost | |+ .+ + o . o ..oo| 2026-05-19 14:49:41.306497 | debian-bookworm -> localhost | |=oo = + S . o. ..| 2026-05-19 14:49:41.306543 | debian-bookworm -> localhost | |o+ + o o . . . | 2026-05-19 14:49:41.306564 | debian-bookworm -> localhost | |. . . o . | 2026-05-19 14:49:41.306581 | debian-bookworm -> localhost | | . o | 2026-05-19 14:49:41.306599 | debian-bookworm -> localhost | | . | 2026-05-19 14:49:41.306616 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-19 14:49:41.306655 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.171906 2026-05-19 14:49:41.312672 | 2026-05-19 14:49:41.312755 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-19 14:49:41.349235 | debian-bookworm | ok 2026-05-19 14:49:41.356702 | debian-bookworm | included: /var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-19 14:49:41.374270 | 2026-05-19 14:49:41.374361 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-19 14:49:41.399429 | debian-bookworm | skipping: Conditional result was False 2026-05-19 14:49:41.404231 | 2026-05-19 14:49:41.404315 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-19 14:49:42.098602 | debian-bookworm | changed 2026-05-19 14:49:42.102930 | 2026-05-19 14:49:42.102998 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-19 14:49:42.391571 | debian-bookworm | ok 2026-05-19 14:49:42.396038 | 2026-05-19 14:49:42.396118 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-19 14:49:43.103810 | debian-bookworm | changed 2026-05-19 14:49:43.114075 | 2026-05-19 14:49:43.114188 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-19 14:49:43.790608 | debian-bookworm | changed 2026-05-19 14:49:43.798560 | 2026-05-19 14:49:43.798664 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-19 14:49:43.812953 | debian-bookworm | skipping: Conditional result was False 2026-05-19 14:49:43.818574 | 2026-05-19 14:49:43.818663 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-19 14:49:44.250922 | debian-bookworm -> localhost | changed 2026-05-19 14:49:44.264090 | 2026-05-19 14:49:44.264197 | TASK [add-build-sshkey : Add back temp key] 2026-05-19 14:49:44.573612 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/work/14d1a1ac66704c13816117690b76c58a_id_rsa (zuul-build-sshkey) 2026-05-19 14:49:44.573955 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008996 2026-05-19 14:49:44.591352 | 2026-05-19 14:49:44.591511 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-19 14:49:44.992884 | debian-bookworm | ok 2026-05-19 14:49:45.001012 | 2026-05-19 14:49:45.001127 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-19 14:49:45.024935 | debian-bookworm | skipping: Conditional result was False 2026-05-19 14:49:45.063973 | 2026-05-19 14:49:45.064078 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-19 14:49:45.558484 | debian-bookworm | ok 2026-05-19 14:49:45.571689 | 2026-05-19 14:49:45.571789 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-19 14:49:45.621550 | debian-bookworm | ok 2026-05-19 14:49:45.627440 | 2026-05-19 14:49:45.627565 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-19 14:49:45.903804 | debian-bookworm -> localhost | ok 2026-05-19 14:49:45.915923 | 2026-05-19 14:49:45.916059 | TASK [validate-host : Collect information about the host] 2026-05-19 14:49:47.039139 | debian-bookworm | ok 2026-05-19 14:49:47.069901 | 2026-05-19 14:49:47.070175 | TASK [validate-host : Sanitize hostname] 2026-05-19 14:49:47.160866 | debian-bookworm | ok 2026-05-19 14:49:47.191410 | 2026-05-19 14:49:47.191510 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-19 14:49:47.663151 | debian-bookworm -> localhost | changed 2026-05-19 14:49:47.668710 | 2026-05-19 14:49:47.668797 | TASK [validate-host : Collect information about zuul worker] 2026-05-19 14:49:48.101144 | debian-bookworm | ok 2026-05-19 14:49:48.105884 | 2026-05-19 14:49:48.105983 | TASK [validate-host : Write out all zuul information for each host] 2026-05-19 14:49:48.715898 | debian-bookworm -> localhost | changed 2026-05-19 14:49:48.724953 | 2026-05-19 14:49:48.725049 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-19 14:49:49.024657 | debian-bookworm | ok 2026-05-19 14:49:49.029958 | 2026-05-19 14:49:49.030043 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-19 14:49:50.092714 | debian-bookworm | changed: 2026-05-19 14:49:50.092987 | debian-bookworm | cd+++++++++ src/ 2026-05-19 14:49:50.093045 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-19 14:49:50.093085 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-19 14:49:50.093119 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-19 14:49:50.093152 | debian-bookworm | Containerfile 2026-05-19 14:49:50.093441 | debian-bookworm | Containerfile 2026-05-19 14:49:50.113071 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/work/logs" 2026-05-19 14:50:22.524445 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/work/artifacts" 2026-05-19 14:50:22.838902 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/14d1a1ac66704c13816117690b76c58a/work/docs" 2026-05-19 14:50:22.853208 | 2026-05-19 14:50:22.853411 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-19 14:50:23.776756 | debian-bookworm | changed: 2026-05-19 14:50:23.777098 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-19 14:50:23.777159 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-19 14:50:23.777209 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-19 14:50:23.777274 | debian-bookworm | changed: All items complete 2026-05-19 14:50:23.777311 | 2026-05-19 14:50:24.371551 | debian-bookworm | changed: .d..t...... ./ 2026-05-19 14:50:24.962301 | debian-bookworm | changed: .d..t...... ./ 2026-05-19 14:50:24.975849 | 2026-05-19 14:50:24.975988 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-19 14:50:25.666137 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.032443 2026-05-19 14:50:26.094435 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006595 2026-05-19 14:50:26.109738 | 2026-05-19 14:50:26.109860 | PLAY RECAP 2026-05-19 14:50:26.109915 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-19 14:50:26.109941 | 2026-05-19 14:50:26.287780 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-19 14:50:26.289001 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-19 14:50:27.403426 | 2026-05-19 14:50:27.403602 | PLAY [Base post] 2026-05-19 14:50:27.443820 | 2026-05-19 14:50:27.443998 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-19 14:50:28.242053 | debian-bookworm | changed 2026-05-19 14:50:28.250989 | 2026-05-19 14:50:28.251137 | PLAY RECAP 2026-05-19 14:50:28.251205 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-19 14:50:28.251269 | 2026-05-19 14:50:28.462653 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-19 14:50:28.463994 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-19 14:50:29.580920 | 2026-05-19 14:50:29.581091 | PLAY [Base post-logs] 2026-05-19 14:50:29.592248 | 2026-05-19 14:50:29.592401 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-19 14:50:30.210261 | localhost | changed 2026-05-19 14:50:30.223311 | 2026-05-19 14:50:30.223491 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-19 14:50:30.250329 | localhost | ok 2026-05-19 14:50:30.254715 | 2026-05-19 14:50:30.254857 | TASK [Set zuul-log-path fact] 2026-05-19 14:50:30.270800 | localhost | ok 2026-05-19 14:50:30.281384 | 2026-05-19 14:50:30.281514 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-19 14:50:30.308754 | localhost | ok 2026-05-19 14:50:30.313782 | 2026-05-19 14:50:30.313986 | TASK [upload-logs : Create log directories] 2026-05-19 14:50:30.928698 | localhost | changed 2026-05-19 14:50:30.931544 | 2026-05-19 14:50:30.931655 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-19 14:50:31.571777 | localhost -> localhost | ok: Runtime: 0:00:00.014617 2026-05-19 14:50:31.576086 | 2026-05-19 14:50:31.576211 | TASK [upload-logs : Upload logs to log server] 2026-05-19 14:50:32.183984 | localhost | Output suppressed because no_log was given 2026-05-19 14:50:32.188208 | 2026-05-19 14:50:32.188338 | LOOP [upload-logs : Compress console log and json output] 2026-05-19 14:50:32.242533 | localhost | skipping: Conditional result was False 2026-05-19 14:50:32.249456 | localhost | skipping: Conditional result was False 2026-05-19 14:50:32.259327 | 2026-05-19 14:50:32.259493 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-19 14:50:32.323176 | localhost | skipping: Conditional result was False 2026-05-19 14:50:32.323544 | 2026-05-19 14:50:32.331163 | localhost | skipping: Conditional result was False 2026-05-19 14:50:32.337662 | 2026-05-19 14:50:32.337872 | LOOP [upload-logs : Upload console log and json output]