2026-08-06 12:19:32.705391 | Job console starting 2026-08-06 12:19:32.744286 | Updating git repos 2026-08-06 12:19:33.380986 | Cloning repos into workspace 2026-08-06 12:19:33.483894 | Restoring repo states 2026-08-06 12:19:33.501773 | Merging changes 2026-08-06 12:19:34.408751 | Checking out repos 2026-08-06 12:19:34.510028 | Preparing playbooks 2026-08-06 12:19:36.760598 | Running Ansible setup 2026-08-06 12:19:42.491860 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-06 12:19:43.937909 | 2026-08-06 12:19:43.938031 | PLAY [Base pre] 2026-08-06 12:19:43.952258 | 2026-08-06 12:19:43.952353 | TASK [Setup log path fact] 2026-08-06 12:19:43.981197 | debian-bookworm | ok 2026-08-06 12:19:44.001881 | 2026-08-06 12:19:44.001999 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-06 12:19:44.028628 | debian-bookworm | ok 2026-08-06 12:19:44.036420 | 2026-08-06 12:19:44.036526 | TASK [emit-job-header : Print job information] 2026-08-06 12:19:44.113772 | # Job Information 2026-08-06 12:19:44.113917 | Ansible Version: 2.16.14 2026-08-06 12:19:44.113946 | Job: python-osism-test-setup 2026-08-06 12:19:44.114024 | Pipeline: check 2026-08-06 12:19:44.114049 | Executor: 521e9411259a 2026-08-06 12:19:44.114067 | Triggered by: https://github.com/osism/python-osism/pull/2565 2026-08-06 12:19:44.114085 | Event ID: 0cc8aec0-9191-11f1-8e88-b0e303ab6f46 2026-08-06 12:19:44.118435 | 2026-08-06 12:19:44.118529 | LOOP [emit-job-header : Print node information] 2026-08-06 12:19:44.332554 | debian-bookworm | ok: 2026-08-06 12:19:44.332702 | debian-bookworm | # Node Information 2026-08-06 12:19:44.332730 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-06 12:19:44.332750 | debian-bookworm | Hostname: debian 2026-08-06 12:19:44.332768 | debian-bookworm | Username: zuul 2026-08-06 12:19:44.332784 | debian-bookworm | Distro: Debian 12.15 2026-08-06 12:19:44.332804 | debian-bookworm | Provider: regiocloud-a 2026-08-06 12:19:44.332821 | debian-bookworm | Region: 2026-08-06 12:19:44.332839 | debian-bookworm | Label: debian-bookworm 2026-08-06 12:19:44.332855 | debian-bookworm | Product Name: OpenStack Nova 2026-08-06 12:19:44.332871 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe51:f1f4 2026-08-06 12:19:44.340996 | 2026-08-06 12:19:44.341097 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-06 12:19:45.524465 | debian-bookworm -> localhost | changed 2026-08-06 12:19:45.530361 | 2026-08-06 12:19:45.530447 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-06 12:19:48.365302 | debian-bookworm -> localhost | changed 2026-08-06 12:19:48.376628 | 2026-08-06 12:19:48.376745 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-06 12:19:49.040708 | debian-bookworm -> localhost | ok 2026-08-06 12:19:49.046143 | 2026-08-06 12:19:49.046281 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-06 12:19:49.077738 | debian-bookworm | ok 2026-08-06 12:19:49.119202 | debian-bookworm | included: /var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-06 12:19:49.136574 | 2026-08-06 12:19:49.136668 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-06 12:19:52.265454 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-06 12:19:52.265701 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/work/2c1fd5af4c80409eb845b28ba803c43c_id_rsa 2026-08-06 12:19:52.265741 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/work/2c1fd5af4c80409eb845b28ba803c43c_id_rsa.pub 2026-08-06 12:19:52.265770 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-06 12:19:52.265798 | debian-bookworm -> localhost | SHA256:G/B/TsC1QnxIgv2FidLFOw4nOkc44CQ2la+UXAEmnfE zuul-build-sshkey 2026-08-06 12:19:52.265822 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-06 12:19:52.265856 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-06 12:19:52.265879 | debian-bookworm -> localhost | | .o==.=.+oo | 2026-08-06 12:19:52.265901 | debian-bookworm -> localhost | | +o*.+ +++.. | 2026-08-06 12:19:52.265921 | debian-bookworm -> localhost | | . * =Eo .+oo | 2026-08-06 12:19:52.265941 | debian-bookworm -> localhost | | = +o+o=o . | 2026-08-06 12:19:52.265961 | debian-bookworm -> localhost | | . . +S=+.. | 2026-08-06 12:19:52.265988 | debian-bookworm -> localhost | | . o .+.o | 2026-08-06 12:19:52.266009 | debian-bookworm -> localhost | | o. . o | 2026-08-06 12:19:52.266029 | debian-bookworm -> localhost | | + | 2026-08-06 12:19:52.266050 | debian-bookworm -> localhost | | . | 2026-08-06 12:19:52.266070 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-06 12:19:52.266126 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.885486 2026-08-06 12:19:52.272673 | 2026-08-06 12:19:52.272770 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-06 12:19:52.292700 | debian-bookworm | ok 2026-08-06 12:19:52.307443 | debian-bookworm | included: /var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-06 12:19:52.314078 | 2026-08-06 12:19:52.314158 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-06 12:19:52.338153 | debian-bookworm | skipping: Conditional result was False 2026-08-06 12:19:52.343862 | 2026-08-06 12:19:52.343975 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-06 12:19:53.115204 | debian-bookworm | changed 2026-08-06 12:19:53.120495 | 2026-08-06 12:19:53.120583 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-06 12:19:53.389920 | debian-bookworm | ok 2026-08-06 12:19:53.407807 | 2026-08-06 12:19:53.407927 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-06 12:19:54.094666 | debian-bookworm | changed 2026-08-06 12:19:54.102137 | 2026-08-06 12:19:54.102240 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-06 12:19:54.794349 | debian-bookworm | changed 2026-08-06 12:19:54.799414 | 2026-08-06 12:19:54.799518 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-06 12:19:54.863067 | debian-bookworm | skipping: Conditional result was False 2026-08-06 12:19:54.868130 | 2026-08-06 12:19:54.868207 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-06 12:19:55.781596 | debian-bookworm -> localhost | changed 2026-08-06 12:19:55.791965 | 2026-08-06 12:19:55.792060 | TASK [add-build-sshkey : Add back temp key] 2026-08-06 12:19:56.607952 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/work/2c1fd5af4c80409eb845b28ba803c43c_id_rsa (zuul-build-sshkey) 2026-08-06 12:19:56.608180 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017246 2026-08-06 12:19:56.615542 | 2026-08-06 12:19:56.615656 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-06 12:19:57.123312 | debian-bookworm | ok 2026-08-06 12:19:57.128292 | 2026-08-06 12:19:57.128380 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-06 12:19:57.151766 | debian-bookworm | skipping: Conditional result was False 2026-08-06 12:19:57.161548 | 2026-08-06 12:19:57.161636 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-06 12:19:57.618794 | debian-bookworm | ok 2026-08-06 12:19:57.653993 | 2026-08-06 12:19:57.654110 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-06 12:19:57.712431 | debian-bookworm | ok 2026-08-06 12:19:57.717538 | 2026-08-06 12:19:57.727001 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-06 12:19:58.310245 | debian-bookworm -> localhost | ok 2026-08-06 12:19:58.317667 | 2026-08-06 12:19:58.317765 | TASK [validate-host : Collect information about the host] 2026-08-06 12:19:59.642169 | debian-bookworm | ok 2026-08-06 12:19:59.671708 | 2026-08-06 12:19:59.671821 | TASK [validate-host : Sanitize hostname] 2026-08-06 12:19:59.824681 | debian-bookworm | ok 2026-08-06 12:19:59.829959 | 2026-08-06 12:19:59.830053 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-06 12:20:00.682592 | debian-bookworm -> localhost | changed 2026-08-06 12:20:00.688961 | 2026-08-06 12:20:00.689075 | TASK [validate-host : Collect information about zuul worker] 2026-08-06 12:20:01.173227 | debian-bookworm | ok 2026-08-06 12:20:01.177928 | 2026-08-06 12:20:01.178021 | TASK [validate-host : Write out all zuul information for each host] 2026-08-06 12:20:01.969433 | debian-bookworm -> localhost | changed 2026-08-06 12:20:01.979180 | 2026-08-06 12:20:01.979274 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-06 12:20:02.268325 | debian-bookworm | ok 2026-08-06 12:20:02.276846 | 2026-08-06 12:20:02.277038 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-06 12:20:03.340968 | debian-bookworm | changed: 2026-08-06 12:20:03.341144 | debian-bookworm | cd+++++++++ src/ 2026-08-06 12:20:03.341177 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-06 12:20:03.341201 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-06 12:20:03.341222 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-06 12:20:03.341242 | debian-bookworm | Containerfile 2026-08-06 12:20:03.341371 | debian-bookworm | Containerfile 2026-08-06 12:20:03.349213 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/work/logs" 2026-08-06 12:22:39.288899 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/work/artifacts" 2026-08-06 12:22:39.605830 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2c1fd5af4c80409eb845b28ba803c43c/work/docs" 2026-08-06 12:22:39.627100 | 2026-08-06 12:22:39.627315 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-06 12:22:40.464536 | debian-bookworm | changed: 2026-08-06 12:22:40.464789 | debian-bookworm | .d..t...... ./ 2026-08-06 12:22:40.464825 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-06 12:22:40.464848 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-06 12:22:40.464869 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-06 12:22:40.464890 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-06 12:22:40.464910 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-06 12:22:40.464965 | debian-bookworm | changed: All items complete 2026-08-06 12:22:40.465007 | 2026-08-06 12:22:41.069297 | debian-bookworm | changed: .d..t...... ./ 2026-08-06 12:22:41.634538 | debian-bookworm | changed: .d..t...... ./ 2026-08-06 12:22:41.651667 | 2026-08-06 12:22:41.651895 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-06 12:22:42.022428 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006855 2026-08-06 12:22:42.317813 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009575 2026-08-06 12:22:42.336569 | 2026-08-06 12:22:42.336702 | PLAY RECAP 2026-08-06 12:22:42.336759 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-06 12:22:42.336785 | 2026-08-06 12:22:42.470504 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-06 12:22:42.471508 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-06 12:22:43.250897 | 2026-08-06 12:22:43.251092 | PLAY [Base post] 2026-08-06 12:22:43.265588 | 2026-08-06 12:22:43.265747 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-06 12:22:44.089370 | debian-bookworm | changed 2026-08-06 12:22:44.101224 | 2026-08-06 12:22:44.101414 | PLAY RECAP 2026-08-06 12:22:44.101504 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-06 12:22:44.101575 | 2026-08-06 12:22:44.288251 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-06 12:22:44.289340 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-06 12:22:45.138384 | 2026-08-06 12:22:45.138583 | PLAY [Base post-logs] 2026-08-06 12:22:45.154988 | 2026-08-06 12:22:45.155373 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-06 12:22:45.601694 | localhost | changed 2026-08-06 12:22:45.612532 | 2026-08-06 12:22:45.612706 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-06 12:22:45.638409 | localhost | ok 2026-08-06 12:22:45.641451 | 2026-08-06 12:22:45.641551 | TASK [Set zuul-log-path fact] 2026-08-06 12:22:45.656305 | localhost | ok 2026-08-06 12:22:45.665266 | 2026-08-06 12:22:45.665371 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-06 12:22:45.690314 | localhost | ok 2026-08-06 12:22:45.693487 | 2026-08-06 12:22:45.693607 | TASK [upload-logs : Create log directories] 2026-08-06 12:22:46.224930 | localhost | changed 2026-08-06 12:22:46.228078 | 2026-08-06 12:22:46.228233 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-06 12:22:46.760436 | localhost -> localhost | ok: Runtime: 0:00:00.004857 2026-08-06 12:22:46.764830 | 2026-08-06 12:22:46.764952 | TASK [upload-logs : Upload logs to log server] 2026-08-06 12:22:47.402245 | localhost | Output suppressed because no_log was given 2026-08-06 12:22:47.406644 | 2026-08-06 12:22:47.407012 | LOOP [upload-logs : Compress console log and json output] 2026-08-06 12:22:47.463650 | localhost | skipping: Conditional result was False 2026-08-06 12:22:47.468559 | localhost | skipping: Conditional result was False 2026-08-06 12:22:47.481225 | 2026-08-06 12:22:47.481447 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-06 12:22:47.536028 | localhost | skipping: Conditional result was False 2026-08-06 12:22:47.536858 | 2026-08-06 12:22:47.539424 | localhost | skipping: Conditional result was False 2026-08-06 12:22:47.555429 | 2026-08-06 12:22:47.555703 | LOOP [upload-logs : Upload console log and json output]