2026-09-10 07:23:38.938296 | Job console starting 2026-09-10 07:23:38.950098 | Updating git repos 2026-09-10 07:23:39.015043 | Cloning repos into workspace 2026-09-10 07:23:39.101920 | Restoring repo states 2026-09-10 07:23:39.111049 | Merging changes 2026-09-10 07:23:40.052861 | Checking out repos 2026-09-10 07:23:40.221941 | Preparing playbooks 2026-09-10 07:23:42.023027 | Running Ansible setup 2026-09-10 07:23:47.721492 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 07:23:49.073281 | 2026-09-10 07:23:49.073395 | PLAY [Base pre] 2026-09-10 07:23:49.118012 | 2026-09-10 07:23:49.118125 | TASK [Setup log path fact] 2026-09-10 07:23:49.144796 | debian-bookworm | ok 2026-09-10 07:23:49.175800 | 2026-09-10 07:23:49.175922 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 07:23:49.203873 | debian-bookworm | ok 2026-09-10 07:23:49.220012 | 2026-09-10 07:23:49.220107 | TASK [emit-job-header : Print job information] 2026-09-10 07:23:49.258614 | # Job Information 2026-09-10 07:23:49.258749 | Ansible Version: 2.16.14 2026-09-10 07:23:49.258777 | Job: python-osism-test-setup 2026-09-10 07:23:49.258803 | Pipeline: check 2026-09-10 07:23:49.258821 | Executor: 521e9411259a 2026-09-10 07:23:49.258838 | Triggered by: https://github.com/osism/python-osism/pull/2687 2026-09-10 07:23:49.258856 | Event ID: 842d0890-ace8-11f1-9131-556e176f2fcc 2026-09-10 07:23:49.263480 | 2026-09-10 07:23:49.263595 | LOOP [emit-job-header : Print node information] 2026-09-10 07:23:49.360963 | debian-bookworm | ok: 2026-09-10 07:23:49.361126 | debian-bookworm | # Node Information 2026-09-10 07:23:49.361184 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-10 07:23:49.361208 | debian-bookworm | Hostname: debian 2026-09-10 07:23:49.361227 | debian-bookworm | Username: zuul 2026-09-10 07:23:49.361245 | debian-bookworm | Distro: Debian 12.15 2026-09-10 07:23:49.361265 | debian-bookworm | Provider: regiocloud-a 2026-09-10 07:23:49.361283 | debian-bookworm | Region: 2026-09-10 07:23:49.361301 | debian-bookworm | Label: debian-bookworm 2026-09-10 07:23:49.361317 | debian-bookworm | Product Name: OpenStack Nova 2026-09-10 07:23:49.361333 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe9e:3ccc 2026-09-10 07:23:49.378939 | 2026-09-10 07:23:49.379035 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-10 07:23:50.982671 | debian-bookworm -> localhost | changed 2026-09-10 07:23:50.996931 | 2026-09-10 07:23:50.997027 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-10 07:23:53.025185 | debian-bookworm -> localhost | changed 2026-09-10 07:23:53.036982 | 2026-09-10 07:23:53.041730 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-10 07:23:53.550734 | debian-bookworm -> localhost | ok 2026-09-10 07:23:53.556585 | 2026-09-10 07:23:53.556680 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-10 07:23:53.595880 | debian-bookworm | ok 2026-09-10 07:23:53.641958 | debian-bookworm | included: /var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-10 07:23:53.647773 | 2026-09-10 07:23:53.647859 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-10 07:23:55.809318 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-10 07:23:55.809519 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/work/59a06f65516b4c02a8b01d7784b101a6_id_rsa 2026-09-10 07:23:55.809723 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/work/59a06f65516b4c02a8b01d7784b101a6_id_rsa.pub 2026-09-10 07:23:55.809751 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-10 07:23:55.809772 | debian-bookworm -> localhost | SHA256:i1VXWGr3+5xc+yn3OHQmyjdKGTlnNJn4NFFvNccsZpc zuul-build-sshkey 2026-09-10 07:23:55.809791 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-10 07:23:55.809817 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-10 07:23:55.809836 | debian-bookworm -> localhost | | o+==| 2026-09-10 07:23:55.809855 | debian-bookworm -> localhost | | .++EO| 2026-09-10 07:23:55.809871 | debian-bookworm -> localhost | | . =oOoo| 2026-09-10 07:23:55.809888 | debian-bookworm -> localhost | | . o * = | 2026-09-10 07:23:55.809904 | debian-bookworm -> localhost | | S + + .| 2026-09-10 07:23:55.809925 | debian-bookworm -> localhost | | o . *o +| 2026-09-10 07:23:55.809943 | debian-bookworm -> localhost | | . . .oo =.| 2026-09-10 07:23:55.809959 | debian-bookworm -> localhost | | .o.=+*| 2026-09-10 07:23:55.809976 | debian-bookworm -> localhost | | .o+BB| 2026-09-10 07:23:55.809993 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-10 07:23:55.810041 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.247143 2026-09-10 07:23:55.815788 | 2026-09-10 07:23:55.815871 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-10 07:23:55.867112 | debian-bookworm | ok 2026-09-10 07:23:55.876848 | debian-bookworm | included: /var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-10 07:23:55.883335 | 2026-09-10 07:23:55.883409 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-10 07:23:55.896917 | debian-bookworm | skipping: Conditional result was False 2026-09-10 07:23:55.901748 | 2026-09-10 07:23:55.901819 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-10 07:23:56.565163 | debian-bookworm | changed 2026-09-10 07:23:56.572648 | 2026-09-10 07:23:56.572743 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-10 07:23:56.824571 | debian-bookworm | ok 2026-09-10 07:23:56.831762 | 2026-09-10 07:23:56.831856 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-10 07:23:57.491999 | debian-bookworm | changed 2026-09-10 07:23:57.496523 | 2026-09-10 07:23:57.496628 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-10 07:23:58.218319 | debian-bookworm | changed 2026-09-10 07:23:58.223402 | 2026-09-10 07:23:58.223475 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-10 07:23:58.268776 | debian-bookworm | skipping: Conditional result was False 2026-09-10 07:23:58.273937 | 2026-09-10 07:23:58.274021 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-10 07:23:59.067308 | debian-bookworm -> localhost | changed 2026-09-10 07:23:59.080339 | 2026-09-10 07:23:59.080438 | TASK [add-build-sshkey : Add back temp key] 2026-09-10 07:23:59.711827 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/work/59a06f65516b4c02a8b01d7784b101a6_id_rsa (zuul-build-sshkey) 2026-09-10 07:23:59.712031 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021319 2026-09-10 07:23:59.719258 | 2026-09-10 07:23:59.719351 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-10 07:24:00.430664 | debian-bookworm | ok 2026-09-10 07:24:00.435596 | 2026-09-10 07:24:00.435685 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-10 07:24:00.473258 | debian-bookworm | skipping: Conditional result was False 2026-09-10 07:24:00.483290 | 2026-09-10 07:24:00.483385 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-10 07:24:01.101977 | debian-bookworm | ok 2026-09-10 07:24:01.121953 | 2026-09-10 07:24:01.122059 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-10 07:24:01.149757 | debian-bookworm | ok 2026-09-10 07:24:01.154744 | 2026-09-10 07:24:01.154827 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-10 07:24:01.714626 | debian-bookworm -> localhost | ok 2026-09-10 07:24:01.720851 | 2026-09-10 07:24:01.720939 | TASK [validate-host : Collect information about the host] 2026-09-10 07:24:03.011894 | debian-bookworm | ok 2026-09-10 07:24:03.033268 | 2026-09-10 07:24:03.033375 | TASK [validate-host : Sanitize hostname] 2026-09-10 07:24:03.226074 | debian-bookworm | ok 2026-09-10 07:24:03.230381 | 2026-09-10 07:24:03.230465 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-10 07:24:04.021841 | debian-bookworm -> localhost | changed 2026-09-10 07:24:04.027168 | 2026-09-10 07:24:04.027263 | TASK [validate-host : Collect information about zuul worker] 2026-09-10 07:24:04.449395 | debian-bookworm | ok 2026-09-10 07:24:04.454266 | 2026-09-10 07:24:04.454363 | TASK [validate-host : Write out all zuul information for each host] 2026-09-10 07:24:05.276866 | debian-bookworm -> localhost | changed 2026-09-10 07:24:05.284445 | 2026-09-10 07:24:05.284535 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-10 07:24:05.572901 | debian-bookworm | ok 2026-09-10 07:24:05.581829 | 2026-09-10 07:24:05.581962 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-10 07:24:07.793856 | debian-bookworm | changed: 2026-09-10 07:24:07.795083 | debian-bookworm | cd+++++++++ src/ 2026-09-10 07:24:07.795154 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-10 07:24:07.795187 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-10 07:24:07.795214 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-10 07:24:07.795240 | debian-bookworm | Containerfile 2026-09-10 07:24:07.795399 | debian-bookworm | Containerfile 2026-09-10 07:24:07.809359 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/work/logs" 2026-09-10 07:26:45.671132 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/work/artifacts" 2026-09-10 07:26:45.930809 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/59a06f65516b4c02a8b01d7784b101a6/work/docs" 2026-09-10 07:26:45.949297 | 2026-09-10 07:26:45.949431 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-10 07:26:46.713224 | debian-bookworm | changed: 2026-09-10 07:26:46.713447 | debian-bookworm | .d..t...... ./ 2026-09-10 07:26:46.713484 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-10 07:26:46.713509 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-10 07:26:46.713530 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-10 07:26:46.713616 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-10 07:26:46.713641 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-10 07:26:46.713686 | debian-bookworm | changed: All items complete 2026-09-10 07:26:46.713713 | 2026-09-10 07:26:47.260848 | debian-bookworm | changed: .d..t...... ./ 2026-09-10 07:26:47.834143 | debian-bookworm | changed: .d..t...... ./ 2026-09-10 07:26:47.855244 | 2026-09-10 07:26:47.855369 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-10 07:26:48.190944 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008265 2026-09-10 07:26:48.467814 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006658 2026-09-10 07:26:48.485971 | 2026-09-10 07:26:48.486082 | PLAY RECAP 2026-09-10 07:26:48.486136 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 07:26:48.486161 | 2026-09-10 07:26:48.654113 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-10 07:26:48.654944 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 07:26:49.419187 | 2026-09-10 07:26:49.419352 | PLAY [Base post] 2026-09-10 07:26:49.448008 | 2026-09-10 07:26:49.448237 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-10 07:26:50.373483 | debian-bookworm | changed 2026-09-10 07:26:50.383203 | 2026-09-10 07:26:50.383328 | PLAY RECAP 2026-09-10 07:26:50.383407 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 07:26:50.383485 | 2026-09-10 07:26:50.505793 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 07:26:50.506631 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-10 07:26:51.296425 | 2026-09-10 07:26:51.296608 | PLAY [Base post-logs] 2026-09-10 07:26:51.307263 | 2026-09-10 07:26:51.307409 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-10 07:26:51.791737 | localhost | changed 2026-09-10 07:26:51.805864 | 2026-09-10 07:26:51.806035 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-10 07:26:51.842436 | localhost | ok 2026-09-10 07:26:51.846968 | 2026-09-10 07:26:51.847097 | TASK [Set zuul-log-path fact] 2026-09-10 07:26:51.863193 | localhost | ok 2026-09-10 07:26:51.875701 | 2026-09-10 07:26:51.875835 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 07:26:51.911901 | localhost | ok 2026-09-10 07:26:51.917691 | 2026-09-10 07:26:51.917839 | TASK [upload-logs : Create log directories] 2026-09-10 07:26:52.437531 | localhost | changed 2026-09-10 07:26:52.442782 | 2026-09-10 07:26:52.442936 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-10 07:26:52.945108 | localhost -> localhost | ok: Runtime: 0:00:00.004222 2026-09-10 07:26:52.951885 | 2026-09-10 07:26:52.952008 | TASK [upload-logs : Upload logs to log server] 2026-09-10 07:26:53.542738 | localhost | Output suppressed because no_log was given 2026-09-10 07:26:53.544950 | 2026-09-10 07:26:53.545062 | LOOP [upload-logs : Compress console log and json output] 2026-09-10 07:26:53.595620 | localhost | skipping: Conditional result was False 2026-09-10 07:26:53.603765 | localhost | skipping: Conditional result was False 2026-09-10 07:26:53.617055 | 2026-09-10 07:26:53.617260 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-10 07:26:53.674837 | localhost | skipping: Conditional result was False 2026-09-10 07:26:53.675152 | 2026-09-10 07:26:53.680021 | localhost | skipping: Conditional result was False 2026-09-10 07:26:53.689482 | 2026-09-10 07:26:53.689720 | LOOP [upload-logs : Upload console log and json output]