2026-03-11 00:44:39.354584 | Job console starting
2026-03-11 00:44:39.372035 | Updating git repos
2026-03-11 00:44:39.452395 | Cloning repos into workspace
2026-03-11 00:44:39.559205 | Restoring repo states
2026-03-11 00:44:39.570476 | Merging changes
2026-03-11 00:44:40.288528 | Checking out repos
2026-03-11 00:44:40.564821 | Preparing playbooks
2026-03-11 00:44:51.421988 | Running Ansible setup
2026-03-11 00:44:55.328756 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-11 00:44:55.886341 | 
2026-03-11 00:44:55.886459 | PLAY [localhost]
2026-03-11 00:44:55.894254 | 
2026-03-11 00:44:55.894337 | TASK [Gathering Facts]
2026-03-11 00:44:57.156384 | localhost | ok
2026-03-11 00:44:57.166761 | 
2026-03-11 00:44:57.166872 | TASK [Setup log path fact]
2026-03-11 00:44:57.193339 | localhost | ok
2026-03-11 00:44:57.208816 | 
2026-03-11 00:44:57.208950 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-11 00:44:57.251071 | localhost | ok
2026-03-11 00:44:57.258514 | 
2026-03-11 00:44:57.258620 | TASK [emit-job-header : Print job information]
2026-03-11 00:44:57.300783 | # Job Information
2026-03-11 00:44:57.301061 | Ansible Version: 2.16.16
2026-03-11 00:44:57.301109 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-03-11 00:44:57.301140 | Pipeline: check
2026-03-11 00:44:57.301169 | Executor: 0a8996d2b663
2026-03-11 00:44:57.301196 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/99
2026-03-11 00:44:57.301227 | Event ID: 53b91b30-1ce3-11f1-884c-c2e8535f96e3
2026-03-11 00:44:57.304584 | 
2026-03-11 00:44:57.304649 | LOOP [emit-job-header : Print node information]
2026-03-11 00:44:57.399754 | localhost | ok:
2026-03-11 00:44:57.400127 | localhost | # Node Information
2026-03-11 00:44:57.400169 | localhost | Inventory Hostname: rockylinux-9
2026-03-11 00:44:57.400202 | localhost | Hostname: np0000158294
2026-03-11 00:44:57.400232 | localhost | Username: zuul
2026-03-11 00:44:57.400265 | localhost | Distro: Rocky 9.7
2026-03-11 00:44:57.400294 | localhost | Provider: yul1
2026-03-11 00:44:57.400323 | localhost | Region: ca-ymq-1
2026-03-11 00:44:57.400351 | localhost | Label: rockylinux-9
2026-03-11 00:44:57.400378 | localhost | Product Name: OpenStack Nova
2026-03-11 00:44:57.400417 | localhost | Interface IP: 199.204.45.244
2026-03-11 00:44:57.409749 | 
2026-03-11 00:44:57.409897 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-11 00:44:57.820922 | localhost -> localhost | changed
2026-03-11 00:44:57.833417 | 
2026-03-11 00:44:57.833526 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-11 00:44:58.919675 | localhost -> localhost | changed
2026-03-11 00:44:58.927185 | 
2026-03-11 00:44:58.927281 | PLAY [all]
2026-03-11 00:44:58.940622 | 
2026-03-11 00:44:58.940891 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-11 00:44:59.181792 | rockylinux-9 -> localhost | ok
2026-03-11 00:44:59.190399 | 
2026-03-11 00:44:59.241191 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-11 00:44:59.273971 | rockylinux-9 | ok
2026-03-11 00:44:59.296484 | rockylinux-9 | included: /var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-11 00:44:59.301649 | 
2026-03-11 00:44:59.301708 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-11 00:45:00.054214 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-03-11 00:45:00.054388 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/work/0266a864f91a43acbab801b0de14be71_id_rsa
2026-03-11 00:45:00.054420 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/work/0266a864f91a43acbab801b0de14be71_id_rsa.pub
2026-03-11 00:45:00.054444 | rockylinux-9 -> localhost | The key fingerprint is:
2026-03-11 00:45:00.054465 | rockylinux-9 -> localhost | SHA256:PTrffcM+mRWnx+BaZCm15xtsLiRxKxCwHmkU+3win2w zuul-build-sshkey
2026-03-11 00:45:00.054499 | rockylinux-9 -> localhost | The key's randomart image is:
2026-03-11 00:45:00.054520 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-03-11 00:45:00.054545 | rockylinux-9 -> localhost | |       +o        |
2026-03-11 00:45:00.054567 | rockylinux-9 -> localhost | |      . +.    .  |
2026-03-11 00:45:00.054587 | rockylinux-9 -> localhost | |       *  .  . o |
2026-03-11 00:45:00.054608 | rockylinux-9 -> localhost | |      o +o ...*.o|
2026-03-11 00:45:00.054629 | rockylinux-9 -> localhost | |       oS++.o=o*o|
2026-03-11 00:45:00.054650 | rockylinux-9 -> localhost | |        +.++ o++=|
2026-03-11 00:45:00.054670 | rockylinux-9 -> localhost | |        oE  +o+.*|
2026-03-11 00:45:00.054691 | rockylinux-9 -> localhost | |        .o ..o O.|
2026-03-11 00:45:00.054714 | rockylinux-9 -> localhost | |          . . +oo|
2026-03-11 00:45:00.054736 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-03-11 00:45:00.054785 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.331768
2026-03-11 00:45:00.063094 | 
2026-03-11 00:45:00.063236 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-11 00:45:00.103669 | rockylinux-9 | ok
2026-03-11 00:45:00.111935 | rockylinux-9 | included: /var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-11 00:45:00.119836 | 
2026-03-11 00:45:00.119899 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-11 00:45:00.144123 | rockylinux-9 | skipping: Conditional result was False
2026-03-11 00:45:00.151528 | 
2026-03-11 00:45:00.151729 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-11 00:45:00.615130 | rockylinux-9 | changed
2026-03-11 00:45:00.620796 | 
2026-03-11 00:45:00.620861 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-11 00:45:00.861976 | rockylinux-9 | ok
2026-03-11 00:45:00.866637 | 
2026-03-11 00:45:00.866702 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-11 00:45:01.777491 | rockylinux-9 | changed
2026-03-11 00:45:01.783404 | 
2026-03-11 00:45:01.783475 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-11 00:45:02.705156 | rockylinux-9 | changed
2026-03-11 00:45:02.713396 | 
2026-03-11 00:45:02.713468 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-11 00:45:02.737674 | rockylinux-9 | skipping: Conditional result was False
2026-03-11 00:45:02.745056 | 
2026-03-11 00:45:02.745196 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-11 00:45:03.114148 | rockylinux-9 -> localhost | changed
2026-03-11 00:45:03.124328 | 
2026-03-11 00:45:03.124476 | TASK [add-build-sshkey : Add back temp key]
2026-03-11 00:45:03.418840 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/work/0266a864f91a43acbab801b0de14be71_id_rsa (zuul-build-sshkey)
2026-03-11 00:45:03.419068 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.016458
2026-03-11 00:45:03.427876 | 
2026-03-11 00:45:03.427959 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-11 00:45:03.805384 | rockylinux-9 | ok
2026-03-11 00:45:03.812668 | 
2026-03-11 00:45:03.812752 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-11 00:45:03.839296 | rockylinux-9 | skipping: Conditional result was False
2026-03-11 00:45:03.852755 | 
2026-03-11 00:45:03.852840 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-11 00:45:04.239619 | rockylinux-9 | ok
2026-03-11 00:45:04.429816 | 
2026-03-11 00:45:04.429923 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-11 00:45:05.390919 | rockylinux-9 | Output suppressed because no_log was given
2026-03-11 00:45:05.399733 | 
2026-03-11 00:45:05.399800 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-11 00:45:05.667698 | rockylinux-9 | ok: "logs"
2026-03-11 00:45:05.668057 | rockylinux-9 | ok: All items complete
2026-03-11 00:45:05.668101 | 
2026-03-11 00:45:05.939240 | rockylinux-9 | ok: "artifacts"
2026-03-11 00:45:06.214720 | rockylinux-9 | ok: "docs"
2026-03-11 00:45:06.224366 | 
2026-03-11 00:45:06.224520 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-11 00:45:06.489371 | rockylinux-9 | changed: "logs"
2026-03-11 00:45:06.732243 | rockylinux-9 | changed: "artifacts"
2026-03-11 00:45:06.978833 | rockylinux-9 | changed: "docs"
2026-03-11 00:45:06.993692 | 
2026-03-11 00:45:06.993877 | PLAY RECAP
2026-03-11 00:45:06.993934 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 00:45:06.993966 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-11 00:45:06.993986 | 
2026-03-11 00:45:07.121709 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-11 00:45:07.122844 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-11 00:45:07.701472 | 
2026-03-11 00:45:07.701599 | PLAY [all]
2026-03-11 00:45:07.713499 | 
2026-03-11 00:45:07.713581 | TASK [setup-uv : Extract archive]
2026-03-11 00:45:10.574999 | rockylinux-9 | changed
2026-03-11 00:45:10.582015 | 
2026-03-11 00:45:10.582097 | TASK [setup-uv : Print version]
2026-03-11 00:45:10.606644 | [rockylinux-9] Waiting on logger
2026-03-11 00:45:11.119903 | rockylinux-9 | ok: Runtime: 0:00:00.015136
2026-03-11 00:45:11.127774 | 
2026-03-11 00:45:11.127893 | PLAY RECAP
2026-03-11 00:45:11.127982 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 00:45:11.128035 | 
2026-03-11 00:45:11.260253 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-11 00:45:11.261489 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-11 00:45:11.832742 | 
2026-03-11 00:45:11.832870 | PLAY [all]
2026-03-11 00:45:11.843235 | 
2026-03-11 00:45:11.843311 | TASK [Copy inventory file for Zuul]
2026-03-11 00:45:13.190419 | rockylinux-9 | changed
2026-03-11 00:45:13.208566 | 
2026-03-11 00:45:13.208729 | TASK [Switch "ansible_host" to private IP]
2026-03-11 00:45:13.584212 | rockylinux-9 | changed: 1 replacements made
2026-03-11 00:45:13.589108 | 
2026-03-11 00:45:13.589174 | TASK [Run Molecule scenario]
2026-03-11 00:45:13.610368 | [rockylinux-9] Waiting on logger
2026-03-11 00:45:21.733932 | [rockylinux-9] Waiting on logger
2026-03-11 00:45:31.913947 | [rockylinux-9] Waiting on logger
2026-03-11 00:45:42.086009 | [rockylinux-9] Waiting on logger
2026-03-11 00:45:52.262021 | [rockylinux-9] Waiting on logger
2026-03-11 00:46:02.438128 | [rockylinux-9] Waiting on logger
2026-03-11 00:46:12.613901 | [rockylinux-9] Waiting on logger
2026-03-11 00:46:21.766076 | [rockylinux-9] Waiting on logger
2026-03-11 00:46:31.942778 | [rockylinux-9] Waiting on logger
2026-03-11 00:46:42.182254 | [rockylinux-9] Waiting on logger
2026-03-11 00:46:52.358268 | [rockylinux-9] Waiting on logger
2026-03-11 00:47:02.534468 | [rockylinux-9] Waiting on logger
2026-03-11 00:47:11.691690 | [rockylinux-9] Waiting on logger
2026-03-11 00:47:21.862462 | [rockylinux-9] Waiting on logger
2026-03-11 00:47:32.042347 | [rockylinux-9] Waiting on logger
2026-03-11 00:47:42.250205 | [rockylinux-9] Waiting on logger
2026-03-11 00:47:44.494579 | rockylinux-9 | ok: Runtime: 0:02:30.174462
2026-03-11 00:47:44.501608 | 
2026-03-11 00:47:44.501660 | PLAY RECAP
2026-03-11 00:47:44.501722 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 00:47:44.501756 | 
2026-03-11 00:47:44.636746 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-11 00:47:44.639208 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-11 00:47:45.209334 | 
2026-03-11 00:47:45.209461 | PLAY [all]
2026-03-11 00:47:45.219591 | 
2026-03-11 00:47:45.219670 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-11 00:47:45.264895 | rockylinux-9 | skipping: Conditional result was False
2026-03-11 00:47:45.274804 | 
2026-03-11 00:47:45.275010 | TASK [fetch-output : Set log path for single node]
2026-03-11 00:47:45.319222 | rockylinux-9 | ok
2026-03-11 00:47:45.325466 | 
2026-03-11 00:47:45.325539 | LOOP [fetch-output : Ensure local output dirs]
2026-03-11 00:47:45.718279 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/work/logs"
2026-03-11 00:47:45.924281 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/work/artifacts"
2026-03-11 00:47:46.122181 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/0266a864f91a43acbab801b0de14be71/work/docs"
2026-03-11 00:47:46.142781 | 
2026-03-11 00:47:46.142903 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-11 00:47:46.839682 | rockylinux-9 | changed: .d..t...... ./
2026-03-11 00:47:46.839889 | rockylinux-9 | changed: All items complete
2026-03-11 00:47:46.839917 | 
2026-03-11 00:47:47.355536 | rockylinux-9 | changed: .d..t...... ./
2026-03-11 00:47:47.897087 | rockylinux-9 | changed: .d..t...... ./
2026-03-11 00:47:47.919243 | 
2026-03-11 00:47:47.919393 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-11 00:47:48.378633 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007705
2026-03-11 00:47:48.608094 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007228
2026-03-11 00:47:48.623427 | 
2026-03-11 00:47:48.623554 | PLAY [all]
2026-03-11 00:47:48.629395 | 
2026-03-11 00:47:48.629457 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-11 00:47:49.099251 | rockylinux-9 | changed
2026-03-11 00:47:49.106016 | 
2026-03-11 00:47:49.106063 | PLAY RECAP
2026-03-11 00:47:49.106106 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-11 00:47:49.106129 | 
2026-03-11 00:47:49.207823 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-11 00:47:49.209495 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-11 00:47:49.740203 | 
2026-03-11 00:47:49.740325 | PLAY [localhost]
2026-03-11 00:47:49.749975 | 
2026-03-11 00:47:49.750044 | TASK [Generate Zuul manifest]
2026-03-11 00:47:49.766861 | localhost | ok
2026-03-11 00:47:49.781558 | 
2026-03-11 00:47:49.872979 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-11 00:47:50.169042 | localhost | changed
2026-03-11 00:47:50.182206 | 
2026-03-11 00:47:50.182277 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-11 00:47:50.212174 | localhost | ok
2026-03-11 00:47:50.222047 | 
2026-03-11 00:47:50.222110 | TASK [Upload logs]
2026-03-11 00:47:50.240398 | localhost | ok
2026-03-11 00:47:50.301596 | 
2026-03-11 00:47:50.301719 | TASK [Set zuul-log-path fact]
2026-03-11 00:47:50.323754 | localhost | ok
2026-03-11 00:47:50.335046 | 
2026-03-11 00:47:50.335115 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-11 00:47:50.366076 | localhost | ok
2026-03-11 00:47:50.374335 | 
2026-03-11 00:47:50.374398 | TASK [upload-logs : Create log directories]
2026-03-11 00:47:50.749823 | localhost | changed
2026-03-11 00:47:50.754829 | 
2026-03-11 00:47:50.754900 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-11 00:47:51.193342 | localhost -> localhost | ok: Runtime: 0:00:00.004816
2026-03-11 00:47:51.198945 | 
2026-03-11 00:47:51.199007 | TASK [upload-logs : Upload logs to log server]
2026-03-11 00:47:51.675291 | localhost | Output suppressed because no_log was given
2026-03-11 00:47:51.680423 | 
2026-03-11 00:47:51.680489 | LOOP [upload-logs : Compress console log and json output]
2026-03-11 00:47:51.731139 | localhost | skipping: Conditional result was False
2026-03-11 00:47:51.737997 | localhost | skipping: Conditional result was False
2026-03-11 00:47:51.752151 | 
2026-03-11 00:47:51.752298 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-11 00:47:51.795113 | localhost | skipping: Conditional result was False
2026-03-11 00:47:51.795467 | 
2026-03-11 00:47:51.799363 | localhost | skipping: Conditional result was False
2026-03-11 00:47:51.817029 | 
2026-03-11 00:47:51.817222 | LOOP [upload-logs : Upload console log and json output]