2026-06-03 00:32:16.375800 | Job console starting 2026-06-03 00:32:16.399114 | Updating git repos 2026-06-03 00:32:16.443330 | Cloning repos into workspace 2026-06-03 00:32:16.509809 | Restoring repo states 2026-06-03 00:32:16.516225 | Merging changes 2026-06-03 00:32:16.921987 | Checking out repos 2026-06-03 00:32:16.952505 | Preparing playbooks 2026-06-03 00:32:18.166612 | Running Ansible setup 2026-06-03 00:32:22.099869 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 00:32:22.763504 | 2026-06-03 00:32:22.763679 | PLAY [localhost] 2026-06-03 00:32:22.771625 | 2026-06-03 00:32:22.771750 | TASK [Gathering Facts] 2026-06-03 00:32:23.779222 | localhost | ok 2026-06-03 00:32:23.788211 | 2026-06-03 00:32:23.788295 | TASK [Setup log path fact] 2026-06-03 00:32:23.818548 | localhost | ok 2026-06-03 00:32:23.830665 | 2026-06-03 00:32:23.830735 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 00:32:23.867209 | localhost | ok 2026-06-03 00:32:23.873431 | 2026-06-03 00:32:23.873529 | TASK [emit-job-header : Print job information] 2026-06-03 00:32:23.914425 | # Job Information 2026-06-03 00:32:23.914633 | Ansible Version: 2.16.18 2026-06-03 00:32:23.914679 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-06-03 00:32:23.914713 | Pipeline: check 2026-06-03 00:32:23.914744 | Executor: 2d72f0692154 2026-06-03 00:32:23.914773 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/131 2026-06-03 00:32:23.914806 | Event ID: 85a18be0-5ee3-11f1-84cf-7f89f832180a 2026-06-03 00:32:23.918924 | 2026-06-03 00:32:23.918994 | LOOP [emit-job-header : Print node information] 2026-06-03 00:32:24.017289 | localhost | ok: 2026-06-03 00:32:24.017523 | localhost | # Node Information 2026-06-03 00:32:24.017568 | localhost | Inventory Hostname: rockylinux-9 2026-06-03 00:32:24.017602 | localhost | Hostname: np0000178225 2026-06-03 00:32:24.017632 | localhost | Username: zuul 2026-06-03 00:32:24.017665 | localhost | Distro: Rocky 9.7 2026-06-03 00:32:24.017695 | localhost | Provider: yul1 2026-06-03 00:32:24.017722 | localhost | Region: ca-ymq-1 2026-06-03 00:32:24.017750 | localhost | Label: rockylinux-9 2026-06-03 00:32:24.017777 | localhost | Product Name: OpenStack Nova 2026-06-03 00:32:24.017804 | localhost | Interface IP: 199.204.45.242 2026-06-03 00:32:24.039651 | 2026-06-03 00:32:24.039755 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-03 00:32:24.464499 | localhost -> localhost | changed 2026-06-03 00:32:24.471833 | 2026-06-03 00:32:24.472024 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-03 00:32:25.317962 | localhost -> localhost | changed 2026-06-03 00:32:25.323947 | 2026-06-03 00:32:25.324010 | PLAY [all] 2026-06-03 00:32:25.332500 | 2026-06-03 00:32:25.333325 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-03 00:32:25.546141 | rockylinux-9 -> localhost | ok 2026-06-03 00:32:25.552021 | 2026-06-03 00:32:25.552092 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-03 00:32:25.577970 | rockylinux-9 | ok 2026-06-03 00:32:25.605879 | rockylinux-9 | included: /var/lib/zuul/builds/cd9488607982472586db08672efd7f23/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-03 00:32:25.613458 | 2026-06-03 00:32:25.613547 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-03 00:32:26.704292 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-03 00:32:26.704483 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/cd9488607982472586db08672efd7f23/work/cd9488607982472586db08672efd7f23_id_rsa 2026-06-03 00:32:26.704527 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/cd9488607982472586db08672efd7f23/work/cd9488607982472586db08672efd7f23_id_rsa.pub 2026-06-03 00:32:26.704560 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-03 00:32:26.704591 | rockylinux-9 -> localhost | SHA256:FPqJVEd/yc1+lZfdmLfB8cRQhLYgycy6s4eiX2sHaK8 zuul-build-sshkey 2026-06-03 00:32:26.704637 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-03 00:32:26.704669 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-03 00:32:26.704703 | rockylinux-9 -> localhost | | o=o. .B+| 2026-06-03 00:32:26.704735 | rockylinux-9 -> localhost | | o o*...+BO| 2026-06-03 00:32:26.704766 | rockylinux-9 -> localhost | | o .. ..o**X| 2026-06-03 00:32:26.704795 | rockylinux-9 -> localhost | | . +.. ..o=| 2026-06-03 00:32:26.704824 | rockylinux-9 -> localhost | | ..S. .o| 2026-06-03 00:32:26.704853 | rockylinux-9 -> localhost | | o + .| 2026-06-03 00:32:26.704882 | rockylinux-9 -> localhost | | . ..= | 2026-06-03 00:32:26.704911 | rockylinux-9 -> localhost | | ..=.o | 2026-06-03 00:32:26.704944 | rockylinux-9 -> localhost | | .oE+.o | 2026-06-03 00:32:26.704981 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-03 00:32:26.713317 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.623729 2026-06-03 00:32:26.719097 | 2026-06-03 00:32:26.719165 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-03 00:32:26.747593 | rockylinux-9 | ok 2026-06-03 00:32:26.755925 | rockylinux-9 | included: /var/lib/zuul/builds/cd9488607982472586db08672efd7f23/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-03 00:32:26.763076 | 2026-06-03 00:32:26.763139 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-03 00:32:26.788253 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 00:32:26.798746 | 2026-06-03 00:32:26.798822 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-03 00:32:27.302130 | rockylinux-9 | changed 2026-06-03 00:32:27.306950 | 2026-06-03 00:32:27.307030 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-03 00:32:27.554193 | rockylinux-9 | ok 2026-06-03 00:32:27.560378 | 2026-06-03 00:32:27.560442 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-03 00:32:28.496376 | rockylinux-9 | changed 2026-06-03 00:32:28.502764 | 2026-06-03 00:32:28.502827 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-03 00:32:29.464557 | rockylinux-9 | changed 2026-06-03 00:32:29.469990 | 2026-06-03 00:32:29.470071 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-03 00:32:29.495975 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 00:32:29.505355 | 2026-06-03 00:32:29.505425 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-03 00:32:29.870013 | rockylinux-9 -> localhost | changed 2026-06-03 00:32:29.884249 | 2026-06-03 00:32:29.884319 | TASK [add-build-sshkey : Add back temp key] 2026-06-03 00:32:30.170116 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/cd9488607982472586db08672efd7f23/work/cd9488607982472586db08672efd7f23_id_rsa (zuul-build-sshkey) 2026-06-03 00:32:30.170333 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014147 2026-06-03 00:32:30.177319 | 2026-06-03 00:32:30.177384 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-03 00:32:30.581084 | rockylinux-9 | ok 2026-06-03 00:32:30.588233 | 2026-06-03 00:32:30.588308 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-03 00:32:30.614035 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 00:32:30.629263 | 2026-06-03 00:32:30.629335 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-03 00:32:31.071045 | rockylinux-9 | ok 2026-06-03 00:32:31.089551 | 2026-06-03 00:32:31.089704 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-03 00:32:32.079095 | rockylinux-9 | Output suppressed because no_log was given 2026-06-03 00:32:32.086912 | 2026-06-03 00:32:32.086975 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-03 00:32:32.341539 | rockylinux-9 | ok: "logs" 2026-06-03 00:32:32.341920 | rockylinux-9 | ok: All items complete 2026-06-03 00:32:32.341970 | 2026-06-03 00:32:32.560737 | rockylinux-9 | ok: "artifacts" 2026-06-03 00:32:32.780227 | rockylinux-9 | ok: "docs" 2026-06-03 00:32:32.790928 | 2026-06-03 00:32:32.791069 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-03 00:32:33.051834 | rockylinux-9 | changed: "logs" 2026-06-03 00:32:33.343064 | rockylinux-9 | changed: "artifacts" 2026-06-03 00:32:33.525382 | rockylinux-9 | changed: "docs" 2026-06-03 00:32:33.541642 | 2026-06-03 00:32:33.541781 | PLAY RECAP 2026-06-03 00:32:33.541831 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 00:32:33.541862 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-03 00:32:33.541926 | 2026-06-03 00:32:33.690012 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 00:32:33.693040 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 00:32:34.289685 | 2026-06-03 00:32:34.289826 | PLAY [all] 2026-06-03 00:32:34.301075 | 2026-06-03 00:32:34.301151 | TASK [setup-uv : Extract archive] 2026-06-03 00:32:37.093540 | rockylinux-9 | changed 2026-06-03 00:32:37.100675 | 2026-06-03 00:32:37.100761 | TASK [setup-uv : Print version] 2026-06-03 00:32:37.123791 | [rockylinux-9] Waiting on logger 2026-06-03 00:32:37.639654 | rockylinux-9 | ok: Runtime: 0:00:00.014471 2026-06-03 00:32:37.649356 | 2026-06-03 00:32:37.649503 | TASK [Install Ansible collection siblings] 2026-06-03 00:32:38.087874 | rockylinux-9 | ok: 2026-06-03 00:32:38.088099 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-03 00:32:38.088139 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-03 00:32:38.090240 | 2026-06-03 00:32:38.090302 | PLAY RECAP 2026-06-03 00:32:38.090363 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 00:32:38.090392 | 2026-06-03 00:32:38.210135 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 00:32:38.211379 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 00:32:38.848704 | 2026-06-03 00:32:38.848878 | PLAY [all] 2026-06-03 00:32:38.863712 | 2026-06-03 00:32:38.863892 | TASK [Copy inventory file for Zuul] 2026-06-03 00:32:40.211732 | rockylinux-9 | changed 2026-06-03 00:32:40.218671 | 2026-06-03 00:32:40.218783 | TASK [Switch "ansible_host" to private IP] 2026-06-03 00:32:40.632677 | rockylinux-9 | changed: 1 replacements made 2026-06-03 00:32:40.639285 | 2026-06-03 00:32:40.639357 | TASK [Run Molecule scenario] 2026-06-03 00:32:40.661811 | [rockylinux-9] Waiting on logger 2026-06-03 00:32:48.806167 | [rockylinux-9] Waiting on logger 2026-06-03 00:32:58.982469 | [rockylinux-9] Waiting on logger 2026-06-03 00:33:08.140018 | [rockylinux-9] Waiting on logger 2026-06-03 00:33:18.441982 | [rockylinux-9] Waiting on logger 2026-06-03 00:33:28.614473 | [rockylinux-9] Waiting on logger 2026-06-03 00:33:38.790032 | [rockylinux-9] Waiting on logger 2026-06-03 00:33:48.966185 | [rockylinux-9] Waiting on logger 2026-06-03 00:33:58.123569 | [rockylinux-9] Waiting on logger 2026-06-03 00:34:08.298029 | [rockylinux-9] Waiting on logger 2026-06-03 00:34:18.470158 | [rockylinux-9] Waiting on logger 2026-06-03 00:34:28.646014 | [rockylinux-9] Waiting on logger 2026-06-03 00:34:37.715086 | rockylinux-9 | ok: Runtime: 0:01:56.379979 2026-06-03 00:34:37.722715 | 2026-06-03 00:34:37.722779 | PLAY RECAP 2026-06-03 00:34:37.722843 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 00:34:37.722868 | 2026-06-03 00:34:37.878317 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 00:34:37.880613 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 00:34:38.522032 | 2026-06-03 00:34:38.522161 | PLAY [all] 2026-06-03 00:34:38.533206 | 2026-06-03 00:34:38.533286 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-03 00:34:38.579259 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 00:34:38.589165 | 2026-06-03 00:34:38.589334 | TASK [fetch-output : Set log path for single node] 2026-06-03 00:34:38.633988 | rockylinux-9 | ok 2026-06-03 00:34:38.640845 | 2026-06-03 00:34:38.640912 | LOOP [fetch-output : Ensure local output dirs] 2026-06-03 00:34:39.028600 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/cd9488607982472586db08672efd7f23/work/logs" 2026-06-03 00:34:39.233986 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cd9488607982472586db08672efd7f23/work/artifacts" 2026-06-03 00:34:39.449725 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cd9488607982472586db08672efd7f23/work/docs" 2026-06-03 00:34:39.468536 | 2026-06-03 00:34:39.468685 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-03 00:34:40.187750 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 00:34:40.187973 | rockylinux-9 | changed: All items complete 2026-06-03 00:34:40.188033 | 2026-06-03 00:34:40.717477 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 00:34:41.225003 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 00:34:41.248569 | 2026-06-03 00:34:41.248750 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-03 00:34:41.734704 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008246 2026-06-03 00:34:41.974338 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008532 2026-06-03 00:34:41.986612 | 2026-06-03 00:34:41.986798 | PLAY [all] 2026-06-03 00:34:41.993305 | 2026-06-03 00:34:41.993376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-03 00:34:42.457691 | rockylinux-9 | changed 2026-06-03 00:34:42.463542 | 2026-06-03 00:34:42.463596 | PLAY RECAP 2026-06-03 00:34:42.463696 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-03 00:34:42.463811 | 2026-06-03 00:34:42.661242 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 00:34:42.664944 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-03 00:34:43.287406 | 2026-06-03 00:34:43.287568 | PLAY [localhost] 2026-06-03 00:34:43.299112 | 2026-06-03 00:34:43.299241 | TASK [Generate Zuul manifest] 2026-06-03 00:34:43.324573 | localhost | ok 2026-06-03 00:34:43.341233 | 2026-06-03 00:34:43.341382 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-03 00:34:43.735882 | localhost | changed 2026-06-03 00:34:43.747416 | 2026-06-03 00:34:43.747561 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-03 00:34:43.778151 | localhost | ok 2026-06-03 00:34:43.784872 | 2026-06-03 00:34:43.784938 | TASK [Upload logs] 2026-06-03 00:34:43.803185 | localhost | ok 2026-06-03 00:34:43.865488 | 2026-06-03 00:34:43.865646 | TASK [Set zuul-log-path fact] 2026-06-03 00:34:43.888489 | localhost | ok 2026-06-03 00:34:43.901353 | 2026-06-03 00:34:43.901422 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 00:34:43.934362 | localhost | ok 2026-06-03 00:34:43.946175 | 2026-06-03 00:34:43.946396 | TASK [upload-logs : Create log directories] 2026-06-03 00:34:44.354336 | localhost | changed 2026-06-03 00:34:44.361861 | 2026-06-03 00:34:44.362157 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-03 00:34:44.778360 | localhost -> localhost | ok: Runtime: 0:00:00.006216 2026-06-03 00:34:44.784174 | 2026-06-03 00:34:44.784268 | TASK [upload-logs : Upload logs to log server] 2026-06-03 00:34:45.279799 | localhost | Output suppressed because no_log was given 2026-06-03 00:34:45.285096 | 2026-06-03 00:34:45.285193 | LOOP [upload-logs : Compress console log and json output] 2026-06-03 00:34:45.327992 | localhost | skipping: Conditional result was False 2026-06-03 00:34:45.335530 | localhost | skipping: Conditional result was False 2026-06-03 00:34:45.346159 | 2026-06-03 00:34:45.346311 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-03 00:34:45.392517 | localhost | skipping: Conditional result was False 2026-06-03 00:34:45.392918 | 2026-06-03 00:34:45.396707 | localhost | skipping: Conditional result was False 2026-06-03 00:34:45.407404 | 2026-06-03 00:34:45.407537 | LOOP [upload-logs : Upload console log and json output]