2026-04-29 20:48:22.857288 | Job console starting
2026-04-29 20:48:22.957057 | Updating git repos
2026-04-29 20:48:23.047523 | Cloning repos into workspace
2026-04-29 20:48:23.836666 | Restoring repo states
2026-04-29 20:48:23.853900 | Merging changes
2026-04-29 20:48:25.223920 | Checking out repos
2026-04-29 20:48:25.566060 | Preparing playbooks
2026-04-29 20:48:30.913758 | Running Ansible setup
2026-04-29 20:48:34.966515 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-29 20:48:35.625703 | 
2026-04-29 20:48:35.625863 | PLAY [localhost]
2026-04-29 20:48:35.633736 | 
2026-04-29 20:48:35.633803 | TASK [Gathering Facts]
2026-04-29 20:48:36.608119 | localhost | ok
2026-04-29 20:48:36.619787 | 
2026-04-29 20:48:36.619962 | TASK [Setup log path fact]
2026-04-29 20:48:36.640253 | localhost | ok
2026-04-29 20:48:36.665389 | 
2026-04-29 20:48:36.665792 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-29 20:48:36.699369 | localhost | ok
2026-04-29 20:48:36.706993 | 
2026-04-29 20:48:36.707063 | TASK [emit-job-header : Print job information]
2026-04-29 20:48:36.755910 | # Job Information
2026-04-29 20:48:36.756076 | Ansible Version: 2.16.18
2026-04-29 20:48:36.756109 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-29 20:48:36.756131 | Pipeline: check
2026-04-29 20:48:36.756151 | Executor: 2d72f0692154
2026-04-29 20:48:36.756171 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126
2026-04-29 20:48:36.756194 | Event ID: 9b4e1cc0-440c-11f1-90ca-0e5250fcd001
2026-04-29 20:48:36.760777 | 
2026-04-29 20:48:36.760848 | LOOP [emit-job-header : Print node information]
2026-04-29 20:48:36.854971 | localhost | ok:
2026-04-29 20:48:36.855162 | localhost | # Node Information
2026-04-29 20:48:36.855191 | localhost | Inventory Hostname: rockylinux-9
2026-04-29 20:48:36.855213 | localhost | Hostname: np0000171288
2026-04-29 20:48:36.855233 | localhost | Username: zuul
2026-04-29 20:48:36.855255 | localhost | Distro: Rocky 9.7
2026-04-29 20:48:36.855275 | localhost | Provider: yul1
2026-04-29 20:48:36.855294 | localhost | Region: ca-ymq-1
2026-04-29 20:48:36.855312 | localhost | Label: rockylinux-9
2026-04-29 20:48:36.855330 | localhost | Product Name: OpenStack Nova
2026-04-29 20:48:36.855348 | localhost | Interface IP: 199.204.45.210
2026-04-29 20:48:36.869688 | 
2026-04-29 20:48:36.869872 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-29 20:48:37.330220 | localhost -> localhost | changed
2026-04-29 20:48:37.337604 | 
2026-04-29 20:48:37.337690 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-29 20:48:38.476388 | localhost -> localhost | changed
2026-04-29 20:48:38.563604 | 
2026-04-29 20:48:38.563707 | PLAY [all]
2026-04-29 20:48:38.573709 | 
2026-04-29 20:48:38.573801 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-29 20:48:38.852498 | rockylinux-9 -> localhost | ok
2026-04-29 20:48:38.858002 | 
2026-04-29 20:48:38.858087 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-29 20:48:38.908951 | rockylinux-9 | ok
2026-04-29 20:48:38.924767 | rockylinux-9 | included: /var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-29 20:48:38.938989 | 
2026-04-29 20:48:38.939133 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-29 20:48:39.959254 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-29 20:48:39.959415 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/work/3c29669230e242008b7463ae6f1b96f9_id_rsa
2026-04-29 20:48:39.959445 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/work/3c29669230e242008b7463ae6f1b96f9_id_rsa.pub
2026-04-29 20:48:39.959498 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-29 20:48:39.959523 | rockylinux-9 -> localhost | SHA256:Q8BwBfDurJqdsxX7Zs0XcLI3XL5punWvr03QUfOQaD4 zuul-build-sshkey
2026-04-29 20:48:39.959557 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-29 20:48:39.959580 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-29 20:48:39.959606 | rockylinux-9 -> localhost | |    o++o.    ..o.|
2026-04-29 20:48:39.959628 | rockylinux-9 -> localhost | |     o..    o ..+|
2026-04-29 20:48:39.959649 | rockylinux-9 -> localhost | |      . .  o   ..|
2026-04-29 20:48:39.959670 | rockylinux-9 -> localhost | |     . .  o E ...|
2026-04-29 20:48:39.959691 | rockylinux-9 -> localhost | |      o S  * +. .|
2026-04-29 20:48:39.959711 | rockylinux-9 -> localhost | |     o o .. = .. |
2026-04-29 20:48:39.959731 | rockylinux-9 -> localhost | |      =  o . o.oo|
2026-04-29 20:48:39.959752 | rockylinux-9 -> localhost | |   o.+ .o o ..++.|
2026-04-29 20:48:39.959775 | rockylinux-9 -> localhost | |  o.=o o.  .o+o++|
2026-04-29 20:48:39.959796 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-29 20:48:39.959844 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.529782
2026-04-29 20:48:39.964467 | 
2026-04-29 20:48:39.964529 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-29 20:48:39.992141 | rockylinux-9 | ok
2026-04-29 20:48:40.000171 | rockylinux-9 | included: /var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-29 20:48:40.008511 | 
2026-04-29 20:48:40.008573 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-29 20:48:40.030982 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:48:40.040991 | 
2026-04-29 20:48:40.041091 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-29 20:48:40.560483 | rockylinux-9 | changed
2026-04-29 20:48:40.645282 | 
2026-04-29 20:48:40.645484 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-29 20:48:40.922087 | rockylinux-9 | ok
2026-04-29 20:48:41.009297 | 
2026-04-29 20:48:41.009477 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-29 20:48:41.888373 | rockylinux-9 | changed
2026-04-29 20:48:41.895161 | 
2026-04-29 20:48:41.895262 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-29 20:48:42.811454 | rockylinux-9 | changed
2026-04-29 20:48:42.929570 | 
2026-04-29 20:48:42.929680 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-29 20:48:42.956667 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:48:42.966728 | 
2026-04-29 20:48:42.966820 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-29 20:48:43.399602 | rockylinux-9 -> localhost | changed
2026-04-29 20:48:43.410278 | 
2026-04-29 20:48:43.410368 | TASK [add-build-sshkey : Add back temp key]
2026-04-29 20:48:43.737754 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/work/3c29669230e242008b7463ae6f1b96f9_id_rsa (zuul-build-sshkey)
2026-04-29 20:48:43.737951 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.030347
2026-04-29 20:48:43.742790 | 
2026-04-29 20:48:43.742859 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-29 20:48:44.150557 | rockylinux-9 | ok
2026-04-29 20:48:44.184429 | 
2026-04-29 20:48:44.184581 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-29 20:48:44.211333 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:48:44.224730 | 
2026-04-29 20:48:44.224822 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-29 20:48:44.654587 | rockylinux-9 | ok
2026-04-29 20:48:44.662196 | 
2026-04-29 20:48:44.662263 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-29 20:48:45.648287 | rockylinux-9 | Output suppressed because no_log was given
2026-04-29 20:48:45.657906 | 
2026-04-29 20:48:45.657977 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-29 20:48:45.898870 | rockylinux-9 | ok: "logs"
2026-04-29 20:48:45.899222 | rockylinux-9 | ok: All items complete
2026-04-29 20:48:45.899265 | 
2026-04-29 20:48:46.131485 | rockylinux-9 | ok: "artifacts"
2026-04-29 20:48:46.368087 | rockylinux-9 | ok: "docs"
2026-04-29 20:48:46.381198 | 
2026-04-29 20:48:46.381362 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-29 20:48:46.636029 | rockylinux-9 | changed: "logs"
2026-04-29 20:48:46.859613 | rockylinux-9 | changed: "artifacts"
2026-04-29 20:48:47.157745 | rockylinux-9 | changed: "docs"
2026-04-29 20:48:47.177868 | 
2026-04-29 20:48:47.177988 | PLAY RECAP
2026-04-29 20:48:47.178036 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-29 20:48:47.178067 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-29 20:48:47.178091 | 
2026-04-29 20:48:47.331275 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-29 20:48:47.333382 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-29 20:48:48.074650 | 
2026-04-29 20:48:48.074874 | PLAY [all]
2026-04-29 20:48:48.089181 | 
2026-04-29 20:48:48.089312 | TASK [setup-uv : Extract archive]
2026-04-29 20:48:50.806416 | rockylinux-9 | changed
2026-04-29 20:48:50.811882 | 
2026-04-29 20:48:50.811969 | TASK [setup-uv : Print version]
2026-04-29 20:48:50.833783 | [rockylinux-9] Waiting on logger
2026-04-29 20:48:51.349866 | rockylinux-9 | ok: Runtime: 0:00:00.013221
2026-04-29 20:48:51.372674 | 
2026-04-29 20:48:51.372820 | PLAY RECAP
2026-04-29 20:48:51.373206 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-29 20:48:51.373260 | 
2026-04-29 20:48:51.517625 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-29 20:48:51.519078 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-29 20:48:52.126056 | 
2026-04-29 20:48:52.158032 | PLAY [all]
2026-04-29 20:48:52.179304 | 
2026-04-29 20:48:52.179529 | TASK [Copy inventory file for Zuul]
2026-04-29 20:48:53.570715 | rockylinux-9 | changed
2026-04-29 20:48:53.576485 | 
2026-04-29 20:48:53.576575 | TASK [Switch "ansible_host" to private IP]
2026-04-29 20:48:54.037684 | rockylinux-9 | changed: 1 replacements made
2026-04-29 20:48:54.105969 | 
2026-04-29 20:48:54.106126 | TASK [Run Molecule scenario]
2026-04-29 20:48:54.132204 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:02.278440 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:12.457947 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:22.634277 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:32.805851 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:41.957971 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:52.134079 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:02.310105 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:12.489684 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:22.661966 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:32.838102 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:42.502122 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:52.691959 | rockylinux-9 | ok: Runtime: 0:01:58.083082
2026-04-29 20:50:52.698803 | 
2026-04-29 20:50:52.698888 | PLAY RECAP
2026-04-29 20:50:52.698959 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-29 20:50:52.699004 | 
2026-04-29 20:50:52.822083 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-29 20:50:52.824759 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-29 20:50:53.396176 | 
2026-04-29 20:50:53.396300 | PLAY [all]
2026-04-29 20:50:53.406791 | 
2026-04-29 20:50:53.406872 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-29 20:50:53.461668 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:50:53.466263 | 
2026-04-29 20:50:53.466349 | TASK [fetch-output : Set log path for single node]
2026-04-29 20:50:53.507380 | rockylinux-9 | ok
2026-04-29 20:50:53.517513 | 
2026-04-29 20:50:53.517935 | LOOP [fetch-output : Ensure local output dirs]
2026-04-29 20:50:53.914110 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/work/logs"
2026-04-29 20:50:54.118076 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/work/artifacts"
2026-04-29 20:50:54.340627 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3c29669230e242008b7463ae6f1b96f9/work/docs"
2026-04-29 20:50:54.356819 | 
2026-04-29 20:50:54.357009 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-29 20:50:55.074580 | rockylinux-9 | changed: .d..t...... ./
2026-04-29 20:50:55.074851 | rockylinux-9 | changed: All items complete
2026-04-29 20:50:55.074892 | 
2026-04-29 20:50:55.589969 | rockylinux-9 | changed: .d..t...... ./
2026-04-29 20:50:56.101502 | rockylinux-9 | changed: .d..t...... ./
2026-04-29 20:50:56.127468 | 
2026-04-29 20:50:56.127783 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-29 20:50:56.545506 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007071
2026-04-29 20:50:56.785948 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.005840
2026-04-29 20:50:56.800502 | 
2026-04-29 20:50:56.800655 | PLAY [all]
2026-04-29 20:50:56.806785 | 
2026-04-29 20:50:56.806851 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-29 20:50:57.275680 | rockylinux-9 | changed
2026-04-29 20:50:57.283551 | 
2026-04-29 20:50:57.283660 | PLAY RECAP
2026-04-29 20:50:57.283725 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-29 20:50:57.283756 | 
2026-04-29 20:50:57.458682 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-29 20:50:57.459849 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-29 20:50:58.056583 | 
2026-04-29 20:50:58.056719 | PLAY [localhost]
2026-04-29 20:50:58.071535 | 
2026-04-29 20:50:58.071654 | TASK [Generate Zuul manifest]
2026-04-29 20:50:58.103391 | localhost | ok
2026-04-29 20:50:58.118768 | 
2026-04-29 20:50:58.118843 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-29 20:50:58.554592 | localhost | changed
2026-04-29 20:50:58.568746 | 
2026-04-29 20:50:58.568899 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-29 20:50:58.602229 | localhost | ok
2026-04-29 20:50:58.614715 | 
2026-04-29 20:50:58.614812 | TASK [Upload logs]
2026-04-29 20:50:58.635001 | localhost | ok
2026-04-29 20:50:58.702391 | 
2026-04-29 20:50:58.702537 | TASK [Set zuul-log-path fact]
2026-04-29 20:50:58.727822 | localhost | ok
2026-04-29 20:50:58.742771 | 
2026-04-29 20:50:58.742863 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-29 20:50:58.776491 | localhost | ok
2026-04-29 20:50:58.786380 | 
2026-04-29 20:50:58.786530 | TASK [upload-logs : Create log directories]
2026-04-29 20:50:59.234884 | localhost | changed
2026-04-29 20:50:59.241487 | 
2026-04-29 20:50:59.241590 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-29 20:50:59.671096 | localhost -> localhost | ok: Runtime: 0:00:00.006186
2026-04-29 20:50:59.681341 | 
2026-04-29 20:50:59.681433 | TASK [upload-logs : Upload logs to log server]
2026-04-29 20:51:00.170550 | localhost | Output suppressed because no_log was given
2026-04-29 20:51:00.176434 | 
2026-04-29 20:51:00.176531 | LOOP [upload-logs : Compress console log and json output]
2026-04-29 20:51:00.222898 | localhost | skipping: Conditional result was False
2026-04-29 20:51:00.230495 | localhost | skipping: Conditional result was False
2026-04-29 20:51:00.240715 | 
2026-04-29 20:51:00.240900 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-29 20:51:00.285293 | localhost | skipping: Conditional result was False
2026-04-29 20:51:00.285907 | 
2026-04-29 20:51:00.288918 | localhost | skipping: Conditional result was False
2026-04-29 20:51:00.304537 | 
2026-04-29 20:51:00.304672 | LOOP [upload-logs : Upload console log and json output]