2026-05-28 06:31:15.349623 | Job console starting 2026-05-28 06:31:15.366643 | Updating git repos 2026-05-28 06:31:15.419145 | Cloning repos into workspace 2026-05-28 06:31:15.469665 | Restoring repo states 2026-05-28 06:31:15.489673 | Merging changes 2026-05-28 06:31:16.281485 | Checking out repos 2026-05-28 06:31:16.355160 | Preparing playbooks 2026-05-28 06:31:18.189196 | Running Ansible setup 2026-05-28 06:31:24.860958 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 06:31:26.061449 | 2026-05-28 06:31:26.061581 | PLAY [Base pre] 2026-05-28 06:31:26.120324 | 2026-05-28 06:31:26.120466 | TASK [Setup log path fact] 2026-05-28 06:31:26.180391 | debian-bookworm | ok 2026-05-28 06:31:26.270912 | 2026-05-28 06:31:26.271113 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 06:31:26.348646 | debian-bookworm | ok 2026-05-28 06:31:26.401546 | 2026-05-28 06:31:26.401663 | TASK [emit-job-header : Print job information] 2026-05-28 06:31:26.521338 | # Job Information 2026-05-28 06:31:26.521514 | Ansible Version: 2.16.14 2026-05-28 06:31:26.521628 | Job: python-black 2026-05-28 06:31:26.521671 | Pipeline: check 2026-05-28 06:31:26.521706 | Executor: 521e9411259a 2026-05-28 06:31:26.521780 | Triggered by: https://github.com/osism/python-osism/pull/2298 2026-05-28 06:31:26.521805 | Event ID: cd4f8d2c-5a5e-11f1-86c0-12f09a045849 2026-05-28 06:31:26.534120 | 2026-05-28 06:31:26.534467 | LOOP [emit-job-header : Print node information] 2026-05-28 06:31:26.927422 | debian-bookworm | ok: 2026-05-28 06:31:26.927821 | debian-bookworm | # Node Information 2026-05-28 06:31:26.927875 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-28 06:31:26.927902 | debian-bookworm | Hostname: debian 2026-05-28 06:31:26.927925 | debian-bookworm | Username: zuul 2026-05-28 06:31:26.927946 | debian-bookworm | Distro: Debian 12.14 2026-05-28 06:31:26.927969 | debian-bookworm | Provider: regiocloud-a 2026-05-28 06:31:26.927991 | debian-bookworm | Region: 2026-05-28 06:31:26.928012 | debian-bookworm | Label: debian-bookworm 2026-05-28 06:31:26.928032 | debian-bookworm | Product Name: OpenStack Nova 2026-05-28 06:31:26.928052 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe22:ff02 2026-05-28 06:31:26.947132 | 2026-05-28 06:31:26.947259 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-28 06:31:27.739375 | debian-bookworm -> localhost | changed 2026-05-28 06:31:27.746086 | 2026-05-28 06:31:27.746188 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-28 06:31:29.618106 | debian-bookworm -> localhost | changed 2026-05-28 06:31:29.627041 | 2026-05-28 06:31:29.627140 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-28 06:31:30.247287 | debian-bookworm -> localhost | ok 2026-05-28 06:31:30.252211 | 2026-05-28 06:31:30.252317 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-28 06:31:30.295938 | debian-bookworm | ok 2026-05-28 06:31:30.323919 | debian-bookworm | included: /var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-28 06:31:30.335273 | 2026-05-28 06:31:30.335372 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-28 06:31:32.617508 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-28 06:31:32.617682 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/work/a9a93d1193e441d5a4eccde9de992e35_id_rsa 2026-05-28 06:31:32.617712 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/work/a9a93d1193e441d5a4eccde9de992e35_id_rsa.pub 2026-05-28 06:31:32.617734 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-28 06:31:32.617754 | debian-bookworm -> localhost | SHA256:RVbmq+rw7fHoHOOpI4/ZjGyb1dMmh/TTkLqorZK1afE zuul-build-sshkey 2026-05-28 06:31:32.617773 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-28 06:31:32.617800 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-28 06:31:32.617818 | debian-bookworm -> localhost | | o.o | 2026-05-28 06:31:32.617836 | debian-bookworm -> localhost | | o o | 2026-05-28 06:31:32.617853 | debian-bookworm -> localhost | | . . | 2026-05-28 06:31:32.617869 | debian-bookworm -> localhost | | . o | 2026-05-28 06:31:32.617885 | debian-bookworm -> localhost | | S . + | 2026-05-28 06:31:32.617909 | debian-bookworm -> localhost | | o o * o | 2026-05-28 06:31:32.617926 | debian-bookworm -> localhost | | o.=. @ * . | 2026-05-28 06:31:32.617943 | debian-bookworm -> localhost | | o.*%E* & . | 2026-05-28 06:31:32.617960 | debian-bookworm -> localhost | | +XB@=X . | 2026-05-28 06:31:32.617977 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-28 06:31:32.618021 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.565584 2026-05-28 06:31:32.624294 | 2026-05-28 06:31:32.624382 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-28 06:31:32.652053 | debian-bookworm | ok 2026-05-28 06:31:32.671550 | debian-bookworm | included: /var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-28 06:31:32.711083 | 2026-05-28 06:31:32.711199 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-28 06:31:32.745450 | debian-bookworm | skipping: Conditional result was False 2026-05-28 06:31:32.750213 | 2026-05-28 06:31:32.750312 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-28 06:31:33.396153 | debian-bookworm | changed 2026-05-28 06:31:33.404121 | 2026-05-28 06:31:33.404215 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-28 06:31:33.688486 | debian-bookworm | ok 2026-05-28 06:31:33.697224 | 2026-05-28 06:31:33.697351 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-28 06:31:34.384743 | debian-bookworm | changed 2026-05-28 06:31:34.397524 | 2026-05-28 06:31:34.397663 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-28 06:31:35.011187 | debian-bookworm | changed 2026-05-28 06:31:35.016535 | 2026-05-28 06:31:35.016628 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-28 06:31:35.050314 | debian-bookworm | skipping: Conditional result was False 2026-05-28 06:31:35.057604 | 2026-05-28 06:31:35.057741 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-28 06:31:35.747971 | debian-bookworm -> localhost | changed 2026-05-28 06:31:35.771315 | 2026-05-28 06:31:35.771446 | TASK [add-build-sshkey : Add back temp key] 2026-05-28 06:31:36.119180 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/work/a9a93d1193e441d5a4eccde9de992e35_id_rsa (zuul-build-sshkey) 2026-05-28 06:31:36.119444 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012507 2026-05-28 06:31:36.131350 | 2026-05-28 06:31:36.131466 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-28 06:31:36.575030 | debian-bookworm | ok 2026-05-28 06:31:36.580691 | 2026-05-28 06:31:36.580796 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-28 06:31:36.625250 | debian-bookworm | skipping: Conditional result was False 2026-05-28 06:31:36.640804 | 2026-05-28 06:31:36.641012 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-28 06:31:37.301161 | debian-bookworm | ok 2026-05-28 06:31:37.317480 | 2026-05-28 06:31:37.317601 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-28 06:31:37.367324 | debian-bookworm | ok 2026-05-28 06:31:37.372452 | 2026-05-28 06:31:37.372552 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-28 06:31:37.885233 | debian-bookworm -> localhost | ok 2026-05-28 06:31:37.891930 | 2026-05-28 06:31:37.892034 | TASK [validate-host : Collect information about the host] 2026-05-28 06:31:39.084239 | debian-bookworm | ok 2026-05-28 06:31:39.100498 | 2026-05-28 06:31:39.100611 | TASK [validate-host : Sanitize hostname] 2026-05-28 06:31:39.230946 | debian-bookworm | ok 2026-05-28 06:31:39.267699 | 2026-05-28 06:31:39.267809 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-28 06:31:40.031211 | debian-bookworm -> localhost | changed 2026-05-28 06:31:40.037678 | 2026-05-28 06:31:40.037784 | TASK [validate-host : Collect information about zuul worker] 2026-05-28 06:31:40.573343 | debian-bookworm | ok 2026-05-28 06:31:40.577978 | 2026-05-28 06:31:40.578072 | TASK [validate-host : Write out all zuul information for each host] 2026-05-28 06:31:41.302576 | debian-bookworm -> localhost | changed 2026-05-28 06:31:41.311671 | 2026-05-28 06:31:41.311775 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-28 06:31:41.608332 | debian-bookworm | ok 2026-05-28 06:31:41.614063 | 2026-05-28 06:31:41.614164 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-28 06:31:42.744501 | debian-bookworm | changed: 2026-05-28 06:31:42.744697 | debian-bookworm | cd+++++++++ src/ 2026-05-28 06:31:42.744733 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-28 06:31:42.744756 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-28 06:31:42.744778 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-28 06:31:42.744798 | debian-bookworm | Containerfile 2026-05-28 06:31:42.744929 | debian-bookworm | Containerfile 2026-05-28 06:31:42.758876 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/work/logs" 2026-05-28 06:32:11.783105 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/work/artifacts" 2026-05-28 06:32:12.070570 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a9a93d1193e441d5a4eccde9de992e35/work/docs" 2026-05-28 06:32:12.099565 | 2026-05-28 06:32:12.099835 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-28 06:32:13.015275 | debian-bookworm | changed: 2026-05-28 06:32:13.015523 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-28 06:32:13.015558 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-28 06:32:13.015586 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-28 06:32:13.015630 | debian-bookworm | changed: All items complete 2026-05-28 06:32:13.015668 | 2026-05-28 06:32:13.610179 | debian-bookworm | changed: .d..t...... ./ 2026-05-28 06:32:14.219578 | debian-bookworm | changed: .d..t...... ./ 2026-05-28 06:32:14.246585 | 2026-05-28 06:32:14.246740 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-28 06:32:14.861410 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009993 2026-05-28 06:32:15.162427 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.012219 2026-05-28 06:32:15.185577 | 2026-05-28 06:32:15.185735 | PLAY RECAP 2026-05-28 06:32:15.185819 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-28 06:32:15.185856 | 2026-05-28 06:32:15.321556 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-28 06:32:15.323790 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 06:32:16.083345 | 2026-05-28 06:32:16.083526 | PLAY [Base post] 2026-05-28 06:32:16.098417 | 2026-05-28 06:32:16.098576 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-28 06:32:16.720029 | debian-bookworm | changed 2026-05-28 06:32:16.729951 | 2026-05-28 06:32:16.730117 | PLAY RECAP 2026-05-28 06:32:16.730218 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 06:32:16.730342 | 2026-05-28 06:32:16.924432 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 06:32:16.925372 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-28 06:32:17.819003 | 2026-05-28 06:32:17.819268 | PLAY [Base post-logs] 2026-05-28 06:32:17.831659 | 2026-05-28 06:32:17.831880 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-28 06:32:18.296457 | localhost | changed 2026-05-28 06:32:18.313907 | 2026-05-28 06:32:18.314105 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-28 06:32:18.341232 | localhost | ok 2026-05-28 06:32:18.344926 | 2026-05-28 06:32:18.345043 | TASK [Set zuul-log-path fact] 2026-05-28 06:32:18.361156 | localhost | ok 2026-05-28 06:32:18.369919 | 2026-05-28 06:32:18.370053 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 06:32:18.396356 | localhost | ok 2026-05-28 06:32:18.400507 | 2026-05-28 06:32:18.400686 | TASK [upload-logs : Create log directories] 2026-05-28 06:32:18.948884 | localhost | changed 2026-05-28 06:32:18.951830 | 2026-05-28 06:32:18.951939 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-28 06:32:19.478307 | localhost -> localhost | ok: Runtime: 0:00:00.004984 2026-05-28 06:32:19.483219 | 2026-05-28 06:32:19.483337 | TASK [upload-logs : Upload logs to log server] 2026-05-28 06:32:20.100295 | localhost | Output suppressed because no_log was given 2026-05-28 06:32:20.102207 | 2026-05-28 06:32:20.102317 | LOOP [upload-logs : Compress console log and json output] 2026-05-28 06:32:20.153213 | localhost | skipping: Conditional result was False 2026-05-28 06:32:20.159446 | localhost | skipping: Conditional result was False 2026-05-28 06:32:20.170451 | 2026-05-28 06:32:20.170605 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-28 06:32:20.233450 | localhost | skipping: Conditional result was False 2026-05-28 06:32:20.233853 | 2026-05-28 06:32:20.246411 | localhost | skipping: Conditional result was False 2026-05-28 06:32:20.251492 | 2026-05-28 06:32:20.251633 | LOOP [upload-logs : Upload console log and json output]