2026-05-26 21:08:44.396130 | Job console starting 2026-05-26 21:08:44.415284 | Updating git repos 2026-05-26 21:08:44.522061 | Cloning repos into workspace 2026-05-26 21:08:44.619655 | Restoring repo states 2026-05-26 21:08:44.677917 | Merging changes 2026-05-26 21:08:45.654576 | Checking out repos 2026-05-26 21:08:45.732263 | Preparing playbooks 2026-05-26 21:08:47.291098 | Running Ansible setup 2026-05-26 21:08:52.589057 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 21:08:53.510786 | 2026-05-26 21:08:53.511092 | PLAY [Base pre] 2026-05-26 21:08:53.529894 | 2026-05-26 21:08:53.530133 | TASK [Setup log path fact] 2026-05-26 21:08:53.588888 | debian-bookworm | ok 2026-05-26 21:08:53.610801 | 2026-05-26 21:08:53.610959 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 21:08:53.639084 | debian-bookworm | ok 2026-05-26 21:08:53.654737 | 2026-05-26 21:08:53.654864 | TASK [emit-job-header : Print job information] 2026-05-26 21:08:53.692976 | # Job Information 2026-05-26 21:08:53.693133 | Ansible Version: 2.16.14 2026-05-26 21:08:53.693167 | Job: python-black 2026-05-26 21:08:53.693200 | Pipeline: check 2026-05-26 21:08:53.693224 | Executor: 521e9411259a 2026-05-26 21:08:53.693245 | Triggered by: https://github.com/osism/python-osism/pull/2304 2026-05-26 21:08:53.693269 | Event ID: f5c3d1f0-5946-11f1-997e-d06cf5a119c0 2026-05-26 21:08:53.697985 | 2026-05-26 21:08:53.698079 | LOOP [emit-job-header : Print node information] 2026-05-26 21:08:53.795340 | debian-bookworm | ok: 2026-05-26 21:08:53.795521 | debian-bookworm | # Node Information 2026-05-26 21:08:53.795556 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-26 21:08:53.795582 | debian-bookworm | Hostname: debian 2026-05-26 21:08:53.795605 | debian-bookworm | Username: zuul 2026-05-26 21:08:53.795626 | debian-bookworm | Distro: Debian 12.14 2026-05-26 21:08:53.795649 | debian-bookworm | Provider: regiocloud-a 2026-05-26 21:08:53.795670 | debian-bookworm | Region: 2026-05-26 21:08:53.795691 | debian-bookworm | Label: debian-bookworm 2026-05-26 21:08:53.795711 | debian-bookworm | Product Name: OpenStack Nova 2026-05-26 21:08:53.795730 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec6:cfd8 2026-05-26 21:08:53.812568 | 2026-05-26 21:08:53.812677 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-26 21:08:54.797136 | debian-bookworm -> localhost | changed 2026-05-26 21:08:54.802808 | 2026-05-26 21:08:54.802918 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-26 21:08:56.772386 | debian-bookworm -> localhost | changed 2026-05-26 21:08:56.785660 | 2026-05-26 21:08:56.785767 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-26 21:08:57.526265 | debian-bookworm -> localhost | ok 2026-05-26 21:08:57.534280 | 2026-05-26 21:08:57.534390 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-26 21:08:57.606489 | debian-bookworm | ok 2026-05-26 21:08:57.682818 | debian-bookworm | included: /var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-26 21:08:57.711720 | 2026-05-26 21:08:57.712267 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-26 21:08:59.972419 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-26 21:08:59.972588 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/work/3a745b016fe147c184d787a9a82111bb_id_rsa 2026-05-26 21:08:59.972617 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/work/3a745b016fe147c184d787a9a82111bb_id_rsa.pub 2026-05-26 21:08:59.972640 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-26 21:08:59.972660 | debian-bookworm -> localhost | SHA256:2v4pq0P0NZdiIRpidyEp1uT38sux8/1pcNQXuzlQvl0 zuul-build-sshkey 2026-05-26 21:08:59.972678 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-26 21:08:59.972704 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-26 21:08:59.972722 | debian-bookworm -> localhost | | oo... | 2026-05-26 21:08:59.972739 | debian-bookworm -> localhost | | =.=.o . o | 2026-05-26 21:08:59.972756 | debian-bookworm -> localhost | | o +.+.. . .o +| 2026-05-26 21:08:59.972772 | debian-bookworm -> localhost | | o. .= o. +E| 2026-05-26 21:08:59.972788 | debian-bookworm -> localhost | | . .So.+ o B| 2026-05-26 21:08:59.972810 | debian-bookworm -> localhost | | .o.o . *.| 2026-05-26 21:08:59.972827 | debian-bookworm -> localhost | | .. . o o .| 2026-05-26 21:08:59.972843 | debian-bookworm -> localhost | | .....= . ..| 2026-05-26 21:08:59.972860 | debian-bookworm -> localhost | | .oo+*o. oo.| 2026-05-26 21:08:59.972876 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-26 21:08:59.972921 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.096784 2026-05-26 21:08:59.979965 | 2026-05-26 21:08:59.980054 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-26 21:09:00.016433 | debian-bookworm | ok 2026-05-26 21:09:00.024107 | debian-bookworm | included: /var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-26 21:09:00.036144 | 2026-05-26 21:09:00.036232 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-26 21:09:00.048861 | debian-bookworm | skipping: Conditional result was False 2026-05-26 21:09:00.053919 | 2026-05-26 21:09:00.054004 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-26 21:09:00.686018 | debian-bookworm | changed 2026-05-26 21:09:00.691318 | 2026-05-26 21:09:00.691424 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-26 21:09:00.935545 | debian-bookworm | ok 2026-05-26 21:09:00.949868 | 2026-05-26 21:09:00.950578 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-26 21:09:01.797967 | debian-bookworm | changed 2026-05-26 21:09:01.812773 | 2026-05-26 21:09:01.812887 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-26 21:09:02.565565 | debian-bookworm | changed 2026-05-26 21:09:02.574433 | 2026-05-26 21:09:02.574540 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-26 21:09:02.607804 | debian-bookworm | skipping: Conditional result was False 2026-05-26 21:09:02.613504 | 2026-05-26 21:09:02.613599 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-26 21:09:03.153649 | debian-bookworm -> localhost | changed 2026-05-26 21:09:03.165896 | 2026-05-26 21:09:03.166009 | TASK [add-build-sshkey : Add back temp key] 2026-05-26 21:09:03.648256 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/work/3a745b016fe147c184d787a9a82111bb_id_rsa (zuul-build-sshkey) 2026-05-26 21:09:03.648467 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.026960 2026-05-26 21:09:03.655161 | 2026-05-26 21:09:03.655248 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-26 21:09:04.066813 | debian-bookworm | ok 2026-05-26 21:09:04.071050 | 2026-05-26 21:09:04.071131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-26 21:09:04.104471 | debian-bookworm | skipping: Conditional result was False 2026-05-26 21:09:04.113006 | 2026-05-26 21:09:04.113099 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-26 21:09:04.560128 | debian-bookworm | ok 2026-05-26 21:09:04.570473 | 2026-05-26 21:09:04.570576 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-26 21:09:04.629623 | debian-bookworm | ok 2026-05-26 21:09:04.641874 | 2026-05-26 21:09:04.641993 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-26 21:09:05.385526 | debian-bookworm -> localhost | ok 2026-05-26 21:09:05.392063 | 2026-05-26 21:09:05.392166 | TASK [validate-host : Collect information about the host] 2026-05-26 21:09:06.657979 | debian-bookworm | ok 2026-05-26 21:09:06.683477 | 2026-05-26 21:09:06.683592 | TASK [validate-host : Sanitize hostname] 2026-05-26 21:09:06.872168 | debian-bookworm | ok 2026-05-26 21:09:06.924181 | 2026-05-26 21:09:06.924298 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-26 21:09:07.652900 | debian-bookworm -> localhost | changed 2026-05-26 21:09:07.658625 | 2026-05-26 21:09:07.658723 | TASK [validate-host : Collect information about zuul worker] 2026-05-26 21:09:08.143318 | debian-bookworm | ok 2026-05-26 21:09:08.153569 | 2026-05-26 21:09:08.153721 | TASK [validate-host : Write out all zuul information for each host] 2026-05-26 21:09:08.928620 | debian-bookworm -> localhost | changed 2026-05-26 21:09:08.939977 | 2026-05-26 21:09:08.940114 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-26 21:09:09.221037 | debian-bookworm | ok 2026-05-26 21:09:09.232188 | 2026-05-26 21:09:09.232301 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-26 21:09:10.305114 | debian-bookworm | changed: 2026-05-26 21:09:10.305288 | debian-bookworm | cd+++++++++ src/ 2026-05-26 21:09:10.305322 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-26 21:09:10.305347 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-26 21:09:10.305369 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-26 21:09:10.305390 | debian-bookworm | Containerfile 2026-05-26 21:09:10.305643 | debian-bookworm | Containerfile 2026-05-26 21:09:10.311803 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/work/logs" 2026-05-26 21:09:41.494168 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/work/artifacts" 2026-05-26 21:09:41.775354 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3a745b016fe147c184d787a9a82111bb/work/docs" 2026-05-26 21:09:41.796427 | 2026-05-26 21:09:41.796581 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-26 21:09:42.617813 | debian-bookworm | changed: 2026-05-26 21:09:42.618052 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-26 21:09:42.618088 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-26 21:09:42.618119 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-26 21:09:42.618166 | debian-bookworm | changed: All items complete 2026-05-26 21:09:42.618192 | 2026-05-26 21:09:43.166001 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 21:09:43.779400 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 21:09:43.802096 | 2026-05-26 21:09:43.802232 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-26 21:09:44.331720 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006769 2026-05-26 21:09:44.605133 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.016997 2026-05-26 21:09:44.617334 | 2026-05-26 21:09:44.617470 | PLAY RECAP 2026-05-26 21:09:44.617524 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-26 21:09:44.617549 | 2026-05-26 21:09:44.756560 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-26 21:09:44.757453 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 21:09:45.627051 | 2026-05-26 21:09:45.627228 | PLAY [Base post] 2026-05-26 21:09:45.640624 | 2026-05-26 21:09:45.640763 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-26 21:09:46.212367 | debian-bookworm | changed 2026-05-26 21:09:46.223460 | 2026-05-26 21:09:46.223635 | PLAY RECAP 2026-05-26 21:09:46.223735 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-26 21:09:46.223830 | 2026-05-26 21:09:46.372365 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 21:09:46.374365 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-26 21:09:47.269860 | 2026-05-26 21:09:47.270032 | PLAY [Base post-logs] 2026-05-26 21:09:47.285118 | 2026-05-26 21:09:47.285274 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-26 21:09:47.806332 | localhost | changed 2026-05-26 21:09:47.818531 | 2026-05-26 21:09:47.820092 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-26 21:09:47.847201 | localhost | ok 2026-05-26 21:09:47.851620 | 2026-05-26 21:09:47.851755 | TASK [Set zuul-log-path fact] 2026-05-26 21:09:47.868772 | localhost | ok 2026-05-26 21:09:47.877093 | 2026-05-26 21:09:47.877224 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 21:09:47.923237 | localhost | ok 2026-05-26 21:09:47.927055 | 2026-05-26 21:09:47.927190 | TASK [upload-logs : Create log directories] 2026-05-26 21:09:48.490072 | localhost | changed 2026-05-26 21:09:48.493201 | 2026-05-26 21:09:48.493315 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-26 21:09:49.007646 | localhost -> localhost | ok: Runtime: 0:00:00.004257 2026-05-26 21:09:49.012279 | 2026-05-26 21:09:49.012407 | TASK [upload-logs : Upload logs to log server] 2026-05-26 21:09:49.608243 | localhost | Output suppressed because no_log was given 2026-05-26 21:09:49.612077 | 2026-05-26 21:09:49.612193 | LOOP [upload-logs : Compress console log and json output] 2026-05-26 21:09:49.664706 | localhost | skipping: Conditional result was False 2026-05-26 21:09:49.670988 | localhost | skipping: Conditional result was False 2026-05-26 21:09:49.685578 | 2026-05-26 21:09:49.685817 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-26 21:09:49.768028 | localhost | skipping: Conditional result was False 2026-05-26 21:09:49.768708 | 2026-05-26 21:09:49.773386 | localhost | skipping: Conditional result was False 2026-05-26 21:09:49.786710 | 2026-05-26 21:09:49.786883 | LOOP [upload-logs : Upload console log and json output]