2026-07-31 03:01:40.952230 | Job console starting 2026-07-31 03:01:40.959133 | Updating git repos 2026-07-31 03:01:41.003547 | Cloning repos into workspace 2026-07-31 03:01:41.057946 | Restoring repo states 2026-07-31 03:01:41.075583 | Merging changes 2026-07-31 03:01:41.075600 | Checking out repos 2026-07-31 03:01:41.151599 | Preparing playbooks 2026-07-31 03:01:42.547951 | Running Ansible setup 2026-07-31 03:01:47.566720 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-31 03:01:48.621627 | 2026-07-31 03:01:48.621787 | PLAY [Base pre] 2026-07-31 03:01:48.639004 | 2026-07-31 03:01:48.639129 | TASK [Setup log path fact] 2026-07-31 03:01:48.669057 | debian-bookworm | ok 2026-07-31 03:01:48.686932 | 2026-07-31 03:01:48.687089 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-31 03:01:48.715562 | debian-bookworm | ok 2026-07-31 03:01:48.729180 | 2026-07-31 03:01:48.729316 | TASK [emit-job-header : Print job information] 2026-07-31 03:01:48.778165 | # Job Information 2026-07-31 03:01:48.778374 | Ansible Version: 2.16.14 2026-07-31 03:01:48.778410 | Job: python-black 2026-07-31 03:01:48.778443 | Pipeline: periodic-daily 2026-07-31 03:01:48.778466 | Executor: 521e9411259a 2026-07-31 03:01:48.778487 | Triggered by: https://github.com/osism/openstack-image-manager 2026-07-31 03:01:48.778508 | Event ID: 6084ea38de7244afba176bdcc6011f8c 2026-07-31 03:01:48.784621 | 2026-07-31 03:01:48.784737 | LOOP [emit-job-header : Print node information] 2026-07-31 03:01:48.912460 | debian-bookworm | ok: 2026-07-31 03:01:48.912759 | debian-bookworm | # Node Information 2026-07-31 03:01:48.912803 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-31 03:01:48.912829 | debian-bookworm | Hostname: debian 2026-07-31 03:01:48.912852 | debian-bookworm | Username: zuul 2026-07-31 03:01:48.912872 | debian-bookworm | Distro: Debian 12.15 2026-07-31 03:01:48.912896 | debian-bookworm | Provider: regiocloud-a 2026-07-31 03:01:48.912917 | debian-bookworm | Region: 2026-07-31 03:01:48.912938 | debian-bookworm | Label: debian-bookworm 2026-07-31 03:01:48.912957 | debian-bookworm | Product Name: OpenStack Nova 2026-07-31 03:01:48.912977 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8e:2e32 2026-07-31 03:01:48.932822 | 2026-07-31 03:01:48.932950 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-31 03:01:49.469012 | debian-bookworm -> localhost | changed 2026-07-31 03:01:49.477001 | 2026-07-31 03:01:49.477630 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-31 03:01:51.256319 | debian-bookworm -> localhost | changed 2026-07-31 03:01:51.280084 | 2026-07-31 03:01:51.280308 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-31 03:01:52.580509 | debian-bookworm -> localhost | ok 2026-07-31 03:01:52.587272 | 2026-07-31 03:01:52.587396 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-31 03:01:52.633989 | debian-bookworm | ok 2026-07-31 03:01:52.696132 | debian-bookworm | included: /var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-31 03:01:52.715423 | 2026-07-31 03:01:52.715538 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-31 03:01:55.935348 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-31 03:01:55.935538 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/work/549ef3dff05f4fcc91764d205e2cc390_id_rsa 2026-07-31 03:01:55.935569 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/work/549ef3dff05f4fcc91764d205e2cc390_id_rsa.pub 2026-07-31 03:01:55.935591 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-31 03:01:55.935610 | debian-bookworm -> localhost | SHA256://yYf61qkjoFQ6IpPb02Pn0ZAl+mfoqHGOEAp48vzqw zuul-build-sshkey 2026-07-31 03:01:55.935628 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-31 03:01:55.935653 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-31 03:01:55.935671 | debian-bookworm -> localhost | | | 2026-07-31 03:01:55.935689 | debian-bookworm -> localhost | |. . . . | 2026-07-31 03:01:55.935731 | debian-bookworm -> localhost | | + . + o | 2026-07-31 03:01:55.935748 | debian-bookworm -> localhost | |. o * o o o | 2026-07-31 03:01:55.935765 | debian-bookworm -> localhost | | o + o +S* | 2026-07-31 03:01:55.935786 | debian-bookworm -> localhost | |. . o + +.o | 2026-07-31 03:01:55.935803 | debian-bookworm -> localhost | | . = = o.+ .| 2026-07-31 03:01:55.935818 | debian-bookworm -> localhost | |+ . . +.= *o.o o| 2026-07-31 03:01:55.935835 | debian-bookworm -> localhost | |E= .oo* o*++o | 2026-07-31 03:01:55.935851 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-31 03:01:55.935894 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.552710 2026-07-31 03:01:55.942408 | 2026-07-31 03:01:55.942500 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-31 03:01:55.979419 | debian-bookworm | ok 2026-07-31 03:01:55.993283 | debian-bookworm | included: /var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-31 03:01:56.005476 | 2026-07-31 03:01:56.025979 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-31 03:01:56.066812 | debian-bookworm | skipping: Conditional result was False 2026-07-31 03:01:56.072870 | 2026-07-31 03:01:56.072987 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-31 03:01:57.096391 | debian-bookworm | changed 2026-07-31 03:01:57.101013 | 2026-07-31 03:01:57.101096 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-31 03:01:57.447737 | debian-bookworm | ok 2026-07-31 03:01:57.452394 | 2026-07-31 03:01:57.452478 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-31 03:01:58.481530 | debian-bookworm | changed 2026-07-31 03:01:58.486392 | 2026-07-31 03:01:58.486474 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-31 03:01:59.340960 | debian-bookworm | changed 2026-07-31 03:01:59.345675 | 2026-07-31 03:01:59.345773 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-31 03:01:59.395891 | debian-bookworm | skipping: Conditional result was False 2026-07-31 03:01:59.400927 | 2026-07-31 03:01:59.401021 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-31 03:02:01.372050 | debian-bookworm -> localhost | changed 2026-07-31 03:02:01.386729 | 2026-07-31 03:02:01.386866 | TASK [add-build-sshkey : Add back temp key] 2026-07-31 03:02:02.607283 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/work/549ef3dff05f4fcc91764d205e2cc390_id_rsa (zuul-build-sshkey) 2026-07-31 03:02:02.607514 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.036868 2026-07-31 03:02:02.619880 | 2026-07-31 03:02:02.619992 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-31 03:02:03.470253 | debian-bookworm | ok 2026-07-31 03:02:03.481984 | 2026-07-31 03:02:03.482125 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-31 03:02:03.567806 | debian-bookworm | skipping: Conditional result was False 2026-07-31 03:02:03.608019 | 2026-07-31 03:02:03.608132 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-31 03:02:04.511655 | debian-bookworm | ok 2026-07-31 03:02:04.523369 | 2026-07-31 03:02:04.530870 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-31 03:02:04.566056 | debian-bookworm | ok 2026-07-31 03:02:04.570232 | 2026-07-31 03:02:04.570313 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-31 03:02:05.165008 | debian-bookworm -> localhost | ok 2026-07-31 03:02:05.170278 | 2026-07-31 03:02:05.170360 | TASK [validate-host : Collect information about the host] 2026-07-31 03:02:06.364225 | debian-bookworm | ok 2026-07-31 03:02:06.391488 | 2026-07-31 03:02:06.391606 | TASK [validate-host : Sanitize hostname] 2026-07-31 03:02:06.646137 | debian-bookworm | ok 2026-07-31 03:02:06.655897 | 2026-07-31 03:02:06.656030 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-31 03:02:08.458103 | debian-bookworm -> localhost | changed 2026-07-31 03:02:08.464314 | 2026-07-31 03:02:08.464414 | TASK [validate-host : Collect information about zuul worker] 2026-07-31 03:02:09.256396 | debian-bookworm | ok 2026-07-31 03:02:09.261364 | 2026-07-31 03:02:09.261515 | TASK [validate-host : Write out all zuul information for each host] 2026-07-31 03:02:10.471688 | debian-bookworm -> localhost | changed 2026-07-31 03:02:10.490095 | 2026-07-31 03:02:10.490193 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-31 03:02:10.864684 | debian-bookworm | ok 2026-07-31 03:02:10.870645 | 2026-07-31 03:02:10.870764 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-31 03:02:12.362125 | debian-bookworm | changed: 2026-07-31 03:02:12.362272 | debian-bookworm | cd+++++++++ src/ 2026-07-31 03:02:12.362301 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-31 03:02:12.362321 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-31 03:02:12.362344 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-07-31 03:02:12.362361 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/work/logs" 2026-07-31 03:03:17.487630 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/work/artifacts" 2026-07-31 03:03:17.801345 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/549ef3dff05f4fcc91764d205e2cc390/work/docs" 2026-07-31 03:03:17.811056 | 2026-07-31 03:03:17.811171 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-31 03:03:18.739670 | debian-bookworm | changed: 2026-07-31 03:03:18.739946 | debian-bookworm | .d..t...... ./ 2026-07-31 03:03:18.739985 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-31 03:03:18.740010 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-31 03:03:18.740031 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-31 03:03:18.740053 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-31 03:03:18.740074 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-31 03:03:18.740120 | debian-bookworm | changed: All items complete 2026-07-31 03:03:18.740146 | 2026-07-31 03:03:19.373138 | debian-bookworm | changed: .d..t...... ./ 2026-07-31 03:03:20.121038 | debian-bookworm | changed: .d..t...... ./ 2026-07-31 03:03:20.142700 | 2026-07-31 03:03:20.142821 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-31 03:03:20.164899 | debian-bookworm | skipping: Conditional result was False 2026-07-31 03:03:20.168684 | debian-bookworm | skipping: Conditional result was False 2026-07-31 03:03:20.182678 | 2026-07-31 03:03:20.182761 | PLAY RECAP 2026-07-31 03:03:20.182812 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-07-31 03:03:20.182863 | 2026-07-31 03:03:20.279043 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-31 03:03:20.279832 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-31 03:03:21.190427 | 2026-07-31 03:03:21.190577 | PLAY [Base post] 2026-07-31 03:03:21.221029 | 2026-07-31 03:03:21.221159 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-31 03:03:21.815550 | debian-bookworm | changed 2026-07-31 03:03:21.829820 | 2026-07-31 03:03:21.829922 | PLAY RECAP 2026-07-31 03:03:21.829979 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-31 03:03:21.830067 | 2026-07-31 03:03:22.124061 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-31 03:03:22.124865 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-31 03:03:23.822486 | 2026-07-31 03:03:23.822682 | PLAY [Base post-logs] 2026-07-31 03:03:23.833981 | 2026-07-31 03:03:23.834126 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-31 03:03:24.590906 | localhost | changed 2026-07-31 03:03:24.598963 | 2026-07-31 03:03:24.600433 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-31 03:03:24.666445 | localhost | ok 2026-07-31 03:03:24.669062 | 2026-07-31 03:03:24.669140 | TASK [Set zuul-log-path fact] 2026-07-31 03:03:24.694731 | localhost | ok 2026-07-31 03:03:24.701661 | 2026-07-31 03:03:24.701875 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-31 03:03:24.725648 | localhost | ok 2026-07-31 03:03:24.728469 | 2026-07-31 03:03:24.728540 | TASK [upload-logs : Create log directories] 2026-07-31 03:03:25.338442 | localhost | changed 2026-07-31 03:03:25.341398 | 2026-07-31 03:03:25.341499 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-31 03:03:25.846611 | localhost -> localhost | ok: Runtime: 0:00:00.019082 2026-07-31 03:03:25.850906 | 2026-07-31 03:03:25.851009 | TASK [upload-logs : Upload logs to log server] 2026-07-31 03:03:26.364965 | localhost | Output suppressed because no_log was given 2026-07-31 03:03:26.368336 | 2026-07-31 03:03:26.368472 | LOOP [upload-logs : Compress console log and json output] 2026-07-31 03:03:26.418937 | localhost | skipping: Conditional result was False 2026-07-31 03:03:26.426901 | localhost | skipping: Conditional result was False 2026-07-31 03:03:26.438030 | 2026-07-31 03:03:26.438125 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-31 03:03:26.499084 | localhost | skipping: Conditional result was False 2026-07-31 03:03:26.499479 | 2026-07-31 03:03:26.503935 | localhost | skipping: Conditional result was False 2026-07-31 03:03:26.509721 | 2026-07-31 03:03:26.509931 | LOOP [upload-logs : Upload console log and json output]