2025-02-03 13:51:46.862839 | Job console starting... 2025-02-03 13:51:46.883690 | Updating repositories 2025-02-03 13:51:46.930824 | Preparing job workspace 2025-02-03 13:51:48.828698 | Running Ansible setup... 2025-02-03 13:51:53.733597 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-03 13:51:54.507314 | 2025-02-03 13:51:54.507485 | PLAY [Base pre] 2025-02-03 13:51:54.538085 | 2025-02-03 13:51:54.538275 | TASK [Setup log path fact] 2025-02-03 13:51:54.585164 | ubuntu-noble | ok 2025-02-03 13:51:54.618929 | 2025-02-03 13:51:54.619233 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 13:51:54.714303 | ubuntu-noble | ok 2025-02-03 13:51:54.730397 | 2025-02-03 13:51:54.730567 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 13:51:54.774722 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:51:54.782680 | 2025-02-03 13:51:54.782826 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 13:51:54.839031 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:51:54.871907 | 2025-02-03 13:51:54.872151 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 13:51:54.900799 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:51:54.922901 | 2025-02-03 13:51:54.923049 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 13:51:54.948199 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:51:54.955617 | 2025-02-03 13:51:54.955742 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 13:51:54.980409 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:51:54.996013 | 2025-02-03 13:51:54.996162 | TASK [emit-job-header : Print job information] 2025-02-03 13:51:55.061862 | # Job Information 2025-02-03 13:51:55.062076 | Ansible Version: 2.15.3 2025-02-03 13:51:55.062115 | Job: ansible-collection-ensure-readme 2025-02-03 13:51:55.062197 | Pipeline: check 2025-02-03 13:51:55.062224 | Executor: 7d211f194f6a 2025-02-03 13:51:55.062247 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1747 2025-02-03 13:51:55.062268 | Event ID: fc9dd180-e235-11ef-827f-afa30ffcf539 2025-02-03 13:51:55.069374 | 2025-02-03 13:51:55.069493 | LOOP [emit-job-header : Print node information] 2025-02-03 13:51:55.237043 | ubuntu-noble | ok: 2025-02-03 13:51:55.237270 | ubuntu-noble | # Node Information 2025-02-03 13:51:55.237306 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-03 13:51:55.237331 | ubuntu-noble | Hostname: ubuntu 2025-02-03 13:51:55.237352 | ubuntu-noble | Username: zuul 2025-02-03 13:51:55.237372 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-03 13:51:55.237392 | ubuntu-noble | Provider: regiocloud-a 2025-02-03 13:51:55.237412 | ubuntu-noble | Label: ubuntu-noble 2025-02-03 13:51:55.237431 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-03 13:51:55.237452 | ubuntu-noble | Interface IP: 81.163.193.161 2025-02-03 13:51:55.251519 | 2025-02-03 13:51:55.251647 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-03 13:51:55.797648 | ubuntu-noble -> localhost | changed 2025-02-03 13:51:55.807348 | 2025-02-03 13:51:55.807472 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-03 13:51:56.965245 | ubuntu-noble -> localhost | changed 2025-02-03 13:51:56.986973 | 2025-02-03 13:51:56.987110 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-03 13:51:57.284082 | ubuntu-noble -> localhost | ok 2025-02-03 13:51:57.293294 | 2025-02-03 13:51:57.293468 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-03 13:51:57.325617 | ubuntu-noble | ok 2025-02-03 13:51:57.342882 | ubuntu-noble | included: /var/lib/zuul/builds/32522e4b292147beb94b6ae28defae49/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-03 13:51:57.351219 | 2025-02-03 13:51:57.351352 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-03 13:51:58.355285 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-03 13:51:58.355521 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/32522e4b292147beb94b6ae28defae49/work/32522e4b292147beb94b6ae28defae49_id_rsa 2025-02-03 13:51:58.355557 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/32522e4b292147beb94b6ae28defae49/work/32522e4b292147beb94b6ae28defae49_id_rsa.pub 2025-02-03 13:51:58.355582 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-03 13:51:58.355603 | ubuntu-noble -> localhost | SHA256:eIFhv/COXX3ypLiT14+uafvWFCto/984qEHCDkv/sFw zuul-build-sshkey 2025-02-03 13:51:58.355625 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-03 13:51:58.355650 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-03 13:51:58.355671 | ubuntu-noble -> localhost | | o | 2025-02-03 13:51:58.355690 | ubuntu-noble -> localhost | | . + | 2025-02-03 13:51:58.355710 | ubuntu-noble -> localhost | | o o | 2025-02-03 13:51:58.355729 | ubuntu-noble -> localhost | | = o . . | 2025-02-03 13:51:58.355749 | ubuntu-noble -> localhost | | + S o + o o| 2025-02-03 13:51:58.355768 | ubuntu-noble -> localhost | | . X + + B ..| 2025-02-03 13:51:58.355786 | ubuntu-noble -> localhost | | o * E.oooo | 2025-02-03 13:51:58.355806 | ubuntu-noble -> localhost | | . =oo+o+oo| 2025-02-03 13:51:58.355825 | ubuntu-noble -> localhost | | o +=+**+=| 2025-02-03 13:51:58.355843 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-03 13:51:58.355889 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.370113 2025-02-03 13:51:58.364824 | 2025-02-03 13:51:58.364953 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-03 13:51:58.418306 | ubuntu-noble | ok 2025-02-03 13:51:58.431225 | ubuntu-noble | included: /var/lib/zuul/builds/32522e4b292147beb94b6ae28defae49/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-03 13:51:58.441069 | 2025-02-03 13:51:58.441595 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-03 13:51:58.467032 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:51:58.474757 | 2025-02-03 13:51:58.474879 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-03 13:51:59.153032 | ubuntu-noble | changed 2025-02-03 13:51:59.166538 | 2025-02-03 13:51:59.166669 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-03 13:51:59.467066 | ubuntu-noble | ok 2025-02-03 13:51:59.490957 | 2025-02-03 13:51:59.491160 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-03 13:52:00.242038 | ubuntu-noble | changed 2025-02-03 13:52:00.268450 | 2025-02-03 13:52:00.268582 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-03 13:52:00.895426 | ubuntu-noble | changed 2025-02-03 13:52:00.906774 | 2025-02-03 13:52:00.906923 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-03 13:52:00.932498 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:52:00.943795 | 2025-02-03 13:52:00.943950 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-03 13:52:01.475188 | ubuntu-noble -> localhost | changed 2025-02-03 13:52:01.492583 | 2025-02-03 13:52:01.492728 | TASK [add-build-sshkey : Add back temp key] 2025-02-03 13:52:01.845385 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/32522e4b292147beb94b6ae28defae49/work/32522e4b292147beb94b6ae28defae49_id_rsa (zuul-build-sshkey) 2025-02-03 13:52:01.845934 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013310 2025-02-03 13:52:01.861014 | 2025-02-03 13:52:01.861179 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-03 13:52:02.393103 | ubuntu-noble | ok 2025-02-03 13:52:02.399981 | 2025-02-03 13:52:02.400109 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-03 13:52:02.438812 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 13:52:02.453393 | 2025-02-03 13:52:02.453519 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-03 13:52:02.949431 | ubuntu-noble | ok 2025-02-03 13:52:02.964348 | 2025-02-03 13:52:02.964507 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-03 13:52:03.001775 | ubuntu-noble | ok 2025-02-03 13:52:03.019926 | 2025-02-03 13:52:03.020057 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-03 13:52:03.421484 | ubuntu-noble -> localhost | ok 2025-02-03 13:52:03.429600 | 2025-02-03 13:52:03.429717 | TASK [validate-host : Collect information about the host] 2025-02-03 13:52:04.591209 | ubuntu-noble | ok 2025-02-03 13:52:04.608803 | 2025-02-03 13:52:04.609087 | TASK [validate-host : Sanitize hostname] 2025-02-03 13:52:04.693988 | ubuntu-noble | ok 2025-02-03 13:52:04.705967 | 2025-02-03 13:52:04.706102 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-03 13:52:05.297026 | ubuntu-noble -> localhost | changed 2025-02-03 13:52:05.304933 | 2025-02-03 13:52:05.305056 | TASK [validate-host : Collect information about zuul worker] 2025-02-03 13:52:05.760539 | ubuntu-noble | ok 2025-02-03 13:52:05.768867 | 2025-02-03 13:52:05.768994 | TASK [validate-host : Write out all zuul information for each host] 2025-02-03 13:52:06.325519 | ubuntu-noble -> localhost | changed 2025-02-03 13:52:06.341982 | 2025-02-03 13:52:06.342106 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-03 13:52:06.619504 | ubuntu-noble | ok 2025-02-03 13:52:06.626725 | 2025-02-03 13:52:06.626837 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-03 13:52:07.761730 | ubuntu-noble | changed: 2025-02-03 13:52:07.761986 | ubuntu-noble | cd+++++++++ src/ 2025-02-03 13:52:07.762029 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-03 13:52:07.762059 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-03 13:52:07.762086 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-03 13:52:07.762112 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.023038 2025-02-03 13:52:25.156799 | 2025-02-03 13:52:25.156920 | TASK [upload-logs : Upload logs to log server] 2025-02-03 13:52:25.738740 | localhost | Output suppressed because no_log was given 2025-02-03 13:52:25.742129 | 2025-02-03 13:52:25.742242 | LOOP [upload-logs : Compress console log and json output] 2025-02-03 13:52:25.815663 | localhost | skipping: Conditional result was False 2025-02-03 13:52:25.835077 | localhost | skipping: Conditional result was False 2025-02-03 13:52:25.842482 | 2025-02-03 13:52:25.842597 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-03 13:52:25.918572 | localhost | skipping: Conditional result was False 2025-02-03 13:52:25.918867 | 2025-02-03 13:52:25.933236 | localhost | skipping: Conditional result was False 2025-02-03 13:52:25.945089 | 2025-02-03 13:52:25.945275 | LOOP [upload-logs : Upload console log and json output]