2026-10-05 22:56:39.573986 | Job console starting 2026-10-05 22:56:39.606137 | Updating git repos 2026-10-05 22:56:39.702998 | Cloning repos into workspace 2026-10-05 22:56:39.787214 | Restoring repo states 2026-10-05 22:56:39.827271 | Merging changes 2026-10-05 22:56:40.718760 | Checking out repos 2026-10-05 22:56:40.813316 | Preparing playbooks 2026-10-05 22:56:43.311795 | Running Ansible setup 2026-10-05 22:56:49.573174 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-05 22:56:50.870829 | 2026-10-05 22:56:50.870948 | PLAY [Base pre] 2026-10-05 22:56:50.884813 | 2026-10-05 22:56:50.884917 | TASK [Setup log path fact] 2026-10-05 22:56:50.921824 | debian-bookworm | ok 2026-10-05 22:56:50.950752 | 2026-10-05 22:56:50.950862 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 22:56:50.993829 | debian-bookworm | ok 2026-10-05 22:56:51.021496 | 2026-10-05 22:56:51.021618 | TASK [emit-job-header : Print job information] 2026-10-05 22:56:51.088917 | # Job Information 2026-10-05 22:56:51.089049 | Ansible Version: 2.16.14 2026-10-05 22:56:51.089079 | Job: python-osism-test-setup 2026-10-05 22:56:51.089106 | Pipeline: check 2026-10-05 22:56:51.089126 | Executor: 521e9411259a 2026-10-05 22:56:51.089143 | Triggered by: https://github.com/osism/python-osism/pull/2758 2026-10-05 22:56:51.089161 | Event ID: fe9872f0-c10f-11f1-9a33-373b17f17a8b 2026-10-05 22:56:51.093217 | 2026-10-05 22:56:51.093294 | LOOP [emit-job-header : Print node information] 2026-10-05 22:56:51.286662 | debian-bookworm | ok: 2026-10-05 22:56:51.286837 | debian-bookworm | # Node Information 2026-10-05 22:56:51.286866 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-05 22:56:51.286887 | debian-bookworm | Hostname: debian 2026-10-05 22:56:51.286905 | debian-bookworm | Username: zuul 2026-10-05 22:56:51.286922 | debian-bookworm | Distro: Debian 12.15 2026-10-05 22:56:51.286944 | debian-bookworm | Provider: regiocloud-a 2026-10-05 22:56:51.286962 | debian-bookworm | Region: 2026-10-05 22:56:51.286979 | debian-bookworm | Label: debian-bookworm 2026-10-05 22:56:51.286995 | debian-bookworm | Product Name: OpenStack Nova 2026-10-05 22:56:51.287011 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8b:cbe0 2026-10-05 22:56:51.295556 | 2026-10-05 22:56:51.295652 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-05 22:56:52.026377 | debian-bookworm -> localhost | changed 2026-10-05 22:56:52.033249 | 2026-10-05 22:56:52.033355 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-05 22:56:53.821194 | debian-bookworm -> localhost | changed 2026-10-05 22:56:53.829751 | 2026-10-05 22:56:53.829839 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-05 22:56:54.315647 | debian-bookworm -> localhost | ok 2026-10-05 22:56:54.320601 | 2026-10-05 22:56:54.320706 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-05 22:56:54.348654 | debian-bookworm | ok 2026-10-05 22:56:54.390404 | debian-bookworm | included: /var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-05 22:56:54.398615 | 2026-10-05 22:56:54.398700 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-05 22:56:56.551684 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-05 22:56:56.551848 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/work/9ec91399976e456c9d5835ba992d5f50_id_rsa 2026-10-05 22:56:56.551880 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/work/9ec91399976e456c9d5835ba992d5f50_id_rsa.pub 2026-10-05 22:56:56.551902 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-05 22:56:56.551923 | debian-bookworm -> localhost | SHA256:/12Vp8xfRK252uO3FTcjjyL4KenBgLqAtm9PXnDKxFI zuul-build-sshkey 2026-10-05 22:56:56.551943 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-05 22:56:56.551970 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-05 22:56:56.551990 | debian-bookworm -> localhost | | | 2026-10-05 22:56:56.552007 | debian-bookworm -> localhost | | .| 2026-10-05 22:56:56.552024 | debian-bookworm -> localhost | | E o| 2026-10-05 22:56:56.552040 | debian-bookworm -> localhost | | + +.| 2026-10-05 22:56:56.552056 | debian-bookworm -> localhost | | o = .S .o==| 2026-10-05 22:56:56.552075 | debian-bookworm -> localhost | |. . + * .. o++B| 2026-10-05 22:56:56.552092 | debian-bookworm -> localhost | |oo + =.... .=.+| 2026-10-05 22:56:56.552109 | debian-bookworm -> localhost | |o o.o .oo o..+.o+| 2026-10-05 22:56:56.552127 | debian-bookworm -> localhost | | oo..o...o o.++o| 2026-10-05 22:56:56.552145 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-05 22:56:56.552184 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.215784 2026-10-05 22:56:56.557297 | 2026-10-05 22:56:56.557376 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-05 22:56:56.593649 | debian-bookworm | ok 2026-10-05 22:56:56.608982 | debian-bookworm | included: /var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-05 22:56:56.636626 | 2026-10-05 22:56:56.636715 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-05 22:56:56.669428 | debian-bookworm | skipping: Conditional result was False 2026-10-05 22:56:56.674026 | 2026-10-05 22:56:56.674098 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-05 22:56:57.416553 | debian-bookworm | changed 2026-10-05 22:56:57.420924 | 2026-10-05 22:56:57.420994 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-05 22:56:57.671083 | debian-bookworm | ok 2026-10-05 22:56:57.676631 | 2026-10-05 22:56:57.676710 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-05 22:56:58.296432 | debian-bookworm | changed 2026-10-05 22:56:58.309741 | 2026-10-05 22:56:58.309827 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-05 22:56:58.966301 | debian-bookworm | changed 2026-10-05 22:56:58.973010 | 2026-10-05 22:56:58.973108 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-05 22:56:58.985844 | debian-bookworm | skipping: Conditional result was False 2026-10-05 22:56:58.994062 | 2026-10-05 22:56:58.994158 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-05 22:56:59.331182 | debian-bookworm -> localhost | changed 2026-10-05 22:56:59.341689 | 2026-10-05 22:56:59.341783 | TASK [add-build-sshkey : Add back temp key] 2026-10-05 22:56:59.681493 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/work/9ec91399976e456c9d5835ba992d5f50_id_rsa (zuul-build-sshkey) 2026-10-05 22:56:59.681706 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012542 2026-10-05 22:56:59.687638 | 2026-10-05 22:56:59.687712 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-05 22:57:00.160449 | debian-bookworm | ok 2026-10-05 22:57:00.164660 | 2026-10-05 22:57:00.164735 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-05 22:57:00.219920 | debian-bookworm | skipping: Conditional result was False 2026-10-05 22:57:00.228144 | 2026-10-05 22:57:00.228229 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-05 22:57:00.732327 | debian-bookworm | ok 2026-10-05 22:57:00.746995 | 2026-10-05 22:57:00.747088 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-05 22:57:00.799956 | debian-bookworm | ok 2026-10-05 22:57:00.805679 | 2026-10-05 22:57:00.805762 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-05 22:57:01.189398 | debian-bookworm -> localhost | ok 2026-10-05 22:57:01.195492 | 2026-10-05 22:57:01.195581 | TASK [validate-host : Collect information about the host] 2026-10-05 22:57:02.528182 | debian-bookworm | ok 2026-10-05 22:57:02.542224 | 2026-10-05 22:57:02.542314 | TASK [validate-host : Sanitize hostname] 2026-10-05 22:57:02.653459 | debian-bookworm | ok 2026-10-05 22:57:02.657566 | 2026-10-05 22:57:02.657640 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-05 22:57:03.136954 | debian-bookworm -> localhost | changed 2026-10-05 22:57:03.141523 | 2026-10-05 22:57:03.149862 | TASK [validate-host : Collect information about zuul worker] 2026-10-05 22:57:03.603845 | debian-bookworm | ok 2026-10-05 22:57:03.607871 | 2026-10-05 22:57:03.607955 | TASK [validate-host : Write out all zuul information for each host] 2026-10-05 22:57:04.431906 | debian-bookworm -> localhost | changed 2026-10-05 22:57:04.439466 | 2026-10-05 22:57:04.439548 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-05 22:57:04.750640 | debian-bookworm | ok 2026-10-05 22:57:04.755073 | 2026-10-05 22:57:04.755144 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-05 22:57:07.095419 | debian-bookworm | changed: 2026-10-05 22:57:07.095604 | debian-bookworm | cd+++++++++ src/ 2026-10-05 22:57:07.095632 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-05 22:57:07.095652 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-05 22:57:07.095669 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-10-05 22:57:07.095685 | debian-bookworm | Containerfile 2026-10-05 22:57:07.095794 | debian-bookworm | Containerfile 2026-10-05 22:57:07.119056 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/work/logs" 2026-10-05 22:59:52.567645 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/work/artifacts" 2026-10-05 22:59:52.836910 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9ec91399976e456c9d5835ba992d5f50/work/docs" 2026-10-05 22:59:52.865329 | 2026-10-05 22:59:52.865592 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-05 22:59:53.644607 | debian-bookworm | changed: 2026-10-05 22:59:53.645084 | debian-bookworm | .d..t...... ./ 2026-10-05 22:59:53.645155 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-05 22:59:53.645201 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-05 22:59:53.645241 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-05 22:59:53.645279 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-05 22:59:53.645315 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-05 22:59:53.645390 | debian-bookworm | changed: All items complete 2026-10-05 22:59:53.645452 | 2026-10-05 22:59:54.226242 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 22:59:54.796088 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 22:59:54.821927 | 2026-10-05 22:59:54.822057 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-05 22:59:55.152779 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010238 2026-10-05 22:59:55.438863 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009962 2026-10-05 22:59:55.463361 | 2026-10-05 22:59:55.463508 | PLAY RECAP 2026-10-05 22:59:55.463590 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-05 22:59:55.463632 | 2026-10-05 22:59:55.590289 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-05 22:59:55.592067 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 22:59:56.369987 | 2026-10-05 22:59:56.370139 | PLAY [Base post] 2026-10-05 22:59:56.383769 | 2026-10-05 22:59:56.383890 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-05 22:59:57.201490 | debian-bookworm | changed 2026-10-05 22:59:57.210475 | 2026-10-05 22:59:57.210599 | PLAY RECAP 2026-10-05 22:59:57.210679 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-05 22:59:57.210757 | 2026-10-05 22:59:57.342987 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 22:59:57.344938 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-05 22:59:58.099402 | 2026-10-05 22:59:58.099571 | PLAY [Base post-logs] 2026-10-05 22:59:58.109870 | 2026-10-05 22:59:58.110000 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-05 22:59:58.573938 | localhost | changed 2026-10-05 22:59:58.584611 | 2026-10-05 22:59:58.584756 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-05 22:59:58.621208 | localhost | ok 2026-10-05 22:59:58.624745 | 2026-10-05 22:59:58.624856 | TASK [Set zuul-log-path fact] 2026-10-05 22:59:58.640244 | localhost | ok 2026-10-05 22:59:58.648633 | 2026-10-05 22:59:58.648737 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 22:59:58.674180 | localhost | ok 2026-10-05 22:59:58.679027 | 2026-10-05 22:59:58.679164 | TASK [upload-logs : Create log directories] 2026-10-05 22:59:59.197296 | localhost | changed 2026-10-05 22:59:59.204477 | 2026-10-05 22:59:59.204687 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-05 22:59:59.722340 | localhost -> localhost | ok: Runtime: 0:00:00.004540 2026-10-05 22:59:59.726614 | 2026-10-05 22:59:59.726730 | TASK [upload-logs : Upload logs to log server] 2026-10-05 23:00:00.284099 | localhost | Output suppressed because no_log was given 2026-10-05 23:00:00.288897 | 2026-10-05 23:00:00.289118 | LOOP [upload-logs : Compress console log and json output] 2026-10-05 23:00:00.338207 | localhost | skipping: Conditional result was False 2026-10-05 23:00:00.344076 | localhost | skipping: Conditional result was False 2026-10-05 23:00:00.351572 | 2026-10-05 23:00:00.351799 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-05 23:00:00.398205 | localhost | skipping: Conditional result was False 2026-10-05 23:00:00.398786 | 2026-10-05 23:00:00.402512 | localhost | skipping: Conditional result was False 2026-10-05 23:00:00.416381 | 2026-10-05 23:00:00.416675 | LOOP [upload-logs : Upload console log and json output]