2026-08-10 14:49:16.217304 | Job console starting 2026-08-10 14:49:16.235546 | Updating git repos 2026-08-10 14:49:16.259128 | Cloning repos into workspace 2026-08-10 14:49:16.289520 | Restoring repo states 2026-08-10 14:49:16.297173 | Merging changes 2026-08-10 14:49:16.907710 | Checking out repos 2026-08-10 14:49:16.948665 | Preparing playbooks 2026-08-10 14:49:19.229180 | Running Ansible setup 2026-08-10 14:49:22.400285 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-10 14:49:23.020859 | 2026-08-10 14:49:23.021564 | PLAY [localhost] 2026-08-10 14:49:23.029684 | 2026-08-10 14:49:23.029778 | TASK [Gathering Facts] 2026-08-10 14:49:23.860425 | localhost | ok 2026-08-10 14:49:23.871484 | 2026-08-10 14:49:23.871589 | TASK [Setup log path fact] 2026-08-10 14:49:23.890953 | localhost | ok 2026-08-10 14:49:23.903233 | 2026-08-10 14:49:23.903300 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-10 14:49:23.932185 | localhost | ok 2026-08-10 14:49:23.939983 | 2026-08-10 14:49:23.940048 | TASK [emit-job-header : Print job information] 2026-08-10 14:49:23.978608 | # Job Information 2026-08-10 14:49:23.978860 | Ansible Version: 2.16.18 2026-08-10 14:49:23.978931 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-08-10 14:49:23.978982 | Pipeline: check 2026-08-10 14:49:23.979027 | Executor: 2d72f0692154 2026-08-10 14:49:23.979070 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/145 2026-08-10 14:49:23.979119 | Event ID: 78f474e0-94c9-11f1-992e-d2d6e493c26e 2026-08-10 14:49:23.984383 | 2026-08-10 14:49:23.984444 | LOOP [emit-job-header : Print node information] 2026-08-10 14:49:24.076492 | localhost | ok: 2026-08-10 14:49:24.076648 | localhost | # Node Information 2026-08-10 14:49:24.076676 | localhost | Inventory Hostname: ubuntu-focal 2026-08-10 14:49:24.076697 | localhost | Hostname: np0000188352 2026-08-10 14:49:24.076716 | localhost | Username: zuul 2026-08-10 14:49:24.076739 | localhost | Distro: Ubuntu 20.04 2026-08-10 14:49:24.076759 | localhost | Provider: yul1 2026-08-10 14:49:24.076778 | localhost | Region: ca-ymq-1 2026-08-10 14:49:24.076797 | localhost | Label: ubuntu-focal 2026-08-10 14:49:24.076815 | localhost | Product Name: OpenStack Nova 2026-08-10 14:49:24.076833 | localhost | Interface IP: 199.19.213.20 2026-08-10 14:49:24.087683 | 2026-08-10 14:49:24.087828 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-10 14:49:24.479016 | localhost -> localhost | changed 2026-08-10 14:49:24.488032 | 2026-08-10 14:49:24.488133 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-10 14:49:25.325459 | localhost -> localhost | changed 2026-08-10 14:49:25.335785 | 2026-08-10 14:49:25.335843 | PLAY [all] 2026-08-10 14:49:25.343316 | 2026-08-10 14:49:25.343385 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-10 14:49:25.591750 | ubuntu-focal -> localhost | ok 2026-08-10 14:49:25.597872 | 2026-08-10 14:49:25.597945 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-10 14:49:25.632407 | ubuntu-focal | ok 2026-08-10 14:49:25.648200 | ubuntu-focal | included: /var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-10 14:49:25.653631 | 2026-08-10 14:49:25.653691 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-10 14:49:26.679811 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-08-10 14:49:26.680017 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/work/b2bcd7114b0b4b579e86792d3068d397_id_rsa 2026-08-10 14:49:26.680061 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/work/b2bcd7114b0b4b579e86792d3068d397_id_rsa.pub 2026-08-10 14:49:26.680093 | ubuntu-focal -> localhost | The key fingerprint is: 2026-08-10 14:49:26.680124 | ubuntu-focal -> localhost | SHA256:zc7LrFcZLTNtcTBr+OX21nTQ6l8DqoRU/t0n9vzhgwk zuul-build-sshkey 2026-08-10 14:49:26.680166 | ubuntu-focal -> localhost | The key's randomart image is: 2026-08-10 14:49:26.680197 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-08-10 14:49:26.680231 | ubuntu-focal -> localhost | | o. | 2026-08-10 14:49:26.680262 | ubuntu-focal -> localhost | | ..+.| 2026-08-10 14:49:26.680291 | ubuntu-focal -> localhost | | . .o+oo| 2026-08-10 14:49:26.680320 | ubuntu-focal -> localhost | | oo =o+= | 2026-08-10 14:49:26.680348 | ubuntu-focal -> localhost | | .S.o Oo =| 2026-08-10 14:49:26.680376 | ubuntu-focal -> localhost | | . .o.E=.oo+| 2026-08-10 14:49:26.680404 | ubuntu-focal -> localhost | | . .o+..*+*| 2026-08-10 14:49:26.680431 | ubuntu-focal -> localhost | | .oo. +.B=| 2026-08-10 14:49:26.680463 | ubuntu-focal -> localhost | | .++ .B| 2026-08-10 14:49:26.680490 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-08-10 14:49:26.680555 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.628208 2026-08-10 14:49:26.686731 | 2026-08-10 14:49:26.686803 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-10 14:49:26.719416 | ubuntu-focal | ok 2026-08-10 14:49:26.729323 | ubuntu-focal | included: /var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-10 14:49:26.737366 | 2026-08-10 14:49:26.737428 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-10 14:49:26.762713 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:49:26.773384 | 2026-08-10 14:49:26.773451 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-10 14:49:27.181811 | ubuntu-focal | changed 2026-08-10 14:49:27.186848 | 2026-08-10 14:49:27.186915 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-10 14:49:27.352134 | ubuntu-focal | ok 2026-08-10 14:49:27.358931 | 2026-08-10 14:49:27.359006 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-10 14:49:27.787609 | ubuntu-focal | changed 2026-08-10 14:49:27.801184 | 2026-08-10 14:49:27.801295 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-10 14:49:28.231067 | ubuntu-focal | changed 2026-08-10 14:49:28.236219 | 2026-08-10 14:49:28.236291 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-10 14:49:28.261291 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:49:28.271903 | 2026-08-10 14:49:28.271975 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-10 14:49:28.631183 | ubuntu-focal -> localhost | changed 2026-08-10 14:49:28.646529 | 2026-08-10 14:49:28.646610 | TASK [add-build-sshkey : Add back temp key] 2026-08-10 14:49:28.919769 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/work/b2bcd7114b0b4b579e86792d3068d397_id_rsa (zuul-build-sshkey) 2026-08-10 14:49:28.919958 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.011512 2026-08-10 14:49:28.924815 | 2026-08-10 14:49:28.924879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-10 14:49:29.214445 | ubuntu-focal | ok 2026-08-10 14:49:29.219404 | 2026-08-10 14:49:29.219475 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-10 14:49:29.245210 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:49:29.261882 | 2026-08-10 14:49:29.261950 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-10 14:49:29.570315 | ubuntu-focal | ok 2026-08-10 14:49:29.577711 | 2026-08-10 14:49:29.577781 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-10 14:49:30.248701 | ubuntu-focal | Output suppressed because no_log was given 2026-08-10 14:49:30.259734 | 2026-08-10 14:49:30.259803 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-10 14:49:30.433858 | ubuntu-focal | ok: "logs" 2026-08-10 14:49:30.434203 | ubuntu-focal | ok: All items complete 2026-08-10 14:49:30.434235 | 2026-08-10 14:49:30.616085 | ubuntu-focal | ok: "artifacts" 2026-08-10 14:49:30.710348 | ubuntu-focal | ok: "docs" 2026-08-10 14:49:30.724021 | 2026-08-10 14:49:30.724246 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-10 14:49:30.900519 | ubuntu-focal | changed: "logs" 2026-08-10 14:49:31.046409 | ubuntu-focal | changed: "artifacts" 2026-08-10 14:49:31.190595 | ubuntu-focal | changed: "docs" 2026-08-10 14:49:31.212470 | 2026-08-10 14:49:31.212618 | PLAY RECAP 2026-08-10 14:49:31.212685 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-10 14:49:31.212728 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-10 14:49:31.212762 | 2026-08-10 14:49:31.378373 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-10 14:49:31.379826 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-10 14:49:31.966583 | 2026-08-10 14:49:31.966708 | PLAY [all] 2026-08-10 14:49:31.978051 | 2026-08-10 14:49:31.978132 | TASK [setup-uv : Extract archive] 2026-08-10 14:49:34.584556 | ubuntu-focal | changed 2026-08-10 14:49:34.594460 | 2026-08-10 14:49:34.594798 | TASK [setup-uv : Print version] 2026-08-10 14:49:34.932426 | ubuntu-focal | uv 0.8.13 2026-08-10 14:49:35.144918 | ubuntu-focal | ok: Runtime: 0:00:00.010633 2026-08-10 14:49:35.155519 | 2026-08-10 14:49:35.155590 | TASK [Install Ansible collection siblings] 2026-08-10 14:49:35.494393 | ubuntu-focal | ok: 2026-08-10 14:49:35.494557 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-08-10 14:49:35.494586 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-08-10 14:49:35.496234 | 2026-08-10 14:49:35.496284 | PLAY RECAP 2026-08-10 14:49:35.496330 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-10 14:49:35.496352 | 2026-08-10 14:49:35.599983 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-10 14:49:35.602570 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-10 14:49:36.167101 | 2026-08-10 14:49:36.167250 | PLAY [all] 2026-08-10 14:49:36.179495 | 2026-08-10 14:49:36.179626 | LOOP [Check inventory directories] 2026-08-10 14:49:36.442711 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-08-10 14:49:36.584685 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/forget-package/inventory" 2026-08-10 14:49:36.722377 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/inventory" 2026-08-10 14:49:36.742037 | 2026-08-10 14:49:36.742246 | LOOP [Copy inventory file for Zuul] 2026-08-10 14:49:37.467383 | ubuntu-focal | changed: 2026-08-10 14:49:37.467573 | ubuntu-focal | { 2026-08-10 14:49:37.467600 | ubuntu-focal | "ansible_loop_var": "item", 2026-08-10 14:49:37.467623 | ubuntu-focal | "changed": false, 2026-08-10 14:49:37.467642 | ubuntu-focal | "failed": false, 2026-08-10 14:49:37.467665 | ubuntu-focal | "invocation": { 2026-08-10 14:49:37.467683 | ubuntu-focal | "module_args": { 2026-08-10 14:49:37.467701 | ubuntu-focal | "checksum_algorithm": "sha1", 2026-08-10 14:49:37.467719 | ubuntu-focal | "follow": true, 2026-08-10 14:49:37.467737 | ubuntu-focal | "get_attributes": true, 2026-08-10 14:49:37.467756 | ubuntu-focal | "get_checksum": true, 2026-08-10 14:49:37.467776 | ubuntu-focal | "get_mime": true, 2026-08-10 14:49:37.467800 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-08-10 14:49:37.467826 | ubuntu-focal | } 2026-08-10 14:49:37.467852 | ubuntu-focal | }, 2026-08-10 14:49:37.467875 | ubuntu-focal | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-08-10 14:49:37.467905 | ubuntu-focal | "stat": { 2026-08-10 14:49:37.467923 | ubuntu-focal | "atime": 1786373370.1731265, 2026-08-10 14:49:37.467941 | ubuntu-focal | "attr_flags": "e", 2026-08-10 14:49:37.467958 | ubuntu-focal | "attributes": [ 2026-08-10 14:49:37.467975 | ubuntu-focal | "extents" 2026-08-10 14:49:37.467992 | ubuntu-focal | ], 2026-08-10 14:49:37.468009 | ubuntu-focal | "block_size": 4096, 2026-08-10 14:49:37.468026 | ubuntu-focal | "blocks": 8, 2026-08-10 14:49:37.468044 | ubuntu-focal | "charset": "binary", 2026-08-10 14:49:37.468062 | ubuntu-focal | "ctime": 1786373370.1731265, 2026-08-10 14:49:37.468079 | ubuntu-focal | "dev": 64513, 2026-08-10 14:49:37.468097 | ubuntu-focal | "device_type": 0, 2026-08-10 14:49:37.468114 | ubuntu-focal | "executable": true, 2026-08-10 14:49:37.468131 | ubuntu-focal | "exists": true, 2026-08-10 14:49:37.468148 | ubuntu-focal | "gid": 1000, 2026-08-10 14:49:37.468165 | ubuntu-focal | "gr_name": "zuul", 2026-08-10 14:49:37.468181 | ubuntu-focal | "inode": 1035293, 2026-08-10 14:49:37.468198 | ubuntu-focal | "isblk": false, 2026-08-10 14:49:37.468216 | ubuntu-focal | "ischr": false, 2026-08-10 14:49:37.468232 | ubuntu-focal | "isdir": true, 2026-08-10 14:49:37.468250 | ubuntu-focal | "isfifo": false, 2026-08-10 14:49:37.468266 | ubuntu-focal | "isgid": false, 2026-08-10 14:49:37.468283 | ubuntu-focal | "islnk": false, 2026-08-10 14:49:37.468300 | ubuntu-focal | "isreg": false, 2026-08-10 14:49:37.468317 | ubuntu-focal | "issock": false, 2026-08-10 14:49:37.468336 | ubuntu-focal | "isuid": false, 2026-08-10 14:49:37.468360 | ubuntu-focal | "mimetype": "inode/directory", 2026-08-10 14:49:37.468385 | ubuntu-focal | "mode": "0755", 2026-08-10 14:49:37.468403 | ubuntu-focal | "mtime": 1786373356.8766274, 2026-08-10 14:49:37.468420 | ubuntu-focal | "nlink": 11, 2026-08-10 14:49:37.468437 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-08-10 14:49:37.468454 | ubuntu-focal | "pw_name": "zuul", 2026-08-10 14:49:37.468475 | ubuntu-focal | "readable": true, 2026-08-10 14:49:37.468493 | ubuntu-focal | "rgrp": true, 2026-08-10 14:49:37.468511 | ubuntu-focal | "roth": true, 2026-08-10 14:49:37.468529 | ubuntu-focal | "rusr": true, 2026-08-10 14:49:37.468547 | ubuntu-focal | "size": 4096, 2026-08-10 14:49:37.468565 | ubuntu-focal | "uid": 1000, 2026-08-10 14:49:37.468582 | ubuntu-focal | "version": "3647942315", 2026-08-10 14:49:37.468600 | ubuntu-focal | "wgrp": false, 2026-08-10 14:49:37.468619 | ubuntu-focal | "woth": false, 2026-08-10 14:49:37.468637 | ubuntu-focal | "writeable": true, 2026-08-10 14:49:37.468655 | ubuntu-focal | "wusr": true, 2026-08-10 14:49:37.468672 | ubuntu-focal | "xgrp": true, 2026-08-10 14:49:37.468689 | ubuntu-focal | "xoth": true, 2026-08-10 14:49:37.468707 | ubuntu-focal | "xusr": true 2026-08-10 14:49:37.468724 | ubuntu-focal | } 2026-08-10 14:49:37.468741 | ubuntu-focal | } 2026-08-10 14:49:37.468778 | ubuntu-focal | ok: All items complete 2026-08-10 14:49:37.468800 | 2026-08-10 14:49:37.470173 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:49:37.473515 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:49:37.492700 | 2026-08-10 14:49:37.492853 | LOOP [Switch "ansible_host" to private IP] 2026-08-10 14:49:37.809637 | ubuntu-focal | changed: 1 replacements made 2026-08-10 14:49:37.810887 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:49:37.811470 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:49:37.829322 | 2026-08-10 14:49:37.829441 | TASK [Run Molecule scenario] 2026-08-10 14:49:38.454092 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-08-10 14:49:39.187486 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-08-10 14:49:39.367771 | ubuntu-focal | Using CPython 3.13.7 2026-08-10 14:49:39.367853 | ubuntu-focal | Creating virtual environment at: .venv 2026-08-10 14:49:39.619112 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-08-10 14:49:39.622817 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-08-10 14:49:39.623343 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-08-10 14:49:39.782606 | ubuntu-focal | Downloading cryptography 2026-08-10 14:49:39.822201 | ubuntu-focal | Downloading pygments 2026-08-10 14:49:39.961140 | ubuntu-focal | Downloading ansible-core 2026-08-10 14:49:39.985882 | ubuntu-focal | Installed 36 packages in 24ms 2026-08-10 14:49:41.135413 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-08-10 14:49:41.135536 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-08-10 14:49:41.906028 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-08-10 14:49:41.906128 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-08-10 14:49:41.906155 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-08-10 14:49:41.906205 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-08-10 14:49:55.859731 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-08-10 14:49:55.859836 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-08-10 14:49:55.860037 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-08-10 14:49:55.860098 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-08-10 14:49:55.865179 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-08-10 14:49:55.865466 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-10 14:49:55.870510 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-08-10 14:49:55.870548 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-08-10 14:49:55.870562 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-08-10 14:49:55.875875 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-08-10 14:49:56.361291 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-08-10 14:49:56.361529 | 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-08-10 14:49:56.361739 | 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-08-10 14:49:56.361903 | ubuntu-focal | 2026-08-10 14:49:56.697820 | ubuntu-focal | 2026-08-10 14:49:56.697963 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-08-10 14:49:56.756324 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-08-10 14:49:56.766443 | ubuntu-focal | INFO [forget-package > create] Executing 2026-08-10 14:49:56.768412 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-10 14:49:56.773414 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-08-10 14:49:57.250003 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-08-10 14:49:57.250124 | 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-08-10 14:49:57.250333 | 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-08-10 14:49:57.250479 | ubuntu-focal | 2026-08-10 14:49:57.593958 | ubuntu-focal | 2026-08-10 14:49:57.594092 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-08-10 14:49:57.594250 | ubuntu-focal | 2026-08-10 14:49:57.594428 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-08-10 14:49:57.594610 | ubuntu-focal | Monday 10 August 2026 14:49:57 +0000 (0:00:00.029) 0:00:00.029 ********* 2026-08-10 14:49:58.706805 | 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-08-10 14:49:58.709352 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:49:58.709529 | ubuntu-focal | 2026-08-10 14:49:58.709712 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-08-10 14:49:58.709885 | ubuntu-focal | Monday 10 August 2026 14:49:58 +0000 (0:00:01.116) 0:00:01.145 ********* 2026-08-10 14:50:11.654125 | ubuntu-focal | changed: [ubuntu-focal] 2026-08-10 14:50:11.654255 | ubuntu-focal | 2026-08-10 14:50:11.654440 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-08-10 14:50:11.654620 | ubuntu-focal | Monday 10 August 2026 14:50:11 +0000 (0:00:12.944) 0:00:14.090 ********* 2026-08-10 14:50:12.291628 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:50:12.291704 | ubuntu-focal | 2026-08-10 14:50:12.291714 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-08-10 14:50:12.291725 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-08-10 14:50:12.291742 | ubuntu-focal | 2026-08-10 14:50:12.291851 | ubuntu-focal | 2026-08-10 14:50:12.292017 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-08-10 14:50:12.292291 | ubuntu-focal | Monday 10 August 2026 14:50:12 +0000 (0:00:00.637) 0:00:14.727 ********* 2026-08-10 14:50:12.292389 | ubuntu-focal | =============================================================================== 2026-08-10 14:50:12.292570 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 12.94s 2026-08-10 14:50:12.292736 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.12s 2026-08-10 14:50:12.292924 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.64s 2026-08-10 14:50:12.411897 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-08-10 14:50:12.452482 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-08-10 14:50:12.923120 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-08-10 14:50:12.923300 | 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-08-10 14:50:12.923487 | 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-08-10 14:50:12.923641 | ubuntu-focal | 2026-08-10 14:50:13.214951 | ubuntu-focal | 2026-08-10 14:50:13.215130 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-08-10 14:50:13.215279 | ubuntu-focal | 2026-08-10 14:50:13.215460 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-08-10 14:50:13.215631 | ubuntu-focal | Monday 10 August 2026 14:50:13 +0000 (0:00:00.007) 0:00:00.007 ********* 2026-08-10 14:50:14.230707 | 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-08-10 14:50:14.232836 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:50:14.233040 | ubuntu-focal | 2026-08-10 14:50:14.233225 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-08-10 14:50:14.233429 | ubuntu-focal | Monday 10 August 2026 14:50:14 +0000 (0:00:01.017) 0:00:01.025 ********* 2026-08-10 14:50:14.582025 | 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-08-10 14:50:14.585083 | ubuntu-focal | changed: [ubuntu-focal] 2026-08-10 14:50:14.585255 | ubuntu-focal | 2026-08-10 14:50:14.585433 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-08-10 14:50:14.585646 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-08-10 14:50:14.585798 | ubuntu-focal | 2026-08-10 14:50:14.585942 | ubuntu-focal | 2026-08-10 14:50:14.586114 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-08-10 14:50:14.586287 | ubuntu-focal | Monday 10 August 2026 14:50:14 +0000 (0:00:00.352) 0:00:01.377 ********* 2026-08-10 14:50:14.586638 | ubuntu-focal | =============================================================================== 2026-08-10 14:50:14.586813 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.02s 2026-08-10 14:50:14.586982 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.35s 2026-08-10 14:50:14.730529 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-08-10 14:50:14.740444 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-08-10 14:50:15.210169 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-08-10 14:50:15.210270 | 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-08-10 14:50:15.210489 | 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-08-10 14:50:15.210595 | ubuntu-focal | 2026-08-10 14:50:15.506629 | ubuntu-focal | 2026-08-10 14:50:15.506767 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-08-10 14:50:15.506970 | ubuntu-focal | 2026-08-10 14:50:15.507094 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-08-10 14:50:15.507312 | ubuntu-focal | Monday 10 August 2026 14:50:15 +0000 (0:00:00.007) 0:00:00.007 ********* 2026-08-10 14:50:16.557638 | 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-08-10 14:50:16.560255 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:50:16.560415 | ubuntu-focal | 2026-08-10 14:50:16.560596 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-08-10 14:50:16.560767 | ubuntu-focal | Monday 10 August 2026 14:50:16 +0000 (0:00:01.053) 0:00:01.061 ********* 2026-08-10 14:50:16.900825 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:50:16.900942 | ubuntu-focal | 2026-08-10 14:50:16.901057 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-08-10 14:50:16.901176 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-08-10 14:50:16.901273 | ubuntu-focal | 2026-08-10 14:50:16.901367 | ubuntu-focal | 2026-08-10 14:50:16.901472 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-08-10 14:50:16.901584 | ubuntu-focal | Monday 10 August 2026 14:50:16 +0000 (0:00:00.340) 0:00:01.402 ********* 2026-08-10 14:50:16.901680 | ubuntu-focal | =============================================================================== 2026-08-10 14:50:16.901784 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.05s 2026-08-10 14:50:16.901901 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.34s 2026-08-10 14:50:17.034770 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-08-10 14:50:17.044798 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-08-10 14:50:17.045261 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-10 14:50:17.050994 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-08-10 14:50:17.532452 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-08-10 14:50:17.532775 | 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-08-10 14:50:17.533138 | 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-08-10 14:50:17.533339 | ubuntu-focal | 2026-08-10 14:50:17.856713 | ubuntu-focal | 2026-08-10 14:50:17.856803 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-08-10 14:50:17.857081 | ubuntu-focal | 2026-08-10 14:50:17.857218 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-08-10 14:50:17.857443 | ubuntu-focal | Monday 10 August 2026 14:50:17 +0000 (0:00:00.009) 0:00:00.009 ********* 2026-08-10 14:50:18.905926 | 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-08-10 14:50:18.908523 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:50:18.908704 | ubuntu-focal | 2026-08-10 14:50:18.908945 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-08-10 14:50:18.909119 | ubuntu-focal | Monday 10 August 2026 14:50:18 +0000 (0:00:01.052) 0:00:01.061 ********* 2026-08-10 14:50:20.694917 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:50:20.695052 | ubuntu-focal | 2026-08-10 14:50:20.695242 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-08-10 14:50:20.695426 | ubuntu-focal | Monday 10 August 2026 14:50:20 +0000 (0:00:01.786) 0:00:02.847 ********* 2026-08-10 14:50:22.476275 | ubuntu-focal | ok: [ubuntu-focal] 2026-08-10 14:50:22.476382 | ubuntu-focal | 2026-08-10 14:50:22.476581 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-08-10 14:50:22.476759 | ubuntu-focal | Monday 10 August 2026 14:50:22 +0000 (0:00:01.781) 0:00:04.628 ********* 2026-08-10 14:50:22.502574 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-08-10 14:50:22.502730 | ubuntu-focal | "changed": false, 2026-08-10 14:50:22.502900 | ubuntu-focal | "msg": "All assertions passed" 2026-08-10 14:50:22.503072 | ubuntu-focal | } 2026-08-10 14:50:22.503230 | ubuntu-focal | 2026-08-10 14:50:22.503409 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-08-10 14:50:22.503583 | ubuntu-focal | Monday 10 August 2026 14:50:22 +0000 (0:00:00.026) 0:00:04.655 ********* 2026-08-10 14:50:22.527898 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-08-10 14:50:22.528052 | ubuntu-focal | "changed": false, 2026-08-10 14:50:22.528219 | ubuntu-focal | "msg": "All assertions passed" 2026-08-10 14:50:22.528381 | ubuntu-focal | } 2026-08-10 14:50:22.528542 | ubuntu-focal | 2026-08-10 14:50:22.528716 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-08-10 14:50:22.528934 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-08-10 14:50:22.529091 | ubuntu-focal | 2026-08-10 14:50:22.529241 | ubuntu-focal | 2026-08-10 14:50:22.529406 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-08-10 14:50:22.529584 | ubuntu-focal | Monday 10 August 2026 14:50:22 +0000 (0:00:00.025) 0:00:04.681 ********* 2026-08-10 14:50:22.529739 | ubuntu-focal | =============================================================================== 2026-08-10 14:50:22.529983 | ubuntu-focal | Populate package facts -------------------------------------------------- 1.79s 2026-08-10 14:50:22.530151 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.78s 2026-08-10 14:50:22.530319 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.05s 2026-08-10 14:50:22.530494 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-08-10 14:50:22.530668 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.03s 2026-08-10 14:50:22.629295 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-08-10 14:50:22.640368 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-08-10 14:50:22.640671 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-10 14:50:22.645654 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-08-10 14:50:22.645686 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-08-10 14:50:22.645764 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-08-10 14:50:22.646109 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-08-10 14:50:22.934107 | ubuntu-focal | ok: Runtime: 0:00:44.558976 2026-08-10 14:50:22.938022 | 2026-08-10 14:50:22.938091 | PLAY RECAP 2026-08-10 14:50:22.938147 | ubuntu-focal | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-10 14:50:22.938178 | 2026-08-10 14:50:23.053234 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-10 14:50:23.055808 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-10 14:50:23.704857 | 2026-08-10 14:50:23.705111 | PLAY [all] 2026-08-10 14:50:23.718680 | 2026-08-10 14:50:23.718833 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-10 14:50:23.765090 | ubuntu-focal | skipping: Conditional result was False 2026-08-10 14:50:23.772739 | 2026-08-10 14:50:23.772935 | TASK [fetch-output : Set log path for single node] 2026-08-10 14:50:23.820297 | ubuntu-focal | ok 2026-08-10 14:50:23.825517 | 2026-08-10 14:50:23.825615 | LOOP [fetch-output : Ensure local output dirs] 2026-08-10 14:50:24.252201 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/work/logs" 2026-08-10 14:50:24.485325 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/work/artifacts" 2026-08-10 14:50:24.696184 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/b2bcd7114b0b4b579e86792d3068d397/work/docs" 2026-08-10 14:50:24.718435 | 2026-08-10 14:50:24.718610 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-10 14:50:25.340995 | ubuntu-focal | changed: .d..t...... ./ 2026-08-10 14:50:25.341210 | ubuntu-focal | changed: All items complete 2026-08-10 14:50:25.341240 | 2026-08-10 14:50:25.744497 | ubuntu-focal | changed: .d..t...... ./ 2026-08-10 14:50:26.165285 | ubuntu-focal | changed: .d..t...... ./ 2026-08-10 14:50:26.285162 | 2026-08-10 14:50:26.285269 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-10 14:50:26.696081 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.007597 2026-08-10 14:50:26.928134 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007971 2026-08-10 14:50:26.949296 | 2026-08-10 14:50:26.949414 | PLAY [all] 2026-08-10 14:50:26.955460 | 2026-08-10 14:50:26.955521 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-10 14:50:27.360506 | ubuntu-focal | changed 2026-08-10 14:50:27.368033 | 2026-08-10 14:50:27.368091 | PLAY RECAP 2026-08-10 14:50:27.368136 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-10 14:50:27.368159 | 2026-08-10 14:50:27.545692 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-10 14:50:27.546857 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-10 14:50:28.155847 | 2026-08-10 14:50:28.155963 | PLAY [localhost] 2026-08-10 14:50:28.166451 | 2026-08-10 14:50:28.166562 | TASK [Generate Zuul manifest] 2026-08-10 14:50:28.186193 | localhost | ok 2026-08-10 14:50:28.202754 | 2026-08-10 14:50:28.202841 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-10 14:50:28.580132 | localhost | changed 2026-08-10 14:50:28.591873 | 2026-08-10 14:50:28.592001 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-10 14:50:28.625519 | localhost | ok 2026-08-10 14:50:28.634922 | 2026-08-10 14:50:28.634988 | TASK [Upload logs] 2026-08-10 14:50:28.656673 | localhost | ok 2026-08-10 14:50:28.719535 | 2026-08-10 14:50:28.719681 | TASK [Set zuul-log-path fact] 2026-08-10 14:50:28.741248 | localhost | ok 2026-08-10 14:50:28.754915 | 2026-08-10 14:50:28.754993 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-10 14:50:28.787209 | localhost | ok 2026-08-10 14:50:28.796252 | 2026-08-10 14:50:28.796323 | TASK [upload-logs : Create log directories] 2026-08-10 14:50:29.202420 | localhost | changed 2026-08-10 14:50:29.208275 | 2026-08-10 14:50:29.208348 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-10 14:50:29.618149 | localhost -> localhost | ok: Runtime: 0:00:00.004679 2026-08-10 14:50:29.625684 | 2026-08-10 14:50:29.625778 | TASK [upload-logs : Upload logs to log server] 2026-08-10 14:50:30.041801 | localhost | Output suppressed because no_log was given 2026-08-10 14:50:30.047281 | 2026-08-10 14:50:30.047364 | LOOP [upload-logs : Compress console log and json output] 2026-08-10 14:50:30.094142 | localhost | skipping: Conditional result was False 2026-08-10 14:50:30.119764 | localhost | skipping: Conditional result was False 2026-08-10 14:50:30.132798 | 2026-08-10 14:50:30.133024 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-10 14:50:30.182410 | localhost | skipping: Conditional result was False 2026-08-10 14:50:30.182754 | 2026-08-10 14:50:30.189439 | localhost | skipping: Conditional result was False 2026-08-10 14:50:30.206437 | 2026-08-10 14:50:30.206721 | LOOP [upload-logs : Upload console log and json output]