2026-10-05 01:05:22.904489 | Job console starting 2026-10-05 01:05:22.931514 | Updating git repos 2026-10-05 01:05:22.973484 | Cloning repos into workspace 2026-10-05 01:05:23.016318 | Restoring repo states 2026-10-05 01:05:23.045983 | Merging changes 2026-10-05 01:05:23.789072 | Checking out repos 2026-10-05 01:05:24.000226 | Preparing playbooks 2026-10-05 01:05:25.453533 | Running Ansible setup 2026-10-05 01:05:29.669566 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-05 01:05:30.472172 | 2026-10-05 01:05:30.472293 | PLAY [Base pre] 2026-10-05 01:05:30.505738 | 2026-10-05 01:05:30.505860 | TASK [Setup log path fact] 2026-10-05 01:05:30.524097 | debian-bookworm | ok 2026-10-05 01:05:30.539918 | 2026-10-05 01:05:30.540039 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 01:05:30.571311 | debian-bookworm | ok 2026-10-05 01:05:30.580838 | 2026-10-05 01:05:30.580940 | TASK [emit-job-header : Print job information] 2026-10-05 01:05:30.620587 | # Job Information 2026-10-05 01:05:30.620745 | Ansible Version: 2.16.14 2026-10-05 01:05:30.620780 | Job: python-osism-test-setup 2026-10-05 01:05:30.620813 | Pipeline: check 2026-10-05 01:05:30.620837 | Executor: 521e9411259a 2026-10-05 01:05:30.620858 | Triggered by: https://github.com/osism/python-osism/pull/2753 2026-10-05 01:05:30.620881 | Event ID: bcadfc50-c058-11f1-8f02-125efa7c3726 2026-10-05 01:05:30.625657 | 2026-10-05 01:05:30.625754 | LOOP [emit-job-header : Print node information] 2026-10-05 01:05:30.733874 | debian-bookworm | ok: 2026-10-05 01:05:30.734033 | debian-bookworm | # Node Information 2026-10-05 01:05:30.734067 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-05 01:05:30.734093 | debian-bookworm | Hostname: debian 2026-10-05 01:05:30.734114 | debian-bookworm | Username: zuul 2026-10-05 01:05:30.734134 | debian-bookworm | Distro: Debian 12.15 2026-10-05 01:05:30.734158 | debian-bookworm | Provider: regiocloud-a 2026-10-05 01:05:30.734179 | debian-bookworm | Region: 2026-10-05 01:05:30.734201 | debian-bookworm | Label: debian-bookworm 2026-10-05 01:05:30.734220 | debian-bookworm | Product Name: OpenStack Nova 2026-10-05 01:05:30.734239 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe9b:e1a0 2026-10-05 01:05:30.751043 | 2026-10-05 01:05:30.751160 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-05 01:05:31.409226 | debian-bookworm -> localhost | changed 2026-10-05 01:05:31.417813 | 2026-10-05 01:05:31.417927 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-05 01:05:33.047227 | debian-bookworm -> localhost | changed 2026-10-05 01:05:33.058907 | 2026-10-05 01:05:33.058992 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-05 01:05:33.543782 | debian-bookworm -> localhost | ok 2026-10-05 01:05:33.555751 | 2026-10-05 01:05:33.556256 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-05 01:05:33.605743 | debian-bookworm | ok 2026-10-05 01:05:33.626045 | debian-bookworm | included: /var/lib/zuul/builds/5bd685458561430b972f564aac448908/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-05 01:05:33.632977 | 2026-10-05 01:05:33.633068 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-05 01:05:35.253987 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-05 01:05:35.254209 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5bd685458561430b972f564aac448908/work/5bd685458561430b972f564aac448908_id_rsa 2026-10-05 01:05:35.254251 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5bd685458561430b972f564aac448908/work/5bd685458561430b972f564aac448908_id_rsa.pub 2026-10-05 01:05:35.254279 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-05 01:05:35.254304 | debian-bookworm -> localhost | SHA256:FQzf55jQkH1Kicw/awOGUWvNV3YhTwMsGG8U5ij+nS4 zuul-build-sshkey 2026-10-05 01:05:35.254327 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-05 01:05:35.254356 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-05 01:05:35.254378 | debian-bookworm -> localhost | | .B*Bo+.+=| 2026-10-05 01:05:35.254400 | debian-bookworm -> localhost | | .o%X+.=oo| 2026-10-05 01:05:35.254421 | debian-bookworm -> localhost | | . +***oo. | 2026-10-05 01:05:35.254440 | debian-bookworm -> localhost | | . oo+.+* | 2026-10-05 01:05:35.254460 | debian-bookworm -> localhost | | .S. .oo. | 2026-10-05 01:05:35.254483 | debian-bookworm -> localhost | | . . = | 2026-10-05 01:05:35.254503 | debian-bookworm -> localhost | | . + . | 2026-10-05 01:05:35.254523 | debian-bookworm -> localhost | | E. | 2026-10-05 01:05:35.254543 | debian-bookworm -> localhost | | .. | 2026-10-05 01:05:35.254591 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-05 01:05:35.254643 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.810614 2026-10-05 01:05:35.262833 | 2026-10-05 01:05:35.262924 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-05 01:05:35.279915 | debian-bookworm | ok 2026-10-05 01:05:35.296821 | debian-bookworm | included: /var/lib/zuul/builds/5bd685458561430b972f564aac448908/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-05 01:05:35.306228 | 2026-10-05 01:05:35.306309 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-05 01:05:35.330477 | debian-bookworm | skipping: Conditional result was False 2026-10-05 01:05:35.336086 | 2026-10-05 01:05:35.336169 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-05 01:05:36.205937 | debian-bookworm | changed 2026-10-05 01:05:36.214544 | 2026-10-05 01:05:36.214671 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-05 01:05:36.566655 | debian-bookworm | ok 2026-10-05 01:05:36.571217 | 2026-10-05 01:05:36.571291 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-05 01:05:37.285406 | debian-bookworm | changed 2026-10-05 01:05:37.290902 | 2026-10-05 01:05:37.290986 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-05 01:05:37.955093 | debian-bookworm | changed 2026-10-05 01:05:37.960569 | 2026-10-05 01:05:37.960666 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-05 01:05:37.993687 | debian-bookworm | skipping: Conditional result was False 2026-10-05 01:05:37.999333 | 2026-10-05 01:05:37.999421 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-05 01:05:38.721045 | debian-bookworm -> localhost | changed 2026-10-05 01:05:38.729775 | 2026-10-05 01:05:38.729853 | TASK [add-build-sshkey : Add back temp key] 2026-10-05 01:05:39.057559 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5bd685458561430b972f564aac448908/work/5bd685458561430b972f564aac448908_id_rsa (zuul-build-sshkey) 2026-10-05 01:05:39.057737 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022395 2026-10-05 01:05:39.063781 | 2026-10-05 01:05:39.063854 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-05 01:05:39.492469 | debian-bookworm | ok 2026-10-05 01:05:39.497345 | 2026-10-05 01:05:39.497428 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-05 01:05:39.510778 | debian-bookworm | skipping: Conditional result was False 2026-10-05 01:05:39.522678 | 2026-10-05 01:05:39.522753 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-05 01:05:39.942766 | debian-bookworm | ok 2026-10-05 01:05:39.953152 | 2026-10-05 01:05:39.953250 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-05 01:05:39.999408 | debian-bookworm | ok 2026-10-05 01:05:40.004198 | 2026-10-05 01:05:40.004276 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-05 01:05:40.607245 | debian-bookworm -> localhost | ok 2026-10-05 01:05:40.614237 | 2026-10-05 01:05:40.614323 | TASK [validate-host : Collect information about the host] 2026-10-05 01:05:41.735632 | debian-bookworm | ok 2026-10-05 01:05:41.747672 | 2026-10-05 01:05:41.747757 | TASK [validate-host : Sanitize hostname] 2026-10-05 01:05:41.855592 | debian-bookworm | ok 2026-10-05 01:05:41.863573 | 2026-10-05 01:05:41.863653 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-05 01:05:42.623434 | debian-bookworm -> localhost | changed 2026-10-05 01:05:42.629035 | 2026-10-05 01:05:42.629122 | TASK [validate-host : Collect information about zuul worker] 2026-10-05 01:05:43.107040 | debian-bookworm | ok 2026-10-05 01:05:43.111518 | 2026-10-05 01:05:43.111618 | TASK [validate-host : Write out all zuul information for each host] 2026-10-05 01:05:43.552769 | debian-bookworm -> localhost | changed 2026-10-05 01:05:43.561602 | 2026-10-05 01:05:43.561687 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-05 01:05:43.874170 | debian-bookworm | ok 2026-10-05 01:05:43.889543 | 2026-10-05 01:05:43.889715 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-05 01:05:46.020359 | debian-bookworm | changed: 2026-10-05 01:05:46.020559 | debian-bookworm | cd+++++++++ src/ 2026-10-05 01:05:46.020600 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-05 01:05:46.020625 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-05 01:05:46.020647 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-10-05 01:05:46.020667 | debian-bookworm | Containerfile 2026-10-05 01:05:46.020796 | debian-bookworm | Containerfile 2026-10-05 01:05:46.038283 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/5bd685458561430b972f564aac448908/work/logs" 2026-10-05 01:08:31.450859 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5bd685458561430b972f564aac448908/work/artifacts" 2026-10-05 01:08:31.745684 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5bd685458561430b972f564aac448908/work/docs" 2026-10-05 01:08:31.760817 | 2026-10-05 01:08:31.760940 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-05 01:08:32.570599 | debian-bookworm | changed: 2026-10-05 01:08:32.570981 | debian-bookworm | .d..t...... ./ 2026-10-05 01:08:32.571071 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-05 01:08:32.571132 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-05 01:08:32.571183 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-05 01:08:32.571231 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-05 01:08:32.571277 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-05 01:08:32.571352 | debian-bookworm | changed: All items complete 2026-10-05 01:08:32.571470 | 2026-10-05 01:08:33.167752 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 01:08:33.764169 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 01:08:33.790827 | 2026-10-05 01:08:33.790961 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-05 01:08:34.126063 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.005518 2026-10-05 01:08:34.395433 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009840 2026-10-05 01:08:34.411172 | 2026-10-05 01:08:34.411274 | PLAY RECAP 2026-10-05 01:08:34.411327 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-05 01:08:34.411354 | 2026-10-05 01:08:34.535303 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-05 01:08:34.537328 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 01:08:35.282929 | 2026-10-05 01:08:35.283088 | PLAY [Base post] 2026-10-05 01:08:35.296187 | 2026-10-05 01:08:35.296314 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-05 01:08:36.136270 | debian-bookworm | changed 2026-10-05 01:08:36.146458 | 2026-10-05 01:08:36.146644 | PLAY RECAP 2026-10-05 01:08:36.146734 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-05 01:08:36.146868 | 2026-10-05 01:08:36.270950 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 01:08:36.271797 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-05 01:08:37.108350 | 2026-10-05 01:08:37.108509 | PLAY [Base post-logs] 2026-10-05 01:08:37.119305 | 2026-10-05 01:08:37.119438 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-05 01:08:37.614157 | localhost | changed 2026-10-05 01:08:37.630249 | 2026-10-05 01:08:37.630394 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-05 01:08:37.657341 | localhost | ok 2026-10-05 01:08:37.661652 | 2026-10-05 01:08:37.661770 | TASK [Set zuul-log-path fact] 2026-10-05 01:08:37.677544 | localhost | ok 2026-10-05 01:08:37.686828 | 2026-10-05 01:08:37.686932 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 01:08:37.712191 | localhost | ok 2026-10-05 01:08:37.717142 | 2026-10-05 01:08:37.717266 | TASK [upload-logs : Create log directories] 2026-10-05 01:08:38.222852 | localhost | changed 2026-10-05 01:08:38.227491 | 2026-10-05 01:08:38.227640 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-05 01:08:38.749134 | localhost -> localhost | ok: Runtime: 0:00:00.007763 2026-10-05 01:08:38.755576 | 2026-10-05 01:08:38.755843 | TASK [upload-logs : Upload logs to log server] 2026-10-05 01:08:39.341033 | localhost | Output suppressed because no_log was given 2026-10-05 01:08:39.343410 | 2026-10-05 01:08:39.343513 | LOOP [upload-logs : Compress console log and json output] 2026-10-05 01:08:39.389279 | localhost | skipping: Conditional result was False 2026-10-05 01:08:39.395590 | localhost | skipping: Conditional result was False 2026-10-05 01:08:39.399970 | 2026-10-05 01:08:39.400131 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-05 01:08:39.445344 | localhost | skipping: Conditional result was False 2026-10-05 01:08:39.445939 | 2026-10-05 01:08:39.449699 | localhost | skipping: Conditional result was False 2026-10-05 01:08:39.462376 | 2026-10-05 01:08:39.462573 | LOOP [upload-logs : Upload console log and json output]