2026-06-08 07:42:24.439686 | Job console starting 2026-06-08 07:42:24.457576 | Updating git repos 2026-06-08 07:42:26.608393 | Cloning repos into workspace 2026-06-08 07:42:40.927482 | Restoring repo states 2026-06-08 07:42:47.588742 | Merging changes 2026-06-08 07:42:49.518633 | Checking out repos 2026-06-08 07:42:51.074275 | Preparing playbooks 2026-06-08 07:42:54.368408 | Running Ansible setup 2026-06-08 07:42:58.205387 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 07:42:58.864937 | 2026-06-08 07:42:58.865425 | PLAY [localhost] 2026-06-08 07:42:58.873613 | 2026-06-08 07:42:58.873731 | TASK [Gathering Facts] 2026-06-08 07:42:59.870967 | localhost | ok 2026-06-08 07:42:59.879059 | 2026-06-08 07:42:59.879140 | TASK [Setup log path fact] 2026-06-08 07:42:59.900400 | localhost | ok 2026-06-08 07:42:59.913348 | 2026-06-08 07:42:59.913417 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:42:59.945975 | localhost | ok 2026-06-08 07:42:59.955681 | 2026-06-08 07:42:59.955766 | TASK [emit-job-header : Print job information] 2026-06-08 07:42:59.997456 | # Job Information 2026-06-08 07:42:59.997745 | Ansible Version: 2.16.18 2026-06-08 07:42:59.997795 | Job: atmosphere-molecule-aio-ovn 2026-06-08 07:42:59.997826 | Pipeline: check 2026-06-08 07:42:59.997852 | Executor: 2d72f0692154 2026-06-08 07:42:59.997880 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4002 2026-06-08 07:42:59.997911 | Event ID: 7407a9e0-630d-11f1-8eff-003ad6dab313 2026-06-08 07:43:00.001803 | 2026-06-08 07:43:00.001886 | LOOP [emit-job-header : Print node information] 2026-06-08 07:43:00.097565 | localhost | ok: 2026-06-08 07:43:00.097908 | localhost | # Node Information 2026-06-08 07:43:00.097953 | localhost | Inventory Hostname: instance 2026-06-08 07:43:00.097985 | localhost | Hostname: np0000178867 2026-06-08 07:43:00.098013 | localhost | Username: zuul 2026-06-08 07:43:00.098046 | localhost | Distro: Ubuntu 22.04 2026-06-08 07:43:00.098075 | localhost | Provider: yul1 2026-06-08 07:43:00.098102 | localhost | Region: ca-ymq-1 2026-06-08 07:43:00.098130 | localhost | Label: ubuntu-jammy-16 2026-06-08 07:43:00.098157 | localhost | Product Name: OpenStack Nova 2026-06-08 07:43:00.098184 | localhost | Interface IP: 199.19.213.133 2026-06-08 07:43:00.108645 | 2026-06-08 07:43:00.108785 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 07:43:00.551136 | localhost -> localhost | changed 2026-06-08 07:43:00.560160 | 2026-06-08 07:43:00.560299 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 07:43:01.858839 | localhost -> localhost | changed 2026-06-08 07:43:01.872004 | 2026-06-08 07:43:01.872153 | PLAY [all] 2026-06-08 07:43:01.881856 | 2026-06-08 07:43:01.881926 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 07:43:02.130146 | instance -> localhost | ok 2026-06-08 07:43:02.143118 | 2026-06-08 07:43:02.143229 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 07:43:02.179158 | instance | ok 2026-06-08 07:43:02.193680 | instance | included: /var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 07:43:02.199549 | 2026-06-08 07:43:02.199611 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 07:43:03.137090 | instance -> localhost | Generating public/private rsa key pair. 2026-06-08 07:43:03.137336 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/work/0d19f219f0b2492b9d6be444db6e5434_id_rsa 2026-06-08 07:43:03.137385 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/work/0d19f219f0b2492b9d6be444db6e5434_id_rsa.pub 2026-06-08 07:43:03.137420 | instance -> localhost | The key fingerprint is: 2026-06-08 07:43:03.137451 | instance -> localhost | SHA256:o477czfPEG/Cz6/1xmrH8QSCO6mT+8b9lP2p3EiX4Xs zuul-build-sshkey 2026-06-08 07:43:03.137503 | instance -> localhost | The key's randomart image is: 2026-06-08 07:43:03.137535 | instance -> localhost | +---[RSA 3072]----+ 2026-06-08 07:43:03.137571 | instance -> localhost | | | 2026-06-08 07:43:03.137602 | instance -> localhost | | | 2026-06-08 07:43:03.137631 | instance -> localhost | | . | 2026-06-08 07:43:03.137669 | instance -> localhost | | . . . | 2026-06-08 07:43:03.137710 | instance -> localhost | | S .o . o | 2026-06-08 07:43:03.137751 | instance -> localhost | | . o+o .++| 2026-06-08 07:43:03.137798 | instance -> localhost | | . ++oo.+B+| 2026-06-08 07:43:03.137846 | instance -> localhost | | o. = =B+o=.E| 2026-06-08 07:43:03.137884 | instance -> localhost | | oooo.*.o*O=B+| 2026-06-08 07:43:03.137915 | instance -> localhost | +----[SHA256]-----+ 2026-06-08 07:43:03.137986 | instance -> localhost | ok: Runtime: 0:00:00.488777 2026-06-08 07:43:03.145421 | 2026-06-08 07:43:03.145515 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 07:43:03.186328 | instance | ok 2026-06-08 07:43:03.194651 | instance | included: /var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 07:43:03.202297 | 2026-06-08 07:43:03.202361 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 07:43:03.226495 | instance | skipping: Conditional result was False 2026-06-08 07:43:03.231881 | 2026-06-08 07:43:03.231946 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 07:43:03.678692 | instance | changed 2026-06-08 07:43:03.683931 | 2026-06-08 07:43:03.683995 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 07:43:03.912438 | instance | ok 2026-06-08 07:43:03.917344 | 2026-06-08 07:43:03.917404 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 07:43:04.400623 | instance | changed 2026-06-08 07:43:04.575660 | 2026-06-08 07:43:04.575835 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 07:43:05.090775 | instance | changed 2026-06-08 07:43:05.230109 | 2026-06-08 07:43:05.230351 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 07:43:05.256115 | instance | skipping: Conditional result was False 2026-06-08 07:43:05.271852 | 2026-06-08 07:43:05.272080 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 07:43:05.734726 | instance -> localhost | changed 2026-06-08 07:43:05.751744 | 2026-06-08 07:43:05.751916 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 07:43:06.029041 | instance -> localhost | Identity added: /var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/work/0d19f219f0b2492b9d6be444db6e5434_id_rsa (zuul-build-sshkey) 2026-06-08 07:43:06.029304 | instance -> localhost | ok: Runtime: 0:00:00.011860 2026-06-08 07:43:06.036519 | 2026-06-08 07:43:06.036613 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 07:43:06.315407 | instance | ok 2026-06-08 07:43:06.496664 | 2026-06-08 07:43:06.496866 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 07:43:06.521903 | instance | skipping: Conditional result was False 2026-06-08 07:43:06.536525 | 2026-06-08 07:43:06.536596 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 07:43:06.857211 | instance | ok 2026-06-08 07:43:06.864310 | 2026-06-08 07:43:06.864451 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 07:43:16.528444 | instance | Output suppressed because no_log was given 2026-06-08 07:43:16.537529 | 2026-06-08 07:43:16.537597 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:43:16.733878 | instance | ok: "logs" 2026-06-08 07:43:16.858075 | instance | ok: All items complete 2026-06-08 07:43:16.858285 | 2026-06-08 07:43:16.882599 | instance | ok: "artifacts" 2026-06-08 07:43:17.035925 | instance | ok: "docs" 2026-06-08 07:43:17.048141 | 2026-06-08 07:43:17.048355 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:43:17.233990 | instance | changed: "logs" 2026-06-08 07:43:17.399563 | instance | changed: "artifacts" 2026-06-08 07:43:17.566803 | instance | changed: "docs" 2026-06-08 07:43:17.627232 | 2026-06-08 07:43:17.627367 | PLAY RECAP 2026-06-08 07:43:17.627437 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-08 07:43:17.627479 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:43:17.627514 | 2026-06-08 07:43:17.957774 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 07:43:17.968683 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 07:43:18.659064 | 2026-06-08 07:43:18.659251 | PLAY [all] 2026-06-08 07:43:18.672322 | 2026-06-08 07:43:18.672466 | TASK [setup-uv : Extract archive] 2026-06-08 07:43:29.752267 | instance | ERROR 2026-06-08 07:43:29.752720 | instance | { 2026-06-08 07:43:29.752769 | instance | "msg": "Failure downloading https://github.com/astral-sh/uv/releases/download/0.8.13/uv-x86_64-unknown-linux-gnu.tar.gz, Connection failure: The read operation timed out" 2026-06-08 07:43:29.752802 | instance | } failure 2026-06-08 07:43:29.754157 | 2026-06-08 07:43:29.754207 | PLAY RECAP 2026-06-08 07:43:29.754250 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:43:29.754274 | 2026-06-08 07:43:29.994662 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 07:43:30.016346 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 07:43:30.687135 | 2026-06-08 07:43:30.687315 | PLAY [all] 2026-06-08 07:43:30.700539 | 2026-06-08 07:43:30.700692 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 07:43:30.756616 | instance | skipping: Conditional result was False 2026-06-08 07:43:30.770275 | 2026-06-08 07:43:30.770524 | TASK [fetch-output : Set log path for single node] 2026-06-08 07:43:30.815131 | instance | ok 2026-06-08 07:43:30.820122 | 2026-06-08 07:43:30.820203 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 07:43:31.217630 | instance -> localhost | ok: "/var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/work/logs" 2026-06-08 07:43:31.438255 | instance -> localhost | changed: "/var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/work/artifacts" 2026-06-08 07:43:31.682992 | instance -> localhost | changed: "/var/lib/zuul/builds/0d19f219f0b2492b9d6be444db6e5434/work/docs" 2026-06-08 07:43:31.708046 | 2026-06-08 07:43:31.708291 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 07:43:32.341328 | instance | ok 2026-06-08 07:43:32.341606 | instance | changed: All items complete 2026-06-08 07:43:32.341652 | 2026-06-08 07:43:32.804435 | instance | changed: .d..t...... ./ 2026-06-08 07:43:33.258331 | instance | changed: .d..t...... ./ 2026-06-08 07:43:33.277702 | 2026-06-08 07:43:33.277876 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 07:43:33.734967 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009538 2026-06-08 07:43:33.962796 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007324 2026-06-08 07:43:33.985868 | 2026-06-08 07:43:33.986017 | PLAY [all] 2026-06-08 07:43:34.000735 | 2026-06-08 07:43:34.001680 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 07:43:34.408655 | instance | changed 2026-06-08 07:43:34.415339 | 2026-06-08 07:43:34.415408 | PLAY RECAP 2026-06-08 07:43:34.415458 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 07:43:34.415480 | 2026-06-08 07:43:34.582598 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 07:43:34.599080 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-08 07:43:35.340508 | 2026-06-08 07:43:35.340717 | PLAY [localhost] 2026-06-08 07:43:35.351803 | 2026-06-08 07:43:35.351969 | TASK [Generate Zuul manifest] 2026-06-08 07:43:35.375112 | localhost | ok 2026-06-08 07:43:35.392941 | 2026-06-08 07:43:35.393149 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 07:43:35.808644 | localhost | changed 2026-06-08 07:43:35.819156 | 2026-06-08 07:43:35.819227 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 07:43:35.953660 | localhost | ok 2026-06-08 07:43:35.964371 | 2026-06-08 07:43:35.964479 | TASK [Upload logs] 2026-06-08 07:43:35.986347 | localhost | ok 2026-06-08 07:43:36.109682 | 2026-06-08 07:43:36.109841 | TASK [Set zuul-log-path fact] 2026-06-08 07:43:36.134166 | localhost | ok 2026-06-08 07:43:36.146284 | 2026-06-08 07:43:36.146366 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:43:36.180654 | localhost | ok 2026-06-08 07:43:36.192352 | 2026-06-08 07:43:36.192558 | TASK [upload-logs : Create log directories] 2026-06-08 07:43:36.610489 | localhost | changed 2026-06-08 07:43:36.617152 | 2026-06-08 07:43:36.617251 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 07:43:37.044565 | localhost -> localhost | ok: Runtime: 0:00:00.005556 2026-06-08 07:43:37.053273 | 2026-06-08 07:43:37.053364 | TASK [upload-logs : Upload logs to log server] 2026-06-08 07:43:37.498555 | localhost | Output suppressed because no_log was given 2026-06-08 07:43:37.503667 | 2026-06-08 07:43:37.503761 | LOOP [upload-logs : Compress console log and json output] 2026-06-08 07:43:37.551915 | localhost | skipping: Conditional result was False 2026-06-08 07:43:37.559742 | localhost | skipping: Conditional result was False 2026-06-08 07:43:37.577795 | 2026-06-08 07:43:37.578047 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-08 07:43:37.622750 | localhost | skipping: Conditional result was False 2026-06-08 07:43:37.623139 | 2026-06-08 07:43:37.635035 | localhost | skipping: Conditional result was False 2026-06-08 07:43:37.653223 | 2026-06-08 07:43:37.653450 | LOOP [upload-logs : Upload console log and json output]