2025-02-07 08:36:08.915853 | Job console starting... 2025-02-07 08:36:08.968294 | Updating repositories 2025-02-07 08:36:09.061307 | Preparing job workspace 2025-02-07 08:36:11.476622 | Running Ansible setup... 2025-02-07 08:36:17.715804 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-07 08:36:18.626114 | 2025-02-07 08:36:18.626316 | PLAY [Base pre] 2025-02-07 08:36:18.687700 | 2025-02-07 08:36:18.687891 | TASK [Setup log path fact] 2025-02-07 08:36:18.732178 | ubuntu-noble | ok 2025-02-07 08:36:18.756081 | 2025-02-07 08:36:18.756245 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-07 08:36:18.839309 | ubuntu-noble | ok 2025-02-07 08:36:18.850085 | 2025-02-07 08:36:18.850225 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-07 08:36:18.926539 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:18.934373 | 2025-02-07 08:36:18.934515 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-07 08:36:19.037165 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:19.044963 | 2025-02-07 08:36:19.045103 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-07 08:36:19.070143 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:19.086974 | 2025-02-07 08:36:19.087121 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-07 08:36:19.122225 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:19.135377 | 2025-02-07 08:36:19.135554 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-07 08:36:19.183096 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:19.212591 | 2025-02-07 08:36:19.212762 | TASK [emit-job-header : Print job information] 2025-02-07 08:36:19.306020 | # Job Information 2025-02-07 08:36:19.306206 | Ansible Version: 2.15.3 2025-02-07 08:36:19.306238 | Job: python-black 2025-02-07 08:36:19.306266 | Pipeline: check 2025-02-07 08:36:19.306288 | Executor: 7d211f194f6a 2025-02-07 08:36:19.306308 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1751 2025-02-07 08:36:19.306327 | Event ID: 8e9d8940-e52e-11ef-985a-ea75c8d487a5 2025-02-07 08:36:19.317397 | 2025-02-07 08:36:19.317525 | LOOP [emit-job-header : Print node information] 2025-02-07 08:36:19.544776 | ubuntu-noble | ok: 2025-02-07 08:36:19.545044 | ubuntu-noble | # Node Information 2025-02-07 08:36:19.545084 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-07 08:36:19.545109 | ubuntu-noble | Hostname: ubuntu 2025-02-07 08:36:19.545131 | ubuntu-noble | Username: zuul 2025-02-07 08:36:19.545152 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-07 08:36:19.545172 | ubuntu-noble | Provider: regiocloud-a 2025-02-07 08:36:19.545191 | ubuntu-noble | Label: ubuntu-noble 2025-02-07 08:36:19.545211 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-07 08:36:19.545230 | ubuntu-noble | Interface IP: 81.163.193.71 2025-02-07 08:36:19.571148 | 2025-02-07 08:36:19.571289 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-07 08:36:20.084469 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:20.092524 | 2025-02-07 08:36:20.092620 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-07 08:36:21.677004 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:21.690809 | 2025-02-07 08:36:21.690912 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-07 08:36:22.031395 | ubuntu-noble -> localhost | ok 2025-02-07 08:36:22.039866 | 2025-02-07 08:36:22.039976 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-07 08:36:22.081205 | ubuntu-noble | ok 2025-02-07 08:36:22.097265 | ubuntu-noble | included: /var/lib/zuul/builds/f2d59ed4be5147a9b7a9c602da09fbf5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-07 08:36:22.104928 | 2025-02-07 08:36:22.105020 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-07 08:36:23.823511 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-07 08:36:23.823796 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/f2d59ed4be5147a9b7a9c602da09fbf5/work/f2d59ed4be5147a9b7a9c602da09fbf5_id_rsa 2025-02-07 08:36:23.823859 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/f2d59ed4be5147a9b7a9c602da09fbf5/work/f2d59ed4be5147a9b7a9c602da09fbf5_id_rsa.pub 2025-02-07 08:36:23.823902 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-07 08:36:23.823939 | ubuntu-noble -> localhost | SHA256:YTH6j4T/bFtwgZn1vb/Qt5OXCSwRwLoY0iSHgL4GzaE zuul-build-sshkey 2025-02-07 08:36:23.823975 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-07 08:36:23.824016 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-07 08:36:23.824051 | ubuntu-noble -> localhost | |... . +.. . | 2025-02-07 08:36:23.824084 | ubuntu-noble -> localhost | |. .o o . + * . . | 2025-02-07 08:36:23.824118 | ubuntu-noble -> localhost | |.+ .= . + + o . .| 2025-02-07 08:36:23.824150 | ubuntu-noble -> localhost | |E.o. o = . . . .| 2025-02-07 08:36:23.824182 | ubuntu-noble -> localhost | |. . . + S . + . | 2025-02-07 08:36:23.824214 | ubuntu-noble -> localhost | | o . + o + o. .| 2025-02-07 08:36:23.824247 | ubuntu-noble -> localhost | |. o . o...*| 2025-02-07 08:36:23.824280 | ubuntu-noble -> localhost | | o.. .==| 2025-02-07 08:36:23.824313 | ubuntu-noble -> localhost | | .+. oo| 2025-02-07 08:36:23.824345 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-07 08:36:23.824435 | ubuntu-noble -> localhost | ok: Runtime: 0:00:01.126491 2025-02-07 08:36:23.834965 | 2025-02-07 08:36:23.835070 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-07 08:36:23.864180 | ubuntu-noble | ok 2025-02-07 08:36:23.875248 | ubuntu-noble | included: /var/lib/zuul/builds/f2d59ed4be5147a9b7a9c602da09fbf5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-07 08:36:23.884893 | 2025-02-07 08:36:23.884974 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-07 08:36:23.908569 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:23.915545 | 2025-02-07 08:36:23.915632 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-07 08:36:24.596825 | ubuntu-noble | changed 2025-02-07 08:36:24.610309 | 2025-02-07 08:36:24.610412 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-07 08:36:24.919326 | ubuntu-noble | ok 2025-02-07 08:36:24.934960 | 2025-02-07 08:36:24.935075 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-07 08:36:25.680690 | ubuntu-noble | changed 2025-02-07 08:36:25.687562 | 2025-02-07 08:36:25.687659 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-07 08:36:26.359912 | ubuntu-noble | changed 2025-02-07 08:36:26.367019 | 2025-02-07 08:36:26.367105 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-07 08:36:26.423503 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:26.430782 | 2025-02-07 08:36:26.430886 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-07 08:36:27.116214 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:27.163482 | 2025-02-07 08:36:27.163564 | TASK [add-build-sshkey : Add back temp key] 2025-02-07 08:36:27.553177 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/f2d59ed4be5147a9b7a9c602da09fbf5/work/f2d59ed4be5147a9b7a9c602da09fbf5_id_rsa (zuul-build-sshkey) 2025-02-07 08:36:27.553370 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010700 2025-02-07 08:36:27.560556 | 2025-02-07 08:36:27.560648 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-07 08:36:27.924937 | ubuntu-noble | ok 2025-02-07 08:36:27.935373 | 2025-02-07 08:36:27.935512 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-07 08:36:27.960947 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:27.972925 | 2025-02-07 08:36:27.973010 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-07 08:36:28.411240 | ubuntu-noble | ok 2025-02-07 08:36:28.429461 | 2025-02-07 08:36:28.429555 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-07 08:36:28.472568 | ubuntu-noble | ok 2025-02-07 08:36:28.480616 | 2025-02-07 08:36:28.480705 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-07 08:36:28.862249 | ubuntu-noble -> localhost | ok 2025-02-07 08:36:28.869399 | 2025-02-07 08:36:28.869476 | TASK [validate-host : Collect information about the host] 2025-02-07 08:36:30.101746 | ubuntu-noble | ok 2025-02-07 08:36:30.111616 | 2025-02-07 08:36:30.111701 | TASK [validate-host : Sanitize hostname] 2025-02-07 08:36:30.220278 | ubuntu-noble | ok 2025-02-07 08:36:30.225680 | 2025-02-07 08:36:30.225839 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-07 08:36:30.803896 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:30.810784 | 2025-02-07 08:36:30.810880 | TASK [validate-host : Collect information about zuul worker] 2025-02-07 08:36:31.257504 | ubuntu-noble | ok 2025-02-07 08:36:31.263730 | 2025-02-07 08:36:31.263852 | TASK [validate-host : Write out all zuul information for each host] 2025-02-07 08:36:31.888156 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:31.901396 | 2025-02-07 08:36:31.901499 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-07 08:36:32.231195 | ubuntu-noble | ok 2025-02-07 08:36:32.247164 | 2025-02-07 08:36:32.247268 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-07 08:36:33.604138 | ubuntu-noble | changed: 2025-02-07 08:36:33.604309 | ubuntu-noble | cd+++++++++ src/ 2025-02-07 08:36:33.604343 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-07 08:36:33.604368 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-07 08:36:33.604407 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-07 08:36:33.604428 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.005089 2025-02-07 08:37:07.013336 | 2025-02-07 08:37:07.013513 | TASK [upload-logs : Upload logs to log server] 2025-02-07 08:37:07.618263 | localhost | Output suppressed because no_log was given 2025-02-07 08:37:07.624080 | 2025-02-07 08:37:07.624234 | LOOP [upload-logs : Compress console log and json output] 2025-02-07 08:37:07.693615 | localhost | skipping: Conditional result was False 2025-02-07 08:37:07.710324 | localhost | skipping: Conditional result was False 2025-02-07 08:37:07.718561 | 2025-02-07 08:37:07.718684 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-07 08:37:07.781645 | localhost | skipping: Conditional result was False 2025-02-07 08:37:07.781993 | 2025-02-07 08:37:07.797153 | localhost | skipping: Conditional result was False 2025-02-07 08:37:07.809649 | 2025-02-07 08:37:07.809769 | LOOP [upload-logs : Upload console log and json output]