2026-07-18 11:58:22.939533 | Job console starting 2026-07-18 11:58:22.964995 | Updating git repos 2026-07-18 11:58:23.041505 | Cloning repos into workspace 2026-07-18 11:58:23.108102 | Restoring repo states 2026-07-18 11:58:23.141547 | Merging changes 2026-07-18 11:58:24.099561 | Checking out repos 2026-07-18 11:58:24.202929 | Preparing playbooks 2026-07-18 11:58:25.632767 | Running Ansible setup 2026-07-18 11:58:30.416522 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-18 11:58:31.457095 | 2026-07-18 11:58:31.457247 | PLAY [Base pre] 2026-07-18 11:58:31.476993 | 2026-07-18 11:58:31.477124 | TASK [Setup log path fact] 2026-07-18 11:58:31.505700 | debian-bookworm | ok 2026-07-18 11:58:31.538605 | 2026-07-18 11:58:31.538746 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-18 11:58:31.566330 | debian-bookworm | ok 2026-07-18 11:58:31.577174 | 2026-07-18 11:58:31.577291 | TASK [emit-job-header : Print job information] 2026-07-18 11:58:31.643660 | # Job Information 2026-07-18 11:58:31.643875 | Ansible Version: 2.16.14 2026-07-18 11:58:31.643916 | Job: python-osism-test-setup 2026-07-18 11:58:31.643949 | Pipeline: check 2026-07-18 11:58:31.643973 | Executor: 521e9411259a 2026-07-18 11:58:31.643995 | Triggered by: https://github.com/osism/python-osism/pull/2486 2026-07-18 11:58:31.644017 | Event ID: e8fc0f00-829f-11f1-9326-4e4a3e7290dc 2026-07-18 11:58:31.649009 | 2026-07-18 11:58:31.649103 | LOOP [emit-job-header : Print node information] 2026-07-18 11:58:31.757281 | debian-bookworm | ok: 2026-07-18 11:58:31.757447 | debian-bookworm | # Node Information 2026-07-18 11:58:31.757481 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-18 11:58:31.757507 | debian-bookworm | Hostname: debian 2026-07-18 11:58:31.757530 | debian-bookworm | Username: zuul 2026-07-18 11:58:31.757551 | debian-bookworm | Distro: Debian 12.15 2026-07-18 11:58:31.757576 | debian-bookworm | Provider: regiocloud-a 2026-07-18 11:58:31.757597 | debian-bookworm | Region: 2026-07-18 11:58:31.757620 | debian-bookworm | Label: debian-bookworm 2026-07-18 11:58:31.757641 | debian-bookworm | Product Name: OpenStack Nova 2026-07-18 11:58:31.757660 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe92:499e 2026-07-18 11:58:31.774082 | 2026-07-18 11:58:31.774196 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-18 11:58:32.273204 | debian-bookworm -> localhost | changed 2026-07-18 11:58:32.281788 | 2026-07-18 11:58:32.281976 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-18 11:58:33.556590 | debian-bookworm -> localhost | changed 2026-07-18 11:58:33.567589 | 2026-07-18 11:58:33.567689 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-18 11:58:33.833852 | debian-bookworm -> localhost | ok 2026-07-18 11:58:33.840060 | 2026-07-18 11:58:33.840161 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-18 11:58:33.862241 | debian-bookworm | ok 2026-07-18 11:58:33.877853 | debian-bookworm | included: /var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-18 11:58:33.884894 | 2026-07-18 11:58:33.884985 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-18 11:58:35.464400 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-18 11:58:35.464623 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/work/42aa7e196aac40649e05cf9442b0ce6c_id_rsa 2026-07-18 11:58:35.464662 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/work/42aa7e196aac40649e05cf9442b0ce6c_id_rsa.pub 2026-07-18 11:58:35.464690 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-18 11:58:35.464717 | debian-bookworm -> localhost | SHA256:SvQD9bADEQrgJaX4uZVBQ3CCGopBoaCnPbYwbUT/TOo zuul-build-sshkey 2026-07-18 11:58:35.464740 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-18 11:58:35.464771 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-18 11:58:35.464795 | debian-bookworm -> localhost | |+*B== +oo | 2026-07-18 11:58:35.464817 | debian-bookworm -> localhost | |X.+* o o + | 2026-07-18 11:58:35.464837 | debian-bookworm -> localhost | |B=o + + o . | 2026-07-18 11:58:35.464856 | debian-bookworm -> localhost | |+B . O o . | 2026-07-18 11:58:35.464876 | debian-bookworm -> localhost | |+ O + + S | 2026-07-18 11:58:35.464903 | debian-bookworm -> localhost | | = * . . . | 2026-07-18 11:58:35.464923 | debian-bookworm -> localhost | | o E . | 2026-07-18 11:58:35.464943 | debian-bookworm -> localhost | | | 2026-07-18 11:58:35.464964 | debian-bookworm -> localhost | | | 2026-07-18 11:58:35.464984 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-18 11:58:35.465036 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.066745 2026-07-18 11:58:35.471490 | 2026-07-18 11:58:35.471601 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-18 11:58:35.519794 | debian-bookworm | ok 2026-07-18 11:58:35.529605 | debian-bookworm | included: /var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-18 11:58:35.560528 | 2026-07-18 11:58:35.560629 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-18 11:58:35.587663 | debian-bookworm | skipping: Conditional result was False 2026-07-18 11:58:35.592761 | 2026-07-18 11:58:35.592844 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-18 11:58:36.315579 | debian-bookworm | changed 2026-07-18 11:58:36.333088 | 2026-07-18 11:58:36.333194 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-18 11:58:36.580081 | debian-bookworm | ok 2026-07-18 11:58:36.587304 | 2026-07-18 11:58:36.587457 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-18 11:58:37.258602 | debian-bookworm | changed 2026-07-18 11:58:37.264537 | 2026-07-18 11:58:37.264644 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-18 11:58:37.894799 | debian-bookworm | changed 2026-07-18 11:58:37.901894 | 2026-07-18 11:58:37.901992 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-18 11:58:37.929692 | debian-bookworm | skipping: Conditional result was False 2026-07-18 11:58:37.943856 | 2026-07-18 11:58:37.944145 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-18 11:58:38.407725 | debian-bookworm -> localhost | changed 2026-07-18 11:58:38.420609 | 2026-07-18 11:58:38.420736 | TASK [add-build-sshkey : Add back temp key] 2026-07-18 11:58:38.762346 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/work/42aa7e196aac40649e05cf9442b0ce6c_id_rsa (zuul-build-sshkey) 2026-07-18 11:58:38.762659 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017131 2026-07-18 11:58:38.773113 | 2026-07-18 11:58:38.773248 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-18 11:58:39.149488 | debian-bookworm | ok 2026-07-18 11:58:39.155133 | 2026-07-18 11:58:39.155252 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-18 11:58:39.179573 | debian-bookworm | skipping: Conditional result was False 2026-07-18 11:58:39.199210 | 2026-07-18 11:58:39.199351 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-18 11:58:39.685822 | debian-bookworm | ok 2026-07-18 11:58:39.702667 | 2026-07-18 11:58:39.702812 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-18 11:58:39.744608 | debian-bookworm | ok 2026-07-18 11:58:39.751489 | 2026-07-18 11:58:39.751638 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-18 11:58:40.162272 | debian-bookworm -> localhost | ok 2026-07-18 11:58:40.169371 | 2026-07-18 11:58:40.169505 | TASK [validate-host : Collect information about the host] 2026-07-18 11:58:41.459726 | debian-bookworm | ok 2026-07-18 11:58:41.474384 | 2026-07-18 11:58:41.474525 | TASK [validate-host : Sanitize hostname] 2026-07-18 11:58:41.647531 | debian-bookworm | ok 2026-07-18 11:58:41.652573 | 2026-07-18 11:58:41.652684 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-18 11:58:42.310151 | debian-bookworm -> localhost | changed 2026-07-18 11:58:42.323634 | 2026-07-18 11:58:42.323833 | TASK [validate-host : Collect information about zuul worker] 2026-07-18 11:58:42.765166 | debian-bookworm | ok 2026-07-18 11:58:42.772183 | 2026-07-18 11:58:42.772344 | TASK [validate-host : Write out all zuul information for each host] 2026-07-18 11:58:43.398471 | debian-bookworm -> localhost | changed 2026-07-18 11:58:43.412335 | 2026-07-18 11:58:43.412656 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-18 11:58:43.698346 | debian-bookworm | ok 2026-07-18 11:58:43.706626 | 2026-07-18 11:58:43.706772 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-18 11:58:44.938684 | debian-bookworm | changed: 2026-07-18 11:58:44.939161 | debian-bookworm | cd+++++++++ src/ 2026-07-18 11:58:44.939307 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-18 11:58:44.939363 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-18 11:58:44.939408 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-18 11:58:44.939444 | debian-bookworm | Containerfile 2026-07-18 11:58:44.939677 | debian-bookworm | Containerfile 2026-07-18 11:58:44.976556 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/work/logs" 2026-07-18 12:01:17.022682 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/work/artifacts" 2026-07-18 12:01:17.347548 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/42aa7e196aac40649e05cf9442b0ce6c/work/docs" 2026-07-18 12:01:17.371426 | 2026-07-18 12:01:17.371617 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-18 12:01:18.199394 | debian-bookworm | changed: 2026-07-18 12:01:18.199712 | debian-bookworm | .d..t...... ./ 2026-07-18 12:01:18.199754 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-18 12:01:18.199780 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-18 12:01:18.199802 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-18 12:01:18.199824 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-18 12:01:18.199843 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-18 12:01:18.199887 | debian-bookworm | changed: All items complete 2026-07-18 12:01:18.199915 | 2026-07-18 12:01:18.822112 | debian-bookworm | changed: .d..t...... ./ 2026-07-18 12:01:19.437245 | debian-bookworm | changed: .d..t...... ./ 2026-07-18 12:01:19.461941 | 2026-07-18 12:01:19.462120 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-18 12:01:19.828995 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.020064 2026-07-18 12:01:20.155276 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007120 2026-07-18 12:01:20.175214 | 2026-07-18 12:01:20.175354 | PLAY RECAP 2026-07-18 12:01:20.175416 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-18 12:01:20.175442 | 2026-07-18 12:01:20.322929 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-18 12:01:20.324727 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-18 12:01:21.102651 | 2026-07-18 12:01:21.102859 | PLAY [Base post] 2026-07-18 12:01:21.118178 | 2026-07-18 12:01:21.118337 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-18 12:01:21.956790 | debian-bookworm | changed 2026-07-18 12:01:21.966447 | 2026-07-18 12:01:21.966572 | PLAY RECAP 2026-07-18 12:01:21.966649 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-18 12:01:21.966731 | 2026-07-18 12:01:22.134957 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-18 12:01:22.136518 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-18 12:01:23.022548 | 2026-07-18 12:01:23.022772 | PLAY [Base post-logs] 2026-07-18 12:01:23.040353 | 2026-07-18 12:01:23.040515 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-18 12:01:23.552638 | localhost | changed 2026-07-18 12:01:23.567585 | 2026-07-18 12:01:23.567781 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-18 12:01:23.605733 | localhost | ok 2026-07-18 12:01:23.612384 | 2026-07-18 12:01:23.612573 | TASK [Set zuul-log-path fact] 2026-07-18 12:01:23.641252 | localhost | ok 2026-07-18 12:01:23.653792 | 2026-07-18 12:01:23.653931 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-18 12:01:23.691655 | localhost | ok 2026-07-18 12:01:23.697816 | 2026-07-18 12:01:23.698000 | TASK [upload-logs : Create log directories] 2026-07-18 12:01:24.299548 | localhost | changed 2026-07-18 12:01:24.306941 | 2026-07-18 12:01:24.307131 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-18 12:01:24.882522 | localhost -> localhost | ok: Runtime: 0:00:00.004768 2026-07-18 12:01:24.892200 | 2026-07-18 12:01:24.892419 | TASK [upload-logs : Upload logs to log server] 2026-07-18 12:01:25.519065 | localhost | Output suppressed because no_log was given 2026-07-18 12:01:25.521189 | 2026-07-18 12:01:25.521305 | LOOP [upload-logs : Compress console log and json output] 2026-07-18 12:01:25.584326 | localhost | skipping: Conditional result was False 2026-07-18 12:01:25.592976 | localhost | skipping: Conditional result was False 2026-07-18 12:01:25.599881 | 2026-07-18 12:01:25.600068 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-18 12:01:25.661895 | localhost | skipping: Conditional result was False 2026-07-18 12:01:25.662235 | 2026-07-18 12:01:25.667021 | localhost | skipping: Conditional result was False 2026-07-18 12:01:25.681866 | 2026-07-18 12:01:25.682181 | LOOP [upload-logs : Upload console log and json output]