2026-07-15 00:48:48.851765 | Job console starting 2026-07-15 00:48:48.861196 | Updating git repos 2026-07-15 00:48:48.883667 | Cloning repos into workspace 2026-07-15 00:48:53.818080 | Restoring repo states 2026-07-15 00:48:53.827123 | Merging changes 2026-07-15 00:48:55.735922 | Checking out repos 2026-07-15 00:48:57.508108 | Preparing playbooks 2026-07-15 00:49:04.392735 | Running Ansible setup 2026-07-15 00:49:09.643930 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-15 00:49:10.494085 | 2026-07-15 00:49:10.494240 | PLAY [localhost] 2026-07-15 00:49:10.503792 | 2026-07-15 00:49:10.503884 | TASK [Gathering Facts] 2026-07-15 00:49:11.524488 | localhost | ok 2026-07-15 00:49:11.536410 | 2026-07-15 00:49:11.536582 | TASK [Setup log path fact] 2026-07-15 00:49:11.559170 | localhost | ok 2026-07-15 00:49:11.574271 | 2026-07-15 00:49:11.574460 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-15 00:49:11.618422 | localhost | ok 2026-07-15 00:49:11.630968 | 2026-07-15 00:49:11.631084 | TASK [emit-job-header : Print job information] 2026-07-15 00:49:11.672809 | # Job Information 2026-07-15 00:49:11.673022 | Ansible Version: 2.16.18 2026-07-15 00:49:11.673079 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-07-15 00:49:11.673105 | Pipeline: check 2026-07-15 00:49:11.673147 | Executor: 2d72f0692154 2026-07-15 00:49:11.673172 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/139 2026-07-15 00:49:11.673195 | Event ID: 4ca5b590-7fe6-11f1-8348-b50f7f188349 2026-07-15 00:49:11.676633 | 2026-07-15 00:49:11.676700 | LOOP [emit-job-header : Print node information] 2026-07-15 00:49:12.169651 | localhost | ok: 2026-07-15 00:49:12.170248 | localhost | # Node Information 2026-07-15 00:49:12.170297 | localhost | Inventory Hostname: ubuntu-focal 2026-07-15 00:49:12.170330 | localhost | Hostname: np0000185363 2026-07-15 00:49:12.170359 | localhost | Username: zuul 2026-07-15 00:49:12.170393 | localhost | Distro: Ubuntu 20.04 2026-07-15 00:49:12.170423 | localhost | Provider: yul1 2026-07-15 00:49:12.170450 | localhost | Region: ca-ymq-1 2026-07-15 00:49:12.170477 | localhost | Label: ubuntu-focal 2026-07-15 00:49:12.170503 | localhost | Product Name: OpenStack Nova 2026-07-15 00:49:12.170529 | localhost | Interface IP: 199.19.213.231 2026-07-15 00:49:12.186639 | 2026-07-15 00:49:12.186807 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-15 00:49:12.565390 | localhost -> localhost | changed 2026-07-15 00:49:12.571275 | 2026-07-15 00:49:12.571359 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-15 00:49:13.973657 | localhost -> localhost | changed 2026-07-15 00:49:13.983241 | 2026-07-15 00:49:13.983666 | PLAY [all] 2026-07-15 00:49:13.991220 | 2026-07-15 00:49:13.991287 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-15 00:49:14.218078 | ubuntu-focal -> localhost | ok 2026-07-15 00:49:14.227922 | 2026-07-15 00:49:14.228009 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-15 00:49:14.262615 | ubuntu-focal | ok 2026-07-15 00:49:14.280889 | ubuntu-focal | included: /var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-15 00:49:14.286443 | 2026-07-15 00:49:14.286501 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-15 00:49:14.936570 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-07-15 00:49:14.936755 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/work/75048ee599b4448caa0ef397e7e59aea_id_rsa 2026-07-15 00:49:14.936797 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/work/75048ee599b4448caa0ef397e7e59aea_id_rsa.pub 2026-07-15 00:49:14.936828 | ubuntu-focal -> localhost | The key fingerprint is: 2026-07-15 00:49:14.936858 | ubuntu-focal -> localhost | SHA256:Xzt0Bc33mVe7SeGNDvKsfHfAW9qBMr8vGyDAOj3hSOY zuul-build-sshkey 2026-07-15 00:49:14.936898 | ubuntu-focal -> localhost | The key's randomart image is: 2026-07-15 00:49:14.936927 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-07-15 00:49:14.936987 | ubuntu-focal -> localhost | | .o | 2026-07-15 00:49:14.937023 | ubuntu-focal -> localhost | | . o=| 2026-07-15 00:49:14.937052 | ubuntu-focal -> localhost | | o + ..@| 2026-07-15 00:49:14.937079 | ubuntu-focal -> localhost | | + = o . . .B+| 2026-07-15 00:49:14.937106 | ubuntu-focal -> localhost | | E +S. =o++ +| 2026-07-15 00:49:14.937133 | ubuntu-focal -> localhost | | . ...=++++.| 2026-07-15 00:49:14.937160 | ubuntu-focal -> localhost | | ...*. *.| 2026-07-15 00:49:14.937187 | ubuntu-focal -> localhost | | o .+= o| 2026-07-15 00:49:14.937217 | ubuntu-focal -> localhost | | . +=o | 2026-07-15 00:49:14.937243 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-07-15 00:49:14.937309 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.255210 2026-07-15 00:49:14.944052 | 2026-07-15 00:49:14.944150 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-15 00:49:14.976381 | ubuntu-focal | ok 2026-07-15 00:49:14.986990 | ubuntu-focal | included: /var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-15 00:49:14.994866 | 2026-07-15 00:49:14.994927 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-15 00:49:15.018928 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:49:15.027627 | 2026-07-15 00:49:15.027690 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-15 00:49:15.465724 | ubuntu-focal | changed 2026-07-15 00:49:15.470793 | 2026-07-15 00:49:15.470858 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-15 00:49:15.638259 | ubuntu-focal | ok 2026-07-15 00:49:15.645215 | 2026-07-15 00:49:15.645308 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-15 00:49:16.082366 | ubuntu-focal | changed 2026-07-15 00:49:16.249506 | 2026-07-15 00:49:16.249645 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-15 00:49:16.696499 | ubuntu-focal | changed 2026-07-15 00:49:16.716295 | 2026-07-15 00:49:16.716408 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-15 00:49:16.740606 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:49:16.750202 | 2026-07-15 00:49:16.750294 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-15 00:49:17.102985 | ubuntu-focal -> localhost | changed 2026-07-15 00:49:17.120488 | 2026-07-15 00:49:17.120602 | TASK [add-build-sshkey : Add back temp key] 2026-07-15 00:49:17.401684 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/work/75048ee599b4448caa0ef397e7e59aea_id_rsa (zuul-build-sshkey) 2026-07-15 00:49:17.401891 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.014621 2026-07-15 00:49:17.408006 | 2026-07-15 00:49:17.408075 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-15 00:49:17.795166 | ubuntu-focal | ok 2026-07-15 00:49:17.800134 | 2026-07-15 00:49:17.800263 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-15 00:49:17.824747 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:49:17.844024 | 2026-07-15 00:49:17.844127 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-07-15 00:49:18.406811 | ubuntu-focal | ok 2026-07-15 00:49:18.855959 | 2026-07-15 00:49:18.856115 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-07-15 00:49:19.703430 | ubuntu-focal | Output suppressed because no_log was given 2026-07-15 00:49:19.713778 | 2026-07-15 00:49:19.713859 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-07-15 00:49:19.955158 | ubuntu-focal | ok: "logs" 2026-07-15 00:49:19.955445 | ubuntu-focal | ok: All items complete 2026-07-15 00:49:19.955482 | 2026-07-15 00:49:20.029169 | ubuntu-focal | ok: "artifacts" 2026-07-15 00:49:20.200349 | ubuntu-focal | ok: "docs" 2026-07-15 00:49:20.209978 | 2026-07-15 00:49:20.210156 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-07-15 00:49:20.389089 | ubuntu-focal | changed: "logs" 2026-07-15 00:49:20.522161 | ubuntu-focal | changed: "artifacts" 2026-07-15 00:49:20.670740 | ubuntu-focal | changed: "docs" 2026-07-15 00:49:20.684508 | 2026-07-15 00:49:20.684652 | PLAY RECAP 2026-07-15 00:49:20.684707 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-15 00:49:20.684745 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-07-15 00:49:20.684769 | 2026-07-15 00:49:20.860627 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-15 00:49:20.861838 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-15 00:49:22.276592 | 2026-07-15 00:49:22.276718 | PLAY [all] 2026-07-15 00:49:22.288104 | 2026-07-15 00:49:22.288206 | TASK [setup-uv : Extract archive] 2026-07-15 00:49:24.428771 | ubuntu-focal | changed 2026-07-15 00:49:24.434638 | 2026-07-15 00:49:24.434716 | TASK [setup-uv : Print version] 2026-07-15 00:49:24.789137 | ubuntu-focal | uv 0.8.13 2026-07-15 00:49:25.116011 | ubuntu-focal | ok: Runtime: 0:00:00.008348 2026-07-15 00:49:25.127021 | 2026-07-15 00:49:25.127101 | TASK [Install Ansible collection siblings] 2026-07-15 00:49:25.495347 | ubuntu-focal | ok: 2026-07-15 00:49:25.601489 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-07-15 00:49:25.601778 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-07-15 00:49:25.605247 | 2026-07-15 00:49:25.605437 | PLAY RECAP 2026-07-15 00:49:25.605523 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-15 00:49:25.605560 | 2026-07-15 00:49:25.787178 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-15 00:49:26.178941 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-15 00:49:26.806608 | 2026-07-15 00:49:26.806730 | PLAY [all] 2026-07-15 00:49:26.817968 | 2026-07-15 00:49:26.818043 | LOOP [Check inventory directories] 2026-07-15 00:49:27.094054 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-07-15 00:49:27.234567 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/forget-package/inventory" 2026-07-15 00:49:27.376031 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/inventory" 2026-07-15 00:49:27.393772 | 2026-07-15 00:49:27.393975 | LOOP [Copy inventory file for Zuul] 2026-07-15 00:49:28.100811 | ubuntu-focal | changed: 2026-07-15 00:49:28.101561 | ubuntu-focal | { 2026-07-15 00:49:28.101619 | ubuntu-focal | "ansible_loop_var": "item", 2026-07-15 00:49:28.101655 | ubuntu-focal | "changed": false, 2026-07-15 00:49:28.101684 | ubuntu-focal | "failed": false, 2026-07-15 00:49:28.101717 | ubuntu-focal | "invocation": { 2026-07-15 00:49:28.101746 | ubuntu-focal | "module_args": { 2026-07-15 00:49:28.101772 | ubuntu-focal | "checksum_algorithm": "sha1", 2026-07-15 00:49:28.101799 | ubuntu-focal | "follow": true, 2026-07-15 00:49:28.101829 | ubuntu-focal | "get_attributes": true, 2026-07-15 00:49:28.101859 | ubuntu-focal | "get_checksum": true, 2026-07-15 00:49:28.101887 | ubuntu-focal | "get_mime": true, 2026-07-15 00:49:28.101914 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-07-15 00:49:28.101945 | ubuntu-focal | } 2026-07-15 00:49:28.101973 | ubuntu-focal | }, 2026-07-15 00:49:28.102001 | ubuntu-focal | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-15 00:49:28.102042 | ubuntu-focal | "stat": { 2026-07-15 00:49:28.102071 | ubuntu-focal | "atime": 1784076559.5985851, 2026-07-15 00:49:28.102100 | ubuntu-focal | "attr_flags": "e", 2026-07-15 00:49:28.102127 | ubuntu-focal | "attributes": [ 2026-07-15 00:49:28.102153 | ubuntu-focal | "extents" 2026-07-15 00:49:28.102180 | ubuntu-focal | ], 2026-07-15 00:49:28.102206 | ubuntu-focal | "block_size": 4096, 2026-07-15 00:49:28.102231 | ubuntu-focal | "blocks": 8, 2026-07-15 00:49:28.102256 | ubuntu-focal | "charset": "binary", 2026-07-15 00:49:28.102284 | ubuntu-focal | "ctime": 1784076559.5985851, 2026-07-15 00:49:28.102314 | ubuntu-focal | "dev": 64513, 2026-07-15 00:49:28.102345 | ubuntu-focal | "device_type": 0, 2026-07-15 00:49:28.102377 | ubuntu-focal | "executable": true, 2026-07-15 00:49:28.102405 | ubuntu-focal | "exists": true, 2026-07-15 00:49:28.102435 | ubuntu-focal | "gid": 1000, 2026-07-15 00:49:28.102466 | ubuntu-focal | "gr_name": "zuul", 2026-07-15 00:49:28.102497 | ubuntu-focal | "inode": 1035293, 2026-07-15 00:49:28.102527 | ubuntu-focal | "isblk": false, 2026-07-15 00:49:28.102553 | ubuntu-focal | "ischr": false, 2026-07-15 00:49:28.102580 | ubuntu-focal | "isdir": true, 2026-07-15 00:49:28.102605 | ubuntu-focal | "isfifo": false, 2026-07-15 00:49:28.102631 | ubuntu-focal | "isgid": false, 2026-07-15 00:49:28.102657 | ubuntu-focal | "islnk": false, 2026-07-15 00:49:28.102682 | ubuntu-focal | "isreg": false, 2026-07-15 00:49:28.102709 | ubuntu-focal | "issock": false, 2026-07-15 00:49:28.102734 | ubuntu-focal | "isuid": false, 2026-07-15 00:49:28.102761 | ubuntu-focal | "mimetype": "inode/directory", 2026-07-15 00:49:28.102786 | ubuntu-focal | "mode": "0755", 2026-07-15 00:49:28.102812 | ubuntu-focal | "mtime": 1784076533.9837918, 2026-07-15 00:49:28.102836 | ubuntu-focal | "nlink": 11, 2026-07-15 00:49:28.102863 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-15 00:49:28.102909 | ubuntu-focal | "pw_name": "zuul", 2026-07-15 00:49:28.102944 | ubuntu-focal | "readable": true, 2026-07-15 00:49:28.102971 | ubuntu-focal | "rgrp": true, 2026-07-15 00:49:28.102998 | ubuntu-focal | "roth": true, 2026-07-15 00:49:28.103023 | ubuntu-focal | "rusr": true, 2026-07-15 00:49:28.103049 | ubuntu-focal | "size": 4096, 2026-07-15 00:49:28.103075 | ubuntu-focal | "uid": 1000, 2026-07-15 00:49:28.103100 | ubuntu-focal | "version": "2008363346", 2026-07-15 00:49:28.103126 | ubuntu-focal | "wgrp": false, 2026-07-15 00:49:28.103155 | ubuntu-focal | "woth": false, 2026-07-15 00:49:28.103182 | ubuntu-focal | "writeable": true, 2026-07-15 00:49:28.103208 | ubuntu-focal | "wusr": true, 2026-07-15 00:49:28.103235 | ubuntu-focal | "xgrp": true, 2026-07-15 00:49:28.103261 | ubuntu-focal | "xoth": true, 2026-07-15 00:49:28.103287 | ubuntu-focal | "xusr": true 2026-07-15 00:49:28.103312 | ubuntu-focal | } 2026-07-15 00:49:28.103338 | ubuntu-focal | } 2026-07-15 00:49:28.103390 | ubuntu-focal | ok: All items complete 2026-07-15 00:49:28.103420 | 2026-07-15 00:49:28.105277 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:49:28.108035 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:49:28.129605 | 2026-07-15 00:49:28.129710 | LOOP [Switch "ansible_host" to private IP] 2026-07-15 00:49:28.448914 | ubuntu-focal | changed: 1 replacements made 2026-07-15 00:49:28.450715 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:49:28.451784 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:49:28.467296 | 2026-07-15 00:49:28.467391 | TASK [Run Molecule scenario] 2026-07-15 00:49:29.043946 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-07-15 00:49:29.669376 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-07-15 00:49:29.865845 | ubuntu-focal | Using CPython 3.13.7 2026-07-15 00:49:29.865988 | ubuntu-focal | Creating virtual environment at: .venv 2026-07-15 00:49:30.319409 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-07-15 00:49:30.320542 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-07-15 00:49:30.320737 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-07-15 00:49:30.498484 | ubuntu-focal | Downloading cryptography 2026-07-15 00:49:30.534559 | ubuntu-focal | Downloading pygments 2026-07-15 00:49:30.671418 | ubuntu-focal | Downloading ansible-core 2026-07-15 00:49:30.698264 | ubuntu-focal | Installed 36 packages in 26ms 2026-07-15 00:49:31.800335 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-07-15 00:49:31.800406 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-07-15 00:49:32.631309 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-07-15 00:49:32.631374 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-07-15 00:49:32.631563 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-07-15 00:49:32.631706 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-07-15 00:49:44.381334 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-07-15 00:49:44.381614 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-07-15 00:49:44.381902 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-07-15 00:49:44.382112 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-07-15 00:49:44.389650 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-15 00:49:44.390148 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-15 00:49:44.395172 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-15 00:49:44.395233 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-15 00:49:44.395302 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-15 00:49:44.400194 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-07-15 00:49:44.914703 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-15 00:49:44.914979 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-07-15 00:49:44.915184 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-07-15 00:49:44.915349 | ubuntu-focal | 2026-07-15 00:49:45.281428 | ubuntu-focal | 2026-07-15 00:49:45.281646 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-07-15 00:49:45.339215 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-07-15 00:49:45.350905 | ubuntu-focal | INFO [forget-package > create] Executing 2026-07-15 00:49:45.352243 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-15 00:49:45.357567 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-07-15 00:49:45.856243 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-15 00:49:45.856428 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-07-15 00:49:45.856622 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-07-15 00:49:45.856774 | ubuntu-focal | 2026-07-15 00:49:46.229963 | ubuntu-focal | 2026-07-15 00:49:46.230129 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-07-15 00:49:46.230301 | ubuntu-focal | 2026-07-15 00:49:46.230476 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-15 00:49:46.230652 | ubuntu-focal | Wednesday 15 July 2026 00:49:46 +0000 (0:00:00.032) 0:00:00.032 ******** 2026-07-15 00:49:47.383778 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-07-15 00:49:47.386217 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:49:47.386370 | ubuntu-focal | 2026-07-15 00:49:47.386544 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-07-15 00:49:47.386717 | ubuntu-focal | Wednesday 15 July 2026 00:49:47 +0000 (0:00:01.157) 0:00:01.189 ******** 2026-07-15 00:50:58.091856 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-15 00:50:58.091936 | ubuntu-focal | 2026-07-15 00:50:58.092050 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-07-15 00:50:58.092170 | ubuntu-focal | Wednesday 15 July 2026 00:50:58 +0000 (0:01:10.705) 0:01:11.894 ******** 2026-07-15 00:50:58.745621 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:50:58.745784 | ubuntu-focal | 2026-07-15 00:50:58.746050 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-15 00:50:58.746277 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-15 00:50:58.746453 | ubuntu-focal | 2026-07-15 00:50:58.746663 | ubuntu-focal | 2026-07-15 00:50:58.746854 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-15 00:50:58.747044 | ubuntu-focal | Wednesday 15 July 2026 00:50:58 +0000 (0:00:00.653) 0:01:12.548 ******** 2026-07-15 00:50:58.747212 | ubuntu-focal | =============================================================================== 2026-07-15 00:50:58.747380 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 70.71s 2026-07-15 00:50:58.747557 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.16s 2026-07-15 00:50:58.747729 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.65s 2026-07-15 00:50:58.880599 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-07-15 00:50:58.887958 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-07-15 00:50:59.357131 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-15 00:50:59.357366 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-07-15 00:50:59.357664 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-07-15 00:50:59.357914 | ubuntu-focal | 2026-07-15 00:50:59.648934 | ubuntu-focal | 2026-07-15 00:50:59.649153 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-15 00:50:59.649375 | ubuntu-focal | 2026-07-15 00:50:59.649586 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-15 00:50:59.649734 | ubuntu-focal | Wednesday 15 July 2026 00:50:59 +0000 (0:00:00.007) 0:00:00.007 ******** 2026-07-15 00:51:00.647045 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-07-15 00:51:00.649193 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:51:00.649361 | ubuntu-focal | 2026-07-15 00:51:00.649551 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-15 00:51:00.649738 | ubuntu-focal | Wednesday 15 July 2026 00:51:00 +0000 (0:00:01.000) 0:00:01.008 ******** 2026-07-15 00:51:00.996779 | ubuntu-focal | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-07-15 00:51:01.001539 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-15 00:51:01.001565 | ubuntu-focal | 2026-07-15 00:51:01.001575 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-15 00:51:01.001584 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-15 00:51:01.001593 | ubuntu-focal | 2026-07-15 00:51:01.001601 | ubuntu-focal | 2026-07-15 00:51:01.001609 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-15 00:51:01.001618 | ubuntu-focal | Wednesday 15 July 2026 00:51:00 +0000 (0:00:00.351) 0:00:01.359 ******** 2026-07-15 00:51:01.001631 | ubuntu-focal | =============================================================================== 2026-07-15 00:51:01.001801 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.00s 2026-07-15 00:51:01.002036 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.35s 2026-07-15 00:51:01.093185 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-07-15 00:51:01.102980 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-07-15 00:51:01.562718 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-15 00:51:01.562924 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-07-15 00:51:01.563130 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-07-15 00:51:01.563303 | ubuntu-focal | 2026-07-15 00:51:01.852879 | ubuntu-focal | 2026-07-15 00:51:01.853010 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-15 00:51:01.853213 | ubuntu-focal | 2026-07-15 00:51:01.853344 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-15 00:51:01.853571 | ubuntu-focal | Wednesday 15 July 2026 00:51:01 +0000 (0:00:00.007) 0:00:00.007 ******** 2026-07-15 00:51:02.897557 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-07-15 00:51:02.899876 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:51:02.900041 | ubuntu-focal | 2026-07-15 00:51:02.900225 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-15 00:51:02.900397 | ubuntu-focal | Wednesday 15 July 2026 00:51:02 +0000 (0:00:01.047) 0:00:01.054 ******** 2026-07-15 00:51:03.235291 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:51:03.235451 | ubuntu-focal | 2026-07-15 00:51:03.235642 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-15 00:51:03.235839 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-15 00:51:03.235993 | ubuntu-focal | 2026-07-15 00:51:03.236147 | ubuntu-focal | 2026-07-15 00:51:03.236317 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-15 00:51:03.236493 | ubuntu-focal | Wednesday 15 July 2026 00:51:03 +0000 (0:00:00.335) 0:00:01.390 ******** 2026-07-15 00:51:03.236653 | ubuntu-focal | =============================================================================== 2026-07-15 00:51:03.236822 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.05s 2026-07-15 00:51:03.236994 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.34s 2026-07-15 00:51:03.318851 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-07-15 00:51:03.328668 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-07-15 00:51:03.329062 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-15 00:51:03.335866 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-07-15 00:51:03.802638 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-15 00:51:03.802793 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-07-15 00:51:03.803032 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-07-15 00:51:03.803145 | ubuntu-focal | 2026-07-15 00:51:04.091054 | ubuntu-focal | 2026-07-15 00:51:04.091148 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-07-15 00:51:04.091357 | ubuntu-focal | 2026-07-15 00:51:04.091477 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-15 00:51:04.091691 | ubuntu-focal | Wednesday 15 July 2026 00:51:04 +0000 (0:00:00.007) 0:00:00.007 ******** 2026-07-15 00:51:05.073580 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-07-15 00:51:05.076211 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:51:05.076459 | ubuntu-focal | 2026-07-15 00:51:05.076677 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-07-15 00:51:05.076877 | ubuntu-focal | Wednesday 15 July 2026 00:51:05 +0000 (0:00:00.985) 0:00:00.992 ******** 2026-07-15 00:51:06.935960 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:51:06.936119 | ubuntu-focal | 2026-07-15 00:51:06.936321 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-07-15 00:51:06.936561 | ubuntu-focal | Wednesday 15 July 2026 00:51:06 +0000 (0:00:01.859) 0:00:02.852 ******** 2026-07-15 00:51:08.639160 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-15 00:51:08.639426 | ubuntu-focal | 2026-07-15 00:51:08.639658 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-07-15 00:51:08.639879 | ubuntu-focal | Wednesday 15 July 2026 00:51:08 +0000 (0:00:01.703) 0:00:04.555 ******** 2026-07-15 00:51:08.667297 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-15 00:51:08.667506 | ubuntu-focal | "changed": false, 2026-07-15 00:51:08.667665 | ubuntu-focal | "msg": "All assertions passed" 2026-07-15 00:51:08.667818 | ubuntu-focal | } 2026-07-15 00:51:08.667960 | ubuntu-focal | 2026-07-15 00:51:08.668119 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-07-15 00:51:08.668275 | ubuntu-focal | Wednesday 15 July 2026 00:51:08 +0000 (0:00:00.027) 0:00:04.583 ******** 2026-07-15 00:51:08.694610 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-15 00:51:08.694896 | ubuntu-focal | "changed": false, 2026-07-15 00:51:08.695037 | ubuntu-focal | "msg": "All assertions passed" 2026-07-15 00:51:08.695180 | ubuntu-focal | } 2026-07-15 00:51:08.695318 | ubuntu-focal | 2026-07-15 00:51:08.695457 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-15 00:51:08.695608 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-15 00:51:08.695736 | ubuntu-focal | 2026-07-15 00:51:08.695859 | ubuntu-focal | 2026-07-15 00:51:08.696004 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-15 00:51:08.696144 | ubuntu-focal | Wednesday 15 July 2026 00:51:08 +0000 (0:00:00.023) 0:00:04.606 ******** 2026-07-15 00:51:08.696273 | ubuntu-focal | =============================================================================== 2026-07-15 00:51:08.696410 | ubuntu-focal | Populate package facts -------------------------------------------------- 1.86s 2026-07-15 00:51:08.696549 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.70s 2026-07-15 00:51:08.696685 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 0.99s 2026-07-15 00:51:08.696826 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-07-15 00:51:08.696968 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.02s 2026-07-15 00:51:08.785189 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-07-15 00:51:08.792612 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-15 00:51:08.793060 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-15 00:51:08.798639 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-15 00:51:08.798745 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-15 00:51:08.798831 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-15 00:51:08.799096 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-07-15 00:51:09.179419 | ubuntu-focal | ok: Runtime: 0:01:40.066099 2026-07-15 00:51:09.183048 | 2026-07-15 00:51:09.183115 | PLAY RECAP 2026-07-15 00:51:09.183173 | ubuntu-focal | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-15 00:51:09.183204 | 2026-07-15 00:51:09.313291 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-15 00:51:09.315999 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-15 00:51:09.886540 | 2026-07-15 00:51:09.886680 | PLAY [all] 2026-07-15 00:51:09.898266 | 2026-07-15 00:51:09.898364 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-15 00:51:09.943303 | ubuntu-focal | skipping: Conditional result was False 2026-07-15 00:51:09.954195 | 2026-07-15 00:51:09.954378 | TASK [fetch-output : Set log path for single node] 2026-07-15 00:51:09.998541 | ubuntu-focal | ok 2026-07-15 00:51:10.005259 | 2026-07-15 00:51:10.005359 | LOOP [fetch-output : Ensure local output dirs] 2026-07-15 00:51:10.385416 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/work/logs" 2026-07-15 00:51:10.588905 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/work/artifacts" 2026-07-15 00:51:10.804284 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/75048ee599b4448caa0ef397e7e59aea/work/docs" 2026-07-15 00:51:10.828632 | 2026-07-15 00:51:10.828729 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-15 00:51:11.425351 | ubuntu-focal | ok 2026-07-15 00:51:11.425585 | ubuntu-focal | changed: All items complete 2026-07-15 00:51:11.425620 | 2026-07-15 00:51:11.827237 | ubuntu-focal | changed: .d..t...... ./ 2026-07-15 00:51:12.227705 | ubuntu-focal | changed: .d..t...... ./ 2026-07-15 00:51:12.252761 | 2026-07-15 00:51:12.252887 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-15 00:51:12.655244 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.006803 2026-07-15 00:51:12.870888 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.008356 2026-07-15 00:51:12.891440 | 2026-07-15 00:51:12.891548 | PLAY [all] 2026-07-15 00:51:12.898787 | 2026-07-15 00:51:12.898884 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-15 00:51:13.293536 | ubuntu-focal | changed 2026-07-15 00:51:13.302369 | 2026-07-15 00:51:13.302441 | PLAY RECAP 2026-07-15 00:51:13.302510 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-15 00:51:13.302542 | 2026-07-15 00:51:13.473462 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-15 00:51:13.474614 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-15 00:51:14.057340 | 2026-07-15 00:51:14.057511 | PLAY [localhost] 2026-07-15 00:51:14.069262 | 2026-07-15 00:51:14.069360 | TASK [Generate Zuul manifest] 2026-07-15 00:51:14.092662 | localhost | ok 2026-07-15 00:51:14.107336 | 2026-07-15 00:51:14.107477 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-15 00:51:14.485222 | localhost | changed 2026-07-15 00:51:14.498185 | 2026-07-15 00:51:14.498273 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-15 00:51:14.533123 | localhost | ok 2026-07-15 00:51:14.540784 | 2026-07-15 00:51:14.540858 | TASK [Upload logs] 2026-07-15 00:51:14.559056 | localhost | ok 2026-07-15 00:51:14.618139 | 2026-07-15 00:51:14.618272 | TASK [Set zuul-log-path fact] 2026-07-15 00:51:14.639602 | localhost | ok 2026-07-15 00:51:14.653171 | 2026-07-15 00:51:14.653250 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-15 00:51:14.684669 | localhost | ok 2026-07-15 00:51:14.694178 | 2026-07-15 00:51:14.694267 | TASK [upload-logs : Create log directories] 2026-07-15 00:51:15.080386 | localhost | changed 2026-07-15 00:51:15.088257 | 2026-07-15 00:51:15.088356 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-15 00:51:15.505115 | localhost -> localhost | ok: Runtime: 0:00:00.005111 2026-07-15 00:51:15.511854 | 2026-07-15 00:51:15.511926 | TASK [upload-logs : Upload logs to log server] 2026-07-15 00:51:15.995305 | localhost | Output suppressed because no_log was given 2026-07-15 00:51:16.000215 | 2026-07-15 00:51:16.000303 | LOOP [upload-logs : Compress console log and json output] 2026-07-15 00:51:16.048127 | localhost | skipping: Conditional result was False 2026-07-15 00:51:16.055512 | localhost | skipping: Conditional result was False 2026-07-15 00:51:16.065736 | 2026-07-15 00:51:16.065940 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-15 00:51:16.105802 | localhost | skipping: Conditional result was False 2026-07-15 00:51:16.106117 | 2026-07-15 00:51:16.110166 | localhost | skipping: Conditional result was False 2026-07-15 00:51:16.119970 | 2026-07-15 00:51:16.120148 | LOOP [upload-logs : Upload console log and json output]