2026-04-21 00:58:20.943903 | Job console starting
2026-04-21 00:58:20.958741 | Updating git repos
2026-04-21 00:58:20.977726 | Cloning repos into workspace
2026-04-21 00:58:21.027111 | Restoring repo states
2026-04-21 00:58:21.035355 | Merging changes
2026-04-21 00:58:21.280003 | Checking out repos
2026-04-21 00:58:21.314901 | Preparing playbooks
2026-04-21 00:58:22.369224 | Running Ansible setup
2026-04-21 00:58:25.495685 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-21 00:58:26.052252 | 
2026-04-21 00:58:26.052379 | PLAY [localhost]
2026-04-21 00:58:26.059641 | 
2026-04-21 00:58:26.059716 | TASK [Gathering Facts]
2026-04-21 00:58:26.977137 | localhost | ok
2026-04-21 00:58:26.984210 | 
2026-04-21 00:58:26.984292 | TASK [Setup log path fact]
2026-04-21 00:58:27.006851 | localhost | ok
2026-04-21 00:58:27.019641 | 
2026-04-21 00:58:27.019711 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-21 00:58:27.064546 | localhost | ok
2026-04-21 00:58:27.077181 | 
2026-04-21 00:58:27.077481 | TASK [emit-job-header : Print job information]
2026-04-21 00:58:27.119897 | # Job Information
2026-04-21 00:58:27.120111 | Ansible Version: 2.16.16
2026-04-21 00:58:27.120180 | Job: ansible-collection-containers-molecule-docker-ubuntu-focal
2026-04-21 00:58:27.120230 | Pipeline: check
2026-04-21 00:58:27.120276 | Executor: 0a8996d2b663
2026-04-21 00:58:27.120332 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/117
2026-04-21 00:58:27.120392 | Event ID: 2be61180-3d1d-11f1-9516-aed3e94cde44
2026-04-21 00:58:27.126195 | 
2026-04-21 00:58:27.126257 | LOOP [emit-job-header : Print node information]
2026-04-21 00:58:27.225955 | localhost | ok:
2026-04-21 00:58:27.226232 | localhost | # Node Information
2026-04-21 00:58:27.226294 | localhost | Inventory Hostname: ubuntu-focal
2026-04-21 00:58:27.226341 | localhost | Hostname: np0000167900
2026-04-21 00:58:27.226386 | localhost | Username: zuul
2026-04-21 00:58:27.226435 | localhost | Distro: Ubuntu 20.04
2026-04-21 00:58:27.226478 | localhost | Provider: yul1
2026-04-21 00:58:27.226520 | localhost | Region: ca-ymq-1
2026-04-21 00:58:27.226561 | localhost | Label: ubuntu-focal
2026-04-21 00:58:27.226600 | localhost | Product Name: OpenStack Nova
2026-04-21 00:58:27.226640 | localhost | Interface IP: 199.19.213.78
2026-04-21 00:58:27.242796 | 
2026-04-21 00:58:27.242917 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-21 00:58:27.647570 | localhost -> localhost | changed
2026-04-21 00:58:27.656498 | 
2026-04-21 00:58:27.656603 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-21 00:58:28.550325 | localhost -> localhost | changed
2026-04-21 00:58:28.560453 | 
2026-04-21 00:58:28.560529 | PLAY [all]
2026-04-21 00:58:28.569827 | 
2026-04-21 00:58:28.569891 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-21 00:58:28.796316 | ubuntu-focal -> localhost | ok
2026-04-21 00:58:28.807816 | 
2026-04-21 00:58:28.807952 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-21 00:58:28.843179 | ubuntu-focal | ok
2026-04-21 00:58:28.858718 | ubuntu-focal | included: /var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-21 00:58:28.864214 | 
2026-04-21 00:58:28.864278 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-21 00:58:29.807312 | ubuntu-focal -> localhost | Generating public/private rsa key pair.
2026-04-21 00:58:29.807512 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/work/1a1c6462ea9548f38f944bbfde346408_id_rsa
2026-04-21 00:58:29.807554 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/work/1a1c6462ea9548f38f944bbfde346408_id_rsa.pub
2026-04-21 00:58:29.807586 | ubuntu-focal -> localhost | The key fingerprint is:
2026-04-21 00:58:29.807616 | ubuntu-focal -> localhost | SHA256:JGghVVFMME3itswqDHALsNgSAzmssvqAbGTd9qtUTg8 zuul-build-sshkey
2026-04-21 00:58:29.807661 | ubuntu-focal -> localhost | The key's randomart image is:
2026-04-21 00:58:29.807692 | ubuntu-focal -> localhost | +---[RSA 3072]----+
2026-04-21 00:58:29.807729 | ubuntu-focal -> localhost | |B...o.*O+        |
2026-04-21 00:58:29.807759 | ubuntu-focal -> localhost | |=* . + oo        |
2026-04-21 00:58:29.807787 | ubuntu-focal -> localhost | |B.o o + .        |
2026-04-21 00:58:29.807814 | ubuntu-focal -> localhost | |++.o.+ +         |
2026-04-21 00:58:29.807841 | ubuntu-focal -> localhost | |o+.. o+ES        |
2026-04-21 00:58:29.807870 | ubuntu-focal -> localhost | |*o  ..= o        |
2026-04-21 00:58:29.807896 | ubuntu-focal -> localhost | |+oo .. o .       |
2026-04-21 00:58:29.807924 | ubuntu-focal -> localhost | |o. ..   .        |
2026-04-21 00:58:29.807954 | ubuntu-focal -> localhost | | ..  ...         |
2026-04-21 00:58:29.807987 | ubuntu-focal -> localhost | +----[SHA256]-----+
2026-04-21 00:58:29.808058 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.547824
2026-04-21 00:58:29.814865 | 
2026-04-21 00:58:29.814950 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-21 00:58:29.849060 | ubuntu-focal | ok
2026-04-21 00:58:29.861102 | ubuntu-focal | included: /var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-21 00:58:29.868550 | 
2026-04-21 00:58:29.868616 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-21 00:58:29.891812 | ubuntu-focal | skipping: Conditional result was False
2026-04-21 00:58:29.902078 | 
2026-04-21 00:58:29.902147 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-21 00:58:30.298780 | ubuntu-focal | changed
2026-04-21 00:58:30.305436 | 
2026-04-21 00:58:30.305518 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-21 00:58:30.460804 | ubuntu-focal | ok
2026-04-21 00:58:30.468861 | 
2026-04-21 00:58:30.468925 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-21 00:58:30.897905 | ubuntu-focal | changed
2026-04-21 00:58:30.904874 | 
2026-04-21 00:58:30.904990 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-21 00:58:31.316785 | ubuntu-focal | changed
2026-04-21 00:58:31.322109 | 
2026-04-21 00:58:31.322175 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-21 00:58:31.345108 | ubuntu-focal | skipping: Conditional result was False
2026-04-21 00:58:31.354497 | 
2026-04-21 00:58:31.354585 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-21 00:58:31.723912 | ubuntu-focal -> localhost | changed
2026-04-21 00:58:31.742148 | 
2026-04-21 00:58:31.742229 | TASK [add-build-sshkey : Add back temp key]
2026-04-21 00:58:32.030121 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/work/1a1c6462ea9548f38f944bbfde346408_id_rsa (zuul-build-sshkey)
2026-04-21 00:58:32.030357 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.012585
2026-04-21 00:58:32.037036 | 
2026-04-21 00:58:32.037131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-21 00:58:32.334339 | ubuntu-focal | ok
2026-04-21 00:58:32.340252 | 
2026-04-21 00:58:32.340314 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-21 00:58:32.365050 | ubuntu-focal | skipping: Conditional result was False
2026-04-21 00:58:32.381838 | 
2026-04-21 00:58:32.381925 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-21 00:58:32.700151 | ubuntu-focal | ok
2026-04-21 00:58:32.705849 | 
2026-04-21 00:58:32.705912 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-21 00:58:34.657733 | ubuntu-focal | Output suppressed because no_log was given
2026-04-21 00:58:34.675281 | 
2026-04-21 00:58:34.675459 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-21 00:58:34.844697 | ubuntu-focal | ok: "logs"
2026-04-21 00:58:34.845056 | ubuntu-focal | ok: All items complete
2026-04-21 00:58:34.845098 | 
2026-04-21 00:58:34.983993 | ubuntu-focal | ok: "artifacts"
2026-04-21 00:58:35.124675 | ubuntu-focal | ok: "docs"
2026-04-21 00:58:35.136835 | 
2026-04-21 00:58:35.137010 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-21 00:58:35.360609 | ubuntu-focal | changed: "logs"
2026-04-21 00:58:35.469458 | ubuntu-focal | changed: "artifacts"
2026-04-21 00:58:35.615760 | ubuntu-focal | changed: "docs"
2026-04-21 00:58:35.636342 | 
2026-04-21 00:58:35.636533 | PLAY RECAP
2026-04-21 00:58:35.636651 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-21 00:58:35.636709 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-21 00:58:35.636741 | 
2026-04-21 00:58:35.797502 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-21 00:58:35.799511 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-21 00:58:36.355374 | 
2026-04-21 00:58:36.355495 | PLAY [all]
2026-04-21 00:58:36.366471 | 
2026-04-21 00:58:36.366543 | TASK [setup-uv : Extract archive]
2026-04-21 00:58:38.516178 | ubuntu-focal | changed
2026-04-21 00:58:38.523970 | 
2026-04-21 00:58:38.524077 | TASK [setup-uv : Print version]
2026-04-21 00:58:38.852363 | ubuntu-focal | uv 0.8.13
2026-04-21 00:58:39.064471 | ubuntu-focal | ok: Runtime: 0:00:00.009146
2026-04-21 00:58:39.070669 | 
2026-04-21 00:58:39.070721 | PLAY RECAP
2026-04-21 00:58:39.070767 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-21 00:58:39.070790 | 
2026-04-21 00:58:39.177583 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-21 00:58:39.179696 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-21 00:58:39.762913 | 
2026-04-21 00:58:39.763050 | PLAY [all]
2026-04-21 00:58:39.773787 | 
2026-04-21 00:58:39.773858 | TASK [Copy inventory file for Zuul]
2026-04-21 00:58:40.568620 | ubuntu-focal | changed
2026-04-21 00:58:40.578350 | 
2026-04-21 00:58:40.578491 | TASK [Switch "ansible_host" to private IP]
2026-04-21 00:58:40.847238 | ubuntu-focal | changed: 1 replacements made
2026-04-21 00:58:40.855753 | 
2026-04-21 00:58:40.855840 | TASK [Run Molecule scenario]
2026-04-21 00:58:41.450638 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-04-21 00:58:42.053906 | ubuntu-focal |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-04-21 00:58:42.220837 | ubuntu-focal | Using CPython 3.13.7
2026-04-21 00:58:42.220902 | ubuntu-focal | Creating virtual environment at: .venv
2026-04-21 00:58:42.697076 | ubuntu-focal | Downloading pygments (1.2MiB)
2026-04-21 00:58:42.697563 | ubuntu-focal | Downloading ansible-core (2.3MiB)
2026-04-21 00:58:42.702111 | ubuntu-focal | Downloading cryptography (4.3MiB)
2026-04-21 00:58:42.903113 | ubuntu-focal |  Downloading cryptography
2026-04-21 00:58:42.925267 | ubuntu-focal |  Downloading pygments
2026-04-21 00:58:43.032842 | ubuntu-focal |  Downloading ansible-core
2026-04-21 00:58:43.056603 | ubuntu-focal | Installed 36 packages in 21ms
2026-04-21 00:58:44.152855 | ubuntu-focal | INFO     Collection 'vexxhost.containers' detected.
2026-04-21 00:58:44.152916 | ubuntu-focal | INFO     Scenarios will be used from 'extensions/molecule'
2026-04-21 00:58:44.918920 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-04-21 00:58:44.918999 | ubuntu-focal | INFO     default scenario not found, disabling shared state.
2026-04-21 00:58:44.919140 | ubuntu-focal | INFO     [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-21 00:58:44.919231 | ubuntu-focal | INFO     [docker > prerun] Performing prerun with role_name_check=0...
2026-04-21 00:58:53.615699 | ubuntu-focal | INFO     [docker > dependency] Executing
2026-04-21 00:58:53.615851 | ubuntu-focal | WARNING  [docker > dependency] Missing roles requirements file: requirements.yml
2026-04-21 00:58:53.616041 | ubuntu-focal | WARNING  [docker > dependency] Missing collections requirements file: collections.yml
2026-04-21 00:58:53.616145 | ubuntu-focal | WARNING  [docker > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-04-21 00:58:53.621327 | ubuntu-focal | INFO     [docker > cleanup] Executing
2026-04-21 00:58:53.621583 | ubuntu-focal | WARNING  [docker > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-21 00:58:53.626532 | ubuntu-focal | INFO     [docker > destroy] Executing
2026-04-21 00:58:53.626566 | ubuntu-focal | WARNING  [docker > destroy] Skipping, '--destroy=never' requested.
2026-04-21 00:58:53.626648 | ubuntu-focal | INFO     [docker > destroy] Executed: Successful
2026-04-21 00:58:53.631586 | ubuntu-focal | INFO     [docker > syntax] Executing
2026-04-21 00:58:54.113495 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-21 00:58:54.113713 | 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-21 00:58:54.113962 | 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-21 00:58:54.114150 | ubuntu-focal |
2026-04-21 00:58:54.533021 | ubuntu-focal |
2026-04-21 00:58:54.533223 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-21 00:58:54.601506 | ubuntu-focal | INFO     [docker > syntax] Executed: Successful
2026-04-21 00:58:54.610849 | ubuntu-focal | INFO     [docker > create] Executing
2026-04-21 00:58:54.612616 | ubuntu-focal | WARNING  [docker > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-21 00:58:54.618117 | ubuntu-focal | INFO     [docker > prepare] Executing
2026-04-21 00:58:55.056444 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-21 00:58:55.056663 | 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-21 00:58:55.056859 | 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-21 00:58:55.057056 | ubuntu-focal |
2026-04-21 00:58:55.395953 | ubuntu-focal |
2026-04-21 00:58:55.396095 | ubuntu-focal | PLAY [Prepare] *****************************************************************
2026-04-21 00:58:55.396298 | ubuntu-focal |
2026-04-21 00:58:55.396487 | ubuntu-focal | TASK [Gathering Facts] *********************************************************
2026-04-21 00:58:55.396730 | ubuntu-focal | Tuesday 21 April 2026  00:58:55 +0000 (0:00:00.028)       0:00:00.028 *********
2026-04-21 00:58:56.470732 | 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-21 00:58:56.473263 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-21 00:58:56.473457 | ubuntu-focal |
2026-04-21 00:58:56.473638 | ubuntu-focal | TASK [Update "apt" cache] ******************************************************
2026-04-21 00:58:56.473818 | ubuntu-focal | Tuesday 21 April 2026  00:58:56 +0000 (0:00:01.077)       0:00:01.106 *********
2026-04-21 00:59:06.319380 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-21 00:59:06.319579 | ubuntu-focal |
2026-04-21 00:59:06.319829 | ubuntu-focal | PLAY RECAP *********************************************************************
2026-04-21 00:59:06.320082 | ubuntu-focal | ubuntu-focal               : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-04-21 00:59:06.320246 | ubuntu-focal |
2026-04-21 00:59:06.320458 | ubuntu-focal |
2026-04-21 00:59:06.320702 | ubuntu-focal | TASKS RECAP ********************************************************************
2026-04-21 00:59:06.320884 | ubuntu-focal | Tuesday 21 April 2026  00:59:06 +0000 (0:00:09.846)       0:00:10.952 *********
2026-04-21 00:59:06.321041 | ubuntu-focal | ===============================================================================
2026-04-21 00:59:06.321206 | ubuntu-focal | Update "apt" cache ------------------------------------------------------ 9.85s
2026-04-21 00:59:06.321380 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.08s
2026-04-21 00:59:06.395409 | ubuntu-focal | INFO     [docker > prepare] Executed: Successful
2026-04-21 00:59:06.403939 | ubuntu-focal | INFO     [docker > converge] Executing
2026-04-21 00:59:06.854328 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-21 00:59:06.854545 | 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-21 00:59:06.854834 | 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-21 00:59:06.855000 | ubuntu-focal |
2026-04-21 00:59:07.246185 | ubuntu-focal |
2026-04-21 00:59:07.246377 | ubuntu-focal | PLAY [Converge] ****************************************************************
2026-04-21 00:59:07.246537 | ubuntu-focal |
2026-04-21 00:59:07.246711 | ubuntu-focal | TASK [Gathering Facts] *********************************************************
2026-04-21 00:59:07.246883 | ubuntu-focal | Tuesday 21 April 2026  00:59:07 +0000 (0:00:00.016)       0:00:00.016 *********
2026-04-21 00:59:08.212562 | 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-21 00:59:08.215168 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-21 00:59:08.215338 | ubuntu-focal |
2026-04-21 00:59:08.215521 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-21 00:59:08.215702 | ubuntu-focal | Tuesday 21 April 2026  00:59:08 +0000 (0:00:00.971)       0:00:00.988 *********
2026-04-21 00:59:08.632904 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-21 00:59:08.633063 | ubuntu-focal |
2026-04-21 00:59:08.633269 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-21 00:59:08.633468 | ubuntu-focal | Tuesday 21 April 2026  00:59:08 +0000 (0:00:00.417)       0:00:01.405 *********
2026-04-21 00:59:08.657123 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-21 00:59:08.657274 | ubuntu-focal |
2026-04-21 00:59:08.657476 | ubuntu-focal | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-04-21 00:59:08.657675 | ubuntu-focal | Tuesday 21 April 2026  00:59:08 +0000 (0:00:00.024)       0:00:01.430 *********
2026-04-21 00:59:09.025019 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-21 00:59:09.025182 | ubuntu-focal |
2026-04-21 00:59:09.025384 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-21 00:59:09.025578 | ubuntu-focal | Tuesday 21 April 2026  00:59:09 +0000 (0:00:00.367)       0:00:01.798 *********
2026-04-21 00:59:09.112212 | ubuntu-focal | ok: [ubuntu-focal] => {
2026-04-21 00:59:09.112374 | ubuntu-focal |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-04-21 00:59:09.112604 | ubuntu-focal | }
2026-04-21 00:59:09.112762 | ubuntu-focal |
2026-04-21 00:59:09.112942 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-04-21 00:59:09.113117 | ubuntu-focal | Tuesday 21 April 2026  00:59:09 +0000 (0:00:00.087)       0:00:01.885 *********
2026-04-21 00:59:09.945657 | 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-21 00:59:09.951799 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-21 00:59:09.951914 | ubuntu-focal |
2026-04-21 00:59:09.952088 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-04-21 00:59:09.952304 | ubuntu-focal | Tuesday 21 April 2026  00:59:09 +0000 (0:00:00.839)       0:00:02.724 *********
2026-04-21 00:59:09.974064 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-21 00:59:09.974214 | ubuntu-focal |
2026-04-21 00:59:09.974397 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-21 00:59:09.974567 | ubuntu-focal | Tuesday 21 April 2026  00:59:09 +0000 (0:00:00.022)       0:00:02.747 *********
2026-04-21 00:59:10.000567 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-21 00:59:10.000693 | ubuntu-focal |
2026-04-21 00:59:10.000865 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-21 00:59:10.001034 | ubuntu-focal | Tuesday 21 April 2026  00:59:09 +0000 (0:00:00.026)       0:00:02.773 *********
2026-04-21 00:59:10.227077 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-21 00:59:10.227215 | ubuntu-focal |
2026-04-21 00:59:10.227436 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-21 00:59:10.227619 | ubuntu-focal | Tuesday 21 April 2026  00:59:10 +0000 (0:00:00.226)       0:00:03.000 *********
2026-04-21 00:59:11.276390 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-21 00:59:11.276543 | ubuntu-focal |
2026-04-21 00:59:11.276724 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-21 00:59:11.276896 | ubuntu-focal | Tuesday 21 April 2026  00:59:11 +0000 (0:00:01.049)       0:00:04.049 *********
2026-04-21 00:59:11.326567 | ubuntu-focal | [ERROR]: Task failed: Finalization of task args for 'ansible.builtin.debug' failed: Error while resolving value for 'msg': object of type 'NoneType' has no attribute 'keys'
2026-04-21 00:59:11.326702 | ubuntu-focal |
2026-04-21 00:59:11.326876 | ubuntu-focal | Task failed.
2026-04-21 00:59:11.327070 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:15:3
2026-04-21 00:59:11.327225 | ubuntu-focal |
2026-04-21 00:59:11.327458 | ubuntu-focal | 13 # under the License.
2026-04-21 00:59:11.327575 | ubuntu-focal | 14
2026-04-21 00:59:11.327738 | ubuntu-focal | 15 - name: Starting download of file
2026-04-21 00:59:11.327895 | ubuntu-focal |      ^ column 3
2026-04-21 00:59:11.328068 | ubuntu-focal |
2026-04-21 00:59:11.328234 | ubuntu-focal | <<< caused by >>>
2026-04-21 00:59:11.328406 | ubuntu-focal |
2026-04-21 00:59:11.328625 | ubuntu-focal | Finalization of task args for 'ansible.builtin.debug' failed.
2026-04-21 00:59:11.328789 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:16:3
2026-04-21 00:59:11.328935 | ubuntu-focal |
2026-04-21 00:59:11.329090 | ubuntu-focal | 14
2026-04-21 00:59:11.329257 | ubuntu-focal | 15 - name: Starting download of file
2026-04-21 00:59:11.329421 | ubuntu-focal | 16   ansible.builtin.debug:
2026-04-21 00:59:11.329581 | ubuntu-focal |      ^ column 3
2026-04-21 00:59:11.329729 | ubuntu-focal |
2026-04-21 00:59:11.329892 | ubuntu-focal | <<< caused by >>>
2026-04-21 00:59:11.330042 | ubuntu-focal |
2026-04-21 00:59:11.330209 | ubuntu-focal | Error while resolving value for 'msg'.
2026-04-21 00:59:11.330401 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:17:10
2026-04-21 00:59:11.330541 | ubuntu-focal |
2026-04-21 00:59:11.330707 | ubuntu-focal | 15 - name: Starting download of file
2026-04-21 00:59:11.330872 | ubuntu-focal | 16   ansible.builtin.debug:
2026-04-21 00:59:11.331040 | ubuntu-focal | 17     msg: "{{ download_artifact_url }}"
2026-04-21 00:59:11.331201 | ubuntu-focal |             ^ column 10
2026-04-21 00:59:11.331350 | ubuntu-focal |
2026-04-21 00:59:11.331509 | ubuntu-focal | <<< caused by >>>
2026-04-21 00:59:11.331656 | ubuntu-focal |
2026-04-21 00:59:11.331823 | ubuntu-focal | object of type 'NoneType' has no attribute 'keys'
2026-04-21 00:59:11.331998 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/containerd/defaults/main.yml:15:22
2026-04-21 00:59:11.332183 | ubuntu-focal |
2026-04-21 00:59:11.332333 | ubuntu-focal | 13 # under the License.
2026-04-21 00:59:11.332497 | ubuntu-focal | 14
2026-04-21 00:59:11.332708 | ubuntu-focal | 15 containerd_versions: "{{ containerd_archive_checksums['amd64'].keys() | list | community.general.version_sort }}"
2026-04-21 00:59:11.332868 | ubuntu-focal |                         ^ column 22
2026-04-21 00:59:11.333016 | ubuntu-focal |
2026-04-21 00:59:11.333319 | ubuntu-focal | fatal: [ubuntu-focal]: FAILED! => {"msg": "Task failed: Finalization of task args for 'ansible.builtin.debug' failed: Error while resolving value for 'msg': object of type 'NoneType' has no attribute 'keys'"}
2026-04-21 00:59:11.333488 | ubuntu-focal |
2026-04-21 00:59:11.333655 | ubuntu-focal | PLAY RECAP *********************************************************************
2026-04-21 00:59:11.333838 | ubuntu-focal | ubuntu-focal               : ok=7    changed=2    unreachable=0    failed=1    skipped=3    rescued=0    ignored=0
2026-04-21 00:59:11.333987 | ubuntu-focal |
2026-04-21 00:59:11.334133 | ubuntu-focal |
2026-04-21 00:59:11.334298 | ubuntu-focal | TASKS RECAP ********************************************************************
2026-04-21 00:59:11.334469 | ubuntu-focal | Tuesday 21 April 2026  00:59:11 +0000 (0:00:00.051)       0:00:04.101 *********
2026-04-21 00:59:11.334621 | ubuntu-focal | ===============================================================================
2026-04-21 00:59:11.334792 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 1.05s
2026-04-21 00:59:11.334955 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 0.97s
2026-04-21 00:59:11.335125 | ubuntu-focal | vexxhost.containers.download_artifact : Download item ------------------- 0.84s
2026-04-21 00:59:11.335293 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.42s
2026-04-21 00:59:11.335462 | ubuntu-focal | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.37s
2026-04-21 00:59:11.335629 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.23s
2026-04-21 00:59:11.335797 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.09s
2026-04-21 00:59:11.335964 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-04-21 00:59:11.336197 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.03s
2026-04-21 00:59:11.336384 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.02s
2026-04-21 00:59:11.336495 | ubuntu-focal | vexxhost.containers.download_artifact : Extract archive ----------------- 0.02s
2026-04-21 00:59:11.424872 | ubuntu-focal | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.QUTN.docker/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-containers/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-21 00:59:11.425014 | ubuntu-focal | ERROR    [docker > converge] Executed: Failed
2026-04-21 00:59:11.425130 | ubuntu-focal | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.QUTN.docker/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-containers/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-21 00:59:11.949829 | ubuntu-focal | ERROR
2026-04-21 00:59:11.950072 | ubuntu-focal | {
2026-04-21 00:59:11.950117 | ubuntu-focal |   "delta": "0:00:30.308973",
2026-04-21 00:59:11.950149 | ubuntu-focal |   "end": "2026-04-21 00:59:11.492791",
2026-04-21 00:59:11.950178 | ubuntu-focal |   "msg": "non-zero return code",
2026-04-21 00:59:11.950206 | ubuntu-focal |   "rc": 2,
2026-04-21 00:59:11.950236 | ubuntu-focal |   "start": "2026-04-21 00:58:41.183818"
2026-04-21 00:59:11.950264 | ubuntu-focal | }
failure
2026-04-21 00:59:11.954253 | 
2026-04-21 00:59:11.954322 | PLAY RECAP
2026-04-21 00:59:11.954387 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-21 00:59:11.954418 | 
2026-04-21 00:59:12.137350 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-21 00:59:12.139669 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-21 00:59:12.711008 | 
2026-04-21 00:59:12.711138 | PLAY [all]
2026-04-21 00:59:12.722587 | 
2026-04-21 00:59:12.722668 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-21 00:59:12.768304 | ubuntu-focal | skipping: Conditional result was False
2026-04-21 00:59:12.777699 | 
2026-04-21 00:59:12.777808 | TASK [fetch-output : Set log path for single node]
2026-04-21 00:59:12.824161 | ubuntu-focal | ok
2026-04-21 00:59:12.828792 | 
2026-04-21 00:59:12.828861 | LOOP [fetch-output : Ensure local output dirs]
2026-04-21 00:59:13.238507 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/work/logs"
2026-04-21 00:59:13.447566 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/work/artifacts"
2026-04-21 00:59:13.679441 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/1a1c6462ea9548f38f944bbfde346408/work/docs"
2026-04-21 00:59:13.700115 | 
2026-04-21 00:59:13.700264 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-21 00:59:14.306632 | ubuntu-focal | ok
2026-04-21 00:59:14.306888 | ubuntu-focal | changed: All items complete
2026-04-21 00:59:14.306926 | 
2026-04-21 00:59:14.714253 | ubuntu-focal | changed: .d..t...... ./
2026-04-21 00:59:15.150309 | ubuntu-focal | changed: .d..t...... ./
2026-04-21 00:59:15.178096 | 
2026-04-21 00:59:15.178245 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-21 00:59:15.615805 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.007316
2026-04-21 00:59:15.829987 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.006406
2026-04-21 00:59:15.855368 | 
2026-04-21 00:59:15.855495 | PLAY [all]
2026-04-21 00:59:15.862656 | 
2026-04-21 00:59:15.862722 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-21 00:59:16.290635 | ubuntu-focal | changed
2026-04-21 00:59:16.613624 | 
2026-04-21 00:59:16.613763 | PLAY RECAP
2026-04-21 00:59:16.613863 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-21 00:59:16.613915 | 
2026-04-21 00:59:16.817123 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-21 00:59:16.818949 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-21 00:59:17.416249 | 
2026-04-21 00:59:17.416382 | PLAY [localhost]
2026-04-21 00:59:17.426770 | 
2026-04-21 00:59:17.426848 | TASK [Generate Zuul manifest]
2026-04-21 00:59:17.448988 | localhost | ok
2026-04-21 00:59:17.467222 | 
2026-04-21 00:59:17.467441 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-21 00:59:17.793299 | localhost | changed
2026-04-21 00:59:17.813652 | 
2026-04-21 00:59:17.813960 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-21 00:59:17.958949 | localhost | ok
2026-04-21 00:59:17.965796 | 
2026-04-21 00:59:17.965862 | TASK [Upload logs]
2026-04-21 00:59:17.988208 | localhost | ok
2026-04-21 00:59:18.052133 | 
2026-04-21 00:59:18.052284 | TASK [Set zuul-log-path fact]
2026-04-21 00:59:18.073364 | localhost | ok
2026-04-21 00:59:18.095441 | 
2026-04-21 00:59:18.095657 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-21 00:59:18.125113 | localhost | ok
2026-04-21 00:59:18.136532 | 
2026-04-21 00:59:18.136625 | TASK [upload-logs : Create log directories]
2026-04-21 00:59:18.579255 | localhost | changed
2026-04-21 00:59:18.586487 | 
2026-04-21 00:59:18.586575 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-21 00:59:19.002319 | localhost -> localhost | ok: Runtime: 0:00:00.004969
2026-04-21 00:59:19.009858 | 
2026-04-21 00:59:19.009953 | TASK [upload-logs : Upload logs to log server]
2026-04-21 00:59:19.467349 | localhost | Output suppressed because no_log was given
2026-04-21 00:59:19.473078 | 
2026-04-21 00:59:19.473145 | LOOP [upload-logs : Compress console log and json output]
2026-04-21 00:59:19.521757 | localhost | skipping: Conditional result was False
2026-04-21 00:59:19.528635 | localhost | skipping: Conditional result was False
2026-04-21 00:59:19.545036 | 
2026-04-21 00:59:19.545197 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-21 00:59:19.590557 | localhost | skipping: Conditional result was False
2026-04-21 00:59:19.590968 | 
2026-04-21 00:59:19.595158 | localhost | skipping: Conditional result was False
2026-04-21 00:59:19.610532 | 
2026-04-21 00:59:19.610779 | LOOP [upload-logs : Upload console log and json output]