2026-05-26 16:24:30.646691 | Job console starting 2026-05-26 16:24:30.664799 | Updating git repos 2026-05-26 16:24:30.705718 | Cloning repos into workspace 2026-05-26 16:24:30.771894 | Restoring repo states 2026-05-26 16:24:30.782210 | Merging changes 2026-05-26 16:24:31.338994 | Checking out repos 2026-05-26 16:24:31.410617 | Preparing playbooks 2026-05-26 16:24:32.281642 | Running Ansible setup 2026-05-26 16:24:36.797063 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 16:24:37.590236 | 2026-05-26 16:24:37.591248 | PLAY [Base pre] 2026-05-26 16:24:37.625785 | 2026-05-26 16:24:37.625981 | TASK [Setup log path fact] 2026-05-26 16:24:37.656818 | debian-bookworm | ok 2026-05-26 16:24:37.675616 | 2026-05-26 16:24:37.675813 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 16:24:37.717594 | debian-bookworm | ok 2026-05-26 16:24:37.728560 | 2026-05-26 16:24:37.728756 | TASK [emit-job-header : Print job information] 2026-05-26 16:24:37.768849 | # Job Information 2026-05-26 16:24:37.769044 | Ansible Version: 2.16.14 2026-05-26 16:24:37.769096 | Job: yamllint 2026-05-26 16:24:37.769129 | Pipeline: check 2026-05-26 16:24:37.769151 | Executor: 521e9411259a 2026-05-26 16:24:37.769173 | Triggered by: https://github.com/osism/testbed/pull/2863 2026-05-26 16:24:37.769196 | Event ID: 58ffe6a0-591f-11f1-9390-cbb6a9cabb5f 2026-05-26 16:24:37.774267 | 2026-05-26 16:24:37.774392 | LOOP [emit-job-header : Print node information] 2026-05-26 16:24:37.883338 | debian-bookworm | ok: 2026-05-26 16:24:37.883602 | debian-bookworm | # Node Information 2026-05-26 16:24:37.883638 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-26 16:24:37.883663 | debian-bookworm | Hostname: debian 2026-05-26 16:24:37.883684 | debian-bookworm | Username: zuul 2026-05-26 16:24:37.883804 | debian-bookworm | Distro: Debian 12.14 2026-05-26 16:24:37.883829 | debian-bookworm | Provider: regiocloud-a 2026-05-26 16:24:37.883851 | debian-bookworm | Region: 2026-05-26 16:24:37.883873 | debian-bookworm | Label: debian-bookworm 2026-05-26 16:24:37.883892 | debian-bookworm | Product Name: OpenStack Nova 2026-05-26 16:24:37.883913 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe08:4993 2026-05-26 16:24:37.907347 | 2026-05-26 16:24:37.907510 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-26 16:24:38.509014 | debian-bookworm -> localhost | changed 2026-05-26 16:24:38.516591 | 2026-05-26 16:24:38.516737 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-26 16:24:39.689517 | debian-bookworm -> localhost | changed 2026-05-26 16:24:39.704766 | 2026-05-26 16:24:39.704908 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-26 16:24:39.985853 | debian-bookworm -> localhost | ok 2026-05-26 16:24:39.992338 | 2026-05-26 16:24:39.992476 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-26 16:24:40.023787 | debian-bookworm | ok 2026-05-26 16:24:40.040134 | debian-bookworm | included: /var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-26 16:24:40.046983 | 2026-05-26 16:24:40.047131 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-26 16:24:41.575623 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-26 16:24:41.575842 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/work/a5036dbc4018448389b2a33728be19f2_id_rsa 2026-05-26 16:24:41.575881 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/work/a5036dbc4018448389b2a33728be19f2_id_rsa.pub 2026-05-26 16:24:41.575907 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-26 16:24:41.575931 | debian-bookworm -> localhost | SHA256:po9swfOPTGQzIBDkNXn2PlnQ2qXJmbXDTn89cwx5bpk zuul-build-sshkey 2026-05-26 16:24:41.575954 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-26 16:24:41.576002 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-26 16:24:41.576025 | debian-bookworm -> localhost | | .+.o. . | 2026-05-26 16:24:41.576046 | debian-bookworm -> localhost | | . o..o . . o | 2026-05-26 16:24:41.576067 | debian-bookworm -> localhost | | . .o.. = O . . | 2026-05-26 16:24:41.576106 | debian-bookworm -> localhost | | . .o O = o .| 2026-05-26 16:24:41.576126 | debian-bookworm -> localhost | | . .So o o =+| 2026-05-26 16:24:41.576150 | debian-bookworm -> localhost | | +=+o . .EB| 2026-05-26 16:24:41.576171 | debian-bookworm -> localhost | | .+.. o+| 2026-05-26 16:24:41.576190 | debian-bookworm -> localhost | | ..=.. | 2026-05-26 16:24:41.576211 | debian-bookworm -> localhost | | .o +.. | 2026-05-26 16:24:41.576231 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-26 16:24:41.576285 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.989113 2026-05-26 16:24:41.583307 | 2026-05-26 16:24:41.583445 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-26 16:24:41.611969 | debian-bookworm | ok 2026-05-26 16:24:41.621530 | debian-bookworm | included: /var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-26 16:24:41.631495 | 2026-05-26 16:24:41.631635 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-26 16:24:41.655669 | debian-bookworm | skipping: Conditional result was False 2026-05-26 16:24:41.661975 | 2026-05-26 16:24:41.662122 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-26 16:24:42.298637 | debian-bookworm | changed 2026-05-26 16:24:42.310053 | 2026-05-26 16:24:42.310277 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-26 16:24:42.576088 | debian-bookworm | ok 2026-05-26 16:24:42.582751 | 2026-05-26 16:24:42.582900 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-26 16:24:43.213686 | debian-bookworm | changed 2026-05-26 16:24:43.221237 | 2026-05-26 16:24:43.221359 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-26 16:24:43.862169 | debian-bookworm | changed 2026-05-26 16:24:43.869331 | 2026-05-26 16:24:43.869472 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-26 16:24:43.893447 | debian-bookworm | skipping: Conditional result was False 2026-05-26 16:24:43.901488 | 2026-05-26 16:24:43.901633 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-26 16:24:44.360656 | debian-bookworm -> localhost | changed 2026-05-26 16:24:44.371812 | 2026-05-26 16:24:44.371947 | TASK [add-build-sshkey : Add back temp key] 2026-05-26 16:24:44.712050 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/work/a5036dbc4018448389b2a33728be19f2_id_rsa (zuul-build-sshkey) 2026-05-26 16:24:44.712371 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012897 2026-05-26 16:24:44.729717 | 2026-05-26 16:24:44.730618 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-26 16:24:45.166053 | debian-bookworm | ok 2026-05-26 16:24:45.174609 | 2026-05-26 16:24:45.174749 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-26 16:24:45.203421 | debian-bookworm | skipping: Conditional result was False 2026-05-26 16:24:45.218367 | 2026-05-26 16:24:45.218512 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-26 16:24:45.659790 | debian-bookworm | ok 2026-05-26 16:24:45.674056 | 2026-05-26 16:24:45.674219 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-26 16:24:45.714890 | debian-bookworm | ok 2026-05-26 16:24:45.720231 | 2026-05-26 16:24:45.720354 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-26 16:24:45.999606 | debian-bookworm -> localhost | ok 2026-05-26 16:24:46.006563 | 2026-05-26 16:24:46.006706 | TASK [validate-host : Collect information about the host] 2026-05-26 16:24:47.172461 | debian-bookworm | ok 2026-05-26 16:24:47.188024 | 2026-05-26 16:24:47.188182 | TASK [validate-host : Sanitize hostname] 2026-05-26 16:24:47.309784 | debian-bookworm | ok 2026-05-26 16:24:47.368760 | 2026-05-26 16:24:47.368901 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-26 16:24:48.062573 | debian-bookworm -> localhost | changed 2026-05-26 16:24:48.068704 | 2026-05-26 16:24:48.068826 | TASK [validate-host : Collect information about zuul worker] 2026-05-26 16:24:48.467564 | debian-bookworm | ok 2026-05-26 16:24:48.475410 | 2026-05-26 16:24:48.475574 | TASK [validate-host : Write out all zuul information for each host] 2026-05-26 16:24:49.066272 | debian-bookworm -> localhost | changed 2026-05-26 16:24:49.078972 | 2026-05-26 16:24:49.079144 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-26 16:24:49.346868 | debian-bookworm | ok 2026-05-26 16:24:49.359037 | 2026-05-26 16:24:49.359201 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-26 16:24:50.617408 | debian-bookworm | changed: 2026-05-26 16:24:50.617637 | debian-bookworm | cd+++++++++ src/ 2026-05-26 16:24:50.617672 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-26 16:24:50.617696 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-26 16:24:50.617718 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-05-26 16:24:50.617738 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/work/logs" 2026-05-26 16:25:15.127921 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/work/artifacts" 2026-05-26 16:25:15.418434 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a5036dbc4018448389b2a33728be19f2/work/docs" 2026-05-26 16:25:15.429788 | 2026-05-26 16:25:15.430008 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-26 16:25:16.210162 | debian-bookworm | changed: 2026-05-26 16:25:16.210394 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-26 16:25:16.210429 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-26 16:25:16.210457 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-26 16:25:16.210502 | debian-bookworm | changed: All items complete 2026-05-26 16:25:16.210529 | 2026-05-26 16:25:16.765937 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 16:25:17.377750 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 16:25:17.391781 | 2026-05-26 16:25:17.392009 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-26 16:25:17.936100 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.005587 2026-05-26 16:25:18.198087 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.013101 2026-05-26 16:25:18.208407 | 2026-05-26 16:25:18.208521 | PLAY RECAP 2026-05-26 16:25:18.208576 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-26 16:25:18.208602 | 2026-05-26 16:25:18.363438 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-26 16:25:18.364338 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 16:25:19.132530 | 2026-05-26 16:25:19.132799 | PLAY [Base post] 2026-05-26 16:25:19.147563 | 2026-05-26 16:25:19.147736 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-26 16:25:19.773254 | debian-bookworm | changed 2026-05-26 16:25:19.787275 | 2026-05-26 16:25:19.787739 | PLAY RECAP 2026-05-26 16:25:19.787938 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-26 16:25:19.788185 | 2026-05-26 16:25:19.945394 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 16:25:19.947510 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-26 16:25:20.741394 | 2026-05-26 16:25:20.742087 | PLAY [Base post-logs] 2026-05-26 16:25:20.753797 | 2026-05-26 16:25:20.753956 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-26 16:25:21.195961 | localhost | changed 2026-05-26 16:25:21.206632 | 2026-05-26 16:25:21.206816 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-26 16:25:21.244922 | localhost | ok 2026-05-26 16:25:21.248511 | 2026-05-26 16:25:21.248633 | TASK [Set zuul-log-path fact] 2026-05-26 16:25:21.276134 | localhost | ok 2026-05-26 16:25:21.290530 | 2026-05-26 16:25:21.290715 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 16:25:21.330222 | localhost | ok 2026-05-26 16:25:21.338617 | 2026-05-26 16:25:21.338863 | TASK [upload-logs : Create log directories] 2026-05-26 16:25:21.885671 | localhost | changed 2026-05-26 16:25:21.888552 | 2026-05-26 16:25:21.888671 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-26 16:25:22.399858 | localhost -> localhost | ok: Runtime: 0:00:00.012117 2026-05-26 16:25:22.409530 | 2026-05-26 16:25:22.409755 | TASK [upload-logs : Upload logs to log server] 2026-05-26 16:25:22.965776 | localhost | Output suppressed because no_log was given 2026-05-26 16:25:22.969307 | 2026-05-26 16:25:22.969490 | LOOP [upload-logs : Compress console log and json output] 2026-05-26 16:25:23.025169 | localhost | skipping: Conditional result was False 2026-05-26 16:25:23.031472 | localhost | skipping: Conditional result was False 2026-05-26 16:25:23.038975 | 2026-05-26 16:25:23.039125 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-26 16:25:23.085520 | localhost | skipping: Conditional result was False 2026-05-26 16:25:23.085825 | 2026-05-26 16:25:23.090792 | localhost | skipping: Conditional result was False 2026-05-26 16:25:23.096129 | 2026-05-26 16:25:23.096248 | LOOP [upload-logs : Upload console log and json output]