2026-05-05 06:06:36.515902 | Job console starting 2026-05-05 06:06:36.527316 | Updating git repos 2026-05-05 06:06:36.583937 | Cloning repos into workspace 2026-05-05 06:06:36.705190 | Restoring repo states 2026-05-05 06:06:36.728479 | Merging changes 2026-05-05 06:06:37.498971 | Checking out repos 2026-05-05 06:06:37.588001 | Preparing playbooks 2026-05-05 06:06:39.108960 | Running Ansible setup 2026-05-05 06:06:45.752686 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-05 06:06:47.499101 | 2026-05-05 06:06:47.499225 | PLAY [Base pre] 2026-05-05 06:06:47.532125 | 2026-05-05 06:06:47.532245 | TASK [Setup log path fact] 2026-05-05 06:06:47.560877 | debian-bookworm | ok 2026-05-05 06:06:47.598359 | 2026-05-05 06:06:47.598495 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 06:06:47.666966 | debian-bookworm | ok 2026-05-05 06:06:47.677946 | 2026-05-05 06:06:47.678057 | TASK [emit-job-header : Print job information] 2026-05-05 06:06:47.763149 | # Job Information 2026-05-05 06:06:47.763319 | Ansible Version: 2.16.14 2026-05-05 06:06:47.763355 | Job: python-osism-test-setup 2026-05-05 06:06:47.763387 | Pipeline: check 2026-05-05 06:06:47.763411 | Executor: 521e9411259a 2026-05-05 06:06:47.763433 | Triggered by: https://github.com/osism/python-osism/pull/2261 2026-05-05 06:06:47.763455 | Event ID: 8cd43a40-4848-11f1-8692-0edee2ee67b9 2026-05-05 06:06:47.770850 | 2026-05-05 06:06:47.770948 | LOOP [emit-job-header : Print node information] 2026-05-05 06:06:48.025704 | debian-bookworm | ok: 2026-05-05 06:06:48.025870 | debian-bookworm | # Node Information 2026-05-05 06:06:48.025906 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-05 06:06:48.025932 | debian-bookworm | Hostname: debian 2026-05-05 06:06:48.025956 | debian-bookworm | Username: zuul 2026-05-05 06:06:48.025977 | debian-bookworm | Distro: Debian 12.13 2026-05-05 06:06:48.026002 | debian-bookworm | Provider: regiocloud-a 2026-05-05 06:06:48.026024 | debian-bookworm | Region: 2026-05-05 06:06:48.026045 | debian-bookworm | Label: debian-bookworm 2026-05-05 06:06:48.026065 | debian-bookworm | Product Name: OpenStack Nova 2026-05-05 06:06:48.026084 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe48:89a3 2026-05-05 06:06:48.044604 | 2026-05-05 06:06:48.044718 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-05 06:06:49.101220 | debian-bookworm -> localhost | changed 2026-05-05 06:06:49.115801 | 2026-05-05 06:06:49.115920 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-05 06:06:51.158582 | debian-bookworm -> localhost | changed 2026-05-05 06:06:51.167169 | 2026-05-05 06:06:51.167257 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-05 06:06:51.710218 | debian-bookworm -> localhost | ok 2026-05-05 06:06:51.716183 | 2026-05-05 06:06:51.716308 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-05 06:06:51.776579 | debian-bookworm | ok 2026-05-05 06:06:51.818211 | debian-bookworm | included: /var/lib/zuul/builds/3d173138aed0434080641688510ba900/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-05 06:06:51.844679 | 2026-05-05 06:06:51.844796 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-05 06:06:55.205502 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-05 06:06:55.205723 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3d173138aed0434080641688510ba900/work/3d173138aed0434080641688510ba900_id_rsa 2026-05-05 06:06:55.205764 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3d173138aed0434080641688510ba900/work/3d173138aed0434080641688510ba900_id_rsa.pub 2026-05-05 06:06:55.205793 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-05 06:06:55.205818 | debian-bookworm -> localhost | SHA256:w4/z1CbUZzPeGmAOwHbn3aXDZy2qJ2Bbczj/+BqQrAI zuul-build-sshkey 2026-05-05 06:06:55.205841 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-05 06:06:55.205871 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-05 06:06:55.205893 | debian-bookworm -> localhost | | | 2026-05-05 06:06:55.205916 | debian-bookworm -> localhost | | . | 2026-05-05 06:06:55.205937 | debian-bookworm -> localhost | | + . . .| 2026-05-05 06:06:55.205957 | debian-bookworm -> localhost | | o + = o oo| 2026-05-05 06:06:55.205978 | debian-bookworm -> localhost | | E S *.= @ =| 2026-05-05 06:06:55.206002 | debian-bookworm -> localhost | | . o*=*.* O | 2026-05-05 06:06:55.206023 | debian-bookworm -> localhost | | ..+++=* o .| 2026-05-05 06:06:55.206043 | debian-bookworm -> localhost | | ..+.+oo o | 2026-05-05 06:06:55.206063 | debian-bookworm -> localhost | | ooo++ | 2026-05-05 06:06:55.206083 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-05 06:06:55.206139 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.303839 2026-05-05 06:06:55.220750 | 2026-05-05 06:06:55.220851 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-05 06:06:55.253630 | debian-bookworm | ok 2026-05-05 06:06:55.278708 | debian-bookworm | included: /var/lib/zuul/builds/3d173138aed0434080641688510ba900/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-05 06:06:55.288349 | 2026-05-05 06:06:55.288457 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-05 06:06:55.311589 | debian-bookworm | skipping: Conditional result was False 2026-05-05 06:06:55.317534 | 2026-05-05 06:06:55.317649 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-05 06:06:56.052409 | debian-bookworm | changed 2026-05-05 06:06:56.060090 | 2026-05-05 06:06:56.060236 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-05 06:06:56.331027 | debian-bookworm | ok 2026-05-05 06:06:56.336644 | 2026-05-05 06:06:56.336751 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-05 06:06:57.045716 | debian-bookworm | changed 2026-05-05 06:06:57.061521 | 2026-05-05 06:06:57.061652 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-05 06:06:57.751932 | debian-bookworm | changed 2026-05-05 06:06:57.757496 | 2026-05-05 06:06:57.757593 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-05 06:06:57.781669 | debian-bookworm | skipping: Conditional result was False 2026-05-05 06:06:57.787958 | 2026-05-05 06:06:57.788059 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-05 06:06:58.498333 | debian-bookworm -> localhost | changed 2026-05-05 06:06:58.516391 | 2026-05-05 06:06:58.516504 | TASK [add-build-sshkey : Add back temp key] 2026-05-05 06:06:58.931732 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3d173138aed0434080641688510ba900/work/3d173138aed0434080641688510ba900_id_rsa (zuul-build-sshkey) 2026-05-05 06:06:58.931942 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009766 2026-05-05 06:06:58.939578 | 2026-05-05 06:06:58.939676 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-05 06:06:59.511269 | debian-bookworm | ok 2026-05-05 06:06:59.516141 | 2026-05-05 06:06:59.516235 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-05 06:06:59.547608 | debian-bookworm | skipping: Conditional result was False 2026-05-05 06:06:59.558229 | 2026-05-05 06:06:59.558350 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-05 06:07:00.064180 | debian-bookworm | ok 2026-05-05 06:07:00.084590 | 2026-05-05 06:07:00.084702 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-05 06:07:00.112690 | debian-bookworm | ok 2026-05-05 06:07:00.117754 | 2026-05-05 06:07:00.117848 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-05 06:07:00.374190 | debian-bookworm -> localhost | ok 2026-05-05 06:07:00.385488 | 2026-05-05 06:07:00.385595 | TASK [validate-host : Collect information about the host] 2026-05-05 06:07:01.528067 | debian-bookworm | ok 2026-05-05 06:07:01.536869 | 2026-05-05 06:07:01.536956 | TASK [validate-host : Sanitize hostname] 2026-05-05 06:07:01.635682 | debian-bookworm | ok 2026-05-05 06:07:01.676021 | 2026-05-05 06:07:01.676174 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-05 06:07:02.498824 | debian-bookworm -> localhost | changed 2026-05-05 06:07:02.504684 | 2026-05-05 06:07:02.504820 | TASK [validate-host : Collect information about zuul worker] 2026-05-05 06:07:03.019165 | debian-bookworm | ok 2026-05-05 06:07:03.024878 | 2026-05-05 06:07:03.025014 | TASK [validate-host : Write out all zuul information for each host] 2026-05-05 06:07:03.601980 | debian-bookworm -> localhost | changed 2026-05-05 06:07:03.611004 | 2026-05-05 06:07:03.611096 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-05 06:07:03.897070 | debian-bookworm | ok 2026-05-05 06:07:03.906160 | 2026-05-05 06:07:03.906263 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-05 06:07:05.047964 | debian-bookworm | changed: 2026-05-05 06:07:05.048147 | debian-bookworm | cd+++++++++ src/ 2026-05-05 06:07:05.048181 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-05 06:07:05.048206 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-05 06:07:05.048228 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-05 06:07:05.048249 | debian-bookworm | Containerfile 2026-05-05 06:07:05.048397 | debian-bookworm | Containerfile 2026-05-05 06:07:05.088257 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/3d173138aed0434080641688510ba900/work/logs" 2026-05-05 06:19:40.679884 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3d173138aed0434080641688510ba900/work/artifacts" 2026-05-05 06:19:40.965500 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3d173138aed0434080641688510ba900/work/docs" 2026-05-05 06:19:40.984504 | 2026-05-05 06:19:40.985830 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-05 06:19:41.867187 | debian-bookworm | changed: 2026-05-05 06:19:41.867481 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-05 06:19:41.867523 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-05 06:19:41.867563 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-05 06:19:41.867616 | debian-bookworm | changed: All items complete 2026-05-05 06:19:41.867643 | 2026-05-05 06:19:42.454826 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 06:19:43.028872 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 06:19:43.041925 | 2026-05-05 06:19:43.042051 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-05 06:19:43.559100 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010260 2026-05-05 06:19:43.841832 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011034 2026-05-05 06:19:43.864630 | 2026-05-05 06:19:43.864786 | PLAY RECAP 2026-05-05 06:19:43.864869 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-05 06:19:43.864907 | 2026-05-05 06:19:44.004087 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-05 06:19:44.006318 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 06:19:44.791326 | 2026-05-05 06:19:44.791553 | PLAY [Base post] 2026-05-05 06:19:44.807328 | 2026-05-05 06:19:44.807527 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-05 06:19:45.681077 | debian-bookworm | changed 2026-05-05 06:19:45.690999 | 2026-05-05 06:19:45.691161 | PLAY RECAP 2026-05-05 06:19:45.691243 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-05 06:19:45.691323 | 2026-05-05 06:19:45.827153 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 06:19:45.828134 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-05 06:19:46.657708 | 2026-05-05 06:19:46.657921 | PLAY [Base post-logs] 2026-05-05 06:19:46.669522 | 2026-05-05 06:19:46.669707 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-05 06:19:47.141326 | localhost | changed 2026-05-05 06:19:47.162131 | 2026-05-05 06:19:47.162388 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-05 06:19:47.202381 | localhost | ok 2026-05-05 06:19:47.208961 | 2026-05-05 06:19:47.209143 | TASK [Set zuul-log-path fact] 2026-05-05 06:19:47.238503 | localhost | ok 2026-05-05 06:19:47.254679 | 2026-05-05 06:19:47.254923 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 06:19:47.293084 | localhost | ok 2026-05-05 06:19:47.298907 | 2026-05-05 06:19:47.299082 | TASK [upload-logs : Create log directories] 2026-05-05 06:19:47.818123 | localhost | changed 2026-05-05 06:19:47.821567 | 2026-05-05 06:19:47.821692 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-05 06:19:48.399561 | localhost -> localhost | ok: Runtime: 0:00:00.007964 2026-05-05 06:19:48.408912 | 2026-05-05 06:19:48.409152 | TASK [upload-logs : Upload logs to log server] 2026-05-05 06:19:49.001071 | localhost | Output suppressed because no_log was given 2026-05-05 06:19:49.003888 | 2026-05-05 06:19:49.004053 | LOOP [upload-logs : Compress console log and json output] 2026-05-05 06:19:49.076945 | localhost | skipping: Conditional result was False 2026-05-05 06:19:49.082554 | localhost | skipping: Conditional result was False 2026-05-05 06:19:49.096869 | 2026-05-05 06:19:49.097161 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-05 06:19:49.151201 | localhost | skipping: Conditional result was False 2026-05-05 06:19:49.151939 | 2026-05-05 06:19:49.154315 | localhost | skipping: Conditional result was False 2026-05-05 06:19:49.163182 | 2026-05-05 06:19:49.163452 | LOOP [upload-logs : Upload console log and json output]