2026-05-11 03:18:34.801616 | Job console starting 2026-05-11 03:18:34.809839 | Updating git repos 2026-05-11 03:18:34.855038 | Cloning repos into workspace 2026-05-11 03:18:35.019879 | Restoring repo states 2026-05-11 03:18:35.037513 | Merging changes 2026-05-11 03:18:35.037527 | Checking out repos 2026-05-11 03:18:35.214852 | Preparing playbooks 2026-05-11 03:18:37.106437 | Running Ansible setup 2026-05-11 03:18:43.168627 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 03:18:44.135339 | 2026-05-11 03:18:44.135466 | PLAY [Base pre] 2026-05-11 03:18:44.165622 | 2026-05-11 03:18:44.165748 | TASK [Setup log path fact] 2026-05-11 03:18:44.193212 | debian-bookworm | ok 2026-05-11 03:18:44.210619 | 2026-05-11 03:18:44.210741 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 03:18:44.258957 | debian-bookworm | ok 2026-05-11 03:18:44.268721 | 2026-05-11 03:18:44.268817 | TASK [emit-job-header : Print job information] 2026-05-11 03:18:44.344516 | # Job Information 2026-05-11 03:18:44.344816 | Ansible Version: 2.16.14 2026-05-11 03:18:44.344847 | Job: python-osism-test-setup 2026-05-11 03:18:44.344875 | Pipeline: periodic-daily 2026-05-11 03:18:44.344894 | Executor: 521e9411259a 2026-05-11 03:18:44.344912 | Triggered by: https://github.com/osism/python-osism 2026-05-11 03:18:44.344930 | Event ID: ae8509a04d73461daa0d5ba239db462b 2026-05-11 03:18:44.349215 | 2026-05-11 03:18:44.349309 | LOOP [emit-job-header : Print node information] 2026-05-11 03:18:44.450748 | debian-bookworm | ok: 2026-05-11 03:18:44.451022 | debian-bookworm | # Node Information 2026-05-11 03:18:44.451058 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-11 03:18:44.451079 | debian-bookworm | Hostname: debian 2026-05-11 03:18:44.451097 | debian-bookworm | Username: zuul 2026-05-11 03:18:44.451113 | debian-bookworm | Distro: Debian 12.13 2026-05-11 03:18:44.451132 | debian-bookworm | Provider: regiocloud-a 2026-05-11 03:18:44.451149 | debian-bookworm | Region: 2026-05-11 03:18:44.451166 | debian-bookworm | Label: debian-bookworm 2026-05-11 03:18:44.451206 | debian-bookworm | Product Name: OpenStack Nova 2026-05-11 03:18:44.451223 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe80:f4b0 2026-05-11 03:18:44.463358 | 2026-05-11 03:18:44.463463 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 03:18:45.385314 | debian-bookworm -> localhost | changed 2026-05-11 03:18:45.393896 | 2026-05-11 03:18:45.394001 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 03:18:46.938143 | debian-bookworm -> localhost | changed 2026-05-11 03:18:46.952104 | 2026-05-11 03:18:46.952241 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 03:18:47.505660 | debian-bookworm -> localhost | ok 2026-05-11 03:18:47.513099 | 2026-05-11 03:18:47.513256 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 03:18:47.546270 | debian-bookworm | ok 2026-05-11 03:18:47.568437 | debian-bookworm | included: /var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 03:18:47.577664 | 2026-05-11 03:18:47.577783 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 03:18:49.779315 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-11 03:18:49.779580 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/work/5a87300aad754f57bdc2094689defc74_id_rsa 2026-05-11 03:18:49.779622 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/work/5a87300aad754f57bdc2094689defc74_id_rsa.pub 2026-05-11 03:18:49.779649 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-11 03:18:49.779673 | debian-bookworm -> localhost | SHA256:TmTFGlE8BTbKcwS/wxmFhLUFKJaFSt3dYcSvALiBASQ zuul-build-sshkey 2026-05-11 03:18:49.779696 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-11 03:18:49.779726 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-11 03:18:49.779748 | debian-bookworm -> localhost | |E.o..+ *+%@OB. | 2026-05-11 03:18:49.779769 | debian-bookworm -> localhost | | . o O.****o | 2026-05-11 03:18:49.779789 | debian-bookworm -> localhost | | . o +*+=. . | 2026-05-11 03:18:49.779809 | debian-bookworm -> localhost | | . .o.+.+ . | 2026-05-11 03:18:49.779828 | debian-bookworm -> localhost | | S =. . | 2026-05-11 03:18:49.779857 | debian-bookworm -> localhost | | o .. | 2026-05-11 03:18:49.779918 | debian-bookworm -> localhost | | . | 2026-05-11 03:18:49.779943 | debian-bookworm -> localhost | | | 2026-05-11 03:18:49.779963 | debian-bookworm -> localhost | | | 2026-05-11 03:18:49.779983 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-11 03:18:49.780048 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.382607 2026-05-11 03:18:49.787979 | 2026-05-11 03:18:49.788088 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 03:18:49.831288 | debian-bookworm | ok 2026-05-11 03:18:49.857601 | debian-bookworm | included: /var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 03:18:49.887597 | 2026-05-11 03:18:49.887722 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 03:18:49.926192 | debian-bookworm | skipping: Conditional result was False 2026-05-11 03:18:49.934374 | 2026-05-11 03:18:49.934485 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 03:18:50.626482 | debian-bookworm | changed 2026-05-11 03:18:50.631126 | 2026-05-11 03:18:50.631213 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 03:18:50.863303 | debian-bookworm | ok 2026-05-11 03:18:50.867691 | 2026-05-11 03:18:50.867768 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 03:18:51.669244 | debian-bookworm | changed 2026-05-11 03:18:51.680830 | 2026-05-11 03:18:51.680938 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 03:18:52.474097 | debian-bookworm | changed 2026-05-11 03:18:52.492083 | 2026-05-11 03:18:52.492204 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 03:18:52.534067 | debian-bookworm | skipping: Conditional result was False 2026-05-11 03:18:52.540907 | 2026-05-11 03:18:52.541018 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 03:18:53.496012 | debian-bookworm -> localhost | changed 2026-05-11 03:18:53.508631 | 2026-05-11 03:18:53.508741 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 03:18:54.139886 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/work/5a87300aad754f57bdc2094689defc74_id_rsa (zuul-build-sshkey) 2026-05-11 03:18:54.140109 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029203 2026-05-11 03:18:54.147919 | 2026-05-11 03:18:54.148017 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 03:18:54.711556 | debian-bookworm | ok 2026-05-11 03:18:54.716440 | 2026-05-11 03:18:54.718961 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 03:18:54.773692 | debian-bookworm | skipping: Conditional result was False 2026-05-11 03:18:54.784014 | 2026-05-11 03:18:54.784124 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-11 03:18:55.345146 | debian-bookworm | ok 2026-05-11 03:18:55.360873 | 2026-05-11 03:18:55.361020 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-11 03:18:55.400118 | debian-bookworm | ok 2026-05-11 03:18:55.405317 | 2026-05-11 03:18:55.405407 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-11 03:18:55.769782 | debian-bookworm -> localhost | ok 2026-05-11 03:18:55.776145 | 2026-05-11 03:18:55.776270 | TASK [validate-host : Collect information about the host] 2026-05-11 03:18:56.902515 | debian-bookworm | ok 2026-05-11 03:18:56.920856 | 2026-05-11 03:18:56.920969 | TASK [validate-host : Sanitize hostname] 2026-05-11 03:18:57.065284 | debian-bookworm | ok 2026-05-11 03:18:57.108420 | 2026-05-11 03:18:57.108533 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-11 03:18:57.770330 | debian-bookworm -> localhost | changed 2026-05-11 03:18:57.778090 | 2026-05-11 03:18:57.778258 | TASK [validate-host : Collect information about zuul worker] 2026-05-11 03:18:58.255351 | debian-bookworm | ok 2026-05-11 03:18:58.264593 | 2026-05-11 03:18:58.264702 | TASK [validate-host : Write out all zuul information for each host] 2026-05-11 03:18:59.101478 | debian-bookworm -> localhost | changed 2026-05-11 03:18:59.119802 | 2026-05-11 03:18:59.119946 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-11 03:18:59.379437 | debian-bookworm | ok 2026-05-11 03:18:59.384898 | 2026-05-11 03:18:59.384986 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-11 03:19:00.468106 | debian-bookworm | changed: 2026-05-11 03:19:00.468304 | debian-bookworm | cd+++++++++ src/ 2026-05-11 03:19:00.468338 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-11 03:19:00.468414 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-11 03:19:00.468440 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-11 03:19:00.468461 | debian-bookworm | Containerfile 2026-05-11 03:19:00.468594 | debian-bookworm | Containerfile 2026-05-11 03:19:00.511799 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/work/logs" 2026-05-11 03:22:28.363429 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/work/artifacts" 2026-05-11 03:22:28.651446 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5a87300aad754f57bdc2094689defc74/work/docs" 2026-05-11 03:22:28.676908 | 2026-05-11 03:22:28.677118 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 03:22:29.532080 | debian-bookworm | changed: 2026-05-11 03:22:29.532407 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-11 03:22:29.532462 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-11 03:22:29.532511 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-11 03:22:29.532576 | debian-bookworm | changed: All items complete 2026-05-11 03:22:29.532612 | 2026-05-11 03:22:30.169354 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 03:22:30.782716 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 03:22:30.811518 | 2026-05-11 03:22:30.811737 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 03:22:30.852685 | debian-bookworm | skipping: Conditional result was False 2026-05-11 03:22:30.856421 | debian-bookworm | skipping: Conditional result was False 2026-05-11 03:22:30.880712 | 2026-05-11 03:22:30.880921 | PLAY RECAP 2026-05-11 03:22:30.881032 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-11 03:22:30.881085 | 2026-05-11 03:22:31.042431 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 03:22:31.044600 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 03:22:31.940965 | 2026-05-11 03:22:31.941210 | PLAY [Base post] 2026-05-11 03:22:31.960431 | 2026-05-11 03:22:31.960640 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 03:22:32.839028 | debian-bookworm | changed 2026-05-11 03:22:32.845943 | 2026-05-11 03:22:32.846067 | PLAY RECAP 2026-05-11 03:22:32.846137 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 03:22:32.846203 | 2026-05-11 03:22:33.018399 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 03:22:33.020591 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 03:22:33.937928 | 2026-05-11 03:22:33.938121 | PLAY [Base post-logs] 2026-05-11 03:22:33.949451 | 2026-05-11 03:22:33.949614 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 03:22:34.485810 | localhost | changed 2026-05-11 03:22:34.502695 | 2026-05-11 03:22:34.503109 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 03:22:34.543975 | localhost | ok 2026-05-11 03:22:34.551072 | 2026-05-11 03:22:34.551400 | TASK [Set zuul-log-path fact] 2026-05-11 03:22:34.575230 | localhost | ok 2026-05-11 03:22:34.595307 | 2026-05-11 03:22:34.595549 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 03:22:34.635433 | localhost | ok 2026-05-11 03:22:34.639009 | 2026-05-11 03:22:34.639128 | TASK [upload-logs : Create log directories] 2026-05-11 03:22:35.227106 | localhost | changed 2026-05-11 03:22:35.230184 | 2026-05-11 03:22:35.230290 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 03:22:35.768642 | localhost -> localhost | ok: Runtime: 0:00:00.008070 2026-05-11 03:22:35.780776 | 2026-05-11 03:22:35.781002 | TASK [upload-logs : Upload logs to log server] 2026-05-11 03:22:36.385557 | localhost | Output suppressed because no_log was given 2026-05-11 03:22:36.387649 | 2026-05-11 03:22:36.387762 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 03:22:36.446944 | localhost | skipping: Conditional result was False 2026-05-11 03:22:36.451864 | localhost | skipping: Conditional result was False 2026-05-11 03:22:36.458999 | 2026-05-11 03:22:36.459187 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 03:22:36.507344 | localhost | skipping: Conditional result was False 2026-05-11 03:22:36.508076 | 2026-05-11 03:22:36.511419 | localhost | skipping: Conditional result was False 2026-05-11 03:22:36.522604 | 2026-05-11 03:22:36.522771 | LOOP [upload-logs : Upload console log and json output]