2026-05-20 06:08:48.211169 | Job console starting 2026-05-20 06:08:48.282343 | Updating git repos 2026-05-20 06:08:48.401539 | Cloning repos into workspace 2026-05-20 06:08:48.555829 | Restoring repo states 2026-05-20 06:08:48.587850 | Merging changes 2026-05-20 06:08:49.513142 | Checking out repos 2026-05-20 06:08:49.597420 | Preparing playbooks 2026-05-20 06:08:50.973953 | Running Ansible setup 2026-05-20 06:08:56.250407 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-20 06:08:57.480779 | 2026-05-20 06:08:57.480924 | PLAY [Base pre] 2026-05-20 06:08:57.549170 | 2026-05-20 06:08:57.551519 | TASK [Setup log path fact] 2026-05-20 06:08:57.627886 | debian-bookworm | ok 2026-05-20 06:08:57.662576 | 2026-05-20 06:08:57.662737 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-20 06:08:57.711164 | debian-bookworm | ok 2026-05-20 06:08:57.733910 | 2026-05-20 06:08:57.734025 | TASK [emit-job-header : Print job information] 2026-05-20 06:08:57.772434 | # Job Information 2026-05-20 06:08:57.772574 | Ansible Version: 2.16.14 2026-05-20 06:08:57.772602 | Job: python-osism-test-setup 2026-05-20 06:08:57.772642 | Pipeline: check 2026-05-20 06:08:57.772661 | Executor: 521e9411259a 2026-05-20 06:08:57.772678 | Triggered by: https://github.com/osism/python-osism/pull/2296 2026-05-20 06:08:57.772696 | Event ID: 56e4dd60-5412-11f1-87df-7c9668516739 2026-05-20 06:08:57.776793 | 2026-05-20 06:08:57.776880 | LOOP [emit-job-header : Print node information] 2026-05-20 06:08:57.926873 | debian-bookworm | ok: 2026-05-20 06:08:57.927054 | debian-bookworm | # Node Information 2026-05-20 06:08:57.927086 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-20 06:08:57.927108 | debian-bookworm | Hostname: debian 2026-05-20 06:08:57.927126 | debian-bookworm | Username: zuul 2026-05-20 06:08:57.927143 | debian-bookworm | Distro: Debian 12.14 2026-05-20 06:08:57.927163 | debian-bookworm | Provider: regiocloud-a 2026-05-20 06:08:57.927181 | debian-bookworm | Region: 2026-05-20 06:08:57.927199 | debian-bookworm | Label: debian-bookworm 2026-05-20 06:08:57.927215 | debian-bookworm | Product Name: OpenStack Nova 2026-05-20 06:08:57.927232 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe46:b406 2026-05-20 06:08:57.954140 | 2026-05-20 06:08:57.954250 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-20 06:08:58.666752 | debian-bookworm -> localhost | changed 2026-05-20 06:08:58.672415 | 2026-05-20 06:08:58.672500 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-20 06:09:00.467357 | debian-bookworm -> localhost | changed 2026-05-20 06:09:00.476095 | 2026-05-20 06:09:00.476180 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-20 06:09:01.082104 | debian-bookworm -> localhost | ok 2026-05-20 06:09:01.094242 | 2026-05-20 06:09:01.094348 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-20 06:09:01.135199 | debian-bookworm | ok 2026-05-20 06:09:01.171204 | debian-bookworm | included: /var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-20 06:09:01.185741 | 2026-05-20 06:09:01.185846 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-20 06:09:02.136352 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-20 06:09:02.136526 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/work/cc8921d66baa48ba9cdf298455f63ad8_id_rsa 2026-05-20 06:09:02.136556 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/work/cc8921d66baa48ba9cdf298455f63ad8_id_rsa.pub 2026-05-20 06:09:02.136577 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-20 06:09:02.136596 | debian-bookworm -> localhost | SHA256:Vmr5O95PRVSsmHPxnIbJnSRJ7Msnh4HK2Hw9+US0Z8g zuul-build-sshkey 2026-05-20 06:09:02.136635 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-20 06:09:02.136664 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-20 06:09:02.136682 | debian-bookworm -> localhost | | o...+| 2026-05-20 06:09:02.136699 | debian-bookworm -> localhost | | +.+.| 2026-05-20 06:09:02.136716 | debian-bookworm -> localhost | | . ++B*=| 2026-05-20 06:09:02.136732 | debian-bookworm -> localhost | | + .+*EO=| 2026-05-20 06:09:02.136748 | debian-bookworm -> localhost | | S . ooB.o| 2026-05-20 06:09:02.136769 | debian-bookworm -> localhost | | + * . O = | 2026-05-20 06:09:02.136785 | debian-bookworm -> localhost | | o O | 2026-05-20 06:09:02.136801 | debian-bookworm -> localhost | | .o . . | 2026-05-20 06:09:02.136817 | debian-bookworm -> localhost | | .o.... | 2026-05-20 06:09:02.136833 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-20 06:09:02.136877 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.374330 2026-05-20 06:09:02.141967 | 2026-05-20 06:09:02.142046 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-20 06:09:02.158999 | debian-bookworm | ok 2026-05-20 06:09:02.167495 | debian-bookworm | included: /var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-20 06:09:02.174829 | 2026-05-20 06:09:02.174919 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-20 06:09:02.187557 | debian-bookworm | skipping: Conditional result was False 2026-05-20 06:09:02.192324 | 2026-05-20 06:09:02.192397 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-20 06:09:02.855807 | debian-bookworm | changed 2026-05-20 06:09:02.860088 | 2026-05-20 06:09:02.860166 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-20 06:09:03.132649 | debian-bookworm | ok 2026-05-20 06:09:03.140498 | 2026-05-20 06:09:03.140631 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-20 06:09:03.813315 | debian-bookworm | changed 2026-05-20 06:09:03.819872 | 2026-05-20 06:09:03.819976 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-20 06:09:04.490333 | debian-bookworm | changed 2026-05-20 06:09:04.495644 | 2026-05-20 06:09:04.495739 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-20 06:09:04.531642 | debian-bookworm | skipping: Conditional result was False 2026-05-20 06:09:04.537385 | 2026-05-20 06:09:04.537480 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-20 06:09:05.134974 | debian-bookworm -> localhost | changed 2026-05-20 06:09:05.151880 | 2026-05-20 06:09:05.151989 | TASK [add-build-sshkey : Add back temp key] 2026-05-20 06:09:05.549577 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/work/cc8921d66baa48ba9cdf298455f63ad8_id_rsa (zuul-build-sshkey) 2026-05-20 06:09:05.549816 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011273 2026-05-20 06:09:05.557337 | 2026-05-20 06:09:05.557445 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-20 06:09:06.035263 | debian-bookworm | ok 2026-05-20 06:09:06.040240 | 2026-05-20 06:09:06.040339 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-20 06:09:06.064274 | debian-bookworm | skipping: Conditional result was False 2026-05-20 06:09:06.076965 | 2026-05-20 06:09:06.077073 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-20 06:09:06.551694 | debian-bookworm | ok 2026-05-20 06:09:06.562651 | 2026-05-20 06:09:06.562764 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-20 06:09:06.591266 | debian-bookworm | ok 2026-05-20 06:09:06.596194 | 2026-05-20 06:09:06.596301 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-20 06:09:06.953653 | debian-bookworm -> localhost | ok 2026-05-20 06:09:06.959808 | 2026-05-20 06:09:06.959900 | TASK [validate-host : Collect information about the host] 2026-05-20 06:09:08.088763 | debian-bookworm | ok 2026-05-20 06:09:08.100036 | 2026-05-20 06:09:08.100144 | TASK [validate-host : Sanitize hostname] 2026-05-20 06:09:08.210323 | debian-bookworm | ok 2026-05-20 06:09:08.313839 | 2026-05-20 06:09:08.313990 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-20 06:09:09.422164 | debian-bookworm -> localhost | changed 2026-05-20 06:09:09.428350 | 2026-05-20 06:09:09.428484 | TASK [validate-host : Collect information about zuul worker] 2026-05-20 06:09:09.889303 | debian-bookworm | ok 2026-05-20 06:09:09.895403 | 2026-05-20 06:09:09.895543 | TASK [validate-host : Write out all zuul information for each host] 2026-05-20 06:09:10.776907 | debian-bookworm -> localhost | changed 2026-05-20 06:09:10.787026 | 2026-05-20 06:09:10.787149 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-20 06:09:11.076293 | debian-bookworm | ok 2026-05-20 06:09:11.084297 | 2026-05-20 06:09:11.084444 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-20 06:09:12.407093 | debian-bookworm | changed: 2026-05-20 06:09:12.407388 | debian-bookworm | cd+++++++++ src/ 2026-05-20 06:09:12.407426 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-20 06:09:12.407450 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-20 06:09:12.407471 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-20 06:09:12.407491 | debian-bookworm | Containerfile 2026-05-20 06:09:12.407661 | debian-bookworm | Containerfile 2026-05-20 06:09:12.428570 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/work/logs" 2026-05-20 06:13:13.446358 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/work/artifacts" 2026-05-20 06:13:13.697641 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/cc8921d66baa48ba9cdf298455f63ad8/work/docs" 2026-05-20 06:13:13.712372 | 2026-05-20 06:13:13.712512 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-20 06:13:14.515706 | debian-bookworm | changed: 2026-05-20 06:13:14.516258 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-20 06:13:14.516360 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-20 06:13:14.516427 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-20 06:13:14.516526 | debian-bookworm | changed: All items complete 2026-05-20 06:13:14.516605 | 2026-05-20 06:13:15.130037 | debian-bookworm | changed: .d..t...... ./ 2026-05-20 06:13:15.743499 | debian-bookworm | changed: .d..t...... ./ 2026-05-20 06:13:15.766561 | 2026-05-20 06:13:15.766747 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-20 06:13:16.294191 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.005648 2026-05-20 06:13:16.592957 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008598 2026-05-20 06:13:16.609809 | 2026-05-20 06:13:16.609944 | PLAY RECAP 2026-05-20 06:13:16.610019 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-20 06:13:16.610057 | 2026-05-20 06:13:16.759937 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-20 06:13:16.760797 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-20 06:13:17.502399 | 2026-05-20 06:13:17.502575 | PLAY [Base post] 2026-05-20 06:13:17.516624 | 2026-05-20 06:13:17.516780 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-20 06:13:18.315113 | debian-bookworm | changed 2026-05-20 06:13:18.321767 | 2026-05-20 06:13:18.321897 | PLAY RECAP 2026-05-20 06:13:18.321966 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-20 06:13:18.322029 | 2026-05-20 06:13:18.461672 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-20 06:13:18.463852 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-20 06:13:19.282481 | 2026-05-20 06:13:19.282688 | PLAY [Base post-logs] 2026-05-20 06:13:19.293717 | 2026-05-20 06:13:19.293864 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-20 06:13:19.909874 | localhost | changed 2026-05-20 06:13:19.920742 | 2026-05-20 06:13:19.920896 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-20 06:13:19.956756 | localhost | ok 2026-05-20 06:13:19.959912 | 2026-05-20 06:13:19.960010 | TASK [Set zuul-log-path fact] 2026-05-20 06:13:19.976285 | localhost | ok 2026-05-20 06:13:19.984796 | 2026-05-20 06:13:19.984909 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-20 06:13:20.020681 | localhost | ok 2026-05-20 06:13:20.024512 | 2026-05-20 06:13:20.024653 | TASK [upload-logs : Create log directories] 2026-05-20 06:13:20.541092 | localhost | changed 2026-05-20 06:13:20.544176 | 2026-05-20 06:13:20.544294 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-20 06:13:21.066528 | localhost -> localhost | ok: Runtime: 0:00:00.007972 2026-05-20 06:13:21.073381 | 2026-05-20 06:13:21.073548 | TASK [upload-logs : Upload logs to log server] 2026-05-20 06:13:21.721312 | localhost | Output suppressed because no_log was given 2026-05-20 06:13:21.723310 | 2026-05-20 06:13:21.723421 | LOOP [upload-logs : Compress console log and json output] 2026-05-20 06:13:21.779643 | localhost | skipping: Conditional result was False 2026-05-20 06:13:21.785813 | localhost | skipping: Conditional result was False 2026-05-20 06:13:21.790469 | 2026-05-20 06:13:21.790622 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-20 06:13:21.835823 | localhost | skipping: Conditional result was False 2026-05-20 06:13:21.836165 | 2026-05-20 06:13:21.844039 | localhost | skipping: Conditional result was False 2026-05-20 06:13:21.852339 | 2026-05-20 06:13:21.852521 | LOOP [upload-logs : Upload console log and json output]