2026-04-17 00:58:33.893905 | Job console starting
2026-04-17 00:58:33.904388 | Updating git repos
2026-04-17 00:58:33.929809 | Cloning repos into workspace
2026-04-17 00:58:33.990318 | Restoring repo states
2026-04-17 00:58:33.999101 | Merging changes
2026-04-17 00:58:34.255211 | Checking out repos
2026-04-17 00:58:34.288479 | Preparing playbooks
2026-04-17 00:58:38.985331 | Running Ansible setup
2026-04-17 00:58:42.413559 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 00:58:43.030350 | 
2026-04-17 00:58:43.030489 | PLAY [localhost]
2026-04-17 00:58:43.038111 | 
2026-04-17 00:58:43.038183 | TASK [Gathering Facts]
2026-04-17 00:58:43.982489 | localhost | ok
2026-04-17 00:58:43.994451 | 
2026-04-17 00:58:43.994586 | TASK [Setup log path fact]
2026-04-17 00:58:44.016607 | localhost | ok
2026-04-17 00:58:44.029284 | 
2026-04-17 00:58:44.029353 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 00:58:44.060627 | localhost | ok
2026-04-17 00:58:44.069219 | 
2026-04-17 00:58:44.069327 | TASK [emit-job-header : Print job information]
2026-04-17 00:58:44.108345 | # Job Information
2026-04-17 00:58:44.108495 | Ansible Version: 2.16.16
2026-04-17 00:58:44.108527 | Job: ansible-collection-containers-molecule-docker-ubuntu-focal
2026-04-17 00:58:44.108549 | Pipeline: check
2026-04-17 00:58:44.108569 | Executor: 0a8996d2b663
2026-04-17 00:58:44.108588 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-17 00:58:44.108611 | Event ID: 64e04cd0-39f8-11f1-9088-4230c452b1b4
2026-04-17 00:58:44.111336 | 
2026-04-17 00:58:44.111413 | LOOP [emit-job-header : Print node information]
2026-04-17 00:58:44.215633 | localhost | ok:
2026-04-17 00:58:44.215847 | localhost | # Node Information
2026-04-17 00:58:44.215877 | localhost | Inventory Hostname: ubuntu-focal
2026-04-17 00:58:44.215898 | localhost | Hostname: np0000166926
2026-04-17 00:58:44.216106 | localhost | Username: zuul
2026-04-17 00:58:44.216129 | localhost | Distro: Ubuntu 20.04
2026-04-17 00:58:44.216149 | localhost | Provider: yul1
2026-04-17 00:58:44.216168 | localhost | Region: ca-ymq-1
2026-04-17 00:58:44.216186 | localhost | Label: ubuntu-focal
2026-04-17 00:58:44.216205 | localhost | Product Name: OpenStack Nova
2026-04-17 00:58:44.216223 | localhost | Interface IP: 199.204.45.57
2026-04-17 00:58:44.225392 | 
2026-04-17 00:58:44.225569 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-17 00:58:44.608518 | localhost -> localhost | changed
2026-04-17 00:58:44.614024 | 
2026-04-17 00:58:44.614098 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-17 00:58:45.764196 | localhost -> localhost | changed
2026-04-17 00:58:45.770186 | 
2026-04-17 00:58:45.770248 | PLAY [all]
2026-04-17 00:58:45.778890 | 
2026-04-17 00:58:45.778962 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-17 00:58:46.027375 | ubuntu-focal -> localhost | ok
2026-04-17 00:58:46.037423 | 
2026-04-17 00:58:46.037512 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-17 00:58:46.069621 | ubuntu-focal | ok
2026-04-17 00:58:46.083751 | ubuntu-focal | included: /var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-17 00:58:46.089426 | 
2026-04-17 00:58:46.089487 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-17 00:58:47.125658 | ubuntu-focal -> localhost | Generating public/private rsa key pair.
2026-04-17 00:58:47.125941 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/work/90e9d1181e7d40fa9ab2baf5c8e2c9ec_id_rsa
2026-04-17 00:58:47.125988 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/work/90e9d1181e7d40fa9ab2baf5c8e2c9ec_id_rsa.pub
2026-04-17 00:58:47.126021 | ubuntu-focal -> localhost | The key fingerprint is:
2026-04-17 00:58:47.126053 | ubuntu-focal -> localhost | SHA256:oHFJ0X4yfvG/Ij7BNctWs5Gdl/x6AMXkqPMergzW9CQ zuul-build-sshkey
2026-04-17 00:58:47.126099 | ubuntu-focal -> localhost | The key's randomart image is:
2026-04-17 00:58:47.126131 | ubuntu-focal -> localhost | +---[RSA 3072]----+
2026-04-17 00:58:47.126169 | ubuntu-focal -> localhost | |      oo     o.  |
2026-04-17 00:58:47.126201 | ubuntu-focal -> localhost | |     . ..    oo  |
2026-04-17 00:58:47.126230 | ubuntu-focal -> localhost | |    . +.    ..ooo|
2026-04-17 00:58:47.126259 | ubuntu-focal -> localhost | |     + .+ o.+ =+o|
2026-04-17 00:58:47.126287 | ubuntu-focal -> localhost | |    .  .S=E=.= +o|
2026-04-17 00:58:47.126316 | ubuntu-focal -> localhost | |        .o+*= o .|
2026-04-17 00:58:47.126344 | ubuntu-focal -> localhost | |        o..o+. o |
2026-04-17 00:58:47.126372 | ubuntu-focal -> localhost | |       . ooo..o .|
2026-04-17 00:58:47.126405 | ubuntu-focal -> localhost | |         .++o..o |
2026-04-17 00:58:47.126433 | ubuntu-focal -> localhost | +----[SHA256]-----+
2026-04-17 00:58:47.126525 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.555226
2026-04-17 00:58:47.134281 | 
2026-04-17 00:58:47.134378 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-17 00:58:47.170056 | ubuntu-focal | ok
2026-04-17 00:58:47.181051 | ubuntu-focal | included: /var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-17 00:58:47.188458 | 
2026-04-17 00:58:47.188528 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-17 00:58:47.206575 | ubuntu-focal | skipping: Conditional result was False
2026-04-17 00:58:47.218494 | 
2026-04-17 00:58:47.218626 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-17 00:58:47.715029 | ubuntu-focal | changed
2026-04-17 00:58:47.720011 | 
2026-04-17 00:58:47.720079 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-17 00:58:47.895991 | ubuntu-focal | ok
2026-04-17 00:58:47.902339 | 
2026-04-17 00:58:47.902407 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-17 00:58:48.503148 | ubuntu-focal | changed
2026-04-17 00:58:48.510418 | 
2026-04-17 00:58:48.510493 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-17 00:58:48.952778 | ubuntu-focal | changed
2026-04-17 00:58:48.961158 | 
2026-04-17 00:58:48.961227 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-17 00:58:48.986180 | ubuntu-focal | skipping: Conditional result was False
2026-04-17 00:58:48.994087 | 
2026-04-17 00:58:48.994179 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-17 00:58:49.413745 | ubuntu-focal -> localhost | changed
2026-04-17 00:58:49.425446 | 
2026-04-17 00:58:49.425516 | TASK [add-build-sshkey : Add back temp key]
2026-04-17 00:58:49.714957 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/work/90e9d1181e7d40fa9ab2baf5c8e2c9ec_id_rsa (zuul-build-sshkey)
2026-04-17 00:58:49.715164 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.013201
2026-04-17 00:58:49.720230 | 
2026-04-17 00:58:49.720293 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-17 00:58:50.022805 | ubuntu-focal | ok
2026-04-17 00:58:50.029679 | 
2026-04-17 00:58:50.108669 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-17 00:58:50.140616 | ubuntu-focal | skipping: Conditional result was False
2026-04-17 00:58:50.156567 | 
2026-04-17 00:58:50.156646 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-17 00:58:50.511306 | ubuntu-focal | ok
2026-04-17 00:58:50.619367 | 
2026-04-17 00:58:50.619443 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-17 00:58:51.428122 | ubuntu-focal | Output suppressed because no_log was given
2026-04-17 00:58:51.440355 | 
2026-04-17 00:58:51.440422 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-17 00:58:51.621541 | ubuntu-focal | ok: "logs"
2026-04-17 00:58:51.622567 | ubuntu-focal | ok: All items complete
2026-04-17 00:58:51.622614 | 
2026-04-17 00:58:51.776212 | ubuntu-focal | ok: "artifacts"
2026-04-17 00:58:51.928980 | ubuntu-focal | ok: "docs"
2026-04-17 00:58:51.941382 | 
2026-04-17 00:58:51.941532 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-17 00:58:52.132890 | ubuntu-focal | changed: "logs"
2026-04-17 00:58:52.355808 | ubuntu-focal | changed: "artifacts"
2026-04-17 00:58:52.452588 | ubuntu-focal | changed: "docs"
2026-04-17 00:58:52.467602 | 
2026-04-17 00:58:52.467763 | PLAY RECAP
2026-04-17 00:58:52.467829 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 00:58:52.467873 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-17 00:58:52.467904 | 
2026-04-17 00:58:52.632782 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 00:58:52.634384 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 00:58:53.234741 | 
2026-04-17 00:58:53.234894 | PLAY [all]
2026-04-17 00:58:53.246802 | 
2026-04-17 00:58:53.246889 | TASK [setup-uv : Extract archive]
2026-04-17 00:58:55.363397 | ubuntu-focal | changed
2026-04-17 00:58:55.373427 | 
2026-04-17 00:58:55.373516 | TASK [setup-uv : Print version]
2026-04-17 00:58:55.758763 | ubuntu-focal | uv 0.8.13
2026-04-17 00:58:55.921208 | ubuntu-focal | ok: Runtime: 0:00:00.006159
2026-04-17 00:58:55.937709 | 
2026-04-17 00:58:55.937904 | PLAY RECAP
2026-04-17 00:58:55.938007 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 00:58:55.938086 | 
2026-04-17 00:58:56.131990 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 00:58:56.135461 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 00:58:56.710930 | 
2026-04-17 00:58:56.711239 | PLAY [all]
2026-04-17 00:58:56.725044 | 
2026-04-17 00:58:56.725161 | TASK [Copy inventory file for Zuul]
2026-04-17 00:58:57.569880 | ubuntu-focal | changed
2026-04-17 00:58:57.581767 | 
2026-04-17 00:58:57.582174 | TASK [Switch "ansible_host" to private IP]
2026-04-17 00:58:57.846074 | ubuntu-focal | changed: 1 replacements made
2026-04-17 00:58:57.851372 | 
2026-04-17 00:58:57.851438 | TASK [Run Molecule scenario]
2026-04-17 00:58:58.495431 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-04-17 00:58:59.279523 | ubuntu-focal |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-04-17 00:58:59.455824 | ubuntu-focal | Using CPython 3.13.7
2026-04-17 00:58:59.455892 | ubuntu-focal | Creating virtual environment at: .venv
2026-04-17 00:58:59.756095 | ubuntu-focal | Downloading pygments (1.2MiB)
2026-04-17 00:58:59.756793 | ubuntu-focal | Downloading ansible-core (2.3MiB)
2026-04-17 00:58:59.792312 | ubuntu-focal | Downloading cryptography (4.3MiB)
2026-04-17 00:59:00.334675 | ubuntu-focal |  Downloading pygments
2026-04-17 00:59:00.466316 | ubuntu-focal |  Downloading ansible-core
2026-04-17 00:59:00.587475 | ubuntu-focal |  Downloading cryptography
2026-04-17 00:59:00.609783 | ubuntu-focal | Installed 36 packages in 21ms
2026-04-17 00:59:01.696391 | ubuntu-focal | INFO     Collection 'vexxhost.containers' detected.
2026-04-17 00:59:01.696466 | ubuntu-focal | INFO     Scenarios will be used from 'extensions/molecule'
2026-04-17 00:59:02.499117 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-04-17 00:59:02.499200 | ubuntu-focal | INFO     default scenario not found, disabling shared state.
2026-04-17 00:59:02.499250 | ubuntu-focal | INFO     [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-17 00:59:02.499321 | ubuntu-focal | INFO     [docker > prerun] Performing prerun with role_name_check=0...
2026-04-17 00:59:11.495673 | ubuntu-focal | INFO     [docker > dependency] Executing
2026-04-17 00:59:11.495815 | ubuntu-focal | WARNING  [docker > dependency] Missing roles requirements file: requirements.yml
2026-04-17 00:59:11.496040 | ubuntu-focal | WARNING  [docker > dependency] Missing collections requirements file: collections.yml
2026-04-17 00:59:11.496084 | ubuntu-focal | WARNING  [docker > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-04-17 00:59:11.501352 | ubuntu-focal | INFO     [docker > cleanup] Executing
2026-04-17 00:59:11.501639 | ubuntu-focal | WARNING  [docker > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-17 00:59:11.515047 | ubuntu-focal | INFO     [docker > destroy] Executing
2026-04-17 00:59:11.515106 | ubuntu-focal | WARNING  [docker > destroy] Skipping, '--destroy=never' requested.
2026-04-17 00:59:11.515299 | ubuntu-focal | INFO     [docker > destroy] Executed: Successful
2026-04-17 00:59:11.526457 | ubuntu-focal | INFO     [docker > syntax] Executing
2026-04-17 00:59:12.012497 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-17 00:59:12.012733 | 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-17 00:59:12.013011 | 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-17 00:59:12.013134 | ubuntu-focal |
2026-04-17 00:59:12.441417 | ubuntu-focal |
2026-04-17 00:59:12.441609 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-17 00:59:12.505796 | ubuntu-focal | INFO     [docker > syntax] Executed: Successful
2026-04-17 00:59:12.513620 | ubuntu-focal | INFO     [docker > create] Executing
2026-04-17 00:59:12.515418 | ubuntu-focal | WARNING  [docker > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-17 00:59:12.522463 | ubuntu-focal | INFO     [docker > prepare] Executing
2026-04-17 00:59:12.973461 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-17 00:59:12.973664 | 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-17 00:59:12.973896 | 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-17 00:59:12.974077 | ubuntu-focal |
2026-04-17 00:59:13.312723 | ubuntu-focal |
2026-04-17 00:59:13.312897 | ubuntu-focal | PLAY [Prepare] *****************************************************************
2026-04-17 00:59:13.313081 | ubuntu-focal |
2026-04-17 00:59:13.313283 | ubuntu-focal | TASK [Gathering Facts] *********************************************************
2026-04-17 00:59:13.313475 | ubuntu-focal | Friday 17 April 2026  00:59:13 +0000 (0:00:00.029)       0:00:00.029 **********
2026-04-17 00:59:14.537298 | 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-17 00:59:14.539010 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-17 00:59:14.539192 | ubuntu-focal |
2026-04-17 00:59:14.539394 | ubuntu-focal | TASK [Update "apt" cache] ******************************************************
2026-04-17 00:59:14.539653 | ubuntu-focal | Friday 17 April 2026  00:59:14 +0000 (0:00:01.227)       0:00:01.256 **********
2026-04-17 01:00:10.885140 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-17 01:00:10.885288 | ubuntu-focal |
2026-04-17 01:00:10.885462 | ubuntu-focal | PLAY RECAP *********************************************************************
2026-04-17 01:00:10.885652 | ubuntu-focal | ubuntu-focal               : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-04-17 01:00:10.885800 | ubuntu-focal |
2026-04-17 01:00:10.885948 | ubuntu-focal |
2026-04-17 01:00:10.886124 | ubuntu-focal | TASKS RECAP ********************************************************************
2026-04-17 01:00:10.886291 | ubuntu-focal | Friday 17 April 2026  01:00:10 +0000 (0:00:56.345)       0:00:57.602 **********
2026-04-17 01:00:10.886451 | ubuntu-focal | ===============================================================================
2026-04-17 01:00:10.886615 | ubuntu-focal | Update "apt" cache ----------------------------------------------------- 56.35s
2026-04-17 01:00:10.886836 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.23s
2026-04-17 01:00:10.957130 | ubuntu-focal | INFO     [docker > prepare] Executed: Successful
2026-04-17 01:00:10.968506 | ubuntu-focal | INFO     [docker > converge] Executing
2026-04-17 01:00:11.409776 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-17 01:00:11.409929 | 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-17 01:00:11.410117 | 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-17 01:00:11.410275 | ubuntu-focal |
2026-04-17 01:00:11.787060 | ubuntu-focal |
2026-04-17 01:00:11.787196 | ubuntu-focal | PLAY [Converge] ****************************************************************
2026-04-17 01:00:11.787344 | ubuntu-focal |
2026-04-17 01:00:11.787521 | ubuntu-focal | TASK [Gathering Facts] *********************************************************
2026-04-17 01:00:11.787689 | ubuntu-focal | Friday 17 April 2026  01:00:11 +0000 (0:00:00.016)       0:00:00.016 **********
2026-04-17 01:00:12.743368 | 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-17 01:00:12.745935 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-17 01:00:12.746122 | ubuntu-focal |
2026-04-17 01:00:12.746319 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-17 01:00:12.746496 | ubuntu-focal | Friday 17 April 2026  01:00:12 +0000 (0:00:00.961)       0:00:00.978 **********
2026-04-17 01:00:13.162546 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-17 01:00:13.162671 | ubuntu-focal |
2026-04-17 01:00:13.162856 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-17 01:00:13.163034 | ubuntu-focal | Friday 17 April 2026  01:00:13 +0000 (0:00:00.416)       0:00:01.394 **********
2026-04-17 01:00:13.188630 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-17 01:00:13.188784 | ubuntu-focal |
2026-04-17 01:00:13.189018 | ubuntu-focal | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-04-17 01:00:13.189210 | ubuntu-focal | Friday 17 April 2026  01:00:13 +0000 (0:00:00.026)       0:00:01.420 **********
2026-04-17 01:00:13.553904 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-17 01:00:13.554059 | ubuntu-focal |
2026-04-17 01:00:13.554247 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-17 01:00:13.554424 | ubuntu-focal | Friday 17 April 2026  01:00:13 +0000 (0:00:00.364)       0:00:01.785 **********
2026-04-17 01:00:13.635574 | ubuntu-focal | ok: [ubuntu-focal] => {
2026-04-17 01:00:13.635735 | ubuntu-focal |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-04-17 01:00:13.635899 | ubuntu-focal | }
2026-04-17 01:00:13.636057 | ubuntu-focal |
2026-04-17 01:00:13.636236 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-04-17 01:00:13.636437 | ubuntu-focal | Friday 17 April 2026  01:00:13 +0000 (0:00:00.081)       0:00:01.867 **********
2026-04-17 01:00:14.518463 | 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-17 01:00:14.524056 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-17 01:00:14.524231 | ubuntu-focal |
2026-04-17 01:00:14.524504 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-04-17 01:00:14.524783 | ubuntu-focal | Friday 17 April 2026  01:00:14 +0000 (0:00:00.888)       0:00:02.756 **********
2026-04-17 01:00:14.546422 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-17 01:00:14.546616 | ubuntu-focal |
2026-04-17 01:00:14.546807 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-17 01:00:14.546997 | ubuntu-focal | Friday 17 April 2026  01:00:14 +0000 (0:00:00.022)       0:00:02.778 **********
2026-04-17 01:00:14.572336 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-17 01:00:14.572521 | ubuntu-focal |
2026-04-17 01:00:14.572713 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-17 01:00:14.572889 | ubuntu-focal | Friday 17 April 2026  01:00:14 +0000 (0:00:00.026)       0:00:02.804 **********
2026-04-17 01:00:14.793721 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-17 01:00:14.793866 | ubuntu-focal |
2026-04-17 01:00:14.794062 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-17 01:00:14.794238 | ubuntu-focal | Friday 17 April 2026  01:00:14 +0000 (0:00:00.221)       0:00:03.025 **********
2026-04-17 01:00:15.833082 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-17 01:00:15.833231 | ubuntu-focal |
2026-04-17 01:00:15.833426 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-17 01:00:15.833602 | ubuntu-focal | Friday 17 April 2026  01:00:15 +0000 (0:00:01.039)       0:00:04.064 **********
2026-04-17 01:00:15.888222 | 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-17 01:00:15.888422 | ubuntu-focal |
2026-04-17 01:00:15.888658 | ubuntu-focal | Task failed.
2026-04-17 01:00:15.888849 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:15:3
2026-04-17 01:00:15.889018 | ubuntu-focal |
2026-04-17 01:00:15.889199 | ubuntu-focal | 13 # under the License.
2026-04-17 01:00:15.889372 | ubuntu-focal | 14
2026-04-17 01:00:15.889556 | ubuntu-focal | 15 - name: Starting download of file
2026-04-17 01:00:15.889726 | ubuntu-focal |      ^ column 3
2026-04-17 01:00:15.889887 | ubuntu-focal |
2026-04-17 01:00:15.890063 | ubuntu-focal | <<< caused by >>>
2026-04-17 01:00:15.890221 | ubuntu-focal |
2026-04-17 01:00:15.890413 | ubuntu-focal | Finalization of task args for 'ansible.builtin.debug' failed.
2026-04-17 01:00:15.890604 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:16:3
2026-04-17 01:00:15.890765 | ubuntu-focal |
2026-04-17 01:00:15.890938 | ubuntu-focal | 14
2026-04-17 01:00:15.891118 | ubuntu-focal | 15 - name: Starting download of file
2026-04-17 01:00:15.891295 | ubuntu-focal | 16   ansible.builtin.debug:
2026-04-17 01:00:15.891467 | ubuntu-focal |      ^ column 3
2026-04-17 01:00:15.891628 | ubuntu-focal |
2026-04-17 01:00:15.891807 | ubuntu-focal | <<< caused by >>>
2026-04-17 01:00:15.891970 | ubuntu-focal |
2026-04-17 01:00:15.892151 | ubuntu-focal | Error while resolving value for 'msg'.
2026-04-17 01:00:15.892365 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:17:10
2026-04-17 01:00:15.892571 | ubuntu-focal |
2026-04-17 01:00:15.892755 | ubuntu-focal | 15 - name: Starting download of file
2026-04-17 01:00:15.892936 | ubuntu-focal | 16   ansible.builtin.debug:
2026-04-17 01:00:15.893117 | ubuntu-focal | 17     msg: "{{ download_artifact_url }}"
2026-04-17 01:00:15.893292 | ubuntu-focal |             ^ column 10
2026-04-17 01:00:15.893454 | ubuntu-focal |
2026-04-17 01:00:15.893631 | ubuntu-focal | <<< caused by >>>
2026-04-17 01:00:15.893792 | ubuntu-focal |
2026-04-17 01:00:15.893973 | ubuntu-focal | object of type 'NoneType' has no attribute 'keys'
2026-04-17 01:00:15.894165 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/containerd/defaults/main.yml:15:22
2026-04-17 01:00:15.894325 | ubuntu-focal |
2026-04-17 01:00:15.894502 | ubuntu-focal | 13 # under the License.
2026-04-17 01:00:15.894683 | ubuntu-focal | 14
2026-04-17 01:00:15.894879 | ubuntu-focal | 15 containerd_versions: "{{ containerd_archive_checksums['amd64'].keys() | list | community.general.version_sort }}"
2026-04-17 01:00:15.895052 | ubuntu-focal |                         ^ column 22
2026-04-17 01:00:15.895214 | ubuntu-focal |
2026-04-17 01:00:15.895548 | 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-17 01:00:15.895726 | ubuntu-focal |
2026-04-17 01:00:15.895904 | ubuntu-focal | PLAY RECAP *********************************************************************
2026-04-17 01:00:15.896105 | ubuntu-focal | ubuntu-focal               : ok=7    changed=2    unreachable=0    failed=1    skipped=3    rescued=0    ignored=0
2026-04-17 01:00:15.896268 | ubuntu-focal |
2026-04-17 01:00:15.896457 | ubuntu-focal |
2026-04-17 01:00:15.896695 | ubuntu-focal | TASKS RECAP ********************************************************************
2026-04-17 01:00:15.896879 | ubuntu-focal | Friday 17 April 2026  01:00:15 +0000 (0:00:00.057)       0:00:04.122 **********
2026-04-17 01:00:15.896959 | ubuntu-focal | ===============================================================================
2026-04-17 01:00:15.897036 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 1.04s
2026-04-17 01:00:15.897113 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 0.96s
2026-04-17 01:00:15.897187 | ubuntu-focal | vexxhost.containers.download_artifact : Download item ------------------- 0.89s
2026-04-17 01:00:15.897264 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.42s
2026-04-17 01:00:15.897341 | ubuntu-focal | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.36s
2026-04-17 01:00:15.897416 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.22s
2026-04-17 01:00:15.897493 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.08s
2026-04-17 01:00:15.897568 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-04-17 01:00:15.897644 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.03s
2026-04-17 01:00:15.897720 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.03s
2026-04-17 01:00:15.897795 | ubuntu-focal | vexxhost.containers.download_artifact : Extract archive ----------------- 0.02s
2026-04-17 01:00:16.065220 | 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-17 01:00:16.065366 | ubuntu-focal | ERROR    [docker > converge] Executed: Failed
2026-04-17 01:00:16.065502 | 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-17 01:00:16.581305 | ubuntu-focal | ERROR
2026-04-17 01:00:16.581578 | ubuntu-focal | {
2026-04-17 01:00:16.581612 | ubuntu-focal |   "delta": "0:01:17.938360",
2026-04-17 01:00:16.581633 | ubuntu-focal |   "end": "2026-04-17 01:00:16.159493",
2026-04-17 01:00:16.581655 | ubuntu-focal |   "msg": "non-zero return code",
2026-04-17 01:00:16.581675 | ubuntu-focal |   "rc": 2,
2026-04-17 01:00:16.581696 | ubuntu-focal |   "start": "2026-04-17 00:58:58.221133"
2026-04-17 01:00:16.581714 | ubuntu-focal | }
failure
2026-04-17 01:00:16.584899 | 
2026-04-17 01:00:16.584948 | PLAY RECAP
2026-04-17 01:00:16.585016 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-17 01:00:16.585039 | 
2026-04-17 01:00:16.744510 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 01:00:16.746219 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 01:00:17.359280 | 
2026-04-17 01:00:17.410303 | PLAY [all]
2026-04-17 01:00:17.427912 | 
2026-04-17 01:00:17.428012 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-17 01:00:17.473450 | ubuntu-focal | skipping: Conditional result was False
2026-04-17 01:00:17.483798 | 
2026-04-17 01:00:17.483910 | TASK [fetch-output : Set log path for single node]
2026-04-17 01:00:17.530530 | ubuntu-focal | ok
2026-04-17 01:00:17.537118 | 
2026-04-17 01:00:17.537212 | LOOP [fetch-output : Ensure local output dirs]
2026-04-17 01:00:17.973140 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/work/logs"
2026-04-17 01:00:18.209421 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/work/artifacts"
2026-04-17 01:00:18.419333 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/90e9d1181e7d40fa9ab2baf5c8e2c9ec/work/docs"
2026-04-17 01:00:18.436379 | 
2026-04-17 01:00:18.436515 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-17 01:00:19.051765 | ubuntu-focal | ok
2026-04-17 01:00:19.052148 | ubuntu-focal | changed: All items complete
2026-04-17 01:00:19.052207 | 
2026-04-17 01:00:19.466561 | ubuntu-focal | changed: .d..t...... ./
2026-04-17 01:00:19.885809 | ubuntu-focal | changed: .d..t...... ./
2026-04-17 01:00:19.911580 | 
2026-04-17 01:00:19.911753 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-17 01:00:20.335823 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.008772
2026-04-17 01:00:20.581958 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.006950
2026-04-17 01:00:20.604584 | 
2026-04-17 01:00:20.604748 | PLAY [all]
2026-04-17 01:00:20.612545 | 
2026-04-17 01:00:20.612618 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-17 01:00:21.041520 | ubuntu-focal | changed
2026-04-17 01:00:21.049429 | 
2026-04-17 01:00:21.049520 | PLAY RECAP
2026-04-17 01:00:21.049596 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-17 01:00:21.049634 | 
2026-04-17 01:00:21.213083 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 01:00:21.215345 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-17 01:00:21.772084 | 
2026-04-17 01:00:21.772208 | PLAY [localhost]
2026-04-17 01:00:21.782268 | 
2026-04-17 01:00:21.782338 | TASK [Generate Zuul manifest]
2026-04-17 01:00:21.803066 | localhost | ok
2026-04-17 01:00:21.819806 | 
2026-04-17 01:00:21.819885 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-17 01:00:22.161877 | localhost | changed
2026-04-17 01:00:22.173499 | 
2026-04-17 01:00:22.173572 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-17 01:00:22.222291 | localhost | ok
2026-04-17 01:00:22.228818 | 
2026-04-17 01:00:22.228887 | TASK [Upload logs]
2026-04-17 01:00:22.247653 | localhost | ok
2026-04-17 01:00:22.306944 | 
2026-04-17 01:00:22.307107 | TASK [Set zuul-log-path fact]
2026-04-17 01:00:22.325106 | localhost | ok
2026-04-17 01:00:22.335610 | 
2026-04-17 01:00:22.335686 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 01:00:22.374340 | localhost | ok
2026-04-17 01:00:22.381470 | 
2026-04-17 01:00:22.381548 | TASK [upload-logs : Create log directories]
2026-04-17 01:00:22.807841 | localhost | changed
2026-04-17 01:00:22.813990 | 
2026-04-17 01:00:22.814069 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-17 01:00:23.282651 | localhost -> localhost | ok: Runtime: 0:00:00.006650
2026-04-17 01:00:23.289567 | 
2026-04-17 01:00:23.289657 | TASK [upload-logs : Upload logs to log server]
2026-04-17 01:00:23.787002 | localhost | Output suppressed because no_log was given
2026-04-17 01:00:23.791956 | 
2026-04-17 01:00:23.792045 | LOOP [upload-logs : Compress console log and json output]
2026-04-17 01:00:23.841252 | localhost | skipping: Conditional result was False
2026-04-17 01:00:23.848493 | localhost | skipping: Conditional result was False
2026-04-17 01:00:23.866822 | 
2026-04-17 01:00:23.867011 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-17 01:00:23.918482 | localhost | skipping: Conditional result was False
2026-04-17 01:00:23.918945 | 
2026-04-17 01:00:23.922559 | localhost | skipping: Conditional result was False
2026-04-17 01:00:23.933262 | 
2026-04-17 01:00:23.933454 | LOOP [upload-logs : Upload console log and json output]