2026-04-28 01:03:56.887379 | Job console starting 2026-04-28 01:03:56.906723 | Updating git repos 2026-04-28 01:03:56.988256 | Cloning repos into workspace 2026-04-28 01:03:57.087275 | Restoring repo states 2026-04-28 01:03:57.095828 | Merging changes 2026-04-28 01:03:57.466085 | Checking out repos 2026-04-28 01:03:57.513073 | Preparing playbooks 2026-04-28 01:03:59.150697 | Running Ansible setup 2026-04-28 01:04:02.752454 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 01:04:03.370848 | 2026-04-28 01:04:03.370973 | PLAY [localhost] 2026-04-28 01:04:03.378481 | 2026-04-28 01:04:03.378566 | TASK [Gathering Facts] 2026-04-28 01:04:04.594780 | localhost | ok 2026-04-28 01:04:04.604283 | 2026-04-28 01:04:04.604367 | TASK [Setup log path fact] 2026-04-28 01:04:04.624221 | localhost | ok 2026-04-28 01:04:04.636187 | 2026-04-28 01:04:04.636257 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 01:04:04.679441 | localhost | ok 2026-04-28 01:04:04.687928 | 2026-04-28 01:04:04.688047 | TASK [emit-job-header : Print job information] 2026-04-28 01:04:04.728319 | # Job Information 2026-04-28 01:04:04.728572 | Ansible Version: 2.16.16 2026-04-28 01:04:04.728642 | Job: ansible-collection-containers-molecule-docker-ubuntu-focal 2026-04-28 01:04:04.728692 | Pipeline: check 2026-04-28 01:04:04.728737 | Executor: 0a8996d2b663 2026-04-28 01:04:04.728782 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/125 2026-04-28 01:04:04.728830 | Event ID: 07f9bf70-429e-11f1-8b75-3e63c4c208bf 2026-04-28 01:04:04.734725 | 2026-04-28 01:04:04.734848 | LOOP [emit-job-header : Print node information] 2026-04-28 01:04:04.944105 | localhost | ok: 2026-04-28 01:04:04.944338 | localhost | # Node Information 2026-04-28 01:04:04.944396 | localhost | Inventory Hostname: ubuntu-focal 2026-04-28 01:04:04.944438 | localhost | Hostname: np0000170747 2026-04-28 01:04:04.944469 | localhost | Username: zuul 2026-04-28 01:04:04.944511 | localhost | Distro: Ubuntu 20.04 2026-04-28 01:04:04.944554 | localhost | Provider: yul1 2026-04-28 01:04:04.944591 | localhost | Region: ca-ymq-1 2026-04-28 01:04:04.944630 | localhost | Label: ubuntu-focal 2026-04-28 01:04:04.944668 | localhost | Product Name: OpenStack Nova 2026-04-28 01:04:04.944705 | localhost | Interface IP: 199.204.45.227 2026-04-28 01:04:04.959558 | 2026-04-28 01:04:04.959703 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-28 01:04:05.439239 | localhost -> localhost | changed 2026-04-28 01:04:05.451324 | 2026-04-28 01:04:05.451453 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-28 01:04:07.119692 | localhost -> localhost | changed 2026-04-28 01:04:07.127573 | 2026-04-28 01:04:07.127656 | PLAY [all] 2026-04-28 01:04:07.135533 | 2026-04-28 01:04:07.135611 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-28 01:04:07.382564 | ubuntu-focal -> localhost | ok 2026-04-28 01:04:07.389172 | 2026-04-28 01:04:07.389317 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-28 01:04:07.422566 | ubuntu-focal | ok 2026-04-28 01:04:07.440671 | ubuntu-focal | included: /var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-28 01:04:07.447947 | 2026-04-28 01:04:07.448014 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-28 01:04:09.038082 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-04-28 01:04:09.038282 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/work/9f2ec046aeb248c8b3c5302fef9642a0_id_rsa 2026-04-28 01:04:09.038315 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/work/9f2ec046aeb248c8b3c5302fef9642a0_id_rsa.pub 2026-04-28 01:04:09.038339 | ubuntu-focal -> localhost | The key fingerprint is: 2026-04-28 01:04:09.038362 | ubuntu-focal -> localhost | SHA256:N/aEOPiViJyBIzOE8V3h67mIBog34smidcUagmhgX+4 zuul-build-sshkey 2026-04-28 01:04:09.038405 | ubuntu-focal -> localhost | The key's randomart image is: 2026-04-28 01:04:09.038428 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-04-28 01:04:09.038457 | ubuntu-focal -> localhost | |.o. o. | 2026-04-28 01:04:09.038480 | ubuntu-focal -> localhost | |.o . + | 2026-04-28 01:04:09.038502 | ubuntu-focal -> localhost | |..= +.o | 2026-04-28 01:04:09.038523 | ubuntu-focal -> localhost | |+..+o+ * o o | 2026-04-28 01:04:09.038543 | ubuntu-focal -> localhost | |=.....O S B . | 2026-04-28 01:04:09.038565 | ubuntu-focal -> localhost | |* o..= o = + | 2026-04-28 01:04:09.038586 | ubuntu-focal -> localhost | |o+o.oEo . . | 2026-04-28 01:04:09.038606 | ubuntu-focal -> localhost | |.=.o . . | 2026-04-28 01:04:09.038629 | ubuntu-focal -> localhost | |+.. . . | 2026-04-28 01:04:09.038651 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-04-28 01:04:09.038700 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.542717 2026-04-28 01:04:09.044265 | 2026-04-28 01:04:09.044331 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-28 01:04:09.080516 | ubuntu-focal | ok 2026-04-28 01:04:09.090853 | ubuntu-focal | included: /var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-28 01:04:09.098604 | 2026-04-28 01:04:09.098670 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-28 01:04:09.124038 | ubuntu-focal | skipping: Conditional result was False 2026-04-28 01:04:09.133720 | 2026-04-28 01:04:09.133823 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-28 01:04:09.539294 | ubuntu-focal | changed 2026-04-28 01:04:09.547084 | 2026-04-28 01:04:09.547241 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-28 01:04:09.709535 | ubuntu-focal | ok 2026-04-28 01:04:10.059503 | 2026-04-28 01:04:10.059697 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-28 01:04:10.477961 | ubuntu-focal | changed 2026-04-28 01:04:10.714334 | 2026-04-28 01:04:10.714501 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-28 01:04:11.149751 | ubuntu-focal | changed 2026-04-28 01:04:11.155461 | 2026-04-28 01:04:11.155535 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-28 01:04:11.179874 | ubuntu-focal | skipping: Conditional result was False 2026-04-28 01:04:11.189909 | 2026-04-28 01:04:11.190002 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-28 01:04:11.769736 | ubuntu-focal -> localhost | changed 2026-04-28 01:04:11.780330 | 2026-04-28 01:04:11.780407 | TASK [add-build-sshkey : Add back temp key] 2026-04-28 01:04:12.051649 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/work/9f2ec046aeb248c8b3c5302fef9642a0_id_rsa (zuul-build-sshkey) 2026-04-28 01:04:12.051897 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.016412 2026-04-28 01:04:12.056710 | 2026-04-28 01:04:12.056770 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-28 01:04:12.395045 | ubuntu-focal | ok 2026-04-28 01:04:12.402452 | 2026-04-28 01:04:12.402526 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-28 01:04:12.429741 | ubuntu-focal | skipping: Conditional result was False 2026-04-28 01:04:12.446841 | 2026-04-28 01:04:12.446917 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-28 01:04:12.796714 | ubuntu-focal | ok 2026-04-28 01:04:12.812750 | 2026-04-28 01:04:12.812945 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-28 01:04:13.657870 | ubuntu-focal | Output suppressed because no_log was given 2026-04-28 01:04:13.676786 | 2026-04-28 01:04:13.676921 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-28 01:04:13.841860 | ubuntu-focal | ok: "logs" 2026-04-28 01:04:14.010323 | ubuntu-focal | ok: All items complete 2026-04-28 01:04:14.018892 | 2026-04-28 01:04:14.025149 | ubuntu-focal | ok: "artifacts" 2026-04-28 01:04:14.118791 | ubuntu-focal | ok: "docs" 2026-04-28 01:04:14.138462 | 2026-04-28 01:04:14.138599 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-28 01:04:14.358889 | ubuntu-focal | changed: "logs" 2026-04-28 01:04:14.462785 | ubuntu-focal | changed: "artifacts" 2026-04-28 01:04:14.599661 | ubuntu-focal | changed: "docs" 2026-04-28 01:04:14.827772 | 2026-04-28 01:04:14.827874 | PLAY RECAP 2026-04-28 01:04:14.827922 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 01:04:14.827954 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-28 01:04:14.827975 | 2026-04-28 01:04:14.967001 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 01:04:14.968763 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 01:04:15.618660 | 2026-04-28 01:04:15.618800 | PLAY [all] 2026-04-28 01:04:15.657725 | 2026-04-28 01:04:15.657882 | TASK [setup-uv : Extract archive] 2026-04-28 01:04:17.860691 | ubuntu-focal | changed 2026-04-28 01:04:17.868269 | 2026-04-28 01:04:17.868371 | TASK [setup-uv : Print version] 2026-04-28 01:04:17.702241 | ubuntu-focal | uv 0.8.13 2026-04-28 01:04:18.411182 | ubuntu-focal | ok: Runtime: 0:00:00.008309 2026-04-28 01:04:18.418713 | 2026-04-28 01:04:18.418770 | PLAY RECAP 2026-04-28 01:04:18.418832 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 01:04:18.418877 | 2026-04-28 01:04:18.535519 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 01:04:18.537977 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 01:04:19.139117 | 2026-04-28 01:04:19.139429 | PLAY [all] 2026-04-28 01:04:19.184552 | 2026-04-28 01:04:19.184724 | TASK [Copy inventory file for Zuul] 2026-04-28 01:04:20.076771 | ubuntu-focal | changed 2026-04-28 01:04:20.081886 | 2026-04-28 01:04:20.081965 | TASK [Switch "ansible_host" to private IP] 2026-04-28 01:04:20.334258 | ubuntu-focal | changed: 1 replacements made 2026-04-28 01:04:20.339506 | 2026-04-28 01:04:20.339574 | TASK [Run Molecule scenario] 2026-04-28 01:04:20.340474 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-04-28 01:04:20.977749 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-04-28 01:04:21.154546 | ubuntu-focal | Using CPython 3.13.7 2026-04-28 01:04:21.154624 | ubuntu-focal | Creating virtual environment at: .venv 2026-04-28 01:04:21.383017 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-04-28 01:04:21.387949 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-04-28 01:04:21.451588 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-04-28 01:04:21.534985 | ubuntu-focal | Downloading cryptography 2026-04-28 01:04:21.564611 | ubuntu-focal | Downloading pygments 2026-04-28 01:04:21.712109 | ubuntu-focal | Downloading ansible-core 2026-04-28 01:04:21.737234 | ubuntu-focal | Installed 36 packages in 24ms 2026-04-28 01:04:23.393349 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-04-28 01:04:23.393424 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-04-28 01:04:24.147710 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-28 01:04:24.147801 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-04-28 01:04:24.147825 | ubuntu-focal | INFO [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-28 01:04:24.147923 | ubuntu-focal | INFO [docker > prerun] Performing prerun with role_name_check=0... 2026-04-28 01:04:38.708511 | ubuntu-focal | INFO [docker > dependency] Executing 2026-04-28 01:04:38.708716 | ubuntu-focal | WARNING [docker > dependency] Missing roles requirements file: requirements.yml 2026-04-28 01:04:38.708971 | ubuntu-focal | WARNING [docker > dependency] Missing collections requirements file: collections.yml 2026-04-28 01:04:38.709132 | ubuntu-focal | WARNING [docker > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-28 01:04:38.714622 | ubuntu-focal | INFO [docker > cleanup] Executing 2026-04-28 01:04:38.714903 | ubuntu-focal | WARNING [docker > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-28 01:04:38.719857 | ubuntu-focal | INFO [docker > destroy] Executing 2026-04-28 01:04:38.719909 | ubuntu-focal | WARNING [docker > destroy] Skipping, '--destroy=never' requested. 2026-04-28 01:04:38.719975 | ubuntu-focal | INFO [docker > destroy] Executed: Successful 2026-04-28 01:04:38.724838 | ubuntu-focal | INFO [docker > syntax] Executing 2026-04-28 01:04:39.218211 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-28 01:04:39.218529 | 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-28 01:04:39.218763 | 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-28 01:04:39.218915 | ubuntu-focal | 2026-04-28 01:04:39.644066 | ubuntu-focal | 2026-04-28 01:04:39.644293 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml 2026-04-28 01:04:39.716714 | ubuntu-focal | INFO [docker > syntax] Executed: Successful 2026-04-28 01:04:39.727394 | ubuntu-focal | INFO [docker > create] Executing 2026-04-28 01:04:39.728811 | ubuntu-focal | WARNING [docker > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-28 01:04:39.733899 | ubuntu-focal | INFO [docker > prepare] Executing 2026-04-28 01:04:40.180430 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-28 01:04:40.180678 | 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-28 01:04:40.180889 | 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-28 01:04:40.181155 | ubuntu-focal | 2026-04-28 01:04:40.533543 | ubuntu-focal | 2026-04-28 01:04:40.533747 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-04-28 01:04:40.533975 | ubuntu-focal | 2026-04-28 01:04:40.534175 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-28 01:04:40.534369 | ubuntu-focal | Tuesday 28 April 2026 01:04:40 +0000 (0:00:00.031) 0:00:00.031 ********* 2026-04-28 01:04:41.638182 | 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-28 01:04:41.639852 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-28 01:04:41.640023 | ubuntu-focal | 2026-04-28 01:04:41.640201 | ubuntu-focal | TASK [Update "apt" cache] ****************************************************** 2026-04-28 01:04:41.640377 | ubuntu-focal | Tuesday 28 April 2026 01:04:41 +0000 (0:00:01.107) 0:00:01.138 ********* 2026-04-28 01:04:53.506603 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-28 01:04:53.506662 | ubuntu-focal | 2026-04-28 01:04:53.506734 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-28 01:04:53.506829 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-28 01:04:53.506927 | ubuntu-focal | 2026-04-28 01:04:53.506992 | ubuntu-focal | 2026-04-28 01:04:53.507084 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-28 01:04:53.507161 | ubuntu-focal | Tuesday 28 April 2026 01:04:53 +0000 (0:00:11.866) 0:00:13.005 ********* 2026-04-28 01:04:53.507239 | ubuntu-focal | =============================================================================== 2026-04-28 01:04:53.507319 | ubuntu-focal | Update "apt" cache ----------------------------------------------------- 11.87s 2026-04-28 01:04:53.507398 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.11s 2026-04-28 01:04:53.582069 | ubuntu-focal | INFO [docker > prepare] Executed: Successful 2026-04-28 01:04:53.587381 | ubuntu-focal | INFO [docker > converge] Executing 2026-04-28 01:04:54.023404 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-28 01:04:54.023485 | 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-28 01:04:54.023587 | 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-28 01:04:54.023658 | ubuntu-focal | 2026-04-28 01:04:54.407422 | ubuntu-focal | 2026-04-28 01:04:54.407561 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-04-28 01:04:54.407735 | ubuntu-focal | 2026-04-28 01:04:54.407981 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-28 01:04:54.408170 | ubuntu-focal | Tuesday 28 April 2026 01:04:54 +0000 (0:00:00.016) 0:00:00.016 ********* 2026-04-28 01:04:55.380306 | 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-28 01:04:55.382868 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-28 01:04:55.383044 | ubuntu-focal | 2026-04-28 01:04:55.383239 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-28 01:04:55.383426 | ubuntu-focal | Tuesday 28 April 2026 01:04:55 +0000 (0:00:00.978) 0:00:00.994 ********* 2026-04-28 01:04:55.805344 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-28 01:04:55.805479 | ubuntu-focal | 2026-04-28 01:04:55.805736 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 01:04:55.805869 | ubuntu-focal | Tuesday 28 April 2026 01:04:55 +0000 (0:00:00.422) 0:00:01.416 ********* 2026-04-28 01:04:55.832153 | ubuntu-focal | skipping: [ubuntu-focal] 2026-04-28 01:04:55.832276 | ubuntu-focal | 2026-04-28 01:04:55.832500 | ubuntu-focal | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-28 01:04:55.832630 | ubuntu-focal | Tuesday 28 April 2026 01:04:55 +0000 (0:00:00.026) 0:00:01.443 ********* 2026-04-28 01:04:56.183674 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-28 01:04:56.183893 | ubuntu-focal | 2026-04-28 01:04:56.184089 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-28 01:04:56.184345 | ubuntu-focal | Tuesday 28 April 2026 01:04:56 +0000 (0:00:00.351) 0:00:01.795 ********* 2026-04-28 01:04:56.262956 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-04-28 01:04:56.263173 | ubuntu-focal | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-04-28 01:04:56.263350 | ubuntu-focal | } 2026-04-28 01:04:56.263561 | ubuntu-focal | 2026-04-28 01:04:56.263909 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-28 01:04:56.264190 | ubuntu-focal | Tuesday 28 April 2026 01:04:56 +0000 (0:00:00.078) 0:00:01.874 ********* 2026-04-28 01:04:56.965167 | 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-28 01:04:56.969916 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-28 01:04:56.970018 | ubuntu-focal | 2026-04-28 01:04:56.970145 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-28 01:04:56.970271 | ubuntu-focal | Tuesday 28 April 2026 01:04:56 +0000 (0:00:00.707) 0:00:02.581 ********* 2026-04-28 01:04:56.994964 | ubuntu-focal | skipping: [ubuntu-focal] 2026-04-28 01:04:56.995056 | ubuntu-focal | 2026-04-28 01:04:56.995174 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 01:04:56.995286 | ubuntu-focal | Tuesday 28 April 2026 01:04:56 +0000 (0:00:00.025) 0:00:02.607 ********* 2026-04-28 01:04:57.014638 | ubuntu-focal | skipping: [ubuntu-focal] 2026-04-28 01:04:57.014733 | ubuntu-focal | 2026-04-28 01:04:57.014845 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-28 01:04:57.014960 | ubuntu-focal | Tuesday 28 April 2026 01:04:57 +0000 (0:00:00.019) 0:00:02.626 ********* 2026-04-28 01:04:57.222607 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-28 01:04:57.222831 | ubuntu-focal | 2026-04-28 01:04:57.223167 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 01:04:57.223439 | ubuntu-focal | Tuesday 28 April 2026 01:04:57 +0000 (0:00:00.207) 0:00:02.834 ********* 2026-04-28 01:04:58.283522 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-28 01:04:58.283761 | ubuntu-focal | 2026-04-28 01:04:58.284050 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-28 01:04:58.284250 | ubuntu-focal | Tuesday 28 April 2026 01:04:58 +0000 (0:00:01.060) 0:00:03.895 ********* 2026-04-28 01:04:58.334703 | 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-28 01:04:58.334844 | ubuntu-focal | 2026-04-28 01:04:58.335087 | ubuntu-focal | Task failed. 2026-04-28 01:04:58.335232 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:15:3 2026-04-28 01:04:58.335438 | ubuntu-focal | 2026-04-28 01:04:58.335581 | ubuntu-focal | 13 # under the License. 2026-04-28 01:04:58.335920 | ubuntu-focal | 14 2026-04-28 01:04:58.336112 | ubuntu-focal | 15 - name: Starting download of file 2026-04-28 01:04:58.336238 | ubuntu-focal | ^ column 3 2026-04-28 01:04:58.336390 | ubuntu-focal | 2026-04-28 01:04:58.336564 | ubuntu-focal | <<< caused by >>> 2026-04-28 01:04:58.336725 | ubuntu-focal | 2026-04-28 01:04:58.336890 | ubuntu-focal | Finalization of task args for 'ansible.builtin.debug' failed. 2026-04-28 01:04:58.337078 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:16:3 2026-04-28 01:04:58.337237 | ubuntu-focal | 2026-04-28 01:04:58.337402 | ubuntu-focal | 14 2026-04-28 01:04:58.337576 | ubuntu-focal | 15 - name: Starting download of file 2026-04-28 01:04:58.337747 | ubuntu-focal | 16 ansible.builtin.debug: 2026-04-28 01:04:58.337912 | ubuntu-focal | ^ column 3 2026-04-28 01:04:58.338065 | ubuntu-focal | 2026-04-28 01:04:58.338270 | ubuntu-focal | <<< caused by >>> 2026-04-28 01:04:58.338417 | ubuntu-focal | 2026-04-28 01:04:58.338617 | ubuntu-focal | Error while resolving value for 'msg'. 2026-04-28 01:04:58.338828 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:17:10 2026-04-28 01:04:58.338975 | ubuntu-focal | 2026-04-28 01:04:58.339147 | ubuntu-focal | 15 - name: Starting download of file 2026-04-28 01:04:58.339316 | ubuntu-focal | 16 ansible.builtin.debug: 2026-04-28 01:04:58.339483 | ubuntu-focal | 17 msg: "{{ download_artifact_url }}" 2026-04-28 01:04:58.339648 | ubuntu-focal | ^ column 10 2026-04-28 01:04:58.339845 | ubuntu-focal | 2026-04-28 01:04:58.340013 | ubuntu-focal | <<< caused by >>> 2026-04-28 01:04:58.340177 | ubuntu-focal | 2026-04-28 01:04:58.340353 | ubuntu-focal | object of type 'NoneType' has no attribute 'keys' 2026-04-28 01:04:58.340539 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/containerd/defaults/main.yml:15:22 2026-04-28 01:04:58.340694 | ubuntu-focal | 2026-04-28 01:04:58.340864 | ubuntu-focal | 13 # under the License. 2026-04-28 01:04:58.341036 | ubuntu-focal | 14 2026-04-28 01:04:58.341228 | ubuntu-focal | 15 containerd_versions: "{{ containerd_archive_checksums['amd64'].keys() | list | community.general.version_sort }}" 2026-04-28 01:04:58.341392 | ubuntu-focal | ^ column 22 2026-04-28 01:04:58.341552 | ubuntu-focal | 2026-04-28 01:04:58.341911 | 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-28 01:04:58.342098 | ubuntu-focal | 2026-04-28 01:04:58.342403 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-28 01:04:58.342623 | ubuntu-focal | ubuntu-focal : ok=7 changed=2 unreachable=0 failed=1 skipped=3 rescued=0 ignored=0 2026-04-28 01:04:58.342781 | ubuntu-focal | 2026-04-28 01:04:58.342993 | ubuntu-focal | 2026-04-28 01:04:58.343178 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-28 01:04:58.343274 | ubuntu-focal | Tuesday 28 April 2026 01:04:58 +0000 (0:00:00.053) 0:00:03.948 ********* 2026-04-28 01:04:58.343360 | ubuntu-focal | =============================================================================== 2026-04-28 01:04:58.343451 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 1.06s 2026-04-28 01:04:58.343530 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 0.98s 2026-04-28 01:04:58.343609 | ubuntu-focal | vexxhost.containers.download_artifact : Download item ------------------- 0.71s 2026-04-28 01:04:58.343686 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.42s 2026-04-28 01:04:58.343826 | ubuntu-focal | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.35s 2026-04-28 01:04:58.343886 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.21s 2026-04-28 01:04:58.343963 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.08s 2026-04-28 01:04:58.344040 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-28 01:04:58.344112 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.03s 2026-04-28 01:04:58.344189 | ubuntu-focal | vexxhost.containers.download_artifact : Extract archive ----------------- 0.03s 2026-04-28 01:04:58.344266 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.02s 2026-04-28 01:04:58.433357 | 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-28 01:04:58.433477 | ubuntu-focal | ERROR [docker > converge] Executed: Failed 2026-04-28 01:04:58.433662 | 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-28 01:04:58.961402 | ubuntu-focal | ERROR 2026-04-28 01:04:58.961619 | ubuntu-focal | { 2026-04-28 01:04:58.961650 | ubuntu-focal | "delta": "0:00:38.350267", 2026-04-28 01:04:58.961672 | ubuntu-focal | "end": "2026-04-28 01:04:58.505539", 2026-04-28 01:04:58.961693 | ubuntu-focal | "msg": "non-zero return code", 2026-04-28 01:04:58.961713 | ubuntu-focal | "rc": 2, 2026-04-28 01:04:58.961733 | ubuntu-focal | "start": "2026-04-28 01:04:20.155272" 2026-04-28 01:04:58.961753 | ubuntu-focal | } failure 2026-04-28 01:04:58.964618 | 2026-04-28 01:04:58.964665 | PLAY RECAP 2026-04-28 01:04:58.964712 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-28 01:04:58.964734 | 2026-04-28 01:04:59.081692 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 01:04:59.083868 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 01:04:59.685775 | 2026-04-28 01:04:59.767991 | PLAY [all] 2026-04-28 01:04:59.784644 | 2026-04-28 01:04:59.784722 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-28 01:04:59.840877 | ubuntu-focal | skipping: Conditional result was False 2026-04-28 01:04:59.852480 | 2026-04-28 01:04:59.852577 | TASK [fetch-output : Set log path for single node] 2026-04-28 01:04:59.897522 | ubuntu-focal | ok 2026-04-28 01:04:59.903100 | 2026-04-28 01:04:59.903174 | LOOP [fetch-output : Ensure local output dirs] 2026-04-28 01:05:00.305116 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/work/logs" 2026-04-28 01:05:00.520097 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/work/artifacts" 2026-04-28 01:05:00.723236 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/9f2ec046aeb248c8b3c5302fef9642a0/work/docs" 2026-04-28 01:05:00.739201 | 2026-04-28 01:05:00.739692 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-28 01:05:01.342125 | ubuntu-focal | changed: .d..t...... ./ 2026-04-28 01:05:01.342339 | ubuntu-focal | changed: All items complete 2026-04-28 01:05:01.342367 | 2026-04-28 01:05:01.744125 | ubuntu-focal | changed: .d..t...... ./ 2026-04-28 01:05:02.174699 | ubuntu-focal | changed: .d..t...... ./ 2026-04-28 01:05:02.196270 | 2026-04-28 01:05:02.196458 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-28 01:05:02.666126 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.007369 2026-04-28 01:05:02.920752 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.022934 2026-04-28 01:05:02.950992 | 2026-04-28 01:05:02.951173 | PLAY [all] 2026-04-28 01:05:02.960807 | 2026-04-28 01:05:02.960911 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-28 01:05:03.388030 | ubuntu-focal | changed 2026-04-28 01:05:03.393559 | 2026-04-28 01:05:03.393613 | PLAY RECAP 2026-04-28 01:05:03.393662 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-28 01:05:03.393686 | 2026-04-28 01:05:03.506761 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 01:05:03.509337 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-28 01:05:04.086935 | 2026-04-28 01:05:04.087088 | PLAY [localhost] 2026-04-28 01:05:04.097660 | 2026-04-28 01:05:04.097733 | TASK [Generate Zuul manifest] 2026-04-28 01:05:04.121924 | localhost | ok 2026-04-28 01:05:04.137472 | 2026-04-28 01:05:04.137550 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-28 01:05:04.473381 | localhost | changed 2026-04-28 01:05:04.494011 | 2026-04-28 01:05:04.494247 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-28 01:05:05.044866 | localhost | ok 2026-04-28 01:05:05.051372 | 2026-04-28 01:05:05.051437 | TASK [Upload logs] 2026-04-28 01:05:05.084410 | localhost | ok 2026-04-28 01:05:05.143229 | 2026-04-28 01:05:05.143355 | TASK [Set zuul-log-path fact] 2026-04-28 01:05:05.162735 | localhost | ok 2026-04-28 01:05:05.173041 | 2026-04-28 01:05:05.173107 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 01:05:05.200717 | localhost | ok 2026-04-28 01:05:05.207687 | 2026-04-28 01:05:05.207754 | TASK [upload-logs : Create log directories] 2026-04-28 01:05:05.615173 | localhost | changed 2026-04-28 01:05:05.619818 | 2026-04-28 01:05:05.619890 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-28 01:05:06.080493 | localhost -> localhost | ok: Runtime: 0:00:00.007073 2026-04-28 01:05:06.086287 | 2026-04-28 01:05:06.086353 | TASK [upload-logs : Upload logs to log server] 2026-04-28 01:05:07.144123 | localhost | Output suppressed because no_log was given 2026-04-28 01:05:07.149714 | 2026-04-28 01:05:07.149833 | LOOP [upload-logs : Compress console log and json output] 2026-04-28 01:05:07.198019 | localhost | skipping: Conditional result was False 2026-04-28 01:05:07.205825 | localhost | skipping: Conditional result was False 2026-04-28 01:05:07.221978 | 2026-04-28 01:05:07.222146 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-28 01:05:07.264860 | localhost | skipping: Conditional result was False 2026-04-28 01:05:07.265166 | 2026-04-28 01:05:07.269574 | localhost | skipping: Conditional result was False 2026-04-28 01:05:07.286626 | 2026-04-28 01:05:07.286787 | LOOP [upload-logs : Upload console log and json output]