2026-08-07 08:59:21.999358 | Job console starting 2026-08-07 08:59:22.010017 | Updating git repos 2026-08-07 08:59:22.068700 | Cloning repos into workspace 2026-08-07 08:59:22.218413 | Restoring repo states 2026-08-07 08:59:22.274729 | Merging changes 2026-08-07 08:59:23.180419 | Checking out repos 2026-08-07 08:59:23.282495 | Preparing playbooks 2026-08-07 08:59:26.090100 | Running Ansible setup 2026-08-07 08:59:33.103957 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-07 08:59:34.428825 | 2026-08-07 08:59:34.428951 | PLAY [Base pre] 2026-08-07 08:59:34.466349 | 2026-08-07 08:59:34.466492 | TASK [Setup log path fact] 2026-08-07 08:59:34.484362 | debian-bookworm | ok 2026-08-07 08:59:34.510609 | 2026-08-07 08:59:34.510727 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-07 08:59:34.562695 | debian-bookworm | ok 2026-08-07 08:59:34.577428 | 2026-08-07 08:59:34.577567 | TASK [emit-job-header : Print job information] 2026-08-07 08:59:34.645888 | # Job Information 2026-08-07 08:59:34.646043 | Ansible Version: 2.16.14 2026-08-07 08:59:34.646071 | Job: python-osism-test-setup 2026-08-07 08:59:34.646100 | Pipeline: check 2026-08-07 08:59:34.646119 | Executor: 521e9411259a 2026-08-07 08:59:34.646136 | Triggered by: https://github.com/osism/python-osism/pull/2578 2026-08-07 08:59:34.646155 | Event ID: 3fc46820-923e-11f1-8486-94a376deadbb 2026-08-07 08:59:34.650559 | 2026-08-07 08:59:34.650668 | LOOP [emit-job-header : Print node information] 2026-08-07 08:59:34.936034 | debian-bookworm | ok: 2026-08-07 08:59:34.936799 | debian-bookworm | # Node Information 2026-08-07 08:59:34.936837 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-07 08:59:34.936859 | debian-bookworm | Hostname: debian 2026-08-07 08:59:34.936878 | debian-bookworm | Username: zuul 2026-08-07 08:59:34.936896 | debian-bookworm | Distro: Debian 12.15 2026-08-07 08:59:34.936916 | debian-bookworm | Provider: regiocloud-a 2026-08-07 08:59:34.937318 | debian-bookworm | Region: 2026-08-07 08:59:34.937339 | debian-bookworm | Label: debian-bookworm 2026-08-07 08:59:34.937358 | debian-bookworm | Product Name: OpenStack Nova 2026-08-07 08:59:34.937913 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefa:427e 2026-08-07 08:59:34.956998 | 2026-08-07 08:59:34.957093 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-07 08:59:36.392225 | debian-bookworm -> localhost | changed 2026-08-07 08:59:36.398618 | 2026-08-07 08:59:36.398703 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-07 08:59:39.157420 | debian-bookworm -> localhost | changed 2026-08-07 08:59:39.169523 | 2026-08-07 08:59:39.169642 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-07 08:59:40.089166 | debian-bookworm -> localhost | ok 2026-08-07 08:59:40.094392 | 2026-08-07 08:59:40.094499 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-07 08:59:40.124604 | debian-bookworm | ok 2026-08-07 08:59:40.172489 | debian-bookworm | included: /var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-07 08:59:40.194992 | 2026-08-07 08:59:40.195098 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-07 08:59:44.144788 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-07 08:59:44.144996 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/work/d728be036d8d4f4ba27a0cce121affab_id_rsa 2026-08-07 08:59:44.145035 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/work/d728be036d8d4f4ba27a0cce121affab_id_rsa.pub 2026-08-07 08:59:44.145066 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-07 08:59:44.145092 | debian-bookworm -> localhost | SHA256:BWP7Mlqp6dhRJ7OYeVc0MH4tN/lpHz1mhm+QY0+T0dk zuul-build-sshkey 2026-08-07 08:59:44.145116 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-07 08:59:44.145149 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-07 08:59:44.145172 | debian-bookworm -> localhost | | + o | 2026-08-07 08:59:44.145194 | debian-bookworm -> localhost | | . = o . .+| 2026-08-07 08:59:44.145214 | debian-bookworm -> localhost | | . o = =oE| 2026-08-07 08:59:44.145234 | debian-bookworm -> localhost | | + o +oo=| 2026-08-07 08:59:44.145257 | debian-bookworm -> localhost | | S o .* %o| 2026-08-07 08:59:44.145282 | debian-bookworm -> localhost | | X B .. @ =| 2026-08-07 08:59:44.145302 | debian-bookworm -> localhost | | O o . +.| 2026-08-07 08:59:44.145322 | debian-bookworm -> localhost | | + o . . | 2026-08-07 08:59:44.145343 | debian-bookworm -> localhost | | . o | 2026-08-07 08:59:44.145364 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-07 08:59:44.145415 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.779466 2026-08-07 08:59:44.152089 | 2026-08-07 08:59:44.152198 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-07 08:59:44.193181 | debian-bookworm | ok 2026-08-07 08:59:44.234547 | debian-bookworm | included: /var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-07 08:59:44.248211 | 2026-08-07 08:59:44.248370 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-07 08:59:44.294766 | debian-bookworm | skipping: Conditional result was False 2026-08-07 08:59:44.301186 | 2026-08-07 08:59:44.301296 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-07 08:59:44.950923 | debian-bookworm | changed 2026-08-07 08:59:44.960830 | 2026-08-07 08:59:44.960937 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-07 08:59:45.214194 | debian-bookworm | ok 2026-08-07 08:59:45.222224 | 2026-08-07 08:59:45.222328 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-07 08:59:46.062324 | debian-bookworm | changed 2026-08-07 08:59:46.068499 | 2026-08-07 08:59:46.068600 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-07 08:59:46.820012 | debian-bookworm | changed 2026-08-07 08:59:46.827175 | 2026-08-07 08:59:46.827267 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-07 08:59:46.857904 | debian-bookworm | skipping: Conditional result was False 2026-08-07 08:59:46.865102 | 2026-08-07 08:59:46.865211 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-07 08:59:48.478148 | debian-bookworm -> localhost | changed 2026-08-07 08:59:48.495256 | 2026-08-07 08:59:48.495396 | TASK [add-build-sshkey : Add back temp key] 2026-08-07 08:59:49.737110 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/work/d728be036d8d4f4ba27a0cce121affab_id_rsa (zuul-build-sshkey) 2026-08-07 08:59:49.737610 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.079180 2026-08-07 08:59:49.752062 | 2026-08-07 08:59:49.752219 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-07 08:59:50.960550 | debian-bookworm | ok 2026-08-07 08:59:50.977519 | 2026-08-07 08:59:50.977628 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-07 08:59:51.053218 | debian-bookworm | skipping: Conditional result was False 2026-08-07 08:59:51.061813 | 2026-08-07 08:59:51.061907 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-07 08:59:51.952497 | debian-bookworm | ok 2026-08-07 08:59:51.977161 | 2026-08-07 08:59:51.977265 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-07 08:59:52.040784 | debian-bookworm | ok 2026-08-07 08:59:52.045190 | 2026-08-07 08:59:52.045270 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-07 08:59:52.636203 | debian-bookworm -> localhost | ok 2026-08-07 08:59:52.641812 | 2026-08-07 08:59:52.641892 | TASK [validate-host : Collect information about the host] 2026-08-07 08:59:53.950026 | debian-bookworm | ok 2026-08-07 08:59:53.966478 | 2026-08-07 08:59:53.966580 | TASK [validate-host : Sanitize hostname] 2026-08-07 08:59:54.170330 | debian-bookworm | ok 2026-08-07 08:59:54.192658 | 2026-08-07 08:59:54.192772 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-07 08:59:55.400803 | debian-bookworm -> localhost | changed 2026-08-07 08:59:55.406552 | 2026-08-07 08:59:55.406652 | TASK [validate-host : Collect information about zuul worker] 2026-08-07 08:59:55.944134 | debian-bookworm | ok 2026-08-07 08:59:55.967089 | 2026-08-07 08:59:55.967278 | TASK [validate-host : Write out all zuul information for each host] 2026-08-07 08:59:56.852248 | debian-bookworm -> localhost | changed 2026-08-07 08:59:56.862665 | 2026-08-07 08:59:56.862770 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-07 08:59:57.179852 | debian-bookworm | ok 2026-08-07 08:59:57.221746 | 2026-08-07 08:59:57.223288 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-07 08:59:58.529946 | debian-bookworm | changed: 2026-08-07 08:59:58.531031 | debian-bookworm | cd+++++++++ src/ 2026-08-07 08:59:58.531111 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-07 08:59:58.531138 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-07 08:59:58.531160 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-07 08:59:58.531181 | debian-bookworm | Containerfile 2026-08-07 08:59:58.531405 | debian-bookworm | Containerfile 2026-08-07 08:59:58.560154 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/work/logs" 2026-08-07 09:02:36.954126 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/work/artifacts" 2026-08-07 09:02:37.317679 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d728be036d8d4f4ba27a0cce121affab/work/docs" 2026-08-07 09:02:37.362290 | 2026-08-07 09:02:37.362565 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-07 09:02:38.174658 | debian-bookworm | changed: 2026-08-07 09:02:38.175096 | debian-bookworm | .d..t...... ./ 2026-08-07 09:02:38.175139 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-07 09:02:38.175225 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-07 09:02:38.175262 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-07 09:02:38.175285 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-07 09:02:38.175305 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-07 09:02:38.175353 | debian-bookworm | changed: All items complete 2026-08-07 09:02:38.175376 | 2026-08-07 09:02:38.745593 | debian-bookworm | changed: .d..t...... ./ 2026-08-07 09:02:39.347677 | debian-bookworm | changed: .d..t...... ./ 2026-08-07 09:02:39.371257 | 2026-08-07 09:02:39.371703 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-07 09:02:39.855237 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009468 2026-08-07 09:02:40.177163 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007172 2026-08-07 09:02:40.195655 | 2026-08-07 09:02:40.195787 | PLAY RECAP 2026-08-07 09:02:40.195845 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-07 09:02:40.196025 | 2026-08-07 09:02:40.381133 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-07 09:02:40.382178 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-07 09:02:41.283581 | 2026-08-07 09:02:41.283779 | PLAY [Base post] 2026-08-07 09:02:41.299380 | 2026-08-07 09:02:41.299588 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-07 09:02:42.131419 | debian-bookworm | changed 2026-08-07 09:02:42.138728 | 2026-08-07 09:02:42.138930 | PLAY RECAP 2026-08-07 09:02:42.139001 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-07 09:02:42.139071 | 2026-08-07 09:02:42.295298 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-07 09:02:42.301489 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-07 09:02:43.297917 | 2026-08-07 09:02:43.298112 | PLAY [Base post-logs] 2026-08-07 09:02:43.311369 | 2026-08-07 09:02:43.311575 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-07 09:02:43.811010 | localhost | changed 2026-08-07 09:02:43.822220 | 2026-08-07 09:02:43.822374 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-07 09:02:43.849132 | localhost | ok 2026-08-07 09:02:43.853073 | 2026-08-07 09:02:43.853189 | TASK [Set zuul-log-path fact] 2026-08-07 09:02:43.868515 | localhost | ok 2026-08-07 09:02:43.877110 | 2026-08-07 09:02:43.877230 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-07 09:02:43.912671 | localhost | ok 2026-08-07 09:02:43.916386 | 2026-08-07 09:02:43.916516 | TASK [upload-logs : Create log directories] 2026-08-07 09:02:44.471196 | localhost | changed 2026-08-07 09:02:44.477088 | 2026-08-07 09:02:44.477214 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-07 09:02:45.013005 | localhost -> localhost | ok: Runtime: 0:00:00.008126 2026-08-07 09:02:45.025039 | 2026-08-07 09:02:45.025260 | TASK [upload-logs : Upload logs to log server] 2026-08-07 09:02:45.659979 | localhost | Output suppressed because no_log was given 2026-08-07 09:02:45.664321 | 2026-08-07 09:02:45.664502 | LOOP [upload-logs : Compress console log and json output] 2026-08-07 09:02:45.727097 | localhost | skipping: Conditional result was False 2026-08-07 09:02:45.733345 | localhost | skipping: Conditional result was False 2026-08-07 09:02:45.747327 | 2026-08-07 09:02:45.747566 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-07 09:02:45.798215 | localhost | skipping: Conditional result was False 2026-08-07 09:02:45.798598 | 2026-08-07 09:02:45.803119 | localhost | skipping: Conditional result was False 2026-08-07 09:02:45.816200 | 2026-08-07 09:02:45.816317 | LOOP [upload-logs : Upload console log and json output]