2026-07-08 15:24:46.906401 | Job console starting 2026-07-08 15:24:46.919318 | Updating git repos 2026-07-08 15:24:47.744658 | Cloning repos into workspace 2026-07-08 15:25:19.936842 | Restoring repo states 2026-07-08 15:25:23.579279 | Merging changes 2026-07-08 15:25:27.277197 | Checking out repos 2026-07-08 15:25:33.056424 | Preparing playbooks 2026-07-08 15:26:16.463818 | Running Ansible setup 2026-07-08 15:26:23.519811 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-08 15:26:24.190809 | 2026-07-08 15:26:24.191001 | PLAY [localhost] 2026-07-08 15:26:24.199748 | 2026-07-08 15:26:24.199844 | TASK [Gathering Facts] 2026-07-08 15:26:25.818070 | localhost | ok 2026-07-08 15:26:25.827049 | 2026-07-08 15:26:25.827148 | TASK [Setup log path fact] 2026-07-08 15:26:25.847191 | localhost | ok 2026-07-08 15:26:25.860440 | 2026-07-08 15:26:25.860576 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-08 15:26:25.890373 | localhost | ok 2026-07-08 15:26:25.898864 | 2026-07-08 15:26:25.898968 | TASK [emit-job-header : Print job information] 2026-07-08 15:26:25.941723 | # Job Information 2026-07-08 15:26:25.942236 | Ansible Version: 2.16.18 2026-07-08 15:26:25.942504 | Job: atmosphere-molecule-csi-rbd 2026-07-08 15:26:25.942590 | Pipeline: check 2026-07-08 15:26:25.942657 | Executor: 2d72f0692154 2026-07-08 15:26:25.942722 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4132 2026-07-08 15:26:25.942802 | Event ID: 5f06a510-7ae0-11f1-821b-3278a0fc5c4d 2026-07-08 15:26:25.948738 | 2026-07-08 15:26:25.948841 | LOOP [emit-job-header : Print node information] 2026-07-08 15:26:26.045580 | localhost | ok: 2026-07-08 15:26:26.045919 | localhost | # Node Information 2026-07-08 15:26:26.045985 | localhost | Inventory Hostname: instance 2026-07-08 15:26:26.046026 | localhost | Hostname: np0000184476 2026-07-08 15:26:26.046081 | localhost | Username: zuul 2026-07-08 15:26:26.046124 | localhost | Distro: Ubuntu 22.04 2026-07-08 15:26:26.046161 | localhost | Provider: yul1 2026-07-08 15:26:26.046195 | localhost | Region: ca-ymq-1 2026-07-08 15:26:26.046227 | localhost | Label: ubuntu-jammy 2026-07-08 15:26:26.046259 | localhost | Product Name: OpenStack Nova 2026-07-08 15:26:26.046292 | localhost | Interface IP: 199.19.213.238 2026-07-08 15:26:26.058913 | 2026-07-08 15:26:26.059093 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-08 15:26:26.480716 | localhost -> localhost | changed 2026-07-08 15:26:26.491148 | 2026-07-08 15:26:27.859101 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-08 15:26:29.685099 | localhost -> localhost | changed 2026-07-08 15:26:29.695723 | 2026-07-08 15:26:29.695863 | PLAY [all] 2026-07-08 15:26:29.705847 | 2026-07-08 15:26:29.705998 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-08 15:26:30.494580 | instance -> localhost | ok 2026-07-08 15:26:30.511923 | 2026-07-08 15:26:30.512339 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-08 15:26:30.546431 | instance | ok 2026-07-08 15:26:30.562414 | instance | included: /var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-08 15:26:30.568534 | 2026-07-08 15:26:30.568612 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-08 15:26:33.371991 | instance -> localhost | Generating public/private rsa key pair. 2026-07-08 15:26:33.372199 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/da440578aff049d09dfa443684edfbee_id_rsa 2026-07-08 15:26:33.372232 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/da440578aff049d09dfa443684edfbee_id_rsa.pub 2026-07-08 15:26:33.372255 | instance -> localhost | The key fingerprint is: 2026-07-08 15:26:33.372276 | instance -> localhost | SHA256:f7iPwO3QMBz1RW9NFyAJmRWr24158Uh4nuvxsC8Mdqc zuul-build-sshkey 2026-07-08 15:26:33.372311 | instance -> localhost | The key's randomart image is: 2026-07-08 15:26:33.372332 | instance -> localhost | +---[RSA 3072]----+ 2026-07-08 15:26:33.372359 | instance -> localhost | | .*+oo+.+| 2026-07-08 15:26:33.372381 | instance -> localhost | | + oo. oo| 2026-07-08 15:26:33.372402 | instance -> localhost | | . .. +| 2026-07-08 15:26:33.372453 | instance -> localhost | | . .. . . | 2026-07-08 15:26:33.372488 | instance -> localhost | | S. . + | 2026-07-08 15:26:33.372515 | instance -> localhost | | . *o+B.=. | 2026-07-08 15:26:33.372536 | instance -> localhost | | +.*+=Oo. | 2026-07-08 15:26:33.372556 | instance -> localhost | | + +.E* | 2026-07-08 15:26:33.372578 | instance -> localhost | | +.o+oo | 2026-07-08 15:26:33.372599 | instance -> localhost | +----[SHA256]-----+ 2026-07-08 15:26:33.372648 | instance -> localhost | ok: Runtime: 0:00:02.334821 2026-07-08 15:26:33.379129 | 2026-07-08 15:26:33.379196 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-08 15:26:33.409625 | instance | ok 2026-07-08 15:26:33.439436 | instance | included: /var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-08 15:26:33.448236 | 2026-07-08 15:26:33.448327 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-08 15:26:33.472575 | instance | skipping: Conditional result was False 2026-07-08 15:26:33.482283 | 2026-07-08 15:26:33.482381 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-08 15:26:33.982048 | instance | changed 2026-07-08 15:26:33.992283 | 2026-07-08 15:26:33.992433 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-08 15:26:34.182791 | instance | ok 2026-07-08 15:26:34.188135 | 2026-07-08 15:26:34.188230 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-08 15:26:34.763062 | instance | changed 2026-07-08 15:26:34.940150 | 2026-07-08 15:26:34.940273 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-08 15:26:35.398424 | instance | changed 2026-07-08 15:26:35.405906 | 2026-07-08 15:26:35.406083 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-08 15:26:35.432040 | instance | skipping: Conditional result was False 2026-07-08 15:26:35.453058 | 2026-07-08 15:26:35.453200 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-08 15:26:36.009670 | instance -> localhost | changed 2026-07-08 15:26:36.030257 | 2026-07-08 15:26:36.090092 | TASK [add-build-sshkey : Add back temp key] 2026-07-08 15:26:36.460370 | instance -> localhost | Identity added: /var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/da440578aff049d09dfa443684edfbee_id_rsa (zuul-build-sshkey) 2026-07-08 15:26:36.460630 | instance -> localhost | ok: Runtime: 0:00:00.011135 2026-07-08 15:26:36.466985 | 2026-07-08 15:26:36.467057 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-08 15:26:36.826619 | instance | ok 2026-07-08 15:26:36.831267 | 2026-07-08 15:26:36.831331 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-08 15:26:36.855379 | instance | skipping: Conditional result was False 2026-07-08 15:26:36.869713 | 2026-07-08 15:26:36.869810 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-07-08 15:26:37.206191 | instance | ok 2026-07-08 15:26:37.212073 | 2026-07-08 15:26:37.212170 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-07-08 15:26:41.183761 | instance | Output suppressed because no_log was given 2026-07-08 15:26:41.216462 | 2026-07-08 15:26:41.216658 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-07-08 15:26:41.398986 | instance | ok: "logs" 2026-07-08 15:26:41.500785 | instance | ok: All items complete 2026-07-08 15:26:41.500990 | 2026-07-08 15:26:41.552014 | instance | ok: "artifacts" 2026-07-08 15:26:41.697423 | instance | ok: "docs" 2026-07-08 15:26:41.721321 | 2026-07-08 15:26:41.721507 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-07-08 15:26:41.897673 | instance | changed: "logs" 2026-07-08 15:26:42.177659 | instance | changed: "artifacts" 2026-07-08 15:26:42.208152 | instance | changed: "docs" 2026-07-08 15:26:42.227728 | 2026-07-08 15:26:42.227890 | PLAY RECAP 2026-07-08 15:26:42.227965 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-07-08 15:26:42.228007 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-08 15:26:42.228039 | 2026-07-08 15:26:42.379870 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-08 15:26:42.537186 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-08 15:26:43.188117 | 2026-07-08 15:26:43.245190 | PLAY [all] 2026-07-08 15:26:43.263129 | 2026-07-08 15:26:43.263343 | TASK [setup-uv : Extract archive] 2026-07-08 15:26:45.405081 | instance | changed 2026-07-08 15:26:45.411132 | 2026-07-08 15:26:45.411239 | TASK [setup-uv : Print version] 2026-07-08 15:26:45.788316 | instance | uv 0.8.13 2026-07-08 15:26:45.953569 | instance | ok: Runtime: 0:00:00.011169 2026-07-08 15:26:45.968935 | 2026-07-08 15:26:45.969120 | TASK [Install Ansible collection siblings] 2026-07-08 15:30:59.456211 | instance | ERROR 2026-07-08 15:30:59.456605 | instance | { 2026-07-08 15:30:59.456647 | instance | "msg": "Data could not be sent to remote host \"199.19.213.238\". Make sure this host can be reached over ssh: ssh: connect to host 199.19.213.238 port 22: No route to host\r\n", 2026-07-08 15:30:59.456682 | instance | "unreachable": true 2026-07-08 15:30:59.456708 | instance | } unreachable 2026-07-08 15:30:59.457804 | 2026-07-08 15:30:59.457858 | PLAY RECAP 2026-07-08 15:30:59.457913 | instance | ok: 2 changed: 2 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-08 15:30:59.457945 | 2026-07-08 15:30:59.591209 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-08 15:30:59.594511 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-08 15:31:00.238870 | 2026-07-08 15:31:00.238990 | PLAY [all] 2026-07-08 15:31:00.250435 | 2026-07-08 15:31:00.250506 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-08 15:31:00.295466 | instance | skipping: Conditional result was False 2026-07-08 15:31:00.305386 | 2026-07-08 15:31:00.305495 | TASK [fetch-output : Set log path for single node] 2026-07-08 15:31:00.349733 | instance | ok 2026-07-08 15:31:00.355940 | 2026-07-08 15:31:00.356031 | LOOP [fetch-output : Ensure local output dirs] 2026-07-08 15:31:00.808565 | instance -> localhost | ok: "/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/logs" 2026-07-08 15:31:01.044475 | instance -> localhost | changed: "/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/artifacts" 2026-07-08 15:31:01.287339 | instance -> localhost | changed: "/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/docs" 2026-07-08 15:31:01.302043 | 2026-07-08 15:31:01.302233 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-08 15:31:04.827459 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/logs', 'src': 'logs'} 2026-07-08 15:31:04.827831 | instance | { 2026-07-08 15:31:04.827874 | instance | "ansible_loop_var": "zj_output", 2026-07-08 15:31:04.827909 | instance | "msg": "ssh: connect to host 199.19.213.238 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n", 2026-07-08 15:31:04.827940 | instance | "rc": 255, 2026-07-08 15:31:04.827967 | instance | "zj_output": { 2026-07-08 15:31:04.827994 | instance | "dest": "/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/logs", 2026-07-08 15:31:04.828035 | instance | "src": "logs" 2026-07-08 15:31:04.828067 | instance | } 2026-07-08 15:31:04.828096 | instance | } 2026-07-08 15:31:04.828144 | instance | changed: All items complete 2026-07-08 15:31:04.828176 | 2026-07-08 15:31:08.138580 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/artifacts', 'src': 'artifacts'} 2026-07-08 15:31:08.138766 | instance | { 2026-07-08 15:31:08.138809 | instance | "ansible_loop_var": "zj_output", 2026-07-08 15:31:08.138838 | instance | "msg": "ssh: connect to host 199.19.213.238 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n", 2026-07-08 15:31:08.138866 | instance | "rc": 255, 2026-07-08 15:31:08.138892 | instance | "zj_output": { 2026-07-08 15:31:08.138918 | instance | "dest": "/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/artifacts", 2026-07-08 15:31:08.138947 | instance | "src": "artifacts" 2026-07-08 15:31:08.138973 | instance | } 2026-07-08 15:31:08.138998 | instance | } 2026-07-08 15:31:11.442536 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/docs', 'src': 'docs'} 2026-07-08 15:31:11.471178 | instance | { 2026-07-08 15:31:11.471357 | instance | "ansible_loop_var": "zj_output", 2026-07-08 15:31:11.471414 | instance | "msg": "ssh: connect to host 199.19.213.238 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n", 2026-07-08 15:31:11.471463 | instance | "rc": 255, 2026-07-08 15:31:11.471509 | instance | "zj_output": { 2026-07-08 15:31:11.471554 | instance | "dest": "/var/lib/zuul/builds/da440578aff049d09dfa443684edfbee/work/docs", 2026-07-08 15:31:11.471597 | instance | "src": "docs" 2026-07-08 15:31:11.471644 | instance | } 2026-07-08 15:31:11.471707 | instance | } failure 2026-07-08 15:31:11.490709 | 2026-07-08 15:31:11.490791 | PLAY RECAP 2026-07-08 15:31:11.490856 | instance | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2026-07-08 15:31:11.490889 | 2026-07-08 15:31:11.640124 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-08 15:31:11.645223 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-08 15:31:12.286076 | 2026-07-08 15:31:12.286274 | PLAY [localhost] 2026-07-08 15:31:12.297094 | 2026-07-08 15:31:12.297224 | TASK [Generate Zuul manifest] 2026-07-08 15:31:12.321112 | localhost | ok 2026-07-08 15:31:12.338811 | 2026-07-08 15:31:12.338912 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-08 15:31:12.736175 | localhost | changed 2026-07-08 15:31:12.749692 | 2026-07-08 15:31:12.749787 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-08 15:31:12.824235 | localhost | ok 2026-07-08 15:31:12.832662 | 2026-07-08 15:31:12.832730 | TASK [Upload logs] 2026-07-08 15:31:12.857558 | localhost | ok 2026-07-08 15:31:12.918455 | 2026-07-08 15:31:12.918667 | TASK [Set zuul-log-path fact] 2026-07-08 15:31:12.941744 | localhost | ok 2026-07-08 15:31:12.955100 | 2026-07-08 15:31:12.955248 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-08 15:31:12.998623 | localhost | ok 2026-07-08 15:31:13.008786 | 2026-07-08 15:31:13.008903 | TASK [upload-logs : Create log directories] 2026-07-08 15:31:13.418788 | localhost | changed 2026-07-08 15:31:13.425684 | 2026-07-08 15:31:13.425788 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-08 15:31:13.966643 | localhost -> localhost | ok: Runtime: 0:00:00.006814 2026-07-08 15:31:13.975488 | 2026-07-08 15:31:13.975558 | TASK [upload-logs : Upload logs to log server] 2026-07-08 15:31:14.463503 | localhost | Output suppressed because no_log was given 2026-07-08 15:31:14.514916 | 2026-07-08 15:31:14.515057 | LOOP [upload-logs : Compress console log and json output] 2026-07-08 15:31:14.565775 | localhost | skipping: Conditional result was False 2026-07-08 15:31:14.572362 | localhost | skipping: Conditional result was False 2026-07-08 15:31:14.591205 | 2026-07-08 15:31:14.591488 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-08 15:31:14.642074 | localhost | skipping: Conditional result was False 2026-07-08 15:31:14.642551 | 2026-07-08 15:31:14.646699 | localhost | skipping: Conditional result was False 2026-07-08 15:31:14.657082 | 2026-07-08 15:31:14.657311 | LOOP [upload-logs : Upload console log and json output]