2026-08-27 16:08:03.433227 | Job console starting 2026-08-27 16:08:03.443330 | Updating git repos 2026-08-27 16:08:03.512858 | Cloning repos into workspace 2026-08-27 16:08:03.562079 | Restoring repo states 2026-08-27 16:08:03.607955 | Merging changes 2026-08-27 16:08:04.460615 | Checking out repos 2026-08-27 16:08:04.535044 | Preparing playbooks 2026-08-27 16:08:05.827219 | Running Ansible setup 2026-08-27 16:08:11.109230 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-27 16:08:12.163012 | 2026-08-27 16:08:12.163211 | PLAY [Base pre] 2026-08-27 16:08:12.199205 | 2026-08-27 16:08:12.199398 | TASK [Setup log path fact] 2026-08-27 16:08:12.230052 | debian-bookworm | ok 2026-08-27 16:08:12.251578 | 2026-08-27 16:08:12.251860 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-27 16:08:12.283068 | debian-bookworm | ok 2026-08-27 16:08:12.301552 | 2026-08-27 16:08:12.303220 | TASK [emit-job-header : Print job information] 2026-08-27 16:08:12.348608 | # Job Information 2026-08-27 16:08:12.348857 | Ansible Version: 2.16.14 2026-08-27 16:08:12.348895 | Job: python-osism-test-setup 2026-08-27 16:08:12.348928 | Pipeline: check 2026-08-27 16:08:12.348951 | Executor: 521e9411259a 2026-08-27 16:08:12.348972 | Triggered by: https://github.com/osism/python-osism/pull/2581 2026-08-27 16:08:12.348995 | Event ID: 49b8a190-a231-11f1-974d-62ca6cf2da08 2026-08-27 16:08:12.355399 | 2026-08-27 16:08:12.355543 | LOOP [emit-job-header : Print node information] 2026-08-27 16:08:12.507274 | debian-bookworm | ok: 2026-08-27 16:08:12.511792 | debian-bookworm | # Node Information 2026-08-27 16:08:12.511905 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-27 16:08:12.511937 | debian-bookworm | Hostname: debian 2026-08-27 16:08:12.511961 | debian-bookworm | Username: zuul 2026-08-27 16:08:12.511984 | debian-bookworm | Distro: Debian 12.15 2026-08-27 16:08:12.512012 | debian-bookworm | Provider: regiocloud-a 2026-08-27 16:08:12.512034 | debian-bookworm | Region: 2026-08-27 16:08:12.512055 | debian-bookworm | Label: debian-bookworm 2026-08-27 16:08:12.512075 | debian-bookworm | Product Name: OpenStack Nova 2026-08-27 16:08:12.512095 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb7:32a6 2026-08-27 16:08:12.530614 | 2026-08-27 16:08:12.530962 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-27 16:08:13.306333 | debian-bookworm -> localhost | changed 2026-08-27 16:08:13.325800 | 2026-08-27 16:08:13.326164 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-27 16:08:15.652810 | debian-bookworm -> localhost | changed 2026-08-27 16:08:15.684865 | 2026-08-27 16:08:15.693046 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-27 16:08:16.806687 | debian-bookworm -> localhost | ok 2026-08-27 16:08:16.821489 | 2026-08-27 16:08:16.821967 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-27 16:08:16.932211 | debian-bookworm | ok 2026-08-27 16:08:16.996550 | debian-bookworm | included: /var/lib/zuul/builds/91844556930945ab87467f846e51c824/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-27 16:08:17.019808 | 2026-08-27 16:08:17.019975 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-27 16:08:19.982322 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-27 16:08:19.982551 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/91844556930945ab87467f846e51c824/work/91844556930945ab87467f846e51c824_id_rsa 2026-08-27 16:08:19.982593 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/91844556930945ab87467f846e51c824/work/91844556930945ab87467f846e51c824_id_rsa.pub 2026-08-27 16:08:19.982651 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-27 16:08:19.982680 | debian-bookworm -> localhost | SHA256:RImlxBs6MTFd+BD+6TBY74Ck3XuwdykgHOslEDT2wRY zuul-build-sshkey 2026-08-27 16:08:19.982704 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-27 16:08:19.982737 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-27 16:08:19.982760 | debian-bookworm -> localhost | | .=.E=o*+. | 2026-08-27 16:08:19.982782 | debian-bookworm -> localhost | | . +==Bo. | 2026-08-27 16:08:19.982803 | debian-bookworm -> localhost | | ..+++=. | 2026-08-27 16:08:19.982823 | debian-bookworm -> localhost | | *oB.+.. | 2026-08-27 16:08:19.982864 | debian-bookworm -> localhost | | . O.O S | 2026-08-27 16:08:19.982889 | debian-bookworm -> localhost | | . + @ . | 2026-08-27 16:08:19.982910 | debian-bookworm -> localhost | | . o * o | 2026-08-27 16:08:19.982930 | debian-bookworm -> localhost | | o o | 2026-08-27 16:08:19.982951 | debian-bookworm -> localhost | | | 2026-08-27 16:08:19.982971 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-27 16:08:19.983024 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.833339 2026-08-27 16:08:19.989320 | 2026-08-27 16:08:19.989458 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-27 16:08:20.017514 | debian-bookworm | ok 2026-08-27 16:08:20.028506 | debian-bookworm | included: /var/lib/zuul/builds/91844556930945ab87467f846e51c824/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-27 16:08:20.036766 | 2026-08-27 16:08:20.036864 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-27 16:08:20.060592 | debian-bookworm | skipping: Conditional result was False 2026-08-27 16:08:20.066760 | 2026-08-27 16:08:20.066872 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-27 16:08:20.735383 | debian-bookworm | changed 2026-08-27 16:08:20.741217 | 2026-08-27 16:08:20.741318 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-27 16:08:21.002700 | debian-bookworm | ok 2026-08-27 16:08:21.009831 | 2026-08-27 16:08:21.009939 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-27 16:08:21.707806 | debian-bookworm | changed 2026-08-27 16:08:21.720027 | 2026-08-27 16:08:21.720227 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-27 16:08:22.479463 | debian-bookworm | changed 2026-08-27 16:08:22.489796 | 2026-08-27 16:08:22.489953 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-27 16:08:22.515684 | debian-bookworm | skipping: Conditional result was False 2026-08-27 16:08:22.522714 | 2026-08-27 16:08:22.522817 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-27 16:08:23.329293 | debian-bookworm -> localhost | changed 2026-08-27 16:08:23.345769 | 2026-08-27 16:08:23.345876 | TASK [add-build-sshkey : Add back temp key] 2026-08-27 16:08:23.774858 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/91844556930945ab87467f846e51c824/work/91844556930945ab87467f846e51c824_id_rsa (zuul-build-sshkey) 2026-08-27 16:08:23.775093 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009108 2026-08-27 16:08:23.793266 | 2026-08-27 16:08:23.793381 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-27 16:08:24.194557 | debian-bookworm | ok 2026-08-27 16:08:24.200699 | 2026-08-27 16:08:24.201254 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-27 16:08:24.227554 | debian-bookworm | skipping: Conditional result was False 2026-08-27 16:08:24.245907 | 2026-08-27 16:08:24.246030 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-27 16:08:24.755144 | debian-bookworm | ok 2026-08-27 16:08:24.771879 | 2026-08-27 16:08:24.772010 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-27 16:08:24.800431 | debian-bookworm | ok 2026-08-27 16:08:24.805673 | 2026-08-27 16:08:24.805770 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-27 16:08:25.204413 | debian-bookworm -> localhost | ok 2026-08-27 16:08:25.212132 | 2026-08-27 16:08:25.212238 | TASK [validate-host : Collect information about the host] 2026-08-27 16:08:26.370591 | debian-bookworm | ok 2026-08-27 16:08:26.391995 | 2026-08-27 16:08:26.392117 | TASK [validate-host : Sanitize hostname] 2026-08-27 16:08:26.530053 | debian-bookworm | ok 2026-08-27 16:08:26.534787 | 2026-08-27 16:08:26.534899 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-27 16:08:27.419756 | debian-bookworm -> localhost | changed 2026-08-27 16:08:27.425559 | 2026-08-27 16:08:27.425672 | TASK [validate-host : Collect information about zuul worker] 2026-08-27 16:08:27.936837 | debian-bookworm | ok 2026-08-27 16:08:27.943034 | 2026-08-27 16:08:27.943899 | TASK [validate-host : Write out all zuul information for each host] 2026-08-27 16:08:28.745863 | debian-bookworm -> localhost | changed 2026-08-27 16:08:28.755483 | 2026-08-27 16:08:28.755592 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-27 16:08:29.026115 | debian-bookworm | ok 2026-08-27 16:08:29.039572 | 2026-08-27 16:08:29.039694 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-27 16:08:31.541420 | debian-bookworm | changed: 2026-08-27 16:08:31.541766 | debian-bookworm | cd+++++++++ src/ 2026-08-27 16:08:31.541892 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-27 16:08:31.541921 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-27 16:08:31.541943 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-27 16:08:31.541999 | debian-bookworm | Containerfile 2026-08-27 16:08:31.542135 | debian-bookworm | Containerfile 2026-08-27 16:08:31.557156 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/91844556930945ab87467f846e51c824/work/logs" 2026-08-27 16:11:07.430597 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/91844556930945ab87467f846e51c824/work/artifacts" 2026-08-27 16:11:07.720868 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/91844556930945ab87467f846e51c824/work/docs" 2026-08-27 16:11:07.739467 | 2026-08-27 16:11:07.739789 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-27 16:11:08.620739 | debian-bookworm | changed: 2026-08-27 16:11:08.621164 | debian-bookworm | .d..t...... ./ 2026-08-27 16:11:08.621225 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-27 16:11:08.621268 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-27 16:11:08.621290 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-27 16:11:08.621312 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-27 16:11:08.621333 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-27 16:11:08.621385 | debian-bookworm | changed: All items complete 2026-08-27 16:11:08.621414 | 2026-08-27 16:11:09.216708 | debian-bookworm | changed: .d..t...... ./ 2026-08-27 16:11:09.826817 | debian-bookworm | changed: .d..t...... ./ 2026-08-27 16:11:09.851042 | 2026-08-27 16:11:09.851261 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-27 16:11:10.246594 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.021653 2026-08-27 16:11:10.552660 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.016139 2026-08-27 16:11:10.571368 | 2026-08-27 16:11:10.571500 | PLAY RECAP 2026-08-27 16:11:10.571557 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-27 16:11:10.571583 | 2026-08-27 16:11:10.753468 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-27 16:11:10.754442 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-27 16:11:11.631701 | 2026-08-27 16:11:11.631920 | PLAY [Base post] 2026-08-27 16:11:11.650534 | 2026-08-27 16:11:11.650719 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-27 16:11:12.536854 | debian-bookworm | changed 2026-08-27 16:11:12.546656 | 2026-08-27 16:11:12.546828 | PLAY RECAP 2026-08-27 16:11:12.546927 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-27 16:11:12.546993 | 2026-08-27 16:11:12.763596 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-27 16:11:12.765633 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-27 16:11:13.828368 | 2026-08-27 16:11:13.828561 | PLAY [Base post-logs] 2026-08-27 16:11:13.852027 | 2026-08-27 16:11:13.852197 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-27 16:11:14.484327 | localhost | changed 2026-08-27 16:11:14.502798 | 2026-08-27 16:11:14.503028 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-27 16:11:14.540188 | localhost | ok 2026-08-27 16:11:14.543568 | 2026-08-27 16:11:14.543895 | TASK [Set zuul-log-path fact] 2026-08-27 16:11:14.559862 | localhost | ok 2026-08-27 16:11:14.570045 | 2026-08-27 16:11:14.570313 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-27 16:11:14.606779 | localhost | ok 2026-08-27 16:11:14.610340 | 2026-08-27 16:11:14.610464 | TASK [upload-logs : Create log directories] 2026-08-27 16:11:15.214672 | localhost | changed 2026-08-27 16:11:15.220450 | 2026-08-27 16:11:15.222778 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-27 16:11:15.776777 | localhost -> localhost | ok: Runtime: 0:00:00.005823 2026-08-27 16:11:15.784601 | 2026-08-27 16:11:15.784827 | TASK [upload-logs : Upload logs to log server] 2026-08-27 16:11:16.435666 | localhost | Output suppressed because no_log was given 2026-08-27 16:11:16.438007 | 2026-08-27 16:11:16.438131 | LOOP [upload-logs : Compress console log and json output] 2026-08-27 16:11:16.499570 | localhost | skipping: Conditional result was False 2026-08-27 16:11:16.510072 | localhost | skipping: Conditional result was False 2026-08-27 16:11:16.516521 | 2026-08-27 16:11:16.516653 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-27 16:11:16.568724 | localhost | skipping: Conditional result was False 2026-08-27 16:11:16.569185 | 2026-08-27 16:11:16.577008 | localhost | skipping: Conditional result was False 2026-08-27 16:11:16.587689 | 2026-08-27 16:11:16.587960 | LOOP [upload-logs : Upload console log and json output]