2026-07-10 00:16:54.330352 | Job console starting 2026-07-10 00:16:54.344031 | Updating git repos 2026-07-10 00:16:54.367858 | Cloning repos into workspace 2026-07-10 00:16:56.225884 | Restoring repo states 2026-07-10 00:16:56.239016 | Merging changes 2026-07-10 00:16:56.636908 | Checking out repos 2026-07-10 00:16:56.786013 | Preparing playbooks 2026-07-10 00:17:01.515615 | Running Ansible setup 2026-07-10 00:17:05.221832 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-10 00:17:05.857314 | 2026-07-10 00:17:06.342335 | PLAY [localhost] 2026-07-10 00:17:06.355527 | 2026-07-10 00:17:06.355633 | TASK [Gathering Facts] 2026-07-10 00:17:07.293262 | localhost | ok 2026-07-10 00:17:07.302164 | 2026-07-10 00:17:07.302242 | TASK [Setup log path fact] 2026-07-10 00:17:07.321101 | localhost | ok 2026-07-10 00:17:07.331031 | 2026-07-10 00:17:07.331094 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-10 00:17:07.375414 | localhost | ok 2026-07-10 00:17:07.386384 | 2026-07-10 00:17:07.386462 | TASK [emit-job-header : Print job information] 2026-07-10 00:17:07.439431 | # Job Information 2026-07-10 00:17:07.439597 | Ansible Version: 2.16.18 2026-07-10 00:17:07.439641 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-07-10 00:17:07.439672 | Pipeline: check 2026-07-10 00:17:07.439700 | Executor: 2d72f0692154 2026-07-10 00:17:07.439728 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/138 2026-07-10 00:17:07.439759 | Event ID: 61a9ca30-7bf4-11f1-9ca8-ec96cd71e004 2026-07-10 00:17:07.443924 | 2026-07-10 00:17:07.448498 | LOOP [emit-job-header : Print node information] 2026-07-10 00:17:07.558236 | localhost | ok: 2026-07-10 00:17:07.558588 | localhost | # Node Information 2026-07-10 00:17:07.558634 | localhost | Inventory Hostname: ubuntu-focal 2026-07-10 00:17:07.558668 | localhost | Hostname: np0000184870 2026-07-10 00:17:07.558699 | localhost | Username: zuul 2026-07-10 00:17:07.558733 | localhost | Distro: Ubuntu 20.04 2026-07-10 00:17:07.558763 | localhost | Provider: yul1 2026-07-10 00:17:07.558792 | localhost | Region: ca-ymq-1 2026-07-10 00:17:07.558821 | localhost | Label: ubuntu-focal 2026-07-10 00:17:07.558849 | localhost | Product Name: OpenStack Nova 2026-07-10 00:17:07.558877 | localhost | Interface IP: 199.204.45.177 2026-07-10 00:17:07.585007 | 2026-07-10 00:17:07.585674 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-10 00:17:08.028927 | localhost -> localhost | changed 2026-07-10 00:17:08.037790 | 2026-07-10 00:17:08.037888 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-10 00:17:08.945263 | localhost -> localhost | changed 2026-07-10 00:17:08.952517 | 2026-07-10 00:17:08.952616 | PLAY [all] 2026-07-10 00:17:08.961401 | 2026-07-10 00:17:08.961526 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-10 00:17:09.189303 | ubuntu-focal -> localhost | ok 2026-07-10 00:17:09.195267 | 2026-07-10 00:17:09.195339 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-10 00:17:09.228728 | ubuntu-focal | ok 2026-07-10 00:17:09.244584 | ubuntu-focal | included: /var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-10 00:17:09.250085 | 2026-07-10 00:17:09.250149 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-10 00:17:10.843762 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-07-10 00:17:10.843967 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/work/0e15fad995a8493cab9c3dcb16885678_id_rsa 2026-07-10 00:17:10.844067 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/work/0e15fad995a8493cab9c3dcb16885678_id_rsa.pub 2026-07-10 00:17:10.844097 | ubuntu-focal -> localhost | The key fingerprint is: 2026-07-10 00:17:10.844122 | ubuntu-focal -> localhost | SHA256:q95hSYUZZOb9i+wS2JrR+Z6flwrZd5z3mHmgObRmFuI zuul-build-sshkey 2026-07-10 00:17:10.844161 | ubuntu-focal -> localhost | The key's randomart image is: 2026-07-10 00:17:10.844184 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-07-10 00:17:10.844216 | ubuntu-focal -> localhost | | .= | 2026-07-10 00:17:10.844240 | ubuntu-focal -> localhost | | + = | 2026-07-10 00:17:10.844263 | ubuntu-focal -> localhost | | + o | 2026-07-10 00:17:10.844285 | ubuntu-focal -> localhost | | . . | 2026-07-10 00:17:10.844307 | ubuntu-focal -> localhost | | +S. . | 2026-07-10 00:17:10.844328 | ubuntu-focal -> localhost | | o.=+.+o....| 2026-07-10 00:17:10.844348 | ubuntu-focal -> localhost | | +=+=oo=.++| 2026-07-10 00:17:10.844370 | ubuntu-focal -> localhost | | o+.oEoOoo=+| 2026-07-10 00:17:10.844393 | ubuntu-focal -> localhost | | .o .o+=+++..| 2026-07-10 00:17:10.844414 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-07-10 00:17:10.844493 | ubuntu-focal -> localhost | ok: Runtime: 0:00:01.073658 2026-07-10 00:17:10.852887 | 2026-07-10 00:17:10.853053 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-10 00:17:10.887650 | ubuntu-focal | ok 2026-07-10 00:17:10.898299 | ubuntu-focal | included: /var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-10 00:17:10.928325 | 2026-07-10 00:17:10.928471 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-10 00:17:10.954099 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:17:10.963094 | 2026-07-10 00:17:10.963274 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-10 00:17:11.434379 | ubuntu-focal | changed 2026-07-10 00:17:11.441519 | 2026-07-10 00:17:11.441597 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-10 00:17:11.622216 | ubuntu-focal | ok 2026-07-10 00:17:11.628319 | 2026-07-10 00:17:11.628399 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-10 00:17:12.077868 | ubuntu-focal | changed 2026-07-10 00:17:12.083806 | 2026-07-10 00:17:12.083872 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-10 00:17:12.537936 | ubuntu-focal | changed 2026-07-10 00:17:12.543502 | 2026-07-10 00:17:12.543568 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-10 00:17:12.570381 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:17:12.580201 | 2026-07-10 00:17:12.580338 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-10 00:17:12.930256 | ubuntu-focal -> localhost | changed 2026-07-10 00:17:12.941820 | 2026-07-10 00:17:12.941906 | TASK [add-build-sshkey : Add back temp key] 2026-07-10 00:17:13.229860 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/work/0e15fad995a8493cab9c3dcb16885678_id_rsa (zuul-build-sshkey) 2026-07-10 00:17:13.230062 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.013917 2026-07-10 00:17:13.235049 | 2026-07-10 00:17:13.235111 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-10 00:17:13.561880 | ubuntu-focal | ok 2026-07-10 00:17:13.566838 | 2026-07-10 00:17:13.566931 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-10 00:17:13.604419 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:17:13.615902 | 2026-07-10 00:17:13.616034 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-07-10 00:17:13.955279 | ubuntu-focal | ok 2026-07-10 00:17:13.961796 | 2026-07-10 00:17:13.961956 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-07-10 00:17:14.761418 | ubuntu-focal | Output suppressed because no_log was given 2026-07-10 00:17:14.773058 | 2026-07-10 00:17:14.773126 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-07-10 00:17:15.005160 | ubuntu-focal | ok: "logs" 2026-07-10 00:17:15.005621 | ubuntu-focal | ok: All items complete 2026-07-10 00:17:15.005691 | 2026-07-10 00:17:15.084100 | ubuntu-focal | ok: "artifacts" 2026-07-10 00:17:15.289476 | ubuntu-focal | ok: "docs" 2026-07-10 00:17:15.308178 | 2026-07-10 00:17:15.308314 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-07-10 00:17:15.499035 | ubuntu-focal | changed: "logs" 2026-07-10 00:17:15.640186 | ubuntu-focal | changed: "artifacts" 2026-07-10 00:17:15.783015 | ubuntu-focal | changed: "docs" 2026-07-10 00:17:15.802203 | 2026-07-10 00:17:15.802337 | PLAY RECAP 2026-07-10 00:17:15.802411 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-10 00:17:15.802461 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-07-10 00:17:15.802501 | 2026-07-10 00:17:16.001735 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-10 00:17:16.003285 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-10 00:17:16.666751 | 2026-07-10 00:17:16.666941 | PLAY [all] 2026-07-10 00:17:16.679880 | 2026-07-10 00:17:16.680058 | TASK [setup-uv : Extract archive] 2026-07-10 00:17:18.810087 | ubuntu-focal | changed 2026-07-10 00:17:18.818733 | 2026-07-10 00:17:18.818835 | TASK [setup-uv : Print version] 2026-07-10 00:17:19.195803 | ubuntu-focal | uv 0.8.13 2026-07-10 00:17:19.351586 | ubuntu-focal | ok: Runtime: 0:00:00.010344 2026-07-10 00:17:19.361340 | 2026-07-10 00:17:19.361409 | TASK [Install Ansible collection siblings] 2026-07-10 00:17:19.725363 | ubuntu-focal | ok: 2026-07-10 00:17:19.725542 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-07-10 00:17:19.725571 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-07-10 00:17:19.727297 | 2026-07-10 00:17:19.727344 | PLAY RECAP 2026-07-10 00:17:19.727391 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-10 00:17:19.727413 | 2026-07-10 00:17:19.873459 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-10 00:17:19.874764 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-10 00:17:20.499633 | 2026-07-10 00:17:20.499788 | PLAY [all] 2026-07-10 00:17:20.511737 | 2026-07-10 00:17:20.511860 | LOOP [Check inventory directories] 2026-07-10 00:17:20.821489 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-07-10 00:17:20.968570 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/forget-package/inventory" 2026-07-10 00:17:21.107746 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/inventory" 2026-07-10 00:17:21.122660 | 2026-07-10 00:17:21.122880 | LOOP [Copy inventory file for Zuul] 2026-07-10 00:17:21.877040 | ubuntu-focal | changed: 2026-07-10 00:17:21.877260 | ubuntu-focal | { 2026-07-10 00:17:21.877303 | ubuntu-focal | "ansible_loop_var": "item", 2026-07-10 00:17:21.877336 | ubuntu-focal | "changed": false, 2026-07-10 00:17:21.877365 | ubuntu-focal | "failed": false, 2026-07-10 00:17:21.877403 | ubuntu-focal | "invocation": { 2026-07-10 00:17:21.877433 | ubuntu-focal | "module_args": { 2026-07-10 00:17:21.877462 | ubuntu-focal | "checksum_algorithm": "sha1", 2026-07-10 00:17:21.877488 | ubuntu-focal | "follow": true, 2026-07-10 00:17:21.877515 | ubuntu-focal | "get_attributes": true, 2026-07-10 00:17:21.877544 | ubuntu-focal | "get_checksum": true, 2026-07-10 00:17:21.877571 | ubuntu-focal | "get_mime": true, 2026-07-10 00:17:21.877609 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-07-10 00:17:21.877656 | ubuntu-focal | } 2026-07-10 00:17:21.877689 | ubuntu-focal | }, 2026-07-10 00:17:21.877717 | ubuntu-focal | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-10 00:17:21.877758 | ubuntu-focal | "stat": { 2026-07-10 00:17:21.877786 | ubuntu-focal | "atime": 1783642634.6580813, 2026-07-10 00:17:21.877813 | ubuntu-focal | "attr_flags": "e", 2026-07-10 00:17:21.877840 | ubuntu-focal | "attributes": [ 2026-07-10 00:17:21.877866 | ubuntu-focal | "extents" 2026-07-10 00:17:21.877892 | ubuntu-focal | ], 2026-07-10 00:17:21.877920 | ubuntu-focal | "block_size": 4096, 2026-07-10 00:17:21.877947 | ubuntu-focal | "blocks": 8, 2026-07-10 00:17:21.877973 | ubuntu-focal | "charset": "binary", 2026-07-10 00:17:21.878001 | ubuntu-focal | "ctime": 1783642634.6580813, 2026-07-10 00:17:21.878028 | ubuntu-focal | "dev": 64513, 2026-07-10 00:17:21.878055 | ubuntu-focal | "device_type": 0, 2026-07-10 00:17:21.878082 | ubuntu-focal | "executable": true, 2026-07-10 00:17:21.878108 | ubuntu-focal | "exists": true, 2026-07-10 00:17:21.878134 | ubuntu-focal | "gid": 1000, 2026-07-10 00:17:21.878160 | ubuntu-focal | "gr_name": "zuul", 2026-07-10 00:17:21.878185 | ubuntu-focal | "inode": 1035293, 2026-07-10 00:17:21.878211 | ubuntu-focal | "isblk": false, 2026-07-10 00:17:21.878238 | ubuntu-focal | "ischr": false, 2026-07-10 00:17:21.878264 | ubuntu-focal | "isdir": true, 2026-07-10 00:17:21.878290 | ubuntu-focal | "isfifo": false, 2026-07-10 00:17:21.878315 | ubuntu-focal | "isgid": false, 2026-07-10 00:17:21.878340 | ubuntu-focal | "islnk": false, 2026-07-10 00:17:21.878365 | ubuntu-focal | "isreg": false, 2026-07-10 00:17:21.878390 | ubuntu-focal | "issock": false, 2026-07-10 00:17:21.878414 | ubuntu-focal | "isuid": false, 2026-07-10 00:17:21.878439 | ubuntu-focal | "mimetype": "inode/directory", 2026-07-10 00:17:21.878464 | ubuntu-focal | "mode": "0755", 2026-07-10 00:17:21.878489 | ubuntu-focal | "mtime": 1783642616.610716, 2026-07-10 00:17:21.878514 | ubuntu-focal | "nlink": 11, 2026-07-10 00:17:21.878539 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-10 00:17:21.878564 | ubuntu-focal | "pw_name": "zuul", 2026-07-10 00:17:21.878597 | ubuntu-focal | "readable": true, 2026-07-10 00:17:21.878623 | ubuntu-focal | "rgrp": true, 2026-07-10 00:17:21.878649 | ubuntu-focal | "roth": true, 2026-07-10 00:17:21.878675 | ubuntu-focal | "rusr": true, 2026-07-10 00:17:21.878707 | ubuntu-focal | "size": 4096, 2026-07-10 00:17:21.878749 | ubuntu-focal | "uid": 1000, 2026-07-10 00:17:21.878787 | ubuntu-focal | "version": "1233873435", 2026-07-10 00:17:21.878815 | ubuntu-focal | "wgrp": false, 2026-07-10 00:17:21.878844 | ubuntu-focal | "woth": false, 2026-07-10 00:17:21.879076 | ubuntu-focal | "writeable": true, 2026-07-10 00:17:21.879108 | ubuntu-focal | "wusr": true, 2026-07-10 00:17:21.879134 | ubuntu-focal | "xgrp": true, 2026-07-10 00:17:21.879174 | ubuntu-focal | "xoth": true, 2026-07-10 00:17:21.879201 | ubuntu-focal | "xusr": true 2026-07-10 00:17:21.879249 | ubuntu-focal | } 2026-07-10 00:17:21.879455 | ubuntu-focal | } 2026-07-10 00:17:21.879525 | ubuntu-focal | ok: All items complete 2026-07-10 00:17:21.879560 | 2026-07-10 00:17:21.881129 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:17:21.881742 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:17:21.896433 | 2026-07-10 00:17:21.896513 | LOOP [Switch "ansible_host" to private IP] 2026-07-10 00:17:22.224303 | ubuntu-focal | changed: 1 replacements made 2026-07-10 00:17:22.226121 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:17:22.226875 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:17:22.244253 | 2026-07-10 00:17:22.244388 | TASK [Run Molecule scenario] 2026-07-10 00:17:22.750140 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-07-10 00:17:23.419766 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-07-10 00:17:23.606299 | ubuntu-focal | Using CPython 3.13.7 2026-07-10 00:17:23.606381 | ubuntu-focal | Creating virtual environment at: .venv 2026-07-10 00:17:24.157279 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-07-10 00:17:24.161294 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-07-10 00:17:24.162289 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-07-10 00:17:24.352775 | ubuntu-focal | Downloading cryptography 2026-07-10 00:17:24.377374 | ubuntu-focal | Downloading pygments 2026-07-10 00:17:24.519369 | ubuntu-focal | Downloading ansible-core 2026-07-10 00:17:24.544951 | ubuntu-focal | Installed 36 packages in 24ms 2026-07-10 00:17:25.612791 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-07-10 00:17:25.612881 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-07-10 00:17:26.383257 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-07-10 00:17:26.383339 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-07-10 00:17:26.383362 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-07-10 00:17:26.383446 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-07-10 00:17:40.303591 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-07-10 00:17:40.303758 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-07-10 00:17:40.303949 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-07-10 00:17:40.304058 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-07-10 00:17:40.309180 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-10 00:17:40.309476 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:17:40.314893 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-10 00:17:40.314963 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-10 00:17:40.315020 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-10 00:17:40.327799 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-07-10 00:17:40.823886 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:17:40.823994 | 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-10 00:17:40.824208 | 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-10 00:17:40.824368 | ubuntu-focal | 2026-07-10 00:17:41.162915 | ubuntu-focal | 2026-07-10 00:17:41.163015 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-07-10 00:17:41.224468 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-07-10 00:17:41.235180 | ubuntu-focal | INFO [forget-package > create] Executing 2026-07-10 00:17:41.236605 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:17:41.241374 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-07-10 00:17:41.723951 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:17:41.724090 | 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-10 00:17:41.724290 | 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-10 00:17:41.724456 | ubuntu-focal | 2026-07-10 00:17:42.089483 | ubuntu-focal | 2026-07-10 00:17:42.089637 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-07-10 00:17:42.089812 | ubuntu-focal | 2026-07-10 00:17:42.089994 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:17:42.090167 | ubuntu-focal | Friday 10 July 2026 00:17:42 +0000 (0:00:00.033) 0:00:00.033 *********** 2026-07-10 00:17:43.234731 | 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-10 00:17:43.237657 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:17:43.237829 | ubuntu-focal | 2026-07-10 00:17:43.238021 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-07-10 00:17:43.238158 | ubuntu-focal | Friday 10 July 2026 00:17:43 +0000 (0:00:01.149) 0:00:01.182 *********** 2026-07-10 00:17:58.516149 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-10 00:17:58.516242 | ubuntu-focal | 2026-07-10 00:17:58.516256 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-07-10 00:17:58.516352 | ubuntu-focal | Friday 10 July 2026 00:17:58 +0000 (0:00:15.278) 0:00:16.460 *********** 2026-07-10 00:17:59.178550 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:17:59.178703 | ubuntu-focal | 2026-07-10 00:17:59.178905 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:17:59.179110 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:17:59.179275 | ubuntu-focal | 2026-07-10 00:17:59.179474 | ubuntu-focal | 2026-07-10 00:17:59.179698 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:17:59.179917 | ubuntu-focal | Friday 10 July 2026 00:17:59 +0000 (0:00:00.662) 0:00:17.123 *********** 2026-07-10 00:17:59.180080 | ubuntu-focal | =============================================================================== 2026-07-10 00:17:59.180250 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 15.28s 2026-07-10 00:17:59.180417 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.15s 2026-07-10 00:17:59.180585 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.66s 2026-07-10 00:17:59.258909 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-07-10 00:17:59.270519 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-07-10 00:17:59.741083 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:17:59.741283 | 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-10 00:17:59.741557 | 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-10 00:17:59.741716 | ubuntu-focal | 2026-07-10 00:18:00.041118 | ubuntu-focal | 2026-07-10 00:18:00.041219 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-10 00:18:00.041396 | ubuntu-focal | 2026-07-10 00:18:00.041572 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:18:00.041815 | ubuntu-focal | Friday 10 July 2026 00:18:00 +0000 (0:00:00.009) 0:00:00.009 *********** 2026-07-10 00:18:01.060827 | 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-10 00:18:01.062911 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:18:01.063063 | ubuntu-focal | 2026-07-10 00:18:01.063232 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-10 00:18:01.063401 | ubuntu-focal | Friday 10 July 2026 00:18:01 +0000 (0:00:01.022) 0:00:01.031 *********** 2026-07-10 00:18:01.421772 | 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-10 00:18:01.424548 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-10 00:18:01.424674 | ubuntu-focal | 2026-07-10 00:18:01.424886 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:18:01.426074 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:18:01.426173 | ubuntu-focal | 2026-07-10 00:18:01.426182 | ubuntu-focal | 2026-07-10 00:18:01.426188 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:18:01.426195 | ubuntu-focal | Friday 10 July 2026 00:18:01 +0000 (0:00:00.362) 0:00:01.393 *********** 2026-07-10 00:18:01.426202 | ubuntu-focal | =============================================================================== 2026-07-10 00:18:01.426208 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.02s 2026-07-10 00:18:01.426214 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.36s 2026-07-10 00:18:01.525708 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-07-10 00:18:01.537541 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-07-10 00:18:02.033918 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:18:02.034208 | 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-10 00:18:02.034395 | 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-10 00:18:02.034551 | ubuntu-focal | 2026-07-10 00:18:02.344325 | ubuntu-focal | 2026-07-10 00:18:02.344539 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-10 00:18:02.344647 | ubuntu-focal | 2026-07-10 00:18:02.344763 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:18:02.344873 | ubuntu-focal | Friday 10 July 2026 00:18:02 +0000 (0:00:00.008) 0:00:00.008 *********** 2026-07-10 00:18:03.367460 | 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-10 00:18:03.370519 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:18:03.370782 | ubuntu-focal | 2026-07-10 00:18:03.371041 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-10 00:18:03.371310 | ubuntu-focal | Friday 10 July 2026 00:18:03 +0000 (0:00:01.026) 0:00:01.034 *********** 2026-07-10 00:18:03.737119 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:18:03.737426 | ubuntu-focal | 2026-07-10 00:18:03.737680 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:18:03.737949 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:18:03.738174 | ubuntu-focal | 2026-07-10 00:18:03.738388 | ubuntu-focal | 2026-07-10 00:18:03.738624 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:18:03.738861 | ubuntu-focal | Friday 10 July 2026 00:18:03 +0000 (0:00:00.366) 0:00:01.401 *********** 2026-07-10 00:18:03.739083 | ubuntu-focal | =============================================================================== 2026-07-10 00:18:03.739313 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.03s 2026-07-10 00:18:03.739544 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.37s 2026-07-10 00:18:03.841141 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-07-10 00:18:03.850506 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-07-10 00:18:03.850985 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:18:03.856545 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-07-10 00:18:04.345714 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:18:04.346001 | 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-10 00:18:04.346235 | 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-10 00:18:04.346399 | ubuntu-focal | 2026-07-10 00:18:04.646865 | ubuntu-focal | 2026-07-10 00:18:04.647138 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-07-10 00:18:04.647302 | ubuntu-focal | 2026-07-10 00:18:04.647474 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:18:04.647645 | ubuntu-focal | Friday 10 July 2026 00:18:04 +0000 (0:00:00.007) 0:00:00.007 *********** 2026-07-10 00:18:05.715542 | 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-10 00:18:05.717366 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:18:05.717615 | ubuntu-focal | 2026-07-10 00:18:05.717857 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-07-10 00:18:05.718095 | ubuntu-focal | Friday 10 July 2026 00:18:05 +0000 (0:00:01.070) 0:00:01.078 *********** 2026-07-10 00:18:07.591283 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:18:07.591535 | ubuntu-focal | 2026-07-10 00:18:07.591699 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-07-10 00:18:07.591895 | ubuntu-focal | Friday 10 July 2026 00:18:07 +0000 (0:00:01.866) 0:00:02.945 *********** 2026-07-10 00:18:09.245925 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:18:09.246265 | ubuntu-focal | 2026-07-10 00:18:09.246601 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-07-10 00:18:09.246987 | ubuntu-focal | Friday 10 July 2026 00:18:09 +0000 (0:00:01.661) 0:00:04.607 *********** 2026-07-10 00:18:09.273939 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-10 00:18:09.274154 | ubuntu-focal | "changed": false, 2026-07-10 00:18:09.274312 | ubuntu-focal | "msg": "All assertions passed" 2026-07-10 00:18:09.274483 | ubuntu-focal | } 2026-07-10 00:18:09.274629 | ubuntu-focal | 2026-07-10 00:18:09.274785 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-07-10 00:18:09.274967 | ubuntu-focal | Friday 10 July 2026 00:18:09 +0000 (0:00:00.025) 0:00:04.632 *********** 2026-07-10 00:18:09.292852 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-10 00:18:09.292883 | ubuntu-focal | "changed": false, 2026-07-10 00:18:09.292893 | ubuntu-focal | "msg": "All assertions passed" 2026-07-10 00:18:09.292903 | ubuntu-focal | } 2026-07-10 00:18:09.292915 | ubuntu-focal | 2026-07-10 00:18:09.292926 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:18:09.293034 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:18:09.293101 | ubuntu-focal | 2026-07-10 00:18:09.293172 | ubuntu-focal | 2026-07-10 00:18:09.293251 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:18:09.293326 | ubuntu-focal | Friday 10 July 2026 00:18:09 +0000 (0:00:00.021) 0:00:04.654 *********** 2026-07-10 00:18:09.293400 | ubuntu-focal | =============================================================================== 2026-07-10 00:18:09.293480 | ubuntu-focal | Populate package facts -------------------------------------------------- 1.87s 2026-07-10 00:18:09.293555 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.66s 2026-07-10 00:18:09.293634 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.07s 2026-07-10 00:18:09.293712 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-07-10 00:18:09.293791 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.02s 2026-07-10 00:18:09.389056 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-07-10 00:18:09.395111 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-10 00:18:09.395402 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:18:09.400371 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-10 00:18:09.400405 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-10 00:18:09.400494 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-10 00:18:09.400788 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-07-10 00:18:09.882233 | ubuntu-focal | ok: Runtime: 0:00:46.937685 2026-07-10 00:18:09.885076 | 2026-07-10 00:18:09.885125 | PLAY RECAP 2026-07-10 00:18:09.885193 | ubuntu-focal | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-10 00:18:09.885248 | 2026-07-10 00:18:10.015505 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-10 00:18:10.018412 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-10 00:18:10.616788 | 2026-07-10 00:18:10.616932 | PLAY [all] 2026-07-10 00:18:10.628277 | 2026-07-10 00:18:10.628380 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-10 00:18:10.673797 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:18:10.684761 | 2026-07-10 00:18:10.684946 | TASK [fetch-output : Set log path for single node] 2026-07-10 00:18:10.729785 | ubuntu-focal | ok 2026-07-10 00:18:10.736356 | 2026-07-10 00:18:10.736450 | LOOP [fetch-output : Ensure local output dirs] 2026-07-10 00:18:11.136712 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/work/logs" 2026-07-10 00:18:11.360571 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/work/artifacts" 2026-07-10 00:18:11.585304 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/0e15fad995a8493cab9c3dcb16885678/work/docs" 2026-07-10 00:18:11.596310 | 2026-07-10 00:18:11.596394 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-10 00:18:12.180083 | ubuntu-focal | ok 2026-07-10 00:18:12.180387 | ubuntu-focal | changed: All items complete 2026-07-10 00:18:12.180430 | 2026-07-10 00:18:12.575541 | ubuntu-focal | changed: .d..t...... ./ 2026-07-10 00:18:12.974111 | ubuntu-focal | changed: .d..t...... ./ 2026-07-10 00:18:12.991791 | 2026-07-10 00:18:12.991934 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-10 00:18:13.575126 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.007509 2026-07-10 00:18:13.833809 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007958 2026-07-10 00:18:13.854067 | 2026-07-10 00:18:13.854228 | PLAY [all] 2026-07-10 00:18:13.860973 | 2026-07-10 00:18:13.861046 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-10 00:18:14.245823 | ubuntu-focal | changed 2026-07-10 00:18:14.265202 | 2026-07-10 00:18:14.265329 | PLAY RECAP 2026-07-10 00:18:14.265380 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-10 00:18:14.265403 | 2026-07-10 00:18:14.398580 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-10 00:18:14.400128 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-10 00:18:15.017165 | 2026-07-10 00:18:15.017312 | PLAY [localhost] 2026-07-10 00:18:15.028680 | 2026-07-10 00:18:15.028789 | TASK [Generate Zuul manifest] 2026-07-10 00:18:15.053499 | localhost | ok 2026-07-10 00:18:15.075589 | 2026-07-10 00:18:15.075807 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-10 00:18:15.488798 | localhost | changed 2026-07-10 00:18:15.503279 | 2026-07-10 00:18:15.503393 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-10 00:18:15.657403 | localhost | ok 2026-07-10 00:18:15.667922 | 2026-07-10 00:18:15.667997 | TASK [Upload logs] 2026-07-10 00:18:15.690585 | localhost | ok 2026-07-10 00:18:15.750741 | 2026-07-10 00:18:15.750859 | TASK [Set zuul-log-path fact] 2026-07-10 00:18:15.769481 | localhost | ok 2026-07-10 00:18:15.780312 | 2026-07-10 00:18:15.780377 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-10 00:18:15.808788 | localhost | ok 2026-07-10 00:18:15.815446 | 2026-07-10 00:18:15.815510 | TASK [upload-logs : Create log directories] 2026-07-10 00:18:17.232942 | localhost | changed 2026-07-10 00:18:17.241522 | 2026-07-10 00:18:17.241625 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-10 00:18:17.656743 | localhost -> localhost | ok: Runtime: 0:00:00.005611 2026-07-10 00:18:17.666354 | 2026-07-10 00:18:17.666451 | TASK [upload-logs : Upload logs to log server] 2026-07-10 00:18:18.109394 | localhost | Output suppressed because no_log was given 2026-07-10 00:18:18.115739 | 2026-07-10 00:18:18.115828 | LOOP [upload-logs : Compress console log and json output] 2026-07-10 00:18:18.161868 | localhost | skipping: Conditional result was False 2026-07-10 00:18:18.169248 | localhost | skipping: Conditional result was False 2026-07-10 00:18:18.185531 | 2026-07-10 00:18:18.185608 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-10 00:18:18.226143 | localhost | skipping: Conditional result was False 2026-07-10 00:18:20.408505 | 2026-07-10 00:18:20.410756 | localhost | skipping: Conditional result was False 2026-07-10 00:18:20.428950 | 2026-07-10 00:18:20.449250 | LOOP [upload-logs : Upload console log and json output]