2026-04-27 12:31:07.680369 | Job console starting 2026-04-27 12:31:07.689579 | Updating git repos 2026-04-27 12:31:07.717285 | Cloning repos into workspace 2026-04-27 12:31:50.315814 | Restoring repo states 2026-04-27 12:31:50.715087 | Merging changes 2026-04-27 12:31:51.807019 | Checking out repos 2026-04-27 12:31:52.172326 | Preparing playbooks 2026-04-27 12:31:58.054039 | Running Ansible setup 2026-04-27 12:32:02.102875 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-27 12:32:02.757510 | 2026-04-27 12:32:02.757673 | PLAY [localhost] 2026-04-27 12:32:02.767508 | 2026-04-27 12:32:02.767670 | TASK [Gathering Facts] 2026-04-27 12:32:03.660742 | localhost | ok 2026-04-27 12:32:03.668061 | 2026-04-27 12:32:03.668159 | TASK [Setup log path fact] 2026-04-27 12:32:03.688306 | localhost | ok 2026-04-27 12:32:03.702798 | 2026-04-27 12:32:03.702968 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-27 12:32:03.730972 | localhost | ok 2026-04-27 12:32:03.739984 | 2026-04-27 12:32:03.740085 | TASK [emit-job-header : Print job information] 2026-04-27 12:32:03.782732 | # Job Information 2026-04-27 12:32:03.782909 | Ansible Version: 2.16.16 2026-04-27 12:32:03.782944 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-04-27 12:32:03.782967 | Pipeline: check 2026-04-27 12:32:03.782988 | Executor: 0a8996d2b663 2026-04-27 12:32:03.783009 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/123 2026-04-27 12:32:03.783032 | Event ID: b6724230-4234-11f1-96cd-837a86eff685 2026-04-27 12:32:03.785890 | 2026-04-27 12:32:03.785960 | LOOP [emit-job-header : Print node information] 2026-04-27 12:32:03.878167 | localhost | ok: 2026-04-27 12:32:03.878401 | localhost | # Node Information 2026-04-27 12:32:03.878444 | localhost | Inventory Hostname: ubuntu-focal 2026-04-27 12:32:03.878481 | localhost | Hostname: np0000170572 2026-04-27 12:32:03.878511 | localhost | Username: zuul 2026-04-27 12:32:03.878545 | localhost | Distro: Ubuntu 20.04 2026-04-27 12:32:03.878575 | localhost | Provider: yul1 2026-04-27 12:32:03.878603 | localhost | Region: ca-ymq-1 2026-04-27 12:32:03.878631 | localhost | Label: ubuntu-focal 2026-04-27 12:32:03.878658 | localhost | Product Name: OpenStack Nova 2026-04-27 12:32:03.878686 | localhost | Interface IP: 199.204.45.141 2026-04-27 12:32:03.890926 | 2026-04-27 12:32:03.891111 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-27 12:32:04.315494 | localhost -> localhost | changed 2026-04-27 12:32:04.320737 | 2026-04-27 12:32:04.320830 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-27 12:32:05.181425 | localhost -> localhost | changed 2026-04-27 12:32:05.187990 | 2026-04-27 12:32:05.188066 | PLAY [all] 2026-04-27 12:32:05.196110 | 2026-04-27 12:32:05.196193 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-27 12:32:05.466628 | ubuntu-focal -> localhost | ok 2026-04-27 12:32:05.474911 | 2026-04-27 12:32:05.475015 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-27 12:32:05.506467 | ubuntu-focal | ok 2026-04-27 12:32:05.522689 | ubuntu-focal | included: /var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-27 12:32:05.529813 | 2026-04-27 12:32:05.529921 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-27 12:32:07.600746 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-04-27 12:32:07.601492 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/work/1d01ce4df56a4e57b7e1e50ec27e04e8_id_rsa 2026-04-27 12:32:07.601550 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/work/1d01ce4df56a4e57b7e1e50ec27e04e8_id_rsa.pub 2026-04-27 12:32:07.601586 | ubuntu-focal -> localhost | The key fingerprint is: 2026-04-27 12:32:07.601617 | ubuntu-focal -> localhost | SHA256:MIay5SpuHDkStTYnJOdvElaeFYnf753b2seS0ofuqrY zuul-build-sshkey 2026-04-27 12:32:07.601667 | ubuntu-focal -> localhost | The key's randomart image is: 2026-04-27 12:32:07.601698 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-04-27 12:32:07.601731 | ubuntu-focal -> localhost | | ..o | 2026-04-27 12:32:07.601761 | ubuntu-focal -> localhost | |. + o.o | 2026-04-27 12:32:07.601790 | ubuntu-focal -> localhost | | *.+o++. | 2026-04-27 12:32:07.601818 | ubuntu-focal -> localhost | |. O=+..o. | 2026-04-27 12:32:07.601846 | ubuntu-focal -> localhost | | +o*. S. | 2026-04-27 12:32:07.601875 | ubuntu-focal -> localhost | |.+..o . | 2026-04-27 12:32:07.601902 | ubuntu-focal -> localhost | |+ +o . . o + | 2026-04-27 12:32:07.601929 | ubuntu-focal -> localhost | |.+ o +o= +| 2026-04-27 12:32:07.601962 | ubuntu-focal -> localhost | |.. .Eo+B=+ | 2026-04-27 12:32:07.601990 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-04-27 12:32:07.602053 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.893588 2026-04-27 12:32:07.614653 | 2026-04-27 12:32:07.614720 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-27 12:32:07.645556 | ubuntu-focal | ok 2026-04-27 12:32:07.655978 | ubuntu-focal | included: /var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-27 12:32:07.663441 | 2026-04-27 12:32:07.663506 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-27 12:32:07.687848 | ubuntu-focal | skipping: Conditional result was False 2026-04-27 12:32:07.700891 | 2026-04-27 12:32:07.701074 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-27 12:32:08.141977 | ubuntu-focal | changed 2026-04-27 12:32:08.153046 | 2026-04-27 12:32:08.153175 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-27 12:32:08.326430 | ubuntu-focal | ok 2026-04-27 12:32:08.332576 | 2026-04-27 12:32:08.332663 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-27 12:32:08.772209 | ubuntu-focal | changed 2026-04-27 12:32:08.822881 | 2026-04-27 12:32:08.823009 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-27 12:32:09.275912 | ubuntu-focal | changed 2026-04-27 12:32:09.284319 | 2026-04-27 12:32:09.284452 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-27 12:32:09.308897 | ubuntu-focal | skipping: Conditional result was False 2026-04-27 12:32:09.319463 | 2026-04-27 12:32:09.319620 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-27 12:32:09.789544 | ubuntu-focal -> localhost | changed 2026-04-27 12:32:09.805490 | 2026-04-27 12:32:09.805591 | TASK [add-build-sshkey : Add back temp key] 2026-04-27 12:32:10.095285 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/work/1d01ce4df56a4e57b7e1e50ec27e04e8_id_rsa (zuul-build-sshkey) 2026-04-27 12:32:10.095549 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.014115 2026-04-27 12:32:10.101472 | 2026-04-27 12:32:10.101537 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-27 12:32:10.423942 | ubuntu-focal | ok 2026-04-27 12:32:10.429506 | 2026-04-27 12:32:10.429579 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-27 12:32:10.455139 | ubuntu-focal | skipping: Conditional result was False 2026-04-27 12:32:10.475529 | 2026-04-27 12:32:10.475681 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-27 12:32:10.797343 | ubuntu-focal | ok 2026-04-27 12:32:10.805351 | 2026-04-27 12:32:10.805464 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-27 12:32:17.698980 | ubuntu-focal | Output suppressed because no_log was given 2026-04-27 12:32:17.724764 | 2026-04-27 12:32:17.724993 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-27 12:32:17.898566 | ubuntu-focal | ok: "logs" 2026-04-27 12:32:17.898944 | ubuntu-focal | ok: All items complete 2026-04-27 12:32:17.898988 | 2026-04-27 12:32:18.042154 | ubuntu-focal | ok: "artifacts" 2026-04-27 12:32:18.188231 | ubuntu-focal | ok: "docs" 2026-04-27 12:32:18.216941 | 2026-04-27 12:32:18.217188 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-27 12:32:18.403849 | ubuntu-focal | changed: "logs" 2026-04-27 12:32:18.620586 | ubuntu-focal | changed: "artifacts" 2026-04-27 12:32:18.678543 | ubuntu-focal | changed: "docs" 2026-04-27 12:32:18.701229 | 2026-04-27 12:32:18.701372 | PLAY RECAP 2026-04-27 12:32:18.701419 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 12:32:18.701449 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-27 12:32:18.701470 | 2026-04-27 12:32:18.884699 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-27 12:32:18.888051 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-27 12:32:19.494105 | 2026-04-27 12:32:19.494273 | PLAY [all] 2026-04-27 12:32:19.508007 | 2026-04-27 12:32:19.508200 | TASK [setup-uv : Extract archive] 2026-04-27 12:32:21.743538 | ubuntu-focal | changed 2026-04-27 12:32:21.749905 | 2026-04-27 12:32:21.994445 | TASK [setup-uv : Print version] 2026-04-27 12:32:22.375792 | ubuntu-focal | uv 0.8.13 2026-04-27 12:32:22.562383 | ubuntu-focal | ok: Runtime: 0:00:00.008657 2026-04-27 12:32:22.572693 | 2026-04-27 12:32:22.572829 | PLAY RECAP 2026-04-27 12:32:22.572889 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 12:32:22.572920 | 2026-04-27 12:32:22.722000 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-27 12:32:22.724028 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-27 12:32:23.429861 | 2026-04-27 12:32:23.430004 | PLAY [all] 2026-04-27 12:32:23.441985 | 2026-04-27 12:32:23.442143 | TASK [Copy inventory file for Zuul] 2026-04-27 12:32:24.323658 | ubuntu-focal | changed 2026-04-27 12:32:24.331989 | 2026-04-27 12:32:24.332168 | TASK [Switch "ansible_host" to private IP] 2026-04-27 12:32:24.589944 | ubuntu-focal | changed: 1 replacements made 2026-04-27 12:32:24.596143 | 2026-04-27 12:32:24.596230 | TASK [Run Molecule scenario] 2026-04-27 12:32:25.238573 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-04-27 12:32:25.918933 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-04-27 12:32:26.098169 | ubuntu-focal | Using CPython 3.13.7 2026-04-27 12:32:26.098269 | ubuntu-focal | Creating virtual environment at: .venv 2026-04-27 12:32:26.415013 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-04-27 12:32:26.416176 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-04-27 12:32:26.416912 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-04-27 12:32:26.569629 | ubuntu-focal | Downloading cryptography 2026-04-27 12:32:26.606784 | ubuntu-focal | Downloading pygments 2026-04-27 12:32:26.732560 | ubuntu-focal | Downloading ansible-core 2026-04-27 12:32:26.755004 | ubuntu-focal | Installed 36 packages in 22ms 2026-04-27 12:32:27.842529 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-04-27 12:32:27.842605 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-04-27 12:32:28.618245 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-27 12:32:28.618307 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-04-27 12:32:28.618367 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-27 12:32:28.618439 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-04-27 12:32:37.304514 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-04-27 12:32:37.304745 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-04-27 12:32:37.305021 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-04-27 12:32:37.305107 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-27 12:32:37.312100 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-04-27 12:32:37.312375 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-27 12:32:37.317275 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-04-27 12:32:37.317314 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-04-27 12:32:37.317339 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-04-27 12:32:37.322368 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-04-27 12:32:37.790331 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-27 12:32:37.790579 | 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-04-27 12:32:37.790810 | 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-04-27 12:32:37.790968 | ubuntu-focal | 2026-04-27 12:32:38.140263 | ubuntu-focal | 2026-04-27 12:32:38.140421 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-04-27 12:32:38.206161 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-04-27 12:32:38.216674 | ubuntu-focal | INFO [forget-package > create] Executing 2026-04-27 12:32:38.218586 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-27 12:32:38.225370 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-04-27 12:32:38.669523 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-27 12:32:38.669800 | 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-04-27 12:32:38.669959 | 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-04-27 12:32:38.670112 | ubuntu-focal | 2026-04-27 12:32:39.006796 | ubuntu-focal | 2026-04-27 12:32:39.006941 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-04-27 12:32:39.007179 | ubuntu-focal | 2026-04-27 12:32:39.007374 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-27 12:32:39.007516 | ubuntu-focal | Monday 27 April 2026 12:32:39 +0000 (0:00:00.029) 0:00:00.029 ********** 2026-04-27 12:32:40.136886 | 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-04-27 12:32:40.138635 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:32:40.138878 | ubuntu-focal | 2026-04-27 12:32:40.139123 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-04-27 12:32:40.139318 | ubuntu-focal | Monday 27 April 2026 12:32:40 +0000 (0:00:01.132) 0:00:01.162 ********** 2026-04-27 12:32:52.399180 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-27 12:32:52.399417 | ubuntu-focal | 2026-04-27 12:32:52.399704 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-04-27 12:32:52.399896 | ubuntu-focal | Monday 27 April 2026 12:32:52 +0000 (0:00:12.259) 0:00:13.422 ********** 2026-04-27 12:32:53.029598 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:32:53.029748 | ubuntu-focal | 2026-04-27 12:32:53.029948 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-27 12:32:53.030159 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-27 12:32:53.030328 | ubuntu-focal | 2026-04-27 12:32:53.030490 | ubuntu-focal | 2026-04-27 12:32:53.030659 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-27 12:32:53.030843 | ubuntu-focal | Monday 27 April 2026 12:32:53 +0000 (0:00:00.631) 0:00:14.053 ********** 2026-04-27 12:32:53.031044 | ubuntu-focal | =============================================================================== 2026-04-27 12:32:53.031220 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 12.26s 2026-04-27 12:32:53.031419 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.13s 2026-04-27 12:32:53.031693 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.63s 2026-04-27 12:32:53.135838 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-04-27 12:32:53.145752 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-04-27 12:32:53.619483 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-27 12:32:53.619616 | 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-04-27 12:32:53.619819 | 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-04-27 12:32:53.619964 | ubuntu-focal | 2026-04-27 12:32:53.919453 | ubuntu-focal | 2026-04-27 12:32:53.919555 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-04-27 12:32:53.919722 | ubuntu-focal | 2026-04-27 12:32:53.919893 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-27 12:32:53.920068 | ubuntu-focal | Monday 27 April 2026 12:32:53 +0000 (0:00:00.007) 0:00:00.007 ********** 2026-04-27 12:32:54.934681 | 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-04-27 12:32:54.936629 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:32:54.936800 | ubuntu-focal | 2026-04-27 12:32:54.936989 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-27 12:32:54.937169 | ubuntu-focal | Monday 27 April 2026 12:32:54 +0000 (0:00:01.017) 0:00:01.024 ********** 2026-04-27 12:32:55.275840 | 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-04-27 12:32:55.279979 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-27 12:32:55.280160 | ubuntu-focal | 2026-04-27 12:32:55.280341 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-27 12:32:55.280537 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-27 12:32:55.280693 | ubuntu-focal | 2026-04-27 12:32:55.280847 | ubuntu-focal | 2026-04-27 12:32:55.281018 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-27 12:32:55.281197 | ubuntu-focal | Monday 27 April 2026 12:32:55 +0000 (0:00:00.343) 0:00:01.368 ********** 2026-04-27 12:32:55.281359 | ubuntu-focal | =============================================================================== 2026-04-27 12:32:55.281530 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.02s 2026-04-27 12:32:55.281705 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.34s 2026-04-27 12:32:55.369591 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-04-27 12:32:55.375013 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-04-27 12:32:55.824292 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-27 12:32:55.824432 | 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-04-27 12:32:55.824657 | 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-04-27 12:32:55.824839 | ubuntu-focal | 2026-04-27 12:32:56.112295 | ubuntu-focal | 2026-04-27 12:32:56.112418 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-04-27 12:32:56.112634 | ubuntu-focal | 2026-04-27 12:32:56.112786 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-27 12:32:56.113016 | ubuntu-focal | Monday 27 April 2026 12:32:56 +0000 (0:00:00.007) 0:00:00.007 ********** 2026-04-27 12:32:57.097634 | 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-04-27 12:32:57.099343 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:32:57.099545 | ubuntu-focal | 2026-04-27 12:32:57.099754 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-27 12:32:57.099939 | ubuntu-focal | Monday 27 April 2026 12:32:57 +0000 (0:00:00.987) 0:00:00.994 ********** 2026-04-27 12:32:57.429561 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:32:57.429862 | ubuntu-focal | 2026-04-27 12:32:57.430163 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-27 12:32:57.430437 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-27 12:32:57.430675 | ubuntu-focal | 2026-04-27 12:32:57.430908 | ubuntu-focal | 2026-04-27 12:32:57.431211 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-27 12:32:57.431479 | ubuntu-focal | Monday 27 April 2026 12:32:57 +0000 (0:00:00.330) 0:00:01.324 ********** 2026-04-27 12:32:57.431722 | ubuntu-focal | =============================================================================== 2026-04-27 12:32:57.431980 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 0.99s 2026-04-27 12:32:57.432222 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.33s 2026-04-27 12:32:57.511006 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-04-27 12:32:57.517251 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-04-27 12:32:57.517661 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-27 12:32:57.523917 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-04-27 12:32:57.985664 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-27 12:32:57.985921 | 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-04-27 12:32:57.986116 | 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-04-27 12:32:57.986270 | ubuntu-focal | 2026-04-27 12:32:58.284443 | ubuntu-focal | 2026-04-27 12:32:58.284647 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-04-27 12:32:58.284828 | ubuntu-focal | 2026-04-27 12:32:58.285027 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-27 12:32:58.285204 | ubuntu-focal | Monday 27 April 2026 12:32:58 +0000 (0:00:00.007) 0:00:00.007 ********** 2026-04-27 12:32:59.264579 | 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-04-27 12:32:59.266236 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:32:59.266397 | ubuntu-focal | 2026-04-27 12:32:59.266589 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-04-27 12:32:59.266774 | ubuntu-focal | Monday 27 April 2026 12:32:59 +0000 (0:00:00.981) 0:00:00.989 ********** 2026-04-27 12:33:01.067985 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:33:01.068080 | ubuntu-focal | 2026-04-27 12:33:01.068288 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-04-27 12:33:01.068439 | ubuntu-focal | Monday 27 April 2026 12:33:01 +0000 (0:00:01.801) 0:00:02.790 ********** 2026-04-27 12:33:02.755467 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-27 12:33:02.755626 | ubuntu-focal | 2026-04-27 12:33:02.755831 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-04-27 12:33:02.756031 | ubuntu-focal | Monday 27 April 2026 12:33:02 +0000 (0:00:01.687) 0:00:04.478 ********** 2026-04-27 12:33:02.780622 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-04-27 12:33:02.780800 | ubuntu-focal | "changed": false, 2026-04-27 12:33:02.780991 | ubuntu-focal | "msg": "All assertions passed" 2026-04-27 12:33:02.781189 | ubuntu-focal | } 2026-04-27 12:33:02.781410 | ubuntu-focal | 2026-04-27 12:33:02.781605 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-04-27 12:33:02.781777 | ubuntu-focal | Monday 27 April 2026 12:33:02 +0000 (0:00:00.025) 0:00:04.503 ********** 2026-04-27 12:33:02.803268 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-04-27 12:33:02.803424 | ubuntu-focal | "changed": false, 2026-04-27 12:33:02.803600 | ubuntu-focal | "msg": "All assertions passed" 2026-04-27 12:33:02.803766 | ubuntu-focal | } 2026-04-27 12:33:02.803936 | ubuntu-focal | 2026-04-27 12:33:02.804108 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-27 12:33:02.804291 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-27 12:33:02.804474 | ubuntu-focal | 2026-04-27 12:33:02.804710 | ubuntu-focal | 2026-04-27 12:33:02.804882 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-27 12:33:02.805057 | ubuntu-focal | Monday 27 April 2026 12:33:02 +0000 (0:00:00.022) 0:00:04.526 ********** 2026-04-27 12:33:02.805213 | ubuntu-focal | =============================================================================== 2026-04-27 12:33:02.805385 | ubuntu-focal | Populate package facts -------------------------------------------------- 1.80s 2026-04-27 12:33:02.805549 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.69s 2026-04-27 12:33:02.805718 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 0.98s 2026-04-27 12:33:02.805890 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-04-27 12:33:02.806062 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.02s 2026-04-27 12:33:02.899480 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-04-27 12:33:02.907353 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-04-27 12:33:02.907735 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-27 12:33:02.914467 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-04-27 12:33:02.914522 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-04-27 12:33:02.914616 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-04-27 12:33:02.914924 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-27 12:33:03.192040 | ubuntu-focal | ok: Runtime: 0:00:38.014058 2026-04-27 12:33:03.196215 | 2026-04-27 12:33:03.196343 | PLAY RECAP 2026-04-27 12:33:03.196415 | ubuntu-focal | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 12:33:03.196448 | 2026-04-27 12:33:03.334215 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-27 12:33:03.336517 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-27 12:33:03.971035 | 2026-04-27 12:33:03.971197 | PLAY [all] 2026-04-27 12:33:03.983129 | 2026-04-27 12:33:03.983218 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-27 12:33:04.028380 | ubuntu-focal | skipping: Conditional result was False 2026-04-27 12:33:04.039672 | 2026-04-27 12:33:04.039774 | TASK [fetch-output : Set log path for single node] 2026-04-27 12:33:04.086458 | ubuntu-focal | ok 2026-04-27 12:33:04.092759 | 2026-04-27 12:33:04.092854 | LOOP [fetch-output : Ensure local output dirs] 2026-04-27 12:33:04.479824 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/work/logs" 2026-04-27 12:33:04.701567 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/work/artifacts" 2026-04-27 12:33:04.913437 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/1d01ce4df56a4e57b7e1e50ec27e04e8/work/docs" 2026-04-27 12:33:04.932190 | 2026-04-27 12:33:04.932842 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-27 12:33:05.538676 | ubuntu-focal | ok 2026-04-27 12:33:05.538896 | ubuntu-focal | changed: All items complete 2026-04-27 12:33:05.538924 | 2026-04-27 12:33:05.948722 | ubuntu-focal | changed: .d..t...... ./ 2026-04-27 12:33:06.373565 | ubuntu-focal | changed: .d..t...... ./ 2026-04-27 12:33:06.403196 | 2026-04-27 12:33:06.403326 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-27 12:33:06.843373 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.008775 2026-04-27 12:33:07.079185 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.008948 2026-04-27 12:33:07.087846 | 2026-04-27 12:33:07.087982 | PLAY [all] 2026-04-27 12:33:07.093888 | 2026-04-27 12:33:07.093961 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-27 12:33:07.503383 | ubuntu-focal | changed 2026-04-27 12:33:07.512747 | 2026-04-27 12:33:07.512815 | PLAY RECAP 2026-04-27 12:33:07.512861 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-27 12:33:07.512884 | 2026-04-27 12:33:07.657361 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-27 12:33:07.658870 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-27 12:33:08.227508 | 2026-04-27 12:33:08.227641 | PLAY [localhost] 2026-04-27 12:33:08.237792 | 2026-04-27 12:33:08.237868 | TASK [Generate Zuul manifest] 2026-04-27 12:33:08.262379 | localhost | ok 2026-04-27 12:33:08.278096 | 2026-04-27 12:33:08.278203 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-27 12:33:08.595961 | localhost | changed 2026-04-27 12:33:08.610035 | 2026-04-27 12:33:08.610133 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-27 12:33:08.642095 | localhost | ok 2026-04-27 12:33:08.650492 | 2026-04-27 12:33:08.650560 | TASK [Upload logs] 2026-04-27 12:33:08.683131 | localhost | ok 2026-04-27 12:33:08.781109 | 2026-04-27 12:33:08.781239 | TASK [Set zuul-log-path fact] 2026-04-27 12:33:08.802883 | localhost | ok 2026-04-27 12:33:08.818378 | 2026-04-27 12:33:08.818543 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-27 12:33:08.848847 | localhost | ok 2026-04-27 12:33:08.858503 | 2026-04-27 12:33:08.858612 | TASK [upload-logs : Create log directories] 2026-04-27 12:33:09.342781 | localhost | changed 2026-04-27 12:33:09.349499 | 2026-04-27 12:33:09.349605 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-27 12:33:09.751891 | localhost -> localhost | ok: Runtime: 0:00:00.005549 2026-04-27 12:33:09.758376 | 2026-04-27 12:33:09.758458 | TASK [upload-logs : Upload logs to log server] 2026-04-27 12:33:10.219904 | localhost | Output suppressed because no_log was given 2026-04-27 12:33:10.225636 | 2026-04-27 12:33:10.225744 | LOOP [upload-logs : Compress console log and json output] 2026-04-27 12:33:10.271843 | localhost | skipping: Conditional result was False 2026-04-27 12:33:10.280451 | localhost | skipping: Conditional result was False 2026-04-27 12:33:10.291082 | 2026-04-27 12:33:10.291310 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-27 12:33:10.331522 | localhost | skipping: Conditional result was False 2026-04-27 12:33:10.331989 | 2026-04-27 12:33:10.337719 | localhost | skipping: Conditional result was False 2026-04-27 12:33:10.350328 | 2026-04-27 12:33:10.350455 | LOOP [upload-logs : Upload console log and json output]