2025-03-25 15:33:52.914615 | Job console starting... 2025-03-25 15:33:52.926092 | Updating repositories 2025-03-25 15:33:52.944878 | Preparing job workspace 2025-03-25 15:33:54.275645 | Running Ansible setup... 2025-03-25 15:33:59.608130 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-25 15:34:00.334763 | 2025-03-25 15:34:00.334911 | PLAY [Base pre] 2025-03-25 15:34:00.364476 | 2025-03-25 15:34:00.364613 | TASK [Setup log path fact] 2025-03-25 15:34:00.396523 | ubuntu-noble-large | ok 2025-03-25 15:34:00.415784 | 2025-03-25 15:34:00.415915 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-25 15:34:00.447532 | ubuntu-noble-large | ok 2025-03-25 15:34:00.460960 | 2025-03-25 15:34:00.461067 | TASK [emit-job-header : Print job information] 2025-03-25 15:34:00.514735 | # Job Information 2025-03-25 15:34:00.514923 | Ansible Version: 2.15.3 2025-03-25 15:34:00.514966 | Job: ci-image-build 2025-03-25 15:34:00.515006 | Pipeline: check 2025-03-25 15:34:00.515032 | Executor: 7d211f194f6a 2025-03-25 15:34:00.515057 | Triggered by: https://github.com/osism/ci-image/pull/59 2025-03-25 15:34:00.515081 | Event ID: 0857c060-098e-11f0-9baf-c8cb7709cd61 2025-03-25 15:34:00.522269 | 2025-03-25 15:34:00.522382 | LOOP [emit-job-header : Print node information] 2025-03-25 15:34:00.687368 | ubuntu-noble-large | ok: 2025-03-25 15:34:00.687559 | ubuntu-noble-large | # Node Information 2025-03-25 15:34:00.687595 | ubuntu-noble-large | Inventory Hostname: ubuntu-noble-large 2025-03-25 15:34:00.687621 | ubuntu-noble-large | Hostname: ubuntu 2025-03-25 15:34:00.687643 | ubuntu-noble-large | Username: zuul 2025-03-25 15:34:00.687664 | ubuntu-noble-large | Distro: Ubuntu 24.04 2025-03-25 15:34:00.687687 | ubuntu-noble-large | Provider: regiocloud-a 2025-03-25 15:34:00.687724 | ubuntu-noble-large | Label: ubuntu-noble-large 2025-03-25 15:34:00.687746 | ubuntu-noble-large | Product Name: OpenStack Nova 2025-03-25 15:34:00.687766 | ubuntu-noble-large | Interface IP: 81.163.193.123 2025-03-25 15:34:00.718183 | 2025-03-25 15:34:00.718325 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-25 15:34:01.202796 | ubuntu-noble-large -> localhost | changed 2025-03-25 15:34:01.219960 | 2025-03-25 15:34:01.220484 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-25 15:34:02.249231 | ubuntu-noble-large -> localhost | changed 2025-03-25 15:34:02.273186 | 2025-03-25 15:34:02.273322 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-25 15:34:02.628495 | ubuntu-noble-large -> localhost | ok 2025-03-25 15:34:02.643167 | 2025-03-25 15:34:02.643337 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-25 15:34:02.678007 | ubuntu-noble-large | ok 2025-03-25 15:34:02.696146 | ubuntu-noble-large | included: /var/lib/zuul/builds/085ba4ec854d44b8987814849bafed43/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-25 15:34:02.703798 | 2025-03-25 15:34:02.703900 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-25 15:34:03.492417 | ubuntu-noble-large -> localhost | Generating public/private rsa key pair. 2025-03-25 15:34:03.492896 | ubuntu-noble-large -> localhost | Your identification has been saved in /var/lib/zuul/builds/085ba4ec854d44b8987814849bafed43/work/085ba4ec854d44b8987814849bafed43_id_rsa 2025-03-25 15:34:03.492991 | ubuntu-noble-large -> localhost | Your public key has been saved in /var/lib/zuul/builds/085ba4ec854d44b8987814849bafed43/work/085ba4ec854d44b8987814849bafed43_id_rsa.pub 2025-03-25 15:34:03.493027 | ubuntu-noble-large -> localhost | The key fingerprint is: 2025-03-25 15:34:03.493059 | ubuntu-noble-large -> localhost | SHA256:Hx0cdO4PinvxgB/R91C3bBFdGGWXAEjYzvI7XHMQT5M zuul-build-sshkey 2025-03-25 15:34:03.493089 | ubuntu-noble-large -> localhost | The key's randomart image is: 2025-03-25 15:34:03.493118 | ubuntu-noble-large -> localhost | +---[RSA 3072]----+ 2025-03-25 15:34:03.493148 | ubuntu-noble-large -> localhost | | +..o+.==X| 2025-03-25 15:34:03.493298 | ubuntu-noble-large -> localhost | | . o o E.+=| 2025-03-25 15:34:03.493354 | ubuntu-noble-large -> localhost | | o *.+.+| 2025-03-25 15:34:03.493384 | ubuntu-noble-large -> localhost | | . o o.+o+.| 2025-03-25 15:34:03.493413 | ubuntu-noble-large -> localhost | | S ..o.+o.| 2025-03-25 15:34:03.493441 | ubuntu-noble-large -> localhost | | o.=+o o.| 2025-03-25 15:34:03.493478 | ubuntu-noble-large -> localhost | | . =.+= .| 2025-03-25 15:34:03.493507 | ubuntu-noble-large -> localhost | | + .o . | 2025-03-25 15:34:03.493535 | ubuntu-noble-large -> localhost | | o. | 2025-03-25 15:34:03.493674 | ubuntu-noble-large -> localhost | +----[SHA256]-----+ 2025-03-25 15:34:03.493809 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.294686 2025-03-25 15:34:03.505916 | 2025-03-25 15:34:03.506027 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-25 15:34:03.546389 | ubuntu-noble-large | ok 2025-03-25 15:34:03.559336 | ubuntu-noble-large | included: /var/lib/zuul/builds/085ba4ec854d44b8987814849bafed43/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-25 15:34:03.569553 | 2025-03-25 15:34:03.569655 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-25 15:34:03.594394 | ubuntu-noble-large | skipping: Conditional result was False 2025-03-25 15:34:03.602096 | 2025-03-25 15:34:03.602203 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-25 15:34:04.369552 | ubuntu-noble-large | changed 2025-03-25 15:34:04.379239 | 2025-03-25 15:34:04.379369 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-25 15:34:04.685678 | ubuntu-noble-large | ok 2025-03-25 15:34:04.695033 | 2025-03-25 15:34:04.695158 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-25 15:34:05.430786 | ubuntu-noble-large | changed 2025-03-25 15:34:05.442165 | 2025-03-25 15:34:05.442298 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-25 15:34:06.166209 | ubuntu-noble-large | changed 2025-03-25 15:34:06.176838 | 2025-03-25 15:34:06.176965 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-25 15:34:06.212052 | ubuntu-noble-large | skipping: Conditional result was False 2025-03-25 15:34:06.226303 | 2025-03-25 15:34:06.226450 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-25 15:34:06.662419 | ubuntu-noble-large -> localhost | changed 2025-03-25 15:34:06.689915 | 2025-03-25 15:34:06.690046 | TASK [add-build-sshkey : Add back temp key] 2025-03-25 15:34:07.051877 | ubuntu-noble-large -> localhost | Identity added: /var/lib/zuul/builds/085ba4ec854d44b8987814849bafed43/work/085ba4ec854d44b8987814849bafed43_id_rsa (zuul-build-sshkey) 2025-03-25 15:34:07.052104 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.015621 2025-03-25 15:34:07.060063 | 2025-03-25 15:34:07.060175 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-25 15:34:07.488550 | ubuntu-noble-large | ok 2025-03-25 15:34:07.498126 | 2025-03-25 15:34:07.498253 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-25 15:34:07.533999 | ubuntu-noble-large | skipping: Conditional result was False 2025-03-25 15:34:07.558727 | 2025-03-25 15:34:07.558838 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-25 15:34:08.038768 | ubuntu-noble-large | ok 2025-03-25 15:34:08.052909 | 2025-03-25 15:34:08.053016 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-25 15:34:08.104207 | ubuntu-noble-large | ok 2025-03-25 15:34:08.112642 | 2025-03-25 15:34:08.112778 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-25 15:34:08.401952 | ubuntu-noble-large -> localhost | ok 2025-03-25 15:34:08.415953 | 2025-03-25 15:34:08.416107 | TASK [validate-host : Collect information about the host] 2025-03-25 15:34:09.666133 | ubuntu-noble-large | ok 2025-03-25 15:34:09.680924 | 2025-03-25 15:34:09.681036 | TASK [validate-host : Sanitize hostname] 2025-03-25 15:34:09.765371 | ubuntu-noble-large | ok 2025-03-25 15:34:09.775287 | 2025-03-25 15:34:09.775408 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-25 15:34:10.379857 | ubuntu-noble-large -> localhost | changed 2025-03-25 15:34:10.395820 | 2025-03-25 15:34:10.395981 | TASK [validate-host : Collect information about zuul worker] 2025-03-25 15:34:10.975876 | ubuntu-noble-large | ok 2025-03-25 15:34:10.983958 | 2025-03-25 15:34:10.984087 | TASK [validate-host : Write out all zuul information for each host] 2025-03-25 15:34:11.591591 | ubuntu-noble-large -> localhost | changed 2025-03-25 15:34:11.604217 | 2025-03-25 15:34:11.604329 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-25 15:34:12.001338 | ubuntu-noble-large | ok 2025-03-25 15:34:12.008284 | 2025-03-25 15:34:12.008418 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-25 15:34:12.828571 | ubuntu-noble-large | changed: 2025-03-25 15:34:12.828936 | ubuntu-noble-large | cd+++++++++ src/ 2025-03-25 15:34:12.829015 | ubuntu-noble-large | cd+++++++++ src/github.com/ 2025-03-25 15:34:12.829068 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ 2025-03-25 15:34:12.829113 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ci-image/ 2025-03-25 15:34:12.829155 | ubuntu-noble-large | To install Python packages system-wide, try apt install 2025-03-25 15:34:33.001090 | ubuntu-noble-large | python3-xyz, where xyz is the package you are trying to 2025-03-25 15:34:33.001249 | ubuntu-noble-large | install. 2025-03-25 15:34:33.001270 | ubuntu-noble-large | 2025-03-25 15:34:33.001298 | ubuntu-noble-large | If you wish to install a non-Debian-packaged Python package, 2025-03-25 15:34:33.001316 | ubuntu-noble-large | create a virtual environment using python3 -m venv path/to/venv. 2025-03-25 15:34:33.001332 | ubuntu-noble-large | Then use path/to/venv/bin/python and path/to/venv/bin/pip. Make 2025-03-25 15:34:33.001349 | ubuntu-noble-large | sure you have python3-full installed. 2025-03-25 15:34:33.001365 | ubuntu-noble-large | 2025-03-25 15:34:33.001379 | ubuntu-noble-large | If you wish to install a non-Debian packaged Python application, 2025-03-25 15:34:33.001421 | ubuntu-noble-large | it may be easiest to use pipx install xyz, which will manage a 2025-03-25 15:34:33.001435 | ubuntu-noble-large | virtual environment for you. Make sure you have pipx installed. 2025-03-25 15:34:33.001450 | ubuntu-noble-large | 2025-03-25 15:34:33.001463 | ubuntu-noble-large | See /usr/share/doc/python3.12/README.venv for more information. 2025-03-25 15:34:33.001477 | ubuntu-noble-large | 2025-03-25 15:34:33.001494 | ubuntu-noble-large | note: If you believe this is a mistake, please contact your Python installation or OS distribution provider. You can override this, at the risk of breaking your Python installation or OS, by passing --break-system-packages. 2025-03-25 15:34:33.001514 | ubuntu-noble-large | hint: See PEP 668 for the detailed specification. 2025-03-25 15:34:33.341191 | ubuntu-noble-large | ERROR 2025-03-25 15:34:33.341648 | ubuntu-noble-large | { 2025-03-25 15:34:33.341791 | ubuntu-noble-large | "delta": "0:00:00.824089", 2025-03-25 15:34:33.341863 | ubuntu-noble-large | "end": "2025-03-25 15:34:33.002218", 2025-03-25 15:34:33.341924 | ubuntu-noble-large | "msg": "non-zero return code", 2025-03-25 15:34:33.341983 | ubuntu-noble-large | "rc": 1, 2025-03-25 15:34:33.342059 | ubuntu-noble-large | "start": "2025-03-25 15:34:32.178129" 2025-03-25 15:34:33.342122 | ubuntu-noble-large | } failure 2025-03-25 15:34:33.346384 | 2025-03-25 15:34:33.346536 | PLAY RECAP 2025-03-25 15:34:33.346657 | ubuntu-noble-large | ok: 8 changed: 5 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0 2025-03-25 15:34:33.346747 | 2025-03-25 15:34:33.471340 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/ci-image/playbooks/pre.yml@main] 2025-03-25 15:34:33.479299 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-25 15:34:34.155765 | 2025-03-25 15:34:34.155910 | PLAY [Base post] 2025-03-25 15:34:34.183630 | 2025-03-25 15:34:34.183794 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-25 15:34:34.864238 | ubuntu-noble-large | changed 2025-03-25 15:34:34.896612 | 2025-03-25 15:34:34.896742 | PLAY RECAP 2025-03-25 15:34:34.896808 | ubuntu-noble-large | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-25 15:34:34.896870 | 2025-03-25 15:34:35.013830 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-25 15:34:35.016546 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-25 15:34:35.729113 | 2025-03-25 15:34:35.729230 | PLAY [Base post-logs] 2025-03-25 15:34:35.743759 | 2025-03-25 15:34:35.743862 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-25 15:34:36.142766 | localhost | changed 2025-03-25 15:34:36.149219 | 2025-03-25 15:34:36.149380 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-25 15:34:36.181821 | localhost | ok 2025-03-25 15:34:36.192341 | 2025-03-25 15:34:36.192495 | TASK [Set zuul-log-path fact] 2025-03-25 15:34:36.222929 | localhost | ok 2025-03-25 15:34:36.237121 | 2025-03-25 15:34:36.237300 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-25 15:34:36.291950 | localhost | ok 2025-03-25 15:34:36.309405 | 2025-03-25 15:34:36.309660 | TASK [upload-logs : Create log directories] 2025-03-25 15:34:36.828456 | localhost | changed 2025-03-25 15:34:36.836977 | 2025-03-25 15:34:36.837130 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-25 15:34:37.397629 | localhost -> localhost | ok: Runtime: 0:00:00.006233 2025-03-25 15:34:37.403165 | 2025-03-25 15:34:37.403281 | TASK [upload-logs : Upload logs to log server] 2025-03-25 15:34:37.977830 | localhost | Output suppressed because no_log was given 2025-03-25 15:34:37.984662 | 2025-03-25 15:34:37.985069 | LOOP [upload-logs : Compress console log and json output] 2025-03-25 15:34:38.059425 | localhost | skipping: Conditional result was False 2025-03-25 15:34:38.076496 | localhost | skipping: Conditional result was False 2025-03-25 15:34:38.089294 | 2025-03-25 15:34:38.089405 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-25 15:34:38.154621 | localhost | skipping: Conditional result was False 2025-03-25 15:34:38.155254 | 2025-03-25 15:34:38.166272 | localhost | skipping: Conditional result was False 2025-03-25 15:34:38.176215 | 2025-03-25 15:34:38.176384 | LOOP [upload-logs : Upload console log and json output]