2026-04-19 01:00:49.404321 | Job console starting
2026-04-19 01:00:49.423460 | Updating git repos
2026-04-19 01:00:49.474305 | Cloning repos into workspace
2026-04-19 01:00:55.121170 | Restoring repo states
2026-04-19 01:00:55.144141 | Merging changes
2026-04-19 01:00:55.600382 | Checking out repos
2026-04-19 01:00:57.265958 | Preparing playbooks
2026-04-19 01:01:16.097473 | Running Ansible setup
2026-04-19 01:01:21.098524 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-19 01:01:21.761731 | 
2026-04-19 01:01:21.762181 | PLAY [localhost]
2026-04-19 01:01:21.769837 | 
2026-04-19 01:01:21.769910 | TASK [Gathering Facts]
2026-04-19 01:01:22.766336 | localhost | ok
2026-04-19 01:01:22.777064 | 
2026-04-19 01:01:22.777176 | TASK [Setup log path fact]
2026-04-19 01:01:22.796077 | localhost | ok
2026-04-19 01:01:22.808224 | 
2026-04-19 01:01:22.808291 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-19 01:01:22.836750 | localhost | ok
2026-04-19 01:01:22.959859 | 
2026-04-19 01:01:22.959990 | TASK [emit-job-header : Print job information]
2026-04-19 01:01:23.005218 | # Job Information
2026-04-19 01:01:23.005447 | Ansible Version: 2.16.16
2026-04-19 01:01:23.005485 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-19 01:01:23.005509 | Pipeline: check
2026-04-19 01:01:23.005530 | Executor: 0a8996d2b663
2026-04-19 01:01:23.005550 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/102
2026-04-19 01:01:23.005573 | Event ID: 175c64a0-3b8b-11f1-9cee-b51e243a86c4
2026-04-19 01:01:23.008566 | 
2026-04-19 01:01:23.008640 | LOOP [emit-job-header : Print node information]
2026-04-19 01:01:23.177888 | localhost | ok:
2026-04-19 01:01:23.178049 | localhost | # Node Information
2026-04-19 01:01:23.178078 | localhost | Inventory Hostname: rockylinux-9
2026-04-19 01:01:23.178102 | localhost | Hostname: np0000167709
2026-04-19 01:01:23.178141 | localhost | Username: zuul
2026-04-19 01:01:23.178164 | localhost | Distro: Rocky 9.7
2026-04-19 01:01:23.178184 | localhost | Provider: yul1
2026-04-19 01:01:23.178202 | localhost | Region: ca-ymq-1
2026-04-19 01:01:23.178220 | localhost | Label: rockylinux-9
2026-04-19 01:01:23.178238 | localhost | Product Name: OpenStack Nova
2026-04-19 01:01:23.178256 | localhost | Interface IP: 199.204.45.233
2026-04-19 01:01:23.193683 | 
2026-04-19 01:01:23.193801 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-19 01:01:23.778032 | localhost -> localhost | changed
2026-04-19 01:01:23.788369 | 
2026-04-19 01:01:23.788511 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-19 01:01:25.251389 | localhost -> localhost | changed
2026-04-19 01:01:25.259683 | 
2026-04-19 01:01:25.259747 | PLAY [all]
2026-04-19 01:01:25.267234 | 
2026-04-19 01:01:25.267301 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-19 01:01:25.520223 | rockylinux-9 -> localhost | ok
2026-04-19 01:01:25.533925 | 
2026-04-19 01:01:25.534078 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-19 01:01:25.574782 | rockylinux-9 | ok
2026-04-19 01:01:25.632877 | rockylinux-9 | included: /var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-19 01:01:25.648203 | 
2026-04-19 01:01:25.648356 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-19 01:01:28.012674 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-19 01:01:28.013855 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/work/16c52cf8219b41bab63c1c83b06f6c8d_id_rsa
2026-04-19 01:01:28.013908 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/work/16c52cf8219b41bab63c1c83b06f6c8d_id_rsa.pub
2026-04-19 01:01:28.013934 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-19 01:01:28.013956 | rockylinux-9 -> localhost | SHA256:zJW71t7NY+EKZg60V9y4JoCcJUFRAbydwNaVdi0H770 zuul-build-sshkey
2026-04-19 01:01:28.013989 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-19 01:01:28.014011 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-19 01:01:28.014042 | rockylinux-9 -> localhost | |      +==oo...o  |
2026-04-19 01:01:28.014064 | rockylinux-9 -> localhost | |       =.. + o.o |
2026-04-19 01:01:28.014084 | rockylinux-9 -> localhost | |      ..+.= . o. |
2026-04-19 01:01:28.014104 | rockylinux-9 -> localhost | |      .+=+ .. + .|
2026-04-19 01:01:28.014123 | rockylinux-9 -> localhost | |       +So.  + o.|
2026-04-19 01:01:28.014143 | rockylinux-9 -> localhost | |        . oo. ...|
2026-04-19 01:01:28.014163 | rockylinux-9 -> localhost | |         oo*.o.E.|
2026-04-19 01:01:28.014182 | rockylinux-9 -> localhost | |         .*.+. * |
2026-04-19 01:01:28.014205 | rockylinux-9 -> localhost | |           ...+ +|
2026-04-19 01:01:28.014225 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-19 01:01:28.014300 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.739373
2026-04-19 01:01:28.020515 | 
2026-04-19 01:01:28.020589 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-19 01:01:28.073185 | rockylinux-9 | ok
2026-04-19 01:01:28.085522 | rockylinux-9 | included: /var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-19 01:01:28.100849 | 
2026-04-19 01:01:28.100978 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-19 01:01:28.135642 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:01:28.141916 | 
2026-04-19 01:01:28.142037 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-19 01:01:28.611944 | rockylinux-9 | changed
2026-04-19 01:01:28.826339 | 
2026-04-19 01:01:28.826468 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-19 01:01:29.098739 | rockylinux-9 | ok
2026-04-19 01:01:29.103473 | 
2026-04-19 01:01:29.103590 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-19 01:01:30.044255 | rockylinux-9 | changed
2026-04-19 01:01:30.051156 | 
2026-04-19 01:01:30.051258 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-19 01:01:31.058992 | rockylinux-9 | changed
2026-04-19 01:01:31.070532 | 
2026-04-19 01:01:31.070613 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-19 01:01:31.094848 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:01:31.105682 | 
2026-04-19 01:01:31.105768 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-19 01:01:31.535457 | rockylinux-9 -> localhost | changed
2026-04-19 01:01:31.544391 | 
2026-04-19 01:01:31.544457 | TASK [add-build-sshkey : Add back temp key]
2026-04-19 01:01:32.486001 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/work/16c52cf8219b41bab63c1c83b06f6c8d_id_rsa (zuul-build-sshkey)
2026-04-19 01:01:32.486166 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.010109
2026-04-19 01:01:32.492552 | 
2026-04-19 01:01:32.492627 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-19 01:01:33.078764 | rockylinux-9 | ok
2026-04-19 01:01:33.083951 | 
2026-04-19 01:01:33.084023 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-19 01:01:33.108622 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:01:33.124495 | 
2026-04-19 01:01:33.124571 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-19 01:01:33.564850 | rockylinux-9 | ok
2026-04-19 01:01:33.571992 | 
2026-04-19 01:01:33.572091 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-19 01:01:34.724567 | rockylinux-9 | Output suppressed because no_log was given
2026-04-19 01:01:34.733154 | 
2026-04-19 01:01:34.733243 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-19 01:01:35.016270 | rockylinux-9 | ok: "logs"
2026-04-19 01:01:35.016501 | rockylinux-9 | ok: All items complete
2026-04-19 01:01:35.016530 | 
2026-04-19 01:01:35.270580 | rockylinux-9 | ok: "artifacts"
2026-04-19 01:01:35.503152 | rockylinux-9 | ok: "docs"
2026-04-19 01:01:35.514043 | 
2026-04-19 01:01:35.514131 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-19 01:01:35.802368 | rockylinux-9 | changed: "logs"
2026-04-19 01:01:36.039574 | rockylinux-9 | changed: "artifacts"
2026-04-19 01:01:36.291220 | rockylinux-9 | changed: "docs"
2026-04-19 01:01:36.487813 | 
2026-04-19 01:01:36.487927 | PLAY RECAP
2026-04-19 01:01:36.487987 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-19 01:01:36.488030 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-19 01:01:36.488060 | 
2026-04-19 01:01:36.764050 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-19 01:01:36.765596 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-19 01:01:37.433374 | 
2026-04-19 01:01:37.433507 | PLAY [all]
2026-04-19 01:01:37.444337 | 
2026-04-19 01:01:37.444427 | TASK [setup-uv : Extract archive]
2026-04-19 01:01:40.251434 | rockylinux-9 | changed
2026-04-19 01:01:40.259534 | 
2026-04-19 01:01:40.259703 | TASK [setup-uv : Print version]
2026-04-19 01:01:40.285496 | [rockylinux-9] Waiting on logger
2026-04-19 01:01:40.804583 | rockylinux-9 | ok: Runtime: 0:00:00.018029
2026-04-19 01:01:40.825523 | 
2026-04-19 01:01:40.825616 | PLAY RECAP
2026-04-19 01:01:40.825666 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-19 01:01:40.825691 | 
2026-04-19 01:01:40.932369 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-19 01:01:40.934599 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-19 01:01:41.486381 | 
2026-04-19 01:01:41.981711 | PLAY [all]
2026-04-19 01:01:42.005850 | 
2026-04-19 01:01:42.006069 | TASK [Copy inventory file for Zuul]
2026-04-19 01:01:43.361197 | rockylinux-9 | changed
2026-04-19 01:01:43.365709 | 
2026-04-19 01:01:43.365784 | TASK [Switch "ansible_host" to private IP]
2026-04-19 01:01:43.710790 | rockylinux-9 | changed: 1 replacements made
2026-04-19 01:01:43.716057 | 
2026-04-19 01:01:43.716126 | TASK [Run Molecule scenario]
2026-04-19 01:01:43.737264 | [rockylinux-9] Waiting on logger
2026-04-19 01:01:51.881742 | [rockylinux-9] Waiting on logger
2026-04-19 01:02:02.058084 | [rockylinux-9] Waiting on logger
2026-04-19 01:02:12.229834 | [rockylinux-9] Waiting on logger
2026-04-19 01:02:21.387305 | [rockylinux-9] Waiting on logger
2026-04-19 01:02:31.845942 | [rockylinux-9] Waiting on logger
2026-04-19 01:02:41.574243 | [rockylinux-9] Waiting on logger
2026-04-19 01:02:51.750092 | [rockylinux-9] Waiting on logger
2026-04-19 01:03:01.926087 | [rockylinux-9] Waiting on logger
2026-04-19 01:03:12.102103 | [rockylinux-9] Waiting on logger
2026-04-19 01:03:22.278092 | [rockylinux-9] Waiting on logger
2026-04-19 01:03:31.430099 | [rockylinux-9] Waiting on logger
2026-04-19 01:03:41.605906 | [rockylinux-9] Waiting on logger
2026-04-19 01:03:51.785764 | [rockylinux-9] Waiting on logger
2026-04-19 01:04:01.958140 | [rockylinux-9] Waiting on logger
2026-04-19 01:04:12.133963 | [rockylinux-9] Waiting on logger
2026-04-19 01:04:21.291306 | [rockylinux-9] Waiting on logger
2026-04-19 01:04:31.461856 | [rockylinux-9] Waiting on logger
2026-04-19 01:04:41.637791 | [rockylinux-9] Waiting on logger
2026-04-19 01:04:42.764746 | rockylinux-9 | ok: Runtime: 0:02:57.521024
2026-04-19 01:04:42.771605 | 
2026-04-19 01:04:42.771720 | PLAY RECAP
2026-04-19 01:04:42.771814 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-19 01:04:42.771866 | 
2026-04-19 01:04:42.882551 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-19 01:04:42.884140 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-19 01:04:43.441624 | 
2026-04-19 01:04:43.441748 | PLAY [all]
2026-04-19 01:04:43.452288 | 
2026-04-19 01:04:43.452377 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-19 01:04:43.497508 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:04:43.506960 | 
2026-04-19 01:04:43.507070 | TASK [fetch-output : Set log path for single node]
2026-04-19 01:04:43.548566 | rockylinux-9 | ok
2026-04-19 01:04:43.554206 | 
2026-04-19 01:04:43.554293 | LOOP [fetch-output : Ensure local output dirs]
2026-04-19 01:04:43.921360 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/work/logs"
2026-04-19 01:04:44.122389 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/work/artifacts"
2026-04-19 01:04:44.338531 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/16c52cf8219b41bab63c1c83b06f6c8d/work/docs"
2026-04-19 01:04:44.354012 | 
2026-04-19 01:04:44.354250 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-19 01:04:45.059715 | rockylinux-9 | changed: .d..t...... ./
2026-04-19 01:04:45.059935 | rockylinux-9 | changed: All items complete
2026-04-19 01:04:45.059963 | 
2026-04-19 01:04:45.948022 | rockylinux-9 | changed: .d..t...... ./
2026-04-19 01:04:46.473424 | rockylinux-9 | changed: .d..t...... ./
2026-04-19 01:04:46.491645 | 
2026-04-19 01:04:46.491796 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-19 01:04:46.914728 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007473
2026-04-19 01:04:47.136907 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007356
2026-04-19 01:04:47.151008 | 
2026-04-19 01:04:47.151189 | PLAY [all]
2026-04-19 01:04:47.157599 | 
2026-04-19 01:04:47.157668 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-19 01:04:47.607611 | rockylinux-9 | changed
2026-04-19 01:04:47.616686 | 
2026-04-19 01:04:47.616732 | PLAY RECAP
2026-04-19 01:04:47.616773 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-19 01:04:47.616795 | 
2026-04-19 01:04:47.713220 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-19 01:04:47.715397 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-19 01:04:48.256634 | 
2026-04-19 01:04:48.256767 | PLAY [localhost]
2026-04-19 01:04:48.266864 | 
2026-04-19 01:04:48.266961 | TASK [Generate Zuul manifest]
2026-04-19 01:04:48.288586 | localhost | ok
2026-04-19 01:04:48.312180 | 
2026-04-19 01:04:48.312360 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-19 01:04:48.616526 | localhost | changed
2026-04-19 01:04:48.628716 | 
2026-04-19 01:04:48.628794 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-19 01:04:48.658711 | localhost | ok
2026-04-19 01:04:48.667621 | 
2026-04-19 01:04:48.667706 | TASK [Upload logs]
2026-04-19 01:04:48.687211 | localhost | ok
2026-04-19 01:04:48.747165 | 
2026-04-19 01:04:48.747294 | TASK [Set zuul-log-path fact]
2026-04-19 01:04:48.767222 | localhost | ok
2026-04-19 01:04:48.779203 | 
2026-04-19 01:04:48.779270 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-19 01:04:48.810716 | localhost | ok
2026-04-19 01:04:48.819068 | 
2026-04-19 01:04:48.819134 | TASK [upload-logs : Create log directories]
2026-04-19 01:04:49.216202 | localhost | changed
2026-04-19 01:04:49.221573 | 
2026-04-19 01:04:49.221637 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-19 01:04:49.597593 | localhost -> localhost | ok: Runtime: 0:00:00.005222
2026-04-19 01:04:49.603998 | 
2026-04-19 01:04:49.604087 | TASK [upload-logs : Upload logs to log server]
2026-04-19 01:04:50.049493 | localhost | Output suppressed because no_log was given
2026-04-19 01:04:50.054244 | 
2026-04-19 01:04:50.054330 | LOOP [upload-logs : Compress console log and json output]
2026-04-19 01:04:50.101525 | localhost | skipping: Conditional result was False
2026-04-19 01:04:50.107420 | localhost | skipping: Conditional result was False
2026-04-19 01:04:50.121733 | 
2026-04-19 01:04:50.121952 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-19 01:04:50.167726 | localhost | skipping: Conditional result was False
2026-04-19 01:04:50.168204 | 
2026-04-19 01:04:50.170640 | localhost | skipping: Conditional result was False
2026-04-19 01:04:50.188018 | 
2026-04-19 01:04:50.188150 | LOOP [upload-logs : Upload console log and json output]