2025-01-09 08:02:19.365043 | Job console starting... 2025-01-09 08:02:19.405608 | Updating repositories 2025-01-09 08:02:19.455254 | Preparing job workspace 2025-01-09 08:02:21.971638 | Running Ansible setup... 2025-01-09 08:02:28.645397 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-09 08:02:29.884937 | 2025-01-09 08:02:29.885160 | PLAY [Base pre] 2025-01-09 08:02:29.929989 | 2025-01-09 08:02:29.930181 | TASK [Setup log path fact] 2025-01-09 08:02:29.974277 | ubuntu-noble | ok 2025-01-09 08:02:29.999879 | 2025-01-09 08:02:30.000041 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-09 08:02:30.067926 | ubuntu-noble | ok 2025-01-09 08:02:30.075008 | 2025-01-09 08:02:30.075140 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-09 08:02:30.132029 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:30.140107 | 2025-01-09 08:02:30.140249 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-09 08:02:30.186138 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:30.197051 | 2025-01-09 08:02:30.197183 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-09 08:02:30.242513 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:30.251152 | 2025-01-09 08:02:30.251297 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-09 08:02:30.300638 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:30.309268 | 2025-01-09 08:02:30.309422 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-09 08:02:30.361071 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:30.384812 | 2025-01-09 08:02:30.384989 | TASK [emit-job-header : Print job information] 2025-01-09 08:02:30.523646 | # Job Information 2025-01-09 08:02:30.523860 | Ansible Version: 2.15.3 2025-01-09 08:02:30.523894 | Job: ansible-collection-ensure-readme 2025-01-09 08:02:30.523925 | Pipeline: check 2025-01-09 08:02:30.523948 | Executor: 7d211f194f6a 2025-01-09 08:02:30.523967 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1735 2025-01-09 08:02:30.523986 | Event ID: 074e9ef0-ce60-11ef-9879-677b5b032314 2025-01-09 08:02:30.542495 | 2025-01-09 08:02:30.542623 | LOOP [emit-job-header : Print node information] 2025-01-09 08:02:30.775643 | ubuntu-noble | ok: 2025-01-09 08:02:30.775791 | ubuntu-noble | # Node Information 2025-01-09 08:02:30.775820 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-09 08:02:30.775840 | ubuntu-noble | Hostname: ubuntu 2025-01-09 08:02:30.775858 | ubuntu-noble | Username: zuul 2025-01-09 08:02:30.775875 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-09 08:02:30.775891 | ubuntu-noble | Provider: regiocloud-a 2025-01-09 08:02:30.775907 | ubuntu-noble | Label: ubuntu-noble 2025-01-09 08:02:30.775922 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-09 08:02:30.775939 | ubuntu-noble | Interface IP: 81.163.193.34 2025-01-09 08:02:30.804365 | 2025-01-09 08:02:30.804497 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-09 08:02:31.390514 | ubuntu-noble -> localhost | changed 2025-01-09 08:02:31.397453 | 2025-01-09 08:02:31.397542 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-09 08:02:32.428569 | ubuntu-noble -> localhost | changed 2025-01-09 08:02:32.439519 | 2025-01-09 08:02:32.439603 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-09 08:02:32.886237 | ubuntu-noble -> localhost | ok 2025-01-09 08:02:32.895915 | 2025-01-09 08:02:32.896024 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-09 08:02:32.925529 | ubuntu-noble | ok 2025-01-09 08:02:32.943060 | ubuntu-noble | included: /var/lib/zuul/builds/bccad50b78014ba1a640cbc4232851e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-09 08:02:32.959793 | 2025-01-09 08:02:32.959887 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-09 08:02:34.359750 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-09 08:02:34.359945 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/bccad50b78014ba1a640cbc4232851e8/work/bccad50b78014ba1a640cbc4232851e8_id_rsa 2025-01-09 08:02:34.359976 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/bccad50b78014ba1a640cbc4232851e8/work/bccad50b78014ba1a640cbc4232851e8_id_rsa.pub 2025-01-09 08:02:34.359997 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-09 08:02:34.360017 | ubuntu-noble -> localhost | SHA256:o6ibQT4S4n8AKCPajtHVPfjeq9CoKehaS3F7Q8fx5P8 zuul-build-sshkey 2025-01-09 08:02:34.360047 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-09 08:02:34.360068 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-09 08:02:34.360086 | ubuntu-noble -> localhost | | | 2025-01-09 08:02:34.360104 | ubuntu-noble -> localhost | | | 2025-01-09 08:02:34.360120 | ubuntu-noble -> localhost | |. . o. . | 2025-01-09 08:02:34.360136 | ubuntu-noble -> localhost | |=. . o.o= | 2025-01-09 08:02:34.360152 | ubuntu-noble -> localhost | |*++.. ..S.o | 2025-01-09 08:02:34.360168 | ubuntu-noble -> localhost | |=+o+ + =.. . | 2025-01-09 08:02:34.360184 | ubuntu-noble -> localhost | |.** + *... . | 2025-01-09 08:02:34.360201 | ubuntu-noble -> localhost | |o++* = o. . . | 2025-01-09 08:02:34.360218 | ubuntu-noble -> localhost | |+.=++ .... E | 2025-01-09 08:02:34.360234 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-09 08:02:34.360275 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.861350 2025-01-09 08:02:34.376443 | 2025-01-09 08:02:34.376555 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-09 08:02:34.428831 | ubuntu-noble | ok 2025-01-09 08:02:34.446315 | ubuntu-noble | included: /var/lib/zuul/builds/bccad50b78014ba1a640cbc4232851e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-09 08:02:34.460833 | 2025-01-09 08:02:34.460928 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-09 08:02:34.511197 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:34.517213 | 2025-01-09 08:02:34.517290 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-09 08:02:35.242800 | ubuntu-noble | changed 2025-01-09 08:02:35.250723 | 2025-01-09 08:02:35.250804 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-09 08:02:35.536617 | ubuntu-noble | ok 2025-01-09 08:02:35.550756 | 2025-01-09 08:02:35.551029 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-09 08:02:36.351198 | ubuntu-noble | changed 2025-01-09 08:02:36.360121 | 2025-01-09 08:02:36.360213 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-09 08:02:37.087147 | ubuntu-noble | changed 2025-01-09 08:02:37.100678 | 2025-01-09 08:02:37.100798 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-09 08:02:37.150729 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:37.165268 | 2025-01-09 08:02:37.165418 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-09 08:02:38.426466 | ubuntu-noble -> localhost | changed 2025-01-09 08:02:38.522821 | 2025-01-09 08:02:38.522941 | TASK [add-build-sshkey : Add back temp key] 2025-01-09 08:02:39.022259 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/bccad50b78014ba1a640cbc4232851e8/work/bccad50b78014ba1a640cbc4232851e8_id_rsa (zuul-build-sshkey) 2025-01-09 08:02:39.022457 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.008855 2025-01-09 08:02:39.029738 | 2025-01-09 08:02:39.029829 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-09 08:02:39.533578 | ubuntu-noble | ok 2025-01-09 08:02:39.541896 | 2025-01-09 08:02:39.542025 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-09 08:02:39.567447 | ubuntu-noble | skipping: Conditional result was False 2025-01-09 08:02:39.585988 | 2025-01-09 08:02:39.586083 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-09 08:02:39.994824 | ubuntu-noble | ok 2025-01-09 08:02:40.013242 | 2025-01-09 08:02:40.013352 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-09 08:02:40.080988 | ubuntu-noble | ok 2025-01-09 08:02:40.090057 | 2025-01-09 08:02:40.090166 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-09 08:02:40.525206 | ubuntu-noble -> localhost | ok 2025-01-09 08:02:40.533557 | 2025-01-09 08:02:40.533649 | TASK [validate-host : Collect information about the host] 2025-01-09 08:02:41.779084 | ubuntu-noble | ok 2025-01-09 08:02:41.793864 | 2025-01-09 08:02:41.793980 | TASK [validate-host : Sanitize hostname] 2025-01-09 08:02:41.939970 | ubuntu-noble | ok 2025-01-09 08:02:41.961821 | 2025-01-09 08:02:41.961969 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-09 08:02:42.577305 | ubuntu-noble -> localhost | changed 2025-01-09 08:02:42.584156 | 2025-01-09 08:02:42.584250 | TASK [validate-host : Collect information about zuul worker] 2025-01-09 08:02:43.075316 | ubuntu-noble | ok 2025-01-09 08:02:43.084744 | 2025-01-09 08:02:43.084858 | TASK [validate-host : Write out all zuul information for each host] 2025-01-09 08:02:43.711470 | ubuntu-noble -> localhost | changed 2025-01-09 08:02:43.723878 | 2025-01-09 08:02:43.723978 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-09 08:02:44.053091 | ubuntu-noble | ok 2025-01-09 08:02:44.067687 | 2025-01-09 08:02:44.067812 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-09 08:02:45.403335 | ubuntu-noble | changed: 2025-01-09 08:02:45.403544 | ubuntu-noble | cd+++++++++ src/ 2025-01-09 08:02:45.403577 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-09 08:02:45.403601 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-09 08:02:45.403623 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-01-09 08:02:45.403644 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.006152 2025-01-09 08:03:04.622927 | 2025-01-09 08:03:04.623034 | TASK [upload-logs : Upload logs to log server] 2025-01-09 08:03:05.153564 | localhost | Output suppressed because no_log was given 2025-01-09 08:03:05.158827 | 2025-01-09 08:03:05.158986 | LOOP [upload-logs : Compress console log and json output] 2025-01-09 08:03:05.228129 | localhost | skipping: Conditional result was False 2025-01-09 08:03:05.256489 | localhost | skipping: Conditional result was False 2025-01-09 08:03:05.265993 | 2025-01-09 08:03:05.266164 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-09 08:03:05.334219 | localhost | skipping: Conditional result was False 2025-01-09 08:03:05.334551 | 2025-01-09 08:03:05.349336 | localhost | skipping: Conditional result was False 2025-01-09 08:03:05.359760 | 2025-01-09 08:03:05.359895 | LOOP [upload-logs : Upload console log and json output]