2025-02-18 19:15:50.281498 | Job console starting... 2025-02-18 19:15:50.321770 | Updating repositories 2025-02-18 19:15:50.375450 | Preparing job workspace 2025-02-18 19:15:53.123727 | Running Ansible setup... 2025-02-18 19:15:59.908712 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-18 19:16:01.197015 | 2025-02-18 19:16:01.197483 | PLAY [Base pre] 2025-02-18 19:16:01.259885 | 2025-02-18 19:16:01.260033 | TASK [Setup log path fact] 2025-02-18 19:16:01.310606 | ubuntu-noble | ok 2025-02-18 19:16:01.331880 | 2025-02-18 19:16:01.332000 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 19:16:01.389260 | ubuntu-noble | ok 2025-02-18 19:16:01.407608 | 2025-02-18 19:16:01.407710 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 19:16:01.441861 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.448923 | 2025-02-18 19:16:01.449024 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 19:16:01.523564 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.530810 | 2025-02-18 19:16:01.530909 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 19:16:01.569722 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.576622 | 2025-02-18 19:16:01.576830 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 19:16:01.610938 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.624059 | 2025-02-18 19:16:01.624165 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 19:16:01.651739 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:01.666229 | 2025-02-18 19:16:01.666341 | TASK [emit-job-header : Print job information] 2025-02-18 19:16:01.728087 | # Job Information 2025-02-18 19:16:01.728245 | Ansible Version: 2.15.3 2025-02-18 19:16:01.728276 | Job: ansible-collection-ensure-readme 2025-02-18 19:16:01.728306 | Pipeline: check 2025-02-18 19:16:01.728327 | Executor: 7d211f194f6a 2025-02-18 19:16:01.728346 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1764 2025-02-18 19:16:01.728365 | Event ID: bef90fe0-ee2c-11ef-98e7-aaa7cad212a0 2025-02-18 19:16:01.738124 | 2025-02-18 19:16:01.738253 | LOOP [emit-job-header : Print node information] 2025-02-18 19:16:01.969864 | ubuntu-noble | ok: 2025-02-18 19:16:01.970020 | ubuntu-noble | # Node Information 2025-02-18 19:16:01.970135 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-18 19:16:01.970162 | ubuntu-noble | Hostname: ubuntu 2025-02-18 19:16:01.970190 | ubuntu-noble | Username: zuul 2025-02-18 19:16:01.970209 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-18 19:16:01.970226 | ubuntu-noble | Provider: regiocloud-a 2025-02-18 19:16:01.970242 | ubuntu-noble | Label: ubuntu-noble 2025-02-18 19:16:01.970258 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-18 19:16:01.970276 | ubuntu-noble | Interface IP: 81.163.193.21 2025-02-18 19:16:01.997705 | 2025-02-18 19:16:01.997819 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-18 19:16:02.532454 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:02.540652 | 2025-02-18 19:16:02.540759 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-18 19:16:03.740954 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:03.751851 | 2025-02-18 19:16:03.751935 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-18 19:16:04.158506 | ubuntu-noble -> localhost | ok 2025-02-18 19:16:04.165437 | 2025-02-18 19:16:04.165519 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-18 19:16:04.203862 | ubuntu-noble | ok 2025-02-18 19:16:04.217347 | ubuntu-noble | included: /var/lib/zuul/builds/564bb5f4c9bc4bb897f298316e42dce1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-18 19:16:04.224528 | 2025-02-18 19:16:04.224607 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-18 19:16:05.072785 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-18 19:16:05.072949 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/564bb5f4c9bc4bb897f298316e42dce1/work/564bb5f4c9bc4bb897f298316e42dce1_id_rsa 2025-02-18 19:16:05.072980 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/564bb5f4c9bc4bb897f298316e42dce1/work/564bb5f4c9bc4bb897f298316e42dce1_id_rsa.pub 2025-02-18 19:16:05.073001 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-18 19:16:05.073020 | ubuntu-noble -> localhost | SHA256:srFDgunYe1+x5ItNN0YR+Hq4mpRQJCDtxjIknT4dohg zuul-build-sshkey 2025-02-18 19:16:05.073038 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-18 19:16:05.073059 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-18 19:16:05.073077 | ubuntu-noble -> localhost | |.o.o. . .. | 2025-02-18 19:16:05.073094 | ubuntu-noble -> localhost | |Eo= .o . . | 2025-02-18 19:16:05.073110 | ubuntu-noble -> localhost | |+* o .. .. | 2025-02-18 19:16:05.073126 | ubuntu-noble -> localhost | |= *o.. .. | 2025-02-18 19:16:05.073142 | ubuntu-noble -> localhost | | +o.o + So. | 2025-02-18 19:16:05.073158 | ubuntu-noble -> localhost | | + + Oo+. | 2025-02-18 19:16:05.073186 | ubuntu-noble -> localhost | |. o * =o+ | 2025-02-18 19:16:05.073203 | ubuntu-noble -> localhost | | ... B.+ . | 2025-02-18 19:16:05.073219 | ubuntu-noble -> localhost | | .. .=.o | 2025-02-18 19:16:05.073235 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-18 19:16:05.073274 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.222340 2025-02-18 19:16:05.080339 | 2025-02-18 19:16:05.080445 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-18 19:16:05.109215 | ubuntu-noble | ok 2025-02-18 19:16:05.118962 | ubuntu-noble | included: /var/lib/zuul/builds/564bb5f4c9bc4bb897f298316e42dce1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-18 19:16:05.126983 | 2025-02-18 19:16:05.127064 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-18 19:16:05.140540 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:05.149527 | 2025-02-18 19:16:05.149604 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-18 19:16:05.858153 | ubuntu-noble | changed 2025-02-18 19:16:05.878735 | 2025-02-18 19:16:05.878843 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-18 19:16:06.178987 | ubuntu-noble | ok 2025-02-18 19:16:06.189569 | 2025-02-18 19:16:06.189683 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-18 19:16:06.900571 | ubuntu-noble | changed 2025-02-18 19:16:06.908275 | 2025-02-18 19:16:06.908367 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-18 19:16:07.648791 | ubuntu-noble | changed 2025-02-18 19:16:07.667854 | 2025-02-18 19:16:07.667956 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-18 19:16:07.721211 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:07.729022 | 2025-02-18 19:16:07.729119 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-18 19:16:08.468559 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:08.479408 | 2025-02-18 19:16:08.479494 | TASK [add-build-sshkey : Add back temp key] 2025-02-18 19:16:08.982512 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/564bb5f4c9bc4bb897f298316e42dce1/work/564bb5f4c9bc4bb897f298316e42dce1_id_rsa (zuul-build-sshkey) 2025-02-18 19:16:08.982709 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010182 2025-02-18 19:16:09.043594 | 2025-02-18 19:16:09.043692 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-18 19:16:09.472249 | ubuntu-noble | ok 2025-02-18 19:16:09.477697 | 2025-02-18 19:16:09.477782 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-18 19:16:09.500964 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 19:16:09.511683 | 2025-02-18 19:16:09.511770 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-18 19:16:09.935619 | ubuntu-noble | ok 2025-02-18 19:16:09.951917 | 2025-02-18 19:16:09.952016 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-18 19:16:09.990733 | ubuntu-noble | ok 2025-02-18 19:16:09.995910 | 2025-02-18 19:16:09.995984 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-18 19:16:10.365619 | ubuntu-noble -> localhost | ok 2025-02-18 19:16:10.371900 | 2025-02-18 19:16:10.371976 | TASK [validate-host : Collect information about the host] 2025-02-18 19:16:11.459395 | ubuntu-noble | ok 2025-02-18 19:16:11.472191 | 2025-02-18 19:16:11.472292 | TASK [validate-host : Sanitize hostname] 2025-02-18 19:16:11.561309 | ubuntu-noble | ok 2025-02-18 19:16:11.567490 | 2025-02-18 19:16:11.567587 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-18 19:16:12.159988 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:12.166988 | 2025-02-18 19:16:12.167084 | TASK [validate-host : Collect information about zuul worker] 2025-02-18 19:16:12.686061 | ubuntu-noble | ok 2025-02-18 19:16:12.692373 | 2025-02-18 19:16:12.692483 | TASK [validate-host : Write out all zuul information for each host] 2025-02-18 19:16:13.353656 | ubuntu-noble -> localhost | changed 2025-02-18 19:16:13.365933 | 2025-02-18 19:16:13.366029 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-18 19:16:13.684320 | ubuntu-noble | ok 2025-02-18 19:16:13.698972 | 2025-02-18 19:16:13.699081 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-18 19:16:15.464251 | ubuntu-noble | changed: 2025-02-18 19:16:15.464435 | ubuntu-noble | cd+++++++++ src/ 2025-02-18 19:16:15.464469 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-18 19:16:15.464493 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-18 19:16:15.464520 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-18 19:16:15.464548 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007354 2025-02-18 19:16:34.731110 | 2025-02-18 19:16:34.731343 | TASK [upload-logs : Upload logs to log server] 2025-02-18 19:16:35.319123 | localhost | Output suppressed because no_log was given 2025-02-18 19:16:35.325108 | 2025-02-18 19:16:35.325323 | LOOP [upload-logs : Compress console log and json output] 2025-02-18 19:16:35.407026 | localhost | skipping: Conditional result was False 2025-02-18 19:16:35.425687 | localhost | skipping: Conditional result was False 2025-02-18 19:16:35.441839 | 2025-02-18 19:16:35.442077 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-18 19:16:35.517480 | localhost | skipping: Conditional result was False 2025-02-18 19:16:35.518098 | 2025-02-18 19:16:35.530911 | localhost | skipping: Conditional result was False 2025-02-18 19:16:35.550742 | 2025-02-18 19:16:35.550971 | LOOP [upload-logs : Upload console log and json output]