2026-02-14 00:46:18.022342 | Job console starting 2026-02-14 00:46:18.043667 | Updating git repos 2026-02-14 00:46:18.096586 | Cloning repos into workspace 2026-02-14 00:46:18.256443 | Restoring repo states 2026-02-14 00:46:18.263843 | Merging changes 2026-02-14 00:46:18.706353 | Checking out repos 2026-02-14 00:46:18.748913 | Preparing playbooks 2026-02-14 00:46:20.627530 | Running Ansible setup 2026-02-14 00:46:24.422768 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-14 00:46:25.034593 | 2026-02-14 00:46:25.040359 | PLAY [localhost] 2026-02-14 00:46:25.051374 | 2026-02-14 00:46:25.051504 | TASK [Gathering Facts] 2026-02-14 00:46:26.000059 | localhost | ok 2026-02-14 00:46:26.011688 | 2026-02-14 00:46:26.011816 | TASK [Setup log path fact] 2026-02-14 00:46:26.041970 | localhost | ok 2026-02-14 00:46:26.057118 | 2026-02-14 00:46:26.057220 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-14 00:46:26.087898 | localhost | ok 2026-02-14 00:46:26.112025 | 2026-02-14 00:46:26.112139 | TASK [emit-job-header : Print job information] 2026-02-14 00:46:26.144084 | # Job Information 2026-02-14 00:46:26.303401 | Ansible Version: 2.16.15 2026-02-14 00:46:26.303545 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-02-14 00:46:26.313106 | Pipeline: check 2026-02-14 00:46:26.319742 | Executor: 3a2793d2bd32 2026-02-14 00:46:26.319813 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/92 2026-02-14 00:46:26.319900 | Event ID: 7be9d3a0-093e-11f1-9258-d7bae6f876bb 2026-02-14 00:46:26.324285 | 2026-02-14 00:46:26.324387 | LOOP [emit-job-header : Print node information] 2026-02-14 00:46:26.433471 | localhost | ok: 2026-02-14 00:46:26.433773 | localhost | # Node Information 2026-02-14 00:46:26.433810 | localhost | Inventory Hostname: rockylinux-9 2026-02-14 00:46:26.433833 | localhost | Hostname: np0000155519 2026-02-14 00:46:26.433853 | localhost | Username: zuul 2026-02-14 00:46:26.433876 | localhost | Distro: Rocky 9.7 2026-02-14 00:46:26.433896 | localhost | Provider: yul1 2026-02-14 00:46:26.433915 | localhost | Region: ca-ymq-1 2026-02-14 00:46:26.433934 | localhost | Label: rockylinux-9 2026-02-14 00:46:26.433952 | localhost | Product Name: OpenStack Nova 2026-02-14 00:46:26.433971 | localhost | Interface IP: 162.253.55.212 2026-02-14 00:46:26.451669 | 2026-02-14 00:46:26.451844 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-14 00:46:26.907744 | localhost -> localhost | changed 2026-02-14 00:46:26.918792 | 2026-02-14 00:46:26.918875 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-14 00:46:27.876339 | localhost -> localhost | changed 2026-02-14 00:46:27.882797 | 2026-02-14 00:46:27.882892 | PLAY [all] 2026-02-14 00:46:27.891651 | 2026-02-14 00:46:27.891783 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-14 00:46:28.210878 | rockylinux-9 -> localhost | ok 2026-02-14 00:46:28.216770 | 2026-02-14 00:46:28.216878 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-14 00:46:28.246789 | rockylinux-9 | ok 2026-02-14 00:46:28.261403 | rockylinux-9 | included: /var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-14 00:46:28.268970 | 2026-02-14 00:46:28.269086 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-14 00:46:29.635454 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-02-14 00:46:29.635700 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/work/72ea6a4984e64ae48e4a6a04decd734f_id_rsa 2026-02-14 00:46:29.635773 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/work/72ea6a4984e64ae48e4a6a04decd734f_id_rsa.pub 2026-02-14 00:46:29.635809 | rockylinux-9 -> localhost | The key fingerprint is: 2026-02-14 00:46:29.635834 | rockylinux-9 -> localhost | SHA256:+NylIYgzbKxe8Lci0dqeGIsxKTGRihWHFULcPxiS0cU zuul-build-sshkey 2026-02-14 00:46:29.635866 | rockylinux-9 -> localhost | The key's randomart image is: 2026-02-14 00:46:29.635888 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-02-14 00:46:29.635915 | rockylinux-9 -> localhost | | o=B++. | 2026-02-14 00:46:29.635938 | rockylinux-9 -> localhost | | .=++ E | 2026-02-14 00:46:29.635959 | rockylinux-9 -> localhost | |o .. + | 2026-02-14 00:46:29.635986 | rockylinux-9 -> localhost | |oo o..oo | 2026-02-14 00:46:29.636006 | rockylinux-9 -> localhost | |= ..B o.S . . | 2026-02-14 00:46:29.636027 | rockylinux-9 -> localhost | | +.=.o o o + | 2026-02-14 00:46:29.636047 | rockylinux-9 -> localhost | |= o+o . o o | 2026-02-14 00:46:29.636073 | rockylinux-9 -> localhost | |.=o=oo . | 2026-02-14 00:46:29.636098 | rockylinux-9 -> localhost | |. +oo.. | 2026-02-14 00:46:29.636120 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-02-14 00:46:29.636186 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.789719 2026-02-14 00:46:29.641488 | 2026-02-14 00:46:29.641569 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-14 00:46:29.667934 | rockylinux-9 | ok 2026-02-14 00:46:29.677258 | rockylinux-9 | included: /var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-14 00:46:29.684761 | 2026-02-14 00:46:29.684833 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-14 00:46:29.710212 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:46:29.719862 | 2026-02-14 00:46:29.719983 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-14 00:46:30.260537 | rockylinux-9 | changed 2026-02-14 00:46:30.267089 | 2026-02-14 00:46:30.267178 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-14 00:46:30.528183 | rockylinux-9 | ok 2026-02-14 00:46:30.547469 | 2026-02-14 00:46:30.547555 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-14 00:46:31.399804 | rockylinux-9 | changed 2026-02-14 00:46:31.406275 | 2026-02-14 00:46:31.406363 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-14 00:46:32.256685 | rockylinux-9 | changed 2026-02-14 00:46:32.263593 | 2026-02-14 00:46:32.301481 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-14 00:46:32.334872 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:46:32.343773 | 2026-02-14 00:46:32.343878 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-14 00:46:32.802233 | rockylinux-9 -> localhost | changed 2026-02-14 00:46:32.811495 | 2026-02-14 00:46:32.828112 | TASK [add-build-sshkey : Add back temp key] 2026-02-14 00:46:33.128163 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/work/72ea6a4984e64ae48e4a6a04decd734f_id_rsa (zuul-build-sshkey) 2026-02-14 00:46:33.128416 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.010708 2026-02-14 00:46:33.135486 | 2026-02-14 00:46:33.135581 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-14 00:46:33.573339 | rockylinux-9 | ok 2026-02-14 00:46:33.589024 | 2026-02-14 00:46:33.589242 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-14 00:46:33.615461 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:46:33.669711 | 2026-02-14 00:46:33.669870 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-14 00:46:34.116044 | rockylinux-9 | ok 2026-02-14 00:46:34.121755 | 2026-02-14 00:46:34.121822 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-14 00:46:35.027313 | rockylinux-9 | Output suppressed because no_log was given 2026-02-14 00:46:35.038288 | 2026-02-14 00:46:35.038439 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-14 00:46:35.299373 | rockylinux-9 | ok: "logs" 2026-02-14 00:46:35.299620 | rockylinux-9 | ok: All items complete 2026-02-14 00:46:35.299653 | 2026-02-14 00:46:35.506879 | rockylinux-9 | ok: "artifacts" 2026-02-14 00:46:35.720584 | rockylinux-9 | ok: "docs" 2026-02-14 00:46:35.730940 | 2026-02-14 00:46:35.731042 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-14 00:46:36.024592 | rockylinux-9 | changed: "logs" 2026-02-14 00:46:36.237032 | rockylinux-9 | changed: "artifacts" 2026-02-14 00:46:36.460770 | rockylinux-9 | changed: "docs" 2026-02-14 00:46:36.479502 | 2026-02-14 00:46:36.479638 | PLAY RECAP 2026-02-14 00:46:36.479690 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-14 00:46:36.479724 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-14 00:46:36.479757 | 2026-02-14 00:46:36.648940 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-14 00:46:36.650793 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-14 00:46:37.265595 | 2026-02-14 00:46:37.265766 | PLAY [all] 2026-02-14 00:46:37.278248 | 2026-02-14 00:46:37.278353 | TASK [setup-uv : Extract archive] 2026-02-14 00:46:39.987527 | rockylinux-9 | changed 2026-02-14 00:46:39.994475 | 2026-02-14 00:46:39.994555 | TASK [setup-uv : Print version] 2026-02-14 00:46:40.031627 | [rockylinux-9] Waiting on logger 2026-02-14 00:46:40.546486 | rockylinux-9 | ok: Runtime: 0:00:00.013843 2026-02-14 00:46:40.554591 | 2026-02-14 00:46:40.554650 | PLAY RECAP 2026-02-14 00:46:40.554695 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-14 00:46:40.554719 | 2026-02-14 00:46:40.690741 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-14 00:46:40.692863 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-14 00:46:41.318557 | 2026-02-14 00:46:41.318706 | PLAY [all] 2026-02-14 00:46:41.330975 | 2026-02-14 00:46:41.331059 | TASK [Copy inventory file for Zuul] 2026-02-14 00:46:42.520581 | rockylinux-9 | changed 2026-02-14 00:46:42.526629 | 2026-02-14 00:46:42.526764 | TASK [Switch "ansible_host" to private IP] 2026-02-14 00:46:42.864263 | rockylinux-9 | changed: 1 replacements made 2026-02-14 00:46:42.869994 | 2026-02-14 00:46:42.870140 | TASK [Run Molecule scenario] 2026-02-14 00:46:42.903812 | [rockylinux-9] Waiting on logger 2026-02-14 00:46:51.051121 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:01.222098 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:11.398124 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:21.573931 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:31.750119 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:41.926034 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:51.089986 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:01.269634 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:11.430242 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:21.605833 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:31.782077 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:41.957936 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:51.146984 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:01.317967 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:11.493875 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:21.670347 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:31.846450 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:42.021995 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:51.173909 | [rockylinux-9] Waiting on logger 2026-02-14 00:50:01.350102 | [rockylinux-9] Waiting on logger 2026-02-14 00:50:11.526069 | [rockylinux-9] Waiting on logger 2026-02-14 00:50:21.702098 | [rockylinux-9] Waiting on logger 2026-02-14 00:50:31.878153 | [rockylinux-9] Waiting on logger 2026-02-14 00:50:41.035344 | [rockylinux-9] Waiting on logger 2026-02-14 00:50:51.206081 | [rockylinux-9] Waiting on logger 2026-02-14 00:51:01.381872 | [rockylinux-9] Waiting on logger 2026-02-14 00:51:11.558065 | [rockylinux-9] Waiting on logger 2026-02-14 00:51:21.733994 | [rockylinux-9] Waiting on logger 2026-02-14 00:51:31.909928 | [rockylinux-9] Waiting on logger 2026-02-14 00:51:41.067441 | [rockylinux-9] Waiting on logger 2026-02-14 00:51:46.669587 | rockylinux-9 | ok: Runtime: 0:05:02.561456 2026-02-14 00:51:46.677149 | 2026-02-14 00:51:46.677199 | PLAY RECAP 2026-02-14 00:51:46.677244 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-14 00:51:46.677265 | 2026-02-14 00:51:46.775402 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-14 00:51:46.777961 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-14 00:51:47.355297 | 2026-02-14 00:51:47.355457 | PLAY [all] 2026-02-14 00:51:47.369638 | 2026-02-14 00:51:47.369809 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-14 00:51:47.406172 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:51:47.411618 | 2026-02-14 00:51:47.411699 | TASK [fetch-output : Set log path for single node] 2026-02-14 00:51:47.438280 | rockylinux-9 | ok 2026-02-14 00:51:47.442326 | 2026-02-14 00:51:47.442392 | LOOP [fetch-output : Ensure local output dirs] 2026-02-14 00:51:47.810444 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/work/logs" 2026-02-14 00:51:48.031554 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/work/artifacts" 2026-02-14 00:51:48.230996 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/72ea6a4984e64ae48e4a6a04decd734f/work/docs" 2026-02-14 00:51:48.251285 | 2026-02-14 00:51:48.251395 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-14 00:51:48.931625 | rockylinux-9 | changed: .d..t...... ./ 2026-02-14 00:51:48.931878 | rockylinux-9 | changed: All items complete 2026-02-14 00:51:48.931907 | 2026-02-14 00:51:49.417763 | rockylinux-9 | changed: .d..t...... ./ 2026-02-14 00:51:49.888416 | rockylinux-9 | changed: .d..t...... ./ 2026-02-14 00:51:49.911582 | 2026-02-14 00:51:49.911724 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-14 00:51:50.352358 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008207 2026-02-14 00:51:50.567558 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006654 2026-02-14 00:51:50.588815 | 2026-02-14 00:51:50.589010 | PLAY [all] 2026-02-14 00:51:50.595023 | 2026-02-14 00:51:50.595094 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-14 00:51:51.045108 | rockylinux-9 | changed 2026-02-14 00:51:51.052003 | 2026-02-14 00:51:51.052055 | PLAY RECAP 2026-02-14 00:51:51.052103 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-14 00:51:51.052124 | 2026-02-14 00:51:51.164829 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-14 00:51:51.167109 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-14 00:51:51.735040 | 2026-02-14 00:51:51.735190 | PLAY [localhost] 2026-02-14 00:51:51.745505 | 2026-02-14 00:51:51.745580 | TASK [Generate Zuul manifest] 2026-02-14 00:51:51.764645 | localhost | ok 2026-02-14 00:51:51.778474 | 2026-02-14 00:51:51.778551 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-14 00:51:52.140424 | localhost | changed 2026-02-14 00:51:52.150682 | 2026-02-14 00:51:52.150752 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-14 00:51:52.183795 | localhost | ok 2026-02-14 00:51:52.192522 | 2026-02-14 00:51:52.192584 | TASK [Upload logs] 2026-02-14 00:51:52.215428 | localhost | ok 2026-02-14 00:51:52.271697 | 2026-02-14 00:51:52.271823 | TASK [Set zuul-log-path fact] 2026-02-14 00:51:52.293288 | localhost | ok 2026-02-14 00:51:52.308313 | 2026-02-14 00:51:52.308381 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-14 00:51:52.338484 | localhost | ok 2026-02-14 00:51:52.346509 | 2026-02-14 00:51:52.346569 | TASK [upload-logs : Create log directories] 2026-02-14 00:51:52.761747 | localhost | changed 2026-02-14 00:51:52.768608 | 2026-02-14 00:51:52.768785 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-14 00:51:53.166660 | localhost -> localhost | ok: Runtime: 0:00:00.005541 2026-02-14 00:51:53.173387 | 2026-02-14 00:51:53.173477 | TASK [upload-logs : Upload logs to log server] 2026-02-14 00:51:53.628810 | localhost | Output suppressed because no_log was given 2026-02-14 00:51:53.634105 | 2026-02-14 00:51:53.634187 | LOOP [upload-logs : Compress console log and json output] 2026-02-14 00:51:53.679987 | localhost | skipping: Conditional result was False 2026-02-14 00:51:53.686103 | localhost | skipping: Conditional result was False 2026-02-14 00:51:53.698253 | 2026-02-14 00:51:53.698453 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-14 00:51:53.739755 | localhost | skipping: Conditional result was False 2026-02-14 00:51:53.740169 | 2026-02-14 00:51:53.742825 | localhost | skipping: Conditional result was False 2026-02-14 00:51:53.753207 | 2026-02-14 00:51:53.753482 | LOOP [upload-logs : Upload console log and json output]