2026-09-02 19:34:41.457308 | Job console starting 2026-09-02 19:34:41.471003 | Updating git repos 2026-09-02 19:34:41.542977 | Cloning repos into workspace 2026-09-02 19:34:41.611613 | Restoring repo states 2026-09-02 19:34:41.640568 | Merging changes 2026-09-02 19:34:42.723062 | Checking out repos 2026-09-02 19:34:42.774190 | Preparing playbooks 2026-09-02 19:34:43.699052 | Running Ansible setup 2026-09-02 19:34:48.322469 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 19:34:49.230295 | 2026-09-02 19:34:49.230417 | PLAY [Base pre] 2026-09-02 19:34:49.246729 | 2026-09-02 19:34:49.246908 | TASK [Setup log path fact] 2026-09-02 19:34:49.265543 | debian-bookworm | ok 2026-09-02 19:34:49.283271 | 2026-09-02 19:34:49.283394 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 19:34:49.310783 | debian-bookworm | ok 2026-09-02 19:34:49.324684 | 2026-09-02 19:34:49.324817 | TASK [emit-job-header : Print job information] 2026-09-02 19:34:49.367177 | # Job Information 2026-09-02 19:34:49.367335 | Ansible Version: 2.16.14 2026-09-02 19:34:49.367372 | Job: python-osism-test-setup 2026-09-02 19:34:49.367406 | Pipeline: check 2026-09-02 19:34:49.367430 | Executor: 521e9411259a 2026-09-02 19:34:49.367451 | Triggered by: https://github.com/osism/python-osism/pull/2663 2026-09-02 19:34:49.367474 | Event ID: 96060240-a704-11f1-9f37-2aa50ca28a24 2026-09-02 19:34:49.372496 | 2026-09-02 19:34:49.372661 | LOOP [emit-job-header : Print node information] 2026-09-02 19:34:49.477467 | debian-bookworm | ok: 2026-09-02 19:34:49.477638 | debian-bookworm | # Node Information 2026-09-02 19:34:49.477673 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-02 19:34:49.477698 | debian-bookworm | Hostname: debian 2026-09-02 19:34:49.477720 | debian-bookworm | Username: zuul 2026-09-02 19:34:49.477740 | debian-bookworm | Distro: Debian 12.15 2026-09-02 19:34:49.477765 | debian-bookworm | Provider: regiocloud-a 2026-09-02 19:34:49.477787 | debian-bookworm | Region: 2026-09-02 19:34:49.477832 | debian-bookworm | Label: debian-bookworm 2026-09-02 19:34:49.477857 | debian-bookworm | Product Name: OpenStack Nova 2026-09-02 19:34:49.477877 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe28:1c47 2026-09-02 19:34:49.494428 | 2026-09-02 19:34:49.494528 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-02 19:34:49.999667 | debian-bookworm -> localhost | changed 2026-09-02 19:34:50.007391 | 2026-09-02 19:34:50.007497 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-02 19:34:51.046234 | debian-bookworm -> localhost | changed 2026-09-02 19:34:51.059674 | 2026-09-02 19:34:51.059776 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-02 19:34:51.357716 | debian-bookworm -> localhost | ok 2026-09-02 19:34:51.362610 | 2026-09-02 19:34:51.362690 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-02 19:34:51.379945 | debian-bookworm | ok 2026-09-02 19:34:51.392365 | debian-bookworm | included: /var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-02 19:34:51.397188 | 2026-09-02 19:34:51.397260 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-02 19:34:53.075704 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-02 19:34:53.076167 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/work/ecf917dd66c04ed7a668e046e2fd65f3_id_rsa 2026-09-02 19:34:53.076278 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/work/ecf917dd66c04ed7a668e046e2fd65f3_id_rsa.pub 2026-09-02 19:34:53.076355 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-02 19:34:53.076427 | debian-bookworm -> localhost | SHA256:Kz773HwYt6CxPLLjMVK7qBaSXW/QxcBVotwYul6IPGk zuul-build-sshkey 2026-09-02 19:34:53.076493 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-02 19:34:53.076573 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-02 19:34:53.076637 | debian-bookworm -> localhost | | .o+o.. | 2026-09-02 19:34:53.076697 | debian-bookworm -> localhost | | o.=o. | 2026-09-02 19:34:53.076753 | debian-bookworm -> localhost | | ..+.. | 2026-09-02 19:34:53.076824 | debian-bookworm -> localhost | | . ooo. | 2026-09-02 19:34:53.076885 | debian-bookworm -> localhost | | oE.ooo S | 2026-09-02 19:34:53.076953 | debian-bookworm -> localhost | |o.oo oo...o . | 2026-09-02 19:34:53.077009 | debian-bookworm -> localhost | | . .o.=..+ = . | 2026-09-02 19:34:53.077064 | debian-bookworm -> localhost | | . ++*=o. o | 2026-09-02 19:34:53.077123 | debian-bookworm -> localhost | | .....*Bo.o. | 2026-09-02 19:34:53.077181 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-02 19:34:53.077311 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.194372 2026-09-02 19:34:53.092098 | 2026-09-02 19:34:53.092235 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-02 19:34:53.114555 | debian-bookworm | ok 2026-09-02 19:34:53.126983 | debian-bookworm | included: /var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-02 19:34:53.134017 | 2026-09-02 19:34:53.134087 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-02 19:34:53.146376 | debian-bookworm | skipping: Conditional result was False 2026-09-02 19:34:53.155311 | 2026-09-02 19:34:53.155400 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-02 19:34:53.852407 | debian-bookworm | changed 2026-09-02 19:34:53.861980 | 2026-09-02 19:34:53.862115 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-02 19:34:54.141759 | debian-bookworm | ok 2026-09-02 19:34:54.153607 | 2026-09-02 19:34:54.153768 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-02 19:34:54.938361 | debian-bookworm | changed 2026-09-02 19:34:54.951491 | 2026-09-02 19:34:54.951596 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-02 19:34:55.704391 | debian-bookworm | changed 2026-09-02 19:34:55.713349 | 2026-09-02 19:34:55.713443 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-02 19:34:55.726606 | debian-bookworm | skipping: Conditional result was False 2026-09-02 19:34:55.733305 | 2026-09-02 19:34:55.733408 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-02 19:34:56.324276 | debian-bookworm -> localhost | changed 2026-09-02 19:34:56.337443 | 2026-09-02 19:34:56.337544 | TASK [add-build-sshkey : Add back temp key] 2026-09-02 19:34:56.865590 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/work/ecf917dd66c04ed7a668e046e2fd65f3_id_rsa (zuul-build-sshkey) 2026-09-02 19:34:56.865790 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.026682 2026-09-02 19:34:56.873111 | 2026-09-02 19:34:56.873205 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-02 19:34:57.462174 | debian-bookworm | ok 2026-09-02 19:34:57.473333 | 2026-09-02 19:34:57.473437 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-02 19:34:57.497013 | debian-bookworm | skipping: Conditional result was False 2026-09-02 19:34:57.508493 | 2026-09-02 19:34:57.508597 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-02 19:34:58.106717 | debian-bookworm | ok 2026-09-02 19:34:58.119757 | 2026-09-02 19:34:58.119897 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-02 19:34:58.147465 | debian-bookworm | ok 2026-09-02 19:34:58.151576 | 2026-09-02 19:34:58.151655 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-02 19:34:58.464261 | debian-bookworm -> localhost | ok 2026-09-02 19:34:58.470915 | 2026-09-02 19:34:58.471009 | TASK [validate-host : Collect information about the host] 2026-09-02 19:34:59.934826 | debian-bookworm | ok 2026-09-02 19:34:59.948298 | 2026-09-02 19:34:59.948399 | TASK [validate-host : Sanitize hostname] 2026-09-02 19:35:00.091451 | debian-bookworm | ok 2026-09-02 19:35:00.096227 | 2026-09-02 19:35:00.096325 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-02 19:35:00.576353 | debian-bookworm -> localhost | changed 2026-09-02 19:35:00.580855 | 2026-09-02 19:35:00.580934 | TASK [validate-host : Collect information about zuul worker] 2026-09-02 19:35:00.992999 | debian-bookworm | ok 2026-09-02 19:35:00.997814 | 2026-09-02 19:35:00.997901 | TASK [validate-host : Write out all zuul information for each host] 2026-09-02 19:35:01.600016 | debian-bookworm -> localhost | changed 2026-09-02 19:35:01.609430 | 2026-09-02 19:35:01.609511 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-02 19:35:01.904105 | debian-bookworm | ok 2026-09-02 19:35:01.917211 | 2026-09-02 19:35:01.917317 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-02 19:35:03.997865 | debian-bookworm | changed: 2026-09-02 19:35:03.998010 | debian-bookworm | cd+++++++++ src/ 2026-09-02 19:35:03.998039 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-02 19:35:03.998060 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-02 19:35:03.998078 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-02 19:35:03.998095 | debian-bookworm | Containerfile 2026-09-02 19:35:03.998201 | debian-bookworm | Containerfile 2026-09-02 19:35:04.022544 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/work/logs" 2026-09-02 19:37:44.557912 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/work/artifacts" 2026-09-02 19:37:44.830780 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ecf917dd66c04ed7a668e046e2fd65f3/work/docs" 2026-09-02 19:37:44.847391 | 2026-09-02 19:37:44.847538 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-02 19:37:45.621701 | debian-bookworm | changed: 2026-09-02 19:37:45.621928 | debian-bookworm | .d..t...... ./ 2026-09-02 19:37:45.621963 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-02 19:37:45.621987 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-02 19:37:45.622009 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-02 19:37:45.622030 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-02 19:37:45.622050 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-02 19:37:45.622090 | debian-bookworm | changed: All items complete 2026-09-02 19:37:45.622113 | 2026-09-02 19:37:46.228076 | debian-bookworm | changed: .d..t...... ./ 2026-09-02 19:37:46.779049 | debian-bookworm | changed: .d..t...... ./ 2026-09-02 19:37:46.804308 | 2026-09-02 19:37:46.804438 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-02 19:37:47.179967 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.012873 2026-09-02 19:37:47.461064 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006594 2026-09-02 19:37:47.471980 | 2026-09-02 19:37:47.472069 | PLAY RECAP 2026-09-02 19:37:47.472120 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-02 19:37:47.472145 | 2026-09-02 19:37:47.609183 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-02 19:37:47.611395 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 19:37:48.387186 | 2026-09-02 19:37:48.387335 | PLAY [Base post] 2026-09-02 19:37:48.400578 | 2026-09-02 19:37:48.400740 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-02 19:37:49.221428 | debian-bookworm | changed 2026-09-02 19:37:49.227730 | 2026-09-02 19:37:49.227843 | PLAY RECAP 2026-09-02 19:37:49.227904 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 19:37:49.227965 | 2026-09-02 19:37:49.352863 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 19:37:49.353803 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-02 19:37:50.128581 | 2026-09-02 19:37:50.128775 | PLAY [Base post-logs] 2026-09-02 19:37:50.139107 | 2026-09-02 19:37:50.139239 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-02 19:37:50.610521 | localhost | changed 2026-09-02 19:37:50.620895 | 2026-09-02 19:37:50.621041 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-02 19:37:50.646164 | localhost | ok 2026-09-02 19:37:50.649277 | 2026-09-02 19:37:50.649375 | TASK [Set zuul-log-path fact] 2026-09-02 19:37:50.663942 | localhost | ok 2026-09-02 19:37:50.671847 | 2026-09-02 19:37:50.671961 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 19:37:50.696311 | localhost | ok 2026-09-02 19:37:50.699558 | 2026-09-02 19:37:50.699684 | TASK [upload-logs : Create log directories] 2026-09-02 19:37:51.186410 | localhost | changed 2026-09-02 19:37:51.191330 | 2026-09-02 19:37:51.191485 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-02 19:37:51.671044 | localhost -> localhost | ok: Runtime: 0:00:00.004103 2026-09-02 19:37:51.675097 | 2026-09-02 19:37:51.675215 | TASK [upload-logs : Upload logs to log server] 2026-09-02 19:37:52.271870 | localhost | Output suppressed because no_log was given 2026-09-02 19:37:52.274149 | 2026-09-02 19:37:52.274279 | LOOP [upload-logs : Compress console log and json output] 2026-09-02 19:37:52.319567 | localhost | skipping: Conditional result was False 2026-09-02 19:37:52.325793 | localhost | skipping: Conditional result was False 2026-09-02 19:37:52.333622 | 2026-09-02 19:37:52.333797 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-02 19:37:52.382094 | localhost | skipping: Conditional result was False 2026-09-02 19:37:52.382351 | 2026-09-02 19:37:52.386999 | localhost | skipping: Conditional result was False 2026-09-02 19:37:52.392050 | 2026-09-02 19:37:52.392157 | LOOP [upload-logs : Upload console log and json output]