2026-05-04 03:05:24.206191 | Job console starting 2026-05-04 03:05:24.215116 | Updating git repos 2026-05-04 03:05:24.240933 | Cloning repos into workspace 2026-05-04 03:05:24.273520 | Restoring repo states 2026-05-04 03:05:24.292607 | Merging changes 2026-05-04 03:05:24.292630 | Checking out repos 2026-05-04 03:05:24.415094 | Preparing playbooks 2026-05-04 03:05:25.674337 | Running Ansible setup 2026-05-04 03:05:31.412046 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-04 03:05:32.436980 | 2026-05-04 03:05:32.437101 | PLAY [Base pre] 2026-05-04 03:05:32.450064 | 2026-05-04 03:05:32.450169 | TASK [Setup log path fact] 2026-05-04 03:05:32.467026 | debian-bookworm | ok 2026-05-04 03:05:32.480088 | 2026-05-04 03:05:32.480205 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-04 03:05:32.509264 | debian-bookworm | ok 2026-05-04 03:05:32.517695 | 2026-05-04 03:05:32.517800 | TASK [emit-job-header : Print job information] 2026-05-04 03:05:32.555030 | # Job Information 2026-05-04 03:05:32.555170 | Ansible Version: 2.16.14 2026-05-04 03:05:32.555197 | Job: python-black 2026-05-04 03:05:32.555223 | Pipeline: periodic-daily 2026-05-04 03:05:32.555242 | Executor: 521e9411259a 2026-05-04 03:05:32.555259 | Triggered by: https://github.com/osism/openstack-project-manager 2026-05-04 03:05:32.555277 | Event ID: fa808e5785b845fbbeea5349504080e4 2026-05-04 03:05:32.559582 | 2026-05-04 03:05:32.559667 | LOOP [emit-job-header : Print node information] 2026-05-04 03:05:32.654903 | debian-bookworm | ok: 2026-05-04 03:05:32.655105 | debian-bookworm | # Node Information 2026-05-04 03:05:32.655140 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-04 03:05:32.655163 | debian-bookworm | Hostname: debian 2026-05-04 03:05:32.655181 | debian-bookworm | Username: zuul 2026-05-04 03:05:32.655198 | debian-bookworm | Distro: Debian 12.13 2026-05-04 03:05:32.655217 | debian-bookworm | Provider: regiocloud-a 2026-05-04 03:05:32.655234 | debian-bookworm | Region: 2026-05-04 03:05:32.655251 | debian-bookworm | Label: debian-bookworm 2026-05-04 03:05:32.655266 | debian-bookworm | Product Name: OpenStack Nova 2026-05-04 03:05:32.655282 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec9:c72c 2026-05-04 03:05:32.665336 | 2026-05-04 03:05:32.665435 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-04 03:05:33.228564 | debian-bookworm -> localhost | changed 2026-05-04 03:05:33.241427 | 2026-05-04 03:05:33.241579 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-04 03:05:35.113004 | debian-bookworm -> localhost | changed 2026-05-04 03:05:35.123667 | 2026-05-04 03:05:35.123779 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-04 03:05:35.891580 | debian-bookworm -> localhost | ok 2026-05-04 03:05:35.899388 | 2026-05-04 03:05:35.899652 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-04 03:05:35.952207 | debian-bookworm | ok 2026-05-04 03:05:36.038563 | debian-bookworm | included: /var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-04 03:05:36.075414 | 2026-05-04 03:05:36.075524 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-04 03:05:38.526202 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-04 03:05:38.526365 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/work/36d5734c36a6484d92a73f8de3be04cd_id_rsa 2026-05-04 03:05:38.526394 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/work/36d5734c36a6484d92a73f8de3be04cd_id_rsa.pub 2026-05-04 03:05:38.526415 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-04 03:05:38.526435 | debian-bookworm -> localhost | SHA256:J5QSKZkEMmrDM85lmGGNqqTzIGoHmzxrgZoYXePoVaw zuul-build-sshkey 2026-05-04 03:05:38.526453 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-04 03:05:38.526479 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-04 03:05:38.526497 | debian-bookworm -> localhost | |oo+o.o.. | 2026-05-04 03:05:38.526514 | debian-bookworm -> localhost | |+++.+ .. . | 2026-05-04 03:05:38.526530 | debian-bookworm -> localhost | |oO o o. o | 2026-05-04 03:05:38.526546 | debian-bookworm -> localhost | |=.* o oo | 2026-05-04 03:05:38.526562 | debian-bookworm -> localhost | |=+ + + S . | 2026-05-04 03:05:38.526583 | debian-bookworm -> localhost | |Ooo E o | 2026-05-04 03:05:38.526601 | debian-bookworm -> localhost | |*B=. | 2026-05-04 03:05:38.526617 | debian-bookworm -> localhost | |=Bo. | 2026-05-04 03:05:38.526633 | debian-bookworm -> localhost | |o.+ | 2026-05-04 03:05:38.526650 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-04 03:05:38.526695 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.096277 2026-05-04 03:05:38.531891 | 2026-05-04 03:05:38.531984 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-04 03:05:38.549465 | debian-bookworm | ok 2026-05-04 03:05:38.557131 | debian-bookworm | included: /var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-04 03:05:38.565184 | 2026-05-04 03:05:38.565261 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-04 03:05:38.577822 | debian-bookworm | skipping: Conditional result was False 2026-05-04 03:05:38.583100 | 2026-05-04 03:05:38.583185 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-04 03:05:39.233176 | debian-bookworm | changed 2026-05-04 03:05:39.240884 | 2026-05-04 03:05:39.240987 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-04 03:05:39.496907 | debian-bookworm | ok 2026-05-04 03:05:39.516774 | 2026-05-04 03:05:39.516908 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-04 03:05:40.291464 | debian-bookworm | changed 2026-05-04 03:05:40.299752 | 2026-05-04 03:05:40.299907 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-04 03:05:40.985415 | debian-bookworm | changed 2026-05-04 03:05:40.992221 | 2026-05-04 03:05:40.992322 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-04 03:05:41.036693 | debian-bookworm | skipping: Conditional result was False 2026-05-04 03:05:41.042863 | 2026-05-04 03:05:41.042979 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-04 03:05:41.450108 | debian-bookworm -> localhost | changed 2026-05-04 03:05:41.464757 | 2026-05-04 03:05:41.464877 | TASK [add-build-sshkey : Add back temp key] 2026-05-04 03:05:41.761610 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/work/36d5734c36a6484d92a73f8de3be04cd_id_rsa (zuul-build-sshkey) 2026-05-04 03:05:41.761816 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018448 2026-05-04 03:05:41.781133 | 2026-05-04 03:05:41.781238 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-04 03:05:42.197009 | debian-bookworm | ok 2026-05-04 03:05:42.202226 | 2026-05-04 03:05:42.202330 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-04 03:05:42.235865 | debian-bookworm | skipping: Conditional result was False 2026-05-04 03:05:42.262669 | 2026-05-04 03:05:42.262786 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-04 03:05:42.699251 | debian-bookworm | ok 2026-05-04 03:05:42.720933 | 2026-05-04 03:05:42.721159 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-04 03:05:42.764710 | debian-bookworm | ok 2026-05-04 03:05:42.769935 | 2026-05-04 03:05:42.770044 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-04 03:05:43.154415 | debian-bookworm -> localhost | ok 2026-05-04 03:05:43.170887 | 2026-05-04 03:05:43.171029 | TASK [validate-host : Collect information about the host] 2026-05-04 03:05:44.335184 | debian-bookworm | ok 2026-05-04 03:05:44.348923 | 2026-05-04 03:05:44.349070 | TASK [validate-host : Sanitize hostname] 2026-05-04 03:05:44.530129 | debian-bookworm | ok 2026-05-04 03:05:44.697951 | 2026-05-04 03:05:44.698733 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-04 03:05:45.704526 | debian-bookworm -> localhost | changed 2026-05-04 03:05:45.710596 | 2026-05-04 03:05:45.710722 | TASK [validate-host : Collect information about zuul worker] 2026-05-04 03:05:46.390645 | debian-bookworm | ok 2026-05-04 03:05:46.398966 | 2026-05-04 03:05:46.399125 | TASK [validate-host : Write out all zuul information for each host] 2026-05-04 03:05:47.213465 | debian-bookworm -> localhost | changed 2026-05-04 03:05:47.239675 | 2026-05-04 03:05:47.239909 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-04 03:05:47.509088 | debian-bookworm | ok 2026-05-04 03:05:47.515373 | 2026-05-04 03:05:47.515500 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-04 03:05:48.427658 | debian-bookworm | changed: 2026-05-04 03:05:48.428039 | debian-bookworm | cd+++++++++ src/ 2026-05-04 03:05:48.428104 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-04 03:05:48.428133 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-04 03:05:48.428156 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-project-manager/ 2026-05-04 03:05:48.428178 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/work/logs" 2026-05-04 03:06:17.725038 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/work/artifacts" 2026-05-04 03:06:18.181480 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/36d5734c36a6484d92a73f8de3be04cd/work/docs" 2026-05-04 03:06:18.190595 | 2026-05-04 03:06:18.190742 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-04 03:06:19.312769 | debian-bookworm | changed: 2026-05-04 03:06:19.312974 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-04 03:06:19.313099 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-04 03:06:19.313129 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-04 03:06:19.313167 | debian-bookworm | changed: All items complete 2026-05-04 03:06:19.313189 | 2026-05-04 03:06:20.242442 | debian-bookworm | changed: .d..t...... ./ 2026-05-04 03:06:20.941817 | debian-bookworm | changed: .d..t...... ./ 2026-05-04 03:06:20.963924 | 2026-05-04 03:06:20.964030 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-04 03:06:20.997631 | debian-bookworm | skipping: Conditional result was False 2026-05-04 03:06:21.005302 | debian-bookworm | skipping: Conditional result was False 2026-05-04 03:06:21.017287 | 2026-05-04 03:06:21.017369 | PLAY RECAP 2026-05-04 03:06:21.017410 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-04 03:06:21.017431 | 2026-05-04 03:06:21.142994 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-04 03:06:21.143722 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-04 03:06:21.941171 | 2026-05-04 03:06:21.941302 | PLAY [Base post] 2026-05-04 03:06:21.953929 | 2026-05-04 03:06:21.954052 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-04 03:06:22.592620 | debian-bookworm | changed 2026-05-04 03:06:22.601075 | 2026-05-04 03:06:22.601170 | PLAY RECAP 2026-05-04 03:06:22.601230 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-04 03:06:22.601293 | 2026-05-04 03:06:22.808105 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-04 03:06:22.808848 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-04 03:06:24.064903 | 2026-05-04 03:06:24.065045 | PLAY [Base post-logs] 2026-05-04 03:06:24.097378 | 2026-05-04 03:06:24.097520 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-04 03:06:26.120301 | localhost | changed 2026-05-04 03:06:26.131293 | 2026-05-04 03:06:26.131483 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-04 03:06:26.198552 | localhost | ok 2026-05-04 03:06:26.201840 | 2026-05-04 03:06:26.201950 | TASK [Set zuul-log-path fact] 2026-05-04 03:06:26.228609 | localhost | ok 2026-05-04 03:06:26.259938 | 2026-05-04 03:06:26.260198 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-04 03:06:26.337364 | localhost | ok 2026-05-04 03:06:26.349582 | 2026-05-04 03:06:26.349716 | TASK [upload-logs : Create log directories] 2026-05-04 03:06:27.899813 | localhost | changed 2026-05-04 03:06:27.902345 | 2026-05-04 03:06:27.902429 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-04 03:06:28.431085 | localhost -> localhost | ok: Runtime: 0:00:00.019340 2026-05-04 03:06:28.434101 | 2026-05-04 03:06:28.434189 | TASK [upload-logs : Upload logs to log server] 2026-05-04 03:06:29.189354 | localhost | Output suppressed because no_log was given 2026-05-04 03:06:29.191065 | 2026-05-04 03:06:29.191157 | LOOP [upload-logs : Compress console log and json output] 2026-05-04 03:06:29.236382 | localhost | skipping: Conditional result was False 2026-05-04 03:06:29.244925 | localhost | skipping: Conditional result was False 2026-05-04 03:06:29.257642 | 2026-05-04 03:06:29.257780 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-04 03:06:29.342216 | localhost | skipping: Conditional result was False 2026-05-04 03:06:29.342462 | 2026-05-04 03:06:29.349995 | localhost | skipping: Conditional result was False 2026-05-04 03:06:29.353784 | 2026-05-04 03:06:29.353922 | LOOP [upload-logs : Upload console log and json output]