2025-02-03 09:47:36.598663 | Job console starting... 2025-02-03 09:47:36.634448 | Updating repositories 2025-02-03 09:47:36.719995 | Preparing job workspace 2025-02-03 09:47:39.211960 | Running Ansible setup... 2025-02-03 09:47:47.411179 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-03 09:47:48.395366 | 2025-02-03 09:47:48.395488 | PLAY [Base pre] 2025-02-03 09:47:48.422878 | 2025-02-03 09:47:48.422992 | TASK [Setup log path fact] 2025-02-03 09:47:48.442643 | ubuntu-noble | ok 2025-02-03 09:47:48.463617 | 2025-02-03 09:47:48.463736 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 09:47:48.513778 | ubuntu-noble | ok 2025-02-03 09:47:48.520336 | 2025-02-03 09:47:48.526355 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 09:47:48.562310 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:47:48.569212 | 2025-02-03 09:47:48.569322 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 09:47:48.674126 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:47:48.681185 | 2025-02-03 09:47:48.681300 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 09:47:48.720936 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:47:48.727827 | 2025-02-03 09:47:48.727923 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 09:47:48.781810 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:47:48.788744 | 2025-02-03 09:47:48.788839 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 09:47:48.832545 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:47:48.862750 | 2025-02-03 09:47:48.862870 | TASK [emit-job-header : Print job information] 2025-02-03 09:47:48.967481 | # Job Information 2025-02-03 09:47:48.967628 | Ansible Version: 2.15.3 2025-02-03 09:47:48.967659 | Job: python-black 2025-02-03 09:47:48.967687 | Pipeline: check 2025-02-03 09:47:48.967708 | Executor: 7d211f194f6a 2025-02-03 09:47:48.967727 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1744 2025-02-03 09:47:48.967746 | Event ID: e12ec750-e213-11ef-9de8-4533b22536c2 2025-02-03 09:47:48.975768 | 2025-02-03 09:47:48.975870 | LOOP [emit-job-header : Print node information] 2025-02-03 09:47:49.241451 | ubuntu-noble | ok: 2025-02-03 09:47:49.241613 | ubuntu-noble | # Node Information 2025-02-03 09:47:49.241682 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-03 09:47:49.241708 | ubuntu-noble | Hostname: ubuntu 2025-02-03 09:47:49.241730 | ubuntu-noble | Username: zuul 2025-02-03 09:47:49.241750 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-03 09:47:49.241770 | ubuntu-noble | Provider: regiocloud-a 2025-02-03 09:47:49.241790 | ubuntu-noble | Label: ubuntu-noble 2025-02-03 09:47:49.241809 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-03 09:47:49.241830 | ubuntu-noble | Interface IP: 81.163.193.215 2025-02-03 09:47:49.267329 | 2025-02-03 09:47:49.267438 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-03 09:47:50.672334 | ubuntu-noble -> localhost | changed 2025-02-03 09:47:50.681357 | 2025-02-03 09:47:50.681481 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-03 09:47:52.598280 | ubuntu-noble -> localhost | changed 2025-02-03 09:47:52.613115 | 2025-02-03 09:47:52.613250 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-03 09:47:53.621810 | ubuntu-noble -> localhost | ok 2025-02-03 09:47:53.631569 | 2025-02-03 09:47:53.631682 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-03 09:47:53.717679 | ubuntu-noble | ok 2025-02-03 09:47:53.797838 | ubuntu-noble | included: /var/lib/zuul/builds/8f80c8ee6b144e5a84d2f5eda22d1509/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-03 09:47:53.826295 | 2025-02-03 09:47:53.826425 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-03 09:47:55.045451 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-03 09:47:55.045660 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f80c8ee6b144e5a84d2f5eda22d1509/work/8f80c8ee6b144e5a84d2f5eda22d1509_id_rsa 2025-02-03 09:47:55.045696 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f80c8ee6b144e5a84d2f5eda22d1509/work/8f80c8ee6b144e5a84d2f5eda22d1509_id_rsa.pub 2025-02-03 09:47:55.045720 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-03 09:47:55.045743 | ubuntu-noble -> localhost | SHA256:9zYscwcFg47fnEZhHIafNUBHUnHGTfxKg5ofqHy4JMo zuul-build-sshkey 2025-02-03 09:47:55.045765 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-03 09:47:55.045789 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-03 09:47:55.045810 | ubuntu-noble -> localhost | | =O====| 2025-02-03 09:47:55.045830 | ubuntu-noble -> localhost | | o.+=oo+| 2025-02-03 09:47:55.045849 | ubuntu-noble -> localhost | | o o =...| 2025-02-03 09:47:55.045868 | ubuntu-noble -> localhost | | . . =.o .| 2025-02-03 09:47:55.045887 | ubuntu-noble -> localhost | | S..B.o o | 2025-02-03 09:47:55.045905 | ubuntu-noble -> localhost | | .=o*.. | 2025-02-03 09:47:55.045924 | ubuntu-noble -> localhost | | ...ooo*.. | 2025-02-03 09:47:55.045944 | ubuntu-noble -> localhost | | . . o+ .=.o | 2025-02-03 09:47:55.045964 | ubuntu-noble -> localhost | | E .o | 2025-02-03 09:47:55.045983 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-03 09:47:55.046028 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.136911 2025-02-03 09:47:55.053716 | 2025-02-03 09:47:55.053801 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-03 09:47:55.107808 | ubuntu-noble | ok 2025-02-03 09:47:55.128180 | ubuntu-noble | included: /var/lib/zuul/builds/8f80c8ee6b144e5a84d2f5eda22d1509/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-03 09:47:55.140127 | 2025-02-03 09:47:55.140225 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-03 09:47:55.164443 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:47:55.171788 | 2025-02-03 09:47:55.171881 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-03 09:47:56.068763 | ubuntu-noble | changed 2025-02-03 09:47:56.079401 | 2025-02-03 09:47:56.079507 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-03 09:47:56.377002 | ubuntu-noble | ok 2025-02-03 09:47:56.384220 | 2025-02-03 09:47:56.384324 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-03 09:47:57.279312 | ubuntu-noble | changed 2025-02-03 09:47:57.286074 | 2025-02-03 09:47:57.286164 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-03 09:47:58.079760 | ubuntu-noble | changed 2025-02-03 09:47:58.125966 | 2025-02-03 09:47:58.126067 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-03 09:47:58.153690 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:47:58.160836 | 2025-02-03 09:47:58.160933 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-03 09:47:58.825943 | ubuntu-noble -> localhost | changed 2025-02-03 09:47:58.839000 | 2025-02-03 09:47:58.839101 | TASK [add-build-sshkey : Add back temp key] 2025-02-03 09:47:59.585973 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/8f80c8ee6b144e5a84d2f5eda22d1509/work/8f80c8ee6b144e5a84d2f5eda22d1509_id_rsa (zuul-build-sshkey) 2025-02-03 09:47:59.586170 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.029012 2025-02-03 09:47:59.596108 | 2025-02-03 09:47:59.596209 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-03 09:48:00.011357 | ubuntu-noble | ok 2025-02-03 09:48:00.017854 | 2025-02-03 09:48:00.017948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-03 09:48:00.064064 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 09:48:00.077891 | 2025-02-03 09:48:00.078000 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-03 09:48:00.584325 | ubuntu-noble | ok 2025-02-03 09:48:00.629846 | 2025-02-03 09:48:00.629960 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-03 09:48:00.686904 | ubuntu-noble | ok 2025-02-03 09:48:00.696406 | 2025-02-03 09:48:00.696500 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-03 09:48:01.113009 | ubuntu-noble -> localhost | ok 2025-02-03 09:48:01.122601 | 2025-02-03 09:48:01.122698 | TASK [validate-host : Collect information about the host] 2025-02-03 09:48:02.492765 | ubuntu-noble | ok 2025-02-03 09:48:02.536777 | 2025-02-03 09:48:02.537378 | TASK [validate-host : Sanitize hostname] 2025-02-03 09:48:02.688797 | ubuntu-noble | ok 2025-02-03 09:48:02.695331 | 2025-02-03 09:48:02.695437 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-03 09:48:03.836658 | ubuntu-noble -> localhost | changed 2025-02-03 09:48:03.843563 | 2025-02-03 09:48:03.843659 | TASK [validate-host : Collect information about zuul worker] 2025-02-03 09:48:04.425987 | ubuntu-noble | ok 2025-02-03 09:48:04.433073 | 2025-02-03 09:48:04.433173 | TASK [validate-host : Write out all zuul information for each host] 2025-02-03 09:48:05.488801 | ubuntu-noble -> localhost | changed 2025-02-03 09:48:05.526158 | 2025-02-03 09:48:05.526269 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-03 09:48:05.892727 | ubuntu-noble | ok 2025-02-03 09:48:05.907856 | 2025-02-03 09:48:05.907957 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-03 09:48:07.525329 | ubuntu-noble | changed: 2025-02-03 09:48:07.526332 | ubuntu-noble | cd+++++++++ src/ 2025-02-03 09:48:07.526525 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-03 09:48:07.526564 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-03 09:48:07.526593 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-03 09:48:07.526624 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.009876 2025-02-03 09:48:42.143714 | 2025-02-03 09:48:42.143851 | TASK [upload-logs : Upload logs to log server] 2025-02-03 09:48:42.759682 | localhost | Output suppressed because no_log was given 2025-02-03 09:48:42.765437 | 2025-02-03 09:48:42.765599 | LOOP [upload-logs : Compress console log and json output] 2025-02-03 09:48:42.841524 | localhost | skipping: Conditional result was False 2025-02-03 09:48:42.860552 | localhost | skipping: Conditional result was False 2025-02-03 09:48:42.873944 | 2025-02-03 09:48:42.874153 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-03 09:48:42.952049 | localhost | skipping: Conditional result was False 2025-02-03 09:48:42.953101 | 2025-02-03 09:48:42.962971 | localhost | skipping: Conditional result was False 2025-02-03 09:48:42.984943 | 2025-02-03 09:48:42.985171 | LOOP [upload-logs : Upload console log and json output]