2026-07-23 04:38:24.206425 | Job console starting 2026-07-23 04:38:24.228406 | Updating git repos 2026-07-23 04:38:24.364597 | Cloning repos into workspace 2026-07-23 04:38:24.564672 | Restoring repo states 2026-07-23 04:38:24.607146 | Merging changes 2026-07-23 04:38:26.168480 | Checking out repos 2026-07-23 04:38:26.241768 | Preparing playbooks 2026-07-23 04:38:28.743721 | Running Ansible setup 2026-07-23 04:38:34.746153 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-23 04:38:36.298418 | 2026-07-23 04:38:36.298572 | PLAY [Base pre] 2026-07-23 04:38:36.321399 | 2026-07-23 04:38:36.321538 | TASK [Setup log path fact] 2026-07-23 04:38:36.352773 | debian-bookworm | ok 2026-07-23 04:38:36.384961 | 2026-07-23 04:38:36.385119 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-23 04:38:36.447133 | debian-bookworm | ok 2026-07-23 04:38:36.483234 | 2026-07-23 04:38:36.483374 | TASK [emit-job-header : Print job information] 2026-07-23 04:38:36.555581 | # Job Information 2026-07-23 04:38:36.555764 | Ansible Version: 2.16.14 2026-07-23 04:38:36.555800 | Job: python-osism-test-setup 2026-07-23 04:38:36.555832 | Pipeline: check 2026-07-23 04:38:36.555855 | Executor: 521e9411259a 2026-07-23 04:38:36.555876 | Triggered by: https://github.com/osism/python-osism/pull/2506 2026-07-23 04:38:36.555899 | Event ID: 4f258280-8650-11f1-91f0-54596d211d52 2026-07-23 04:38:36.562397 | 2026-07-23 04:38:36.562520 | LOOP [emit-job-header : Print node information] 2026-07-23 04:38:36.693760 | debian-bookworm | ok: 2026-07-23 04:38:36.693937 | debian-bookworm | # Node Information 2026-07-23 04:38:36.693970 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-23 04:38:36.693994 | debian-bookworm | Hostname: debian 2026-07-23 04:38:36.694015 | debian-bookworm | Username: zuul 2026-07-23 04:38:36.694035 | debian-bookworm | Distro: Debian 12.15 2026-07-23 04:38:36.694058 | debian-bookworm | Provider: regiocloud-a 2026-07-23 04:38:36.694078 | debian-bookworm | Region: 2026-07-23 04:38:36.694099 | debian-bookworm | Label: debian-bookworm 2026-07-23 04:38:36.694118 | debian-bookworm | Product Name: OpenStack Nova 2026-07-23 04:38:36.694137 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe70:c470 2026-07-23 04:38:36.719487 | 2026-07-23 04:38:36.719621 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-23 04:38:37.760883 | debian-bookworm -> localhost | changed 2026-07-23 04:38:37.773257 | 2026-07-23 04:38:37.773378 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-23 04:38:39.887303 | debian-bookworm -> localhost | changed 2026-07-23 04:38:39.903157 | 2026-07-23 04:38:39.903281 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-23 04:38:40.949819 | debian-bookworm -> localhost | ok 2026-07-23 04:38:40.955847 | 2026-07-23 04:38:40.955984 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-23 04:38:40.974598 | debian-bookworm | ok 2026-07-23 04:38:40.998179 | debian-bookworm | included: /var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-23 04:38:41.005072 | 2026-07-23 04:38:41.005174 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-23 04:38:44.830763 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-23 04:38:44.831794 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/work/94c54ef79bfa41caadd5baac2deb0f0d_id_rsa 2026-07-23 04:38:44.831855 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/work/94c54ef79bfa41caadd5baac2deb0f0d_id_rsa.pub 2026-07-23 04:38:44.831878 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-23 04:38:44.831900 | debian-bookworm -> localhost | SHA256:eWI1Mvab7cjvd767iQGEHNj/1vBoxuNQt9fRVppflAs zuul-build-sshkey 2026-07-23 04:38:44.831919 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-23 04:38:44.831944 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-23 04:38:44.831962 | debian-bookworm -> localhost | | o. .| 2026-07-23 04:38:44.831980 | debian-bookworm -> localhost | | ...o E .o| 2026-07-23 04:38:44.831998 | debian-bookworm -> localhost | | +o+. .++| 2026-07-23 04:38:44.832015 | debian-bookworm -> localhost | | . *.o oo++| 2026-07-23 04:38:44.832032 | debian-bookworm -> localhost | | S o.+ *o=| 2026-07-23 04:38:44.832051 | debian-bookworm -> localhost | | . o =.O ++| 2026-07-23 04:38:44.832069 | debian-bookworm -> localhost | | o B.. .| 2026-07-23 04:38:44.832086 | debian-bookworm -> localhost | | . o .+ o| 2026-07-23 04:38:44.832103 | debian-bookworm -> localhost | | oo+o B=| 2026-07-23 04:38:44.832119 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-23 04:38:44.832164 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.630490 2026-07-23 04:38:44.837928 | 2026-07-23 04:38:44.838017 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-23 04:38:44.856170 | debian-bookworm | ok 2026-07-23 04:38:44.879696 | debian-bookworm | included: /var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-23 04:38:44.902233 | 2026-07-23 04:38:44.902342 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-23 04:38:44.915626 | debian-bookworm | skipping: Conditional result was False 2026-07-23 04:38:44.920838 | 2026-07-23 04:38:44.920932 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-23 04:38:45.640089 | debian-bookworm | changed 2026-07-23 04:38:45.645933 | 2026-07-23 04:38:45.646038 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-23 04:38:45.890643 | debian-bookworm | ok 2026-07-23 04:38:45.896631 | 2026-07-23 04:38:45.896793 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-23 04:38:46.520953 | debian-bookworm | changed 2026-07-23 04:38:46.527339 | 2026-07-23 04:38:46.527433 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-23 04:38:47.125180 | debian-bookworm | changed 2026-07-23 04:38:47.140981 | 2026-07-23 04:38:47.141104 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-23 04:38:47.174692 | debian-bookworm | skipping: Conditional result was False 2026-07-23 04:38:47.182212 | 2026-07-23 04:38:47.182344 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-23 04:38:47.918336 | debian-bookworm -> localhost | changed 2026-07-23 04:38:47.932371 | 2026-07-23 04:38:47.936289 | TASK [add-build-sshkey : Add back temp key] 2026-07-23 04:38:48.962040 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/work/94c54ef79bfa41caadd5baac2deb0f0d_id_rsa (zuul-build-sshkey) 2026-07-23 04:38:48.962232 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030575 2026-07-23 04:38:48.969167 | 2026-07-23 04:38:48.969262 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-23 04:38:49.538549 | debian-bookworm | ok 2026-07-23 04:38:49.544567 | 2026-07-23 04:38:49.544675 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-23 04:38:49.613142 | debian-bookworm | skipping: Conditional result was False 2026-07-23 04:38:49.623731 | 2026-07-23 04:38:49.623848 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-23 04:38:50.278106 | debian-bookworm | ok 2026-07-23 04:38:50.296351 | 2026-07-23 04:38:50.296469 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-23 04:38:50.398541 | debian-bookworm | ok 2026-07-23 04:38:50.404269 | 2026-07-23 04:38:50.404369 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-23 04:38:51.343594 | debian-bookworm -> localhost | ok 2026-07-23 04:38:51.350090 | 2026-07-23 04:38:51.350194 | TASK [validate-host : Collect information about the host] 2026-07-23 04:38:52.646141 | debian-bookworm | ok 2026-07-23 04:38:52.695834 | 2026-07-23 04:38:52.695959 | TASK [validate-host : Sanitize hostname] 2026-07-23 04:38:52.928962 | debian-bookworm | ok 2026-07-23 04:38:52.933660 | 2026-07-23 04:38:52.933765 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-23 04:38:54.048440 | debian-bookworm -> localhost | changed 2026-07-23 04:38:54.053746 | 2026-07-23 04:38:54.053837 | TASK [validate-host : Collect information about zuul worker] 2026-07-23 04:38:54.821048 | debian-bookworm | ok 2026-07-23 04:38:54.824953 | 2026-07-23 04:38:54.825039 | TASK [validate-host : Write out all zuul information for each host] 2026-07-23 04:38:55.697796 | debian-bookworm -> localhost | changed 2026-07-23 04:38:55.710028 | 2026-07-23 04:38:55.710136 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-23 04:38:55.991971 | debian-bookworm | ok 2026-07-23 04:38:56.004455 | 2026-07-23 04:38:56.004564 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-23 04:38:57.340547 | debian-bookworm | changed: 2026-07-23 04:38:57.341016 | debian-bookworm | cd+++++++++ src/ 2026-07-23 04:38:57.341076 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-23 04:38:57.341103 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-23 04:38:57.341345 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-23 04:38:57.341367 | debian-bookworm | Containerfile 2026-07-23 04:38:57.341504 | debian-bookworm | Containerfile 2026-07-23 04:38:57.356638 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/work/logs" 2026-07-23 04:41:31.356470 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/work/artifacts" 2026-07-23 04:41:31.655635 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/94c54ef79bfa41caadd5baac2deb0f0d/work/docs" 2026-07-23 04:41:31.675498 | 2026-07-23 04:41:31.675670 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-23 04:41:32.463992 | debian-bookworm | changed: 2026-07-23 04:41:32.464314 | debian-bookworm | .d..t...... ./ 2026-07-23 04:41:32.464350 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-23 04:41:32.464375 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-23 04:41:32.464398 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-23 04:41:32.464419 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-23 04:41:32.464440 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-23 04:41:32.464481 | debian-bookworm | changed: All items complete 2026-07-23 04:41:32.464506 | 2026-07-23 04:41:33.027988 | debian-bookworm | changed: .d..t...... ./ 2026-07-23 04:41:33.648659 | debian-bookworm | changed: .d..t...... ./ 2026-07-23 04:41:33.669148 | 2026-07-23 04:41:33.669262 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-23 04:41:33.974973 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.004663 2026-07-23 04:41:34.271817 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006947 2026-07-23 04:41:34.290298 | 2026-07-23 04:41:34.290464 | PLAY RECAP 2026-07-23 04:41:34.290582 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-23 04:41:34.290615 | 2026-07-23 04:41:34.497652 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-23 04:41:34.499181 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-23 04:41:35.317908 | 2026-07-23 04:41:35.318145 | PLAY [Base post] 2026-07-23 04:41:35.333129 | 2026-07-23 04:41:35.333315 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-23 04:41:36.095997 | debian-bookworm | changed 2026-07-23 04:41:36.108571 | 2026-07-23 04:41:36.108740 | PLAY RECAP 2026-07-23 04:41:36.108836 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-23 04:41:36.108936 | 2026-07-23 04:41:36.252735 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-23 04:41:36.254820 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-23 04:41:37.172524 | 2026-07-23 04:41:37.172717 | PLAY [Base post-logs] 2026-07-23 04:41:37.185237 | 2026-07-23 04:41:37.185384 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-23 04:41:37.674665 | localhost | changed 2026-07-23 04:41:37.686408 | 2026-07-23 04:41:37.686758 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-23 04:41:37.713534 | localhost | ok 2026-07-23 04:41:37.717063 | 2026-07-23 04:41:37.717182 | TASK [Set zuul-log-path fact] 2026-07-23 04:41:37.733928 | localhost | ok 2026-07-23 04:41:37.745319 | 2026-07-23 04:41:37.745448 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-23 04:41:37.771978 | localhost | ok 2026-07-23 04:41:37.775210 | 2026-07-23 04:41:37.775318 | TASK [upload-logs : Create log directories] 2026-07-23 04:41:38.321657 | localhost | changed 2026-07-23 04:41:38.325567 | 2026-07-23 04:41:38.325701 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-23 04:41:38.901984 | localhost -> localhost | ok: Runtime: 0:00:00.006014 2026-07-23 04:41:38.906636 | 2026-07-23 04:41:38.906774 | TASK [upload-logs : Upload logs to log server] 2026-07-23 04:41:39.549974 | localhost | Output suppressed because no_log was given 2026-07-23 04:41:39.552474 | 2026-07-23 04:41:39.552618 | LOOP [upload-logs : Compress console log and json output] 2026-07-23 04:41:39.635131 | localhost | skipping: Conditional result was False 2026-07-23 04:41:39.642997 | localhost | skipping: Conditional result was False 2026-07-23 04:41:39.656440 | 2026-07-23 04:41:39.656605 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-23 04:41:39.717883 | localhost | skipping: Conditional result was False 2026-07-23 04:41:39.718435 | 2026-07-23 04:41:39.722981 | localhost | skipping: Conditional result was False 2026-07-23 04:41:39.732422 | 2026-07-23 04:41:39.732616 | LOOP [upload-logs : Upload console log and json output]