2025-11-23 00:21:07.596560 | Job console starting...
2025-11-23 00:21:07.609725 | Updating repositories
2025-11-23 00:21:07.800065 | Preparing job workspace
2025-11-23 00:21:13.208835 | Running Ansible setup...
2025-11-23 00:21:20.829487 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-23 00:21:21.479872 | 
2025-11-23 00:21:21.480004 | PLAY [localhost]
2025-11-23 00:21:21.488355 | 
2025-11-23 00:21:21.488435 | TASK [Gathering Facts]
2025-11-23 00:21:22.577625 | localhost | ok
2025-11-23 00:21:22.608101 | 
2025-11-23 00:21:22.608293 | TASK [Setup log path fact]
2025-11-23 00:21:22.634092 | localhost | ok
2025-11-23 00:21:22.661804 | 
2025-11-23 00:21:22.661953 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-23 00:21:22.708025 | localhost | ok
2025-11-23 00:21:22.733080 | 
2025-11-23 00:21:22.733301 | TASK [emit-job-header : Print job information]
2025-11-23 00:21:22.775412 | # Job Information
2025-11-23 00:21:22.775588 | Ansible Version: 2.15.12
2025-11-23 00:21:22.775621 | Job: ansible-test-sanity-docker-stable-2.9
2025-11-23 00:21:22.775642 | Pipeline: periodic
2025-11-23 00:21:22.775660 | Executor: ze02.softwarefactory-project.io
2025-11-23 00:21:22.775713 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-23 00:21:22.775735 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/9df/ansible/9df6cb49010b4ed789019cfec1cd2958/
2025-11-23 00:21:22.775754 | Event ID: 957440c764f64053b5278469ab93a4cd
2025-11-23 00:21:22.779595 | 
2025-11-23 00:21:22.779660 | LOOP [emit-job-header : Print node information]
2025-11-23 00:21:22.878393 | localhost | ok:
2025-11-23 00:21:22.878569 | localhost | # Node Information
2025-11-23 00:21:22.878595 | localhost | Inventory Hostname: controller
2025-11-23 00:21:22.878615 | localhost | Hostname: np0005532479
2025-11-23 00:21:22.878634 | localhost | Username: zuul
2025-11-23 00:21:22.878656 | localhost | Distro: Fedora 37
2025-11-23 00:21:22.878695 | localhost | Provider: ansible-vexxhost-ams1
2025-11-23 00:21:22.878714 | localhost | Region: ams1
2025-11-23 00:21:22.878731 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-23 00:21:22.878748 | localhost | Product Name: OpenStack Nova
2025-11-23 00:21:22.878765 | localhost | Interface IP: 38.129.16.86
2025-11-23 00:21:22.895029 | 
2025-11-23 00:21:22.895338 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-23 00:21:23.265760 | localhost -> localhost | changed
2025-11-23 00:21:23.271696 | 
2025-11-23 00:21:23.271770 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-23 00:21:24.208233 | localhost -> localhost | changed
2025-11-23 00:21:24.239353 | 
2025-11-23 00:21:24.239457 | PLAY [all:!appliance*]
2025-11-23 00:21:24.263832 | 
2025-11-23 00:21:24.263926 | TASK [include_role : start-zuul-console]
2025-11-23 00:21:24.284726 | controller | ok
2025-11-23 00:21:24.300267 | 
2025-11-23 00:21:24.300376 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-23 00:21:25.260542 | controller | ok
2025-11-23 00:21:25.285209 | 
2025-11-23 00:21:25.285302 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-23 00:21:27.801035 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-23 00:21:27.816895 | 
2025-11-23 00:21:27.817016 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-23 00:21:28.179160 | controller | skipping: Conditional result was False
2025-11-23 00:21:28.187659 | 
2025-11-23 00:21:28.187770 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-23 00:21:28.223371 | controller | skipping: Conditional result was False
2025-11-23 00:21:28.232777 | 
2025-11-23 00:21:28.232897 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-23 00:21:28.269048 | controller | skipping: Conditional result was False
2025-11-23 00:21:28.282033 | 
2025-11-23 00:21:28.282175 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-23 00:21:28.320345 | controller | skipping: Conditional result was False
2025-11-23 00:21:28.335116 | 
2025-11-23 00:21:28.335349 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-23 00:21:28.373915 | controller | skipping: Conditional result was False
2025-11-23 00:21:28.386631 | 
2025-11-23 00:21:28.386805 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-23 00:21:28.403985 | controller | skipping: Conditional result was False
2025-11-23 00:21:28.428898 | 
2025-11-23 00:21:28.429129 | TASK [Disable Fedora Modular]
2025-11-23 00:21:29.410459 | controller | changed
2025-11-23 00:21:29.423284 | 
2025-11-23 00:21:29.423414 | TASK [Enable EPEL]
2025-11-23 00:21:29.452586 | controller | skipping: Conditional result was False
2025-11-23 00:21:29.467154 | 
2025-11-23 00:21:29.467320 | TASK [Register the RHEL node]
2025-11-23 00:21:29.881257 | 
2025-11-23 00:21:29.881543 | TASK [Show the subscription-manager status]
2025-11-23 00:21:30.274371 | controller | skipping: Conditional result was False
2025-11-23 00:21:30.288177 | 
2025-11-23 00:21:30.288441 | TASK [Enable EPEL on RHEL]
2025-11-23 00:21:30.693744 | controller | skipping: Conditional result was False
2025-11-23 00:21:30.707791 | 
2025-11-23 00:21:30.707952 | TASK [Install git and tox]
2025-11-23 00:23:22.645469 | controller | changed
2025-11-23 00:23:22.658525 | 
2025-11-23 00:23:22.658655 | TASK [include_role : prepare-workspace]
2025-11-23 00:23:22.698271 | controller | ok
2025-11-23 00:23:22.738199 | 
2025-11-23 00:23:22.738384 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-23 00:23:23.536716 | controller | ok
2025-11-23 00:23:23.555853 | 
2025-11-23 00:23:23.555994 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-23 00:23:42.703107 | controller | Output suppressed because no_log was given
2025-11-23 00:23:42.714765 | 
2025-11-23 00:23:42.714883 | TASK [include_role : prepare-workspace-openshift]
2025-11-23 00:23:42.742918 | controller | skipping: Conditional result was False
2025-11-23 00:23:42.781955 | 
2025-11-23 00:23:42.782903 | PLAY [all:!appliance]
2025-11-23 00:23:42.807225 | 
2025-11-23 00:23:42.807338 | TASK [Run add-build-sshkey role (RSA)]
2025-11-23 00:23:42.838091 | controller | ok
2025-11-23 00:23:42.854712 | 
2025-11-23 00:23:42.854814 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-23 00:23:43.145133 | controller -> localhost | ok
2025-11-23 00:23:43.160308 | 
2025-11-23 00:23:43.160722 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-23 00:23:43.203976 | controller | ok
2025-11-23 00:23:43.234888 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-23 00:23:43.244464 | 
2025-11-23 00:23:43.244636 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-23 00:23:43.884246 | controller -> localhost | Generating public/private rsa key pair.
2025-11-23 00:23:43.884469 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/9df6cb49010b4ed789019cfec1cd2958_id_rsa.
2025-11-23 00:23:43.884503 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/9df6cb49010b4ed789019cfec1cd2958_id_rsa.pub.
2025-11-23 00:23:43.884529 | controller -> localhost | The key fingerprint is:
2025-11-23 00:23:43.884552 | controller -> localhost | SHA256:ik32LJRo1bdZP6uiKif7T11n7rzNEnDTRlu2ImUgA7s zuul-build-sshkey
2025-11-23 00:23:43.884576 | controller -> localhost | The key's randomart image is:
2025-11-23 00:23:43.884599 | controller -> localhost | +---[RSA 2048]----+
2025-11-23 00:23:43.884623 | controller -> localhost | |       ..o ..    |
2025-11-23 00:23:43.884648 | controller -> localhost | |       .. o  o  +|
2025-11-23 00:23:43.884753 | controller -> localhost | |      ... . +  ++|
2025-11-23 00:23:43.884786 | controller -> localhost | |     o ... =.oooo|
2025-11-23 00:23:43.884810 | controller -> localhost | |    o =ES o oo*o |
2025-11-23 00:23:43.884845 | controller -> localhost | |   . * + . . +.o |
2025-11-23 00:23:43.884873 | controller -> localhost | |    . + + .   o. |
2025-11-23 00:23:43.884898 | controller -> localhost | |    o .o  .  +.o |
2025-11-23 00:23:43.884921 | controller -> localhost | |    .*ooo. .. +oo|
2025-11-23 00:23:43.884946 | controller -> localhost | +----[SHA256]-----+
2025-11-23 00:23:43.885002 | controller -> localhost | ok: Runtime: 0:00:00.160485
2025-11-23 00:23:43.891849 | 
2025-11-23 00:23:43.891913 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-23 00:23:43.930215 | controller | ok
2025-11-23 00:23:43.948541 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-23 00:23:43.961949 | 
2025-11-23 00:23:43.962057 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-23 00:23:43.989500 | controller | skipping: Conditional result was False
2025-11-23 00:23:43.999621 | 
2025-11-23 00:23:43.999749 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-23 00:23:45.010015 | controller | changed
2025-11-23 00:23:45.016290 | 
2025-11-23 00:23:45.016399 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-23 00:23:45.627418 | controller | ok
2025-11-23 00:23:45.642386 | 
2025-11-23 00:23:45.642548 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-23 00:23:48.572149 | controller | changed
2025-11-23 00:23:48.584935 | 
2025-11-23 00:23:48.585075 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-23 00:23:51.514639 | controller | changed
2025-11-23 00:23:51.528493 | 
2025-11-23 00:23:51.528634 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-23 00:23:51.653337 | controller | skipping: Conditional result was False
2025-11-23 00:23:51.661295 | 
2025-11-23 00:23:51.661387 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-23 00:23:51.994437 | controller -> localhost | changed
2025-11-23 00:23:52.021010 | 
2025-11-23 00:23:52.021188 | TASK [add-build-sshkey : Add back temp key]
2025-11-23 00:23:52.353394 | controller -> localhost | Identity added: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/9df6cb49010b4ed789019cfec1cd2958_id_rsa (zuul-build-sshkey)
2025-11-23 00:23:52.353574 | controller -> localhost | ok: Runtime: 0:00:00.012033
2025-11-23 00:23:52.360240 | 
2025-11-23 00:23:52.360302 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-23 00:23:53.237868 | controller | ok
2025-11-23 00:23:53.250275 | 
2025-11-23 00:23:53.250421 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-23 00:23:53.288811 | controller | skipping: Conditional result was False
2025-11-23 00:23:53.316113 | 
2025-11-23 00:23:53.316291 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-23 00:23:53.357184 | controller | ok
2025-11-23 00:23:53.387872 | 
2025-11-23 00:23:53.388000 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-23 00:23:53.689811 | controller -> localhost | ok
2025-11-23 00:23:53.705409 | 
2025-11-23 00:23:53.705552 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-23 00:23:53.746901 | controller | ok
2025-11-23 00:23:53.771293 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-23 00:23:53.781153 | 
2025-11-23 00:23:53.781242 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-23 00:23:54.136300 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-23 00:23:54.136604 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/9df6cb49010b4ed789019cfec1cd2958_id_ecdsa.
2025-11-23 00:23:54.136644 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/9df6cb49010b4ed789019cfec1cd2958_id_ecdsa.pub.
2025-11-23 00:23:54.136719 | controller -> localhost | The key fingerprint is:
2025-11-23 00:23:54.136753 | controller -> localhost | SHA256:TyX3IC4nTClcrcEqbP7psoiq5pU6esbjevnwmMmNFLc zuul-build-sshkey
2025-11-23 00:23:54.136780 | controller -> localhost | The key's randomart image is:
2025-11-23 00:23:54.136806 | controller -> localhost | +---[ECDSA 521]---+
2025-11-23 00:23:54.136831 | controller -> localhost | |       ...       |
2025-11-23 00:23:54.136855 | controller -> localhost | |     . .o..      |
2025-11-23 00:23:54.136880 | controller -> localhost | |   .  o.ooo +    |
2025-11-23 00:23:54.136903 | controller -> localhost | |    + .+.. = o   |
2025-11-23 00:23:54.136926 | controller -> localhost | |  .o..  S +   .  |
2025-11-23 00:23:54.136950 | controller -> localhost | |   oo.   *       |
2025-11-23 00:23:54.136973 | controller -> localhost | | .o+E. .  .      |
2025-11-23 00:23:54.136995 | controller -> localhost | | *XX. o          |
2025-11-23 00:23:54.137017 | controller -> localhost | |%OXo=+.          |
2025-11-23 00:23:54.137042 | controller -> localhost | +----[SHA256]-----+
2025-11-23 00:23:54.137110 | controller -> localhost | ok: Runtime: 0:00:00.020063
2025-11-23 00:23:54.147259 | 
2025-11-23 00:23:54.147369 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-23 00:23:54.181755 | controller | ok
2025-11-23 00:23:54.193166 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-23 00:23:54.207255 | 
2025-11-23 00:23:54.207376 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-23 00:23:54.233284 | controller | skipping: Conditional result was False
2025-11-23 00:23:54.248212 | 
2025-11-23 00:23:54.248390 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-23 00:23:55.104707 | controller | changed
2025-11-23 00:23:55.117650 | 
2025-11-23 00:23:55.117831 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-23 00:23:55.721191 | controller | ok
2025-11-23 00:23:55.727527 | 
2025-11-23 00:23:55.727600 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-23 00:23:58.650370 | controller | changed
2025-11-23 00:23:58.656442 | 
2025-11-23 00:23:58.656537 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-23 00:24:01.547161 | controller | changed
2025-11-23 00:24:01.561564 | 
2025-11-23 00:24:01.561862 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-23 00:24:01.590165 | controller | skipping: Conditional result was False
2025-11-23 00:24:01.606140 | 
2025-11-23 00:24:01.606337 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-23 00:24:01.889353 | controller -> localhost | changed
2025-11-23 00:24:01.917284 | 
2025-11-23 00:24:01.917468 | TASK [add-build-sshkey : Add back temp key]
2025-11-23 00:24:02.181145 | controller -> localhost | Identity added: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/9df6cb49010b4ed789019cfec1cd2958_id_ecdsa (zuul-build-sshkey)
2025-11-23 00:24:02.181485 | controller -> localhost | ok: Runtime: 0:00:00.007319
2025-11-23 00:24:02.195596 | 
2025-11-23 00:24:02.195787 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-23 00:24:02.862036 | controller | ok
2025-11-23 00:24:02.871034 | 
2025-11-23 00:24:02.871152 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-23 00:24:02.896479 | controller | skipping: Conditional result was False
2025-11-23 00:24:02.914088 | 
2025-11-23 00:24:02.914219 | TASK [include_role : remove-zuul-sshkey]
2025-11-23 00:24:02.939525 | controller | skipping: Conditional result was False
2025-11-23 00:24:02.949408 | 
2025-11-23 00:24:02.949530 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-23 00:24:03.566595 | controller | ok: "logs"
2025-11-23 00:24:03.566927 | controller | ok: All items complete
2025-11-23 00:24:03.566956 | 
2025-11-23 00:24:04.140615 | controller | ok: "artifacts"
2025-11-23 00:24:04.712552 | controller | ok: "docs"
2025-11-23 00:24:04.729347 | 
2025-11-23 00:24:04.729553 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-23 00:24:05.364060 | controller | changed: "logs"
2025-11-23 00:24:05.956050 | controller | changed: "artifacts"
2025-11-23 00:24:06.539382 | controller | changed: "docs"
2025-11-23 00:24:06.629762 | 
2025-11-23 00:24:06.629858 | PLAY RECAP
2025-11-23 00:24:06.629899 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-23 00:24:06.629925 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-23 00:24:06.629944 | 
2025-11-23 00:24:06.737875 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-23 00:24:06.738867 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-23 00:24:07.349437 | 
2025-11-23 00:24:07.349547 | PLAY [all]
2025-11-23 00:24:07.372843 | 
2025-11-23 00:24:07.372944 | TASK [Install binary dependencies]
2025-11-23 00:24:07.443009 | controller | ok
2025-11-23 00:24:07.473598 | 
2025-11-23 00:24:07.473755 | TASK [bindep : Include find tasks]
2025-11-23 00:24:07.513772 | controller | ok
2025-11-23 00:24:07.521571 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-23 00:24:07.528124 | 
2025-11-23 00:24:07.528191 | TASK [bindep : Look for bindep.txt]
2025-11-23 00:24:08.421127 | controller | ok
2025-11-23 00:24:08.427195 | 
2025-11-23 00:24:08.427282 | TASK [bindep : Define bindep_file fact]
2025-11-23 00:24:08.451790 | controller | skipping: Conditional result was False
2025-11-23 00:24:08.458502 | 
2025-11-23 00:24:08.458576 | TASK [bindep : Look for other-requirements.txt]
2025-11-23 00:24:09.074924 | controller | ok
2025-11-23 00:24:09.089856 | 
2025-11-23 00:24:09.090029 | TASK [bindep : Define bindep_file fact]
2025-11-23 00:24:09.126449 | controller | skipping: Conditional result was False
2025-11-23 00:24:09.135572 | 
2025-11-23 00:24:09.135698 | TASK [bindep : Look for bindep fallback file]
2025-11-23 00:24:09.171491 | controller | skipping: Conditional result was False
2025-11-23 00:24:09.181004 | 
2025-11-23 00:24:09.181121 | TASK [bindep : Define bindep_file fact]
2025-11-23 00:24:09.215899 | controller | skipping: Conditional result was False
2025-11-23 00:24:09.224348 | 
2025-11-23 00:24:09.224444 | TASK [bindep : Include bindep tasks]
2025-11-23 00:24:09.248694 | controller | skipping: Conditional result was False
2025-11-23 00:24:09.257396 | 
2025-11-23 00:24:09.257489 | TASK [bindep : Include install tasks]
2025-11-23 00:24:09.281504 | controller | skipping: Conditional result was False
2025-11-23 00:24:09.290111 | 
2025-11-23 00:24:09.290206 | LOOP [bindep : Include package tasks]
2025-11-23 00:24:09.365512 | 
2025-11-23 00:24:09.365878 | TASK [Run test-setup role]
2025-11-23 00:24:09.389348 | controller | ok
2025-11-23 00:24:09.412975 | 
2025-11-23 00:24:09.413087 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-23 00:24:10.011094 | controller | ok
2025-11-23 00:24:10.026875 | 
2025-11-23 00:24:10.027062 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-23 00:24:10.405295 | controller | skipping: Conditional result was False
2025-11-23 00:24:10.458036 | 
2025-11-23 00:24:10.458154 | PLAY RECAP
2025-11-23 00:24:10.458221 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-23 00:24:10.458262 | 
2025-11-23 00:24:10.564705 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-23 00:24:10.565539 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-23 00:24:11.221486 | 
2025-11-23 00:24:11.221607 | PLAY [controller]
2025-11-23 00:24:11.241791 | 
2025-11-23 00:24:11.241882 | TASK [Create the /root directory]
2025-11-23 00:24:12.223405 | controller | ok
2025-11-23 00:24:12.235793 | 
2025-11-23 00:24:12.235946 | TASK [Install glibc-langpack-en]
2025-11-23 00:24:19.085176 | controller | ok: Nothing to do
2025-11-23 00:24:19.097643 | 
2025-11-23 00:24:19.097835 | TASK [Ensure controller directory exists]
2025-11-23 00:24:19.883061 | controller | changed
2025-11-23 00:24:19.895899 | 
2025-11-23 00:24:19.896052 | TASK [Install container runtime]
2025-11-23 00:24:19.973256 | controller | ok
2025-11-23 00:24:20.031730 | 
2025-11-23 00:24:20.031888 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-23 00:24:20.095443 | controller | ok: "/var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-23 00:24:20.115655 | 
2025-11-23 00:24:20.115877 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-23 00:24:20.144125 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.157504 | 
2025-11-23 00:24:20.157652 | TASK [ensure-docker : Sanity check for distro install]
2025-11-23 00:24:20.195911 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.208918 | 
2025-11-23 00:24:20.209205 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-23 00:24:20.238181 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.253457 | 
2025-11-23 00:24:20.253614 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-23 00:24:20.291420 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.306420 | 
2025-11-23 00:24:20.306589 | TASK [ensure-docker : Include tasks for docker install]
2025-11-23 00:24:20.335784 | controller | ok
2025-11-23 00:24:20.353252 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-23 00:24:20.365153 | 
2025-11-23 00:24:20.365259 | TASK [ensure-docker : Not Implemented]
2025-11-23 00:24:20.391176 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.400432 | 
2025-11-23 00:24:20.400532 | TASK [Set up docker mirrors]
2025-11-23 00:24:20.424590 | controller | ok
2025-11-23 00:24:20.454267 | 
2025-11-23 00:24:20.454415 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-23 00:24:20.490545 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.500475 | 
2025-11-23 00:24:20.500583 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-23 00:24:20.536858 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.546629 | 
2025-11-23 00:24:20.546750 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-23 00:24:20.582642 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.591766 | 
2025-11-23 00:24:20.591861 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-23 00:24:20.617698 | controller | skipping: Conditional result was False
2025-11-23 00:24:20.677205 | 
2025-11-23 00:24:20.677356 | TASK [Add all repositories]
2025-11-23 00:24:20.699332 | controller | ok
2025-11-23 00:24:20.722997 | 
2025-11-23 00:24:20.723102 | LOOP [ensure-package-repositories : Install repositories]
2025-11-23 00:24:20.765904 | controller | ok: "/var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-23 00:24:20.766193 | controller | ok: All items complete
2025-11-23 00:24:20.766250 | 
2025-11-23 00:24:20.777811 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-23 00:24:20.784334 | 
2025-11-23 00:24:20.784400 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-23 00:24:21.679832 | controller | changed
2025-11-23 00:24:21.693813 | 
2025-11-23 00:24:21.693957 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-23 00:24:24.775369 | controller | changed:
2025-11-23 00:24:24.775743 | controller | {
2025-11-23 00:24:24.775829 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-23 00:24:24.775892 | controller | }
2025-11-23 00:24:24.791041 | 
2025-11-23 00:24:24.791238 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-23 00:24:26.028917 | controller | changed:
2025-11-23 00:24:26.029220 | controller | {
2025-11-23 00:24:26.029281 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-23 00:24:26.029330 | controller | }
2025-11-23 00:24:26.029391 | controller | changed: All items complete
2025-11-23 00:24:26.029447 | 
2025-11-23 00:24:26.049001 | 
2025-11-23 00:24:26.049236 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-23 00:24:26.711493 | controller | changed
2025-11-23 00:24:26.718105 | 
2025-11-23 00:24:26.718171 | LOOP [ensure-package-repositories : Add repositories]
2025-11-23 00:24:27.459092 | controller | changed:
2025-11-23 00:24:27.459483 | controller | {
2025-11-23 00:24:27.459545 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-23 00:24:27.459760 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-23 00:24:27.459828 | controller |   "gpgcheck": true,
2025-11-23 00:24:27.459872 | controller |   "name": "docker-ce-stable"
2025-11-23 00:24:27.460108 | controller | }
2025-11-23 00:24:27.481886 | 
2025-11-23 00:24:27.482016 | TASK [ensure-docker : Install pre-reqs]
2025-11-23 00:24:32.408056 | controller | ok: Nothing to do
2025-11-23 00:24:32.413883 | 
2025-11-23 00:24:32.413961 | TASK [ensure-docker : Remove packages]
2025-11-23 00:24:35.177622 | controller | ok: Nothing to do
2025-11-23 00:24:35.183600 | 
2025-11-23 00:24:35.183682 | TASK [ensure-docker : Install upstream docker using package]
2025-11-23 00:25:50.994408 | controller | changed
2025-11-23 00:25:51.024979 | 
2025-11-23 00:25:51.025109 | TASK [ensure-docker : Setup docker]
2025-11-23 00:25:51.046912 | controller | ok
2025-11-23 00:25:51.061239 | controller | included: /var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-23 00:25:51.078046 | 
2025-11-23 00:25:51.078211 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-23 00:25:52.034622 | controller | ok
2025-11-23 00:25:52.049152 | 
2025-11-23 00:25:52.049362 | TASK [ensure-docker : Add user to docker group]
2025-11-23 00:25:53.055555 | controller | changed
2025-11-23 00:25:53.063872 | 
2025-11-23 00:25:53.063963 | TASK [Update docker daemon proxy configuration]
2025-11-23 00:25:53.100841 | controller | skipping: Conditional result was False
2025-11-23 00:25:53.118598 | 
2025-11-23 00:25:53.118818 | TASK [Lower default MTU]
2025-11-23 00:25:53.145633 | controller | skipping: Conditional result was False
2025-11-23 00:25:53.156438 | 
2025-11-23 00:25:53.156530 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-23 00:25:53.957490 | controller | inactive
2025-11-23 00:25:54.528265 | controller | ok: Runtime: 0:00:00.014445
2025-11-23 00:25:54.537538 | 
2025-11-23 00:25:54.537633 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-23 00:25:55.240439 | controller | inactive
2025-11-23 00:25:55.410630 | controller | ok: Runtime: 0:00:00.014986
2025-11-23 00:25:55.426583 | 
2025-11-23 00:25:55.426958 | TASK [ensure-docker : Restart docker]
2025-11-23 00:25:55.495046 | Trigger a Docker restart
2025-11-23 00:25:55.507479 | 
2025-11-23 00:25:55.507635 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-23 00:25:55.545469 | 
2025-11-23 00:25:55.545597 | TASK [ensure-docker : Install docker-compose]
2025-11-23 00:25:55.561582 | controller | skipping: Conditional result was False
2025-11-23 00:25:55.572439 | 
2025-11-23 00:25:55.572560 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-23 00:25:55.589778 | 
2025-11-23 00:25:55.589869 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-23 00:25:58.763169 | controller | changed
2025-11-23 00:25:58.783341 | 
2025-11-23 00:25:58.783484 | TASK [ensure-docker : Assure docker service is running]
2025-11-23 00:26:02.322925 | controller | changed
2025-11-23 00:26:02.336224 | 
2025-11-23 00:26:02.336341 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-23 00:26:04.313010 | controller | ok
2025-11-23 00:26:04.323938 | 
2025-11-23 00:26:04.324041 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-23 00:26:05.217704 | controller | ok
2025-11-23 00:26:05.230072 | 
2025-11-23 00:26:05.230210 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-23 00:26:05.831917 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-23 00:26:06.100073 | controller | ok: Runtime: 0:00:00.032786
2025-11-23 00:26:06.113233 | 
2025-11-23 00:26:06.113375 | TASK [ensure-docker : Get version details]
2025-11-23 00:26:06.715854 | controller | Client: Docker Engine - Community
2025-11-23 00:26:06.715931 | controller |  Version:           24.0.7
2025-11-23 00:26:06.715998 | controller |  API version:       1.43
2025-11-23 00:26:06.716009 | controller |  Go version:        go1.20.10
2025-11-23 00:26:06.716038 | controller |  Git commit:        afdd53b
2025-11-23 00:26:06.716046 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-23 00:26:06.716055 | controller |  OS/Arch:           linux/amd64
2025-11-23 00:26:06.716064 | controller |  Context:           default
2025-11-23 00:26:06.716072 | controller |
2025-11-23 00:26:06.716079 | controller | Server: Docker Engine - Community
2025-11-23 00:26:06.716086 | controller |  Engine:
2025-11-23 00:26:06.716096 | controller |   Version:          24.0.7
2025-11-23 00:26:06.716123 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-23 00:26:06.716140 | controller |   Go version:       go1.20.10
2025-11-23 00:26:06.716147 | controller |   Git commit:       311b9ff
2025-11-23 00:26:06.716153 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-23 00:26:06.716159 | controller |   OS/Arch:          linux/amd64
2025-11-23 00:26:06.716165 | controller |   Experimental:     false
2025-11-23 00:26:06.716171 | controller |  containerd:
2025-11-23 00:26:06.716185 | controller |   Version:          1.6.25
2025-11-23 00:26:06.716192 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-23 00:26:06.716198 | controller |  runc:
2025-11-23 00:26:06.716203 | controller |   Version:          1.1.10
2025-11-23 00:26:06.716210 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-23 00:26:06.716218 | controller |  docker-init:
2025-11-23 00:26:06.716227 | controller |   Version:          0.19.0
2025-11-23 00:26:06.718137 | controller |   GitCommit:        de40ad0
2025-11-23 00:26:06.989753 | controller | ok: Runtime: 0:00:00.033463
2025-11-23 00:26:07.008507 | 
2025-11-23 00:26:07.008730 | TASK [ensure-docker : Dump installed docker details]
2025-11-23 00:26:07.051140 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-11-23 00:26:06.685040', 'end': '2025-11-23 00:26:06.718503', 'delta': '0:00:00.033463', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-295d-b8ae-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-11-23 00:26:07.064949 | 
2025-11-23 00:26:07.065088 | TASK [Use buildset registry]
2025-11-23 00:26:07.091854 | controller | skipping: Conditional result was False
2025-11-23 00:26:07.146133 | 
2025-11-23 00:26:07.146248 | TASK [Ensure python3.8 is present]
2025-11-23 00:26:07.170960 | controller | skipping: Conditional result was False
2025-11-23 00:26:07.180496 | 
2025-11-23 00:26:07.180588 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-23 00:26:07.204288 | controller | ok
2025-11-23 00:26:07.231061 | 
2025-11-23 00:26:07.231140 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-23 00:26:13.740311 | controller | ok: Nothing to do
2025-11-23 00:26:13.746064 | 
2025-11-23 00:26:13.746132 | TASK [our-ensure-python : Also install python3-devel]
2025-11-23 00:26:27.957839 | controller | changed
2025-11-23 00:26:27.976053 | 
2025-11-23 00:26:27.976136 | TASK [Run ensure-virtualenv role]
2025-11-23 00:26:27.995474 | controller | ok
2025-11-23 00:26:28.017077 | 
2025-11-23 00:26:28.017197 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-23 00:26:28.716708 | controller | /usr/bin/virtualenv
2025-11-23 00:26:28.873902 | controller | ok: Runtime: 0:00:00.004518
2025-11-23 00:26:28.882604 | 
2025-11-23 00:26:28.883039 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-23 00:26:28.920079 | controller | skipping: Conditional result was False
2025-11-23 00:26:28.920541 | controller | changed: All items complete
2025-11-23 00:26:28.920610 | 
2025-11-23 00:26:28.946442 | 
2025-11-23 00:26:28.946596 | TASK [Find the full path of the Python interpreter]
2025-11-23 00:26:29.510670 | controller | /usr/bin/python3.8
2025-11-23 00:26:29.810375 | controller | ok
2025-11-23 00:26:29.816146 | 
2025-11-23 00:26:29.816224 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-23 00:26:32.392636 | controller | created virtual environment CPython3.8.18.final.0-64 in 1308ms
2025-11-23 00:26:32.495329 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-23 00:26:32.495383 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-23 00:26:32.495398 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-23 00:26:32.495417 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-23 00:26:32.679399 | controller | changed
2025-11-23 00:26:32.731696 | 
2025-11-23 00:26:32.731998 | TASK [Set selinux package]
2025-11-23 00:26:32.766910 | controller | ok
2025-11-23 00:26:32.774641 | 
2025-11-23 00:26:32.774772 | TASK [Set selinux package (Fedora)]
2025-11-23 00:26:32.808343 | controller | ok
2025-11-23 00:26:32.815907 | 
2025-11-23 00:26:32.816001 | TASK [Install selinux into virtualenv]
2025-11-23 00:26:37.846694 | controller | Collecting selinux-please-lie-to-me
2025-11-23 00:26:38.073208 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-23 00:26:39.193243 | controller | Collecting setuptools<50.0.0
2025-11-23 00:26:39.207903 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-23 00:26:39.287057 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 11.5 MB/s eta 0:00:00
2025-11-23 00:26:39.596084 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-23 00:26:39.605041 | controller |   Attempting uninstall: setuptools
2025-11-23 00:26:39.606836 | controller |     Found existing installation: setuptools 62.6.0
2025-11-23 00:26:39.871883 | controller |     Uninstalling setuptools-62.6.0:
2025-11-23 00:26:39.886681 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-23 00:26:40.403892 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-23 00:26:40.574548 | controller |
2025-11-23 00:26:40.767714 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-23 00:26:40.767767 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-23 00:26:41.199469 | controller | ok: Runtime: 0:00:06.851734
2025-11-23 00:26:41.205444 | 
2025-11-23 00:26:41.205514 | TASK [Install pytest-forked into virtualenv]
2025-11-23 00:26:43.164660 | controller | Collecting pytest-forked
2025-11-23 00:26:43.225462 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-23 00:26:43.285522 | controller | Collecting py
2025-11-23 00:26:43.290563 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-23 00:26:43.319005 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.6 MB/s eta 0:00:00
2025-11-23 00:26:43.482534 | controller | Collecting pytest>=3.10
2025-11-23 00:26:43.487485 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-23 00:26:43.505639 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 23.3 MB/s eta 0:00:00
2025-11-23 00:26:43.567439 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-23 00:26:43.572064 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-11-23 00:26:43.630111 | controller | Collecting packaging
2025-11-23 00:26:43.634638 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-23 00:26:43.642998 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.1 MB/s eta 0:00:00
2025-11-23 00:26:43.686196 | controller | Collecting pluggy<2,>=1.5
2025-11-23 00:26:43.690835 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-23 00:26:43.718372 | controller | Collecting iniconfig
2025-11-23 00:26:43.723110 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-23 00:26:43.785545 | controller | Collecting tomli>=1
2025-11-23 00:26:43.790176 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-23 00:26:43.863441 | controller | Collecting typing-extensions>=4.6.0
2025-11-23 00:26:43.868699 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-23 00:26:43.878993 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.1 MB/s eta 0:00:00
2025-11-23 00:26:43.966168 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-23 00:26:44.602461 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-23 00:26:44.607189 | controller |
2025-11-23 00:26:44.776172 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-23 00:26:44.776204 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-23 00:26:45.062843 | controller | ok: Runtime: 0:00:02.513773
2025-11-23 00:26:45.076215 | 
2025-11-23 00:26:45.076359 | TASK [Update pip]
2025-11-23 00:26:47.601529 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-23 00:26:48.101341 | controller | Collecting pip
2025-11-23 00:26:48.226786 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-23 00:26:48.332836 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 18.7 MB/s eta 0:00:00
2025-11-23 00:26:48.543301 | controller | Installing collected packages: pip
2025-11-23 00:26:48.543511 | controller |   Attempting uninstall: pip
2025-11-23 00:26:48.544512 | controller |     Found existing installation: pip 22.2.2
2025-11-23 00:26:49.241923 | controller |     Uninstalling pip-22.2.2:
2025-11-23 00:26:49.310772 | controller |       Successfully uninstalled pip-22.2.2
2025-11-23 00:26:51.861542 | controller | Successfully installed pip-25.0.1
2025-11-23 00:26:52.461951 | controller | ok: Runtime: 0:00:05.830126
2025-11-23 00:26:52.474092 | 
2025-11-23 00:26:52.474225 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-23 00:26:53.717441 | controller | changed
2025-11-23 00:26:53.729898 | 
2025-11-23 00:26:53.730034 | TASK [Install ansible into virtualenv]
2025-11-23 00:26:55.459664 | controller | Processing ./src/github.com/ansible/ansible
2025-11-23 00:26:55.461147 | controller |   Preparing metadata (setup.py): started
2025-11-23 00:26:57.020685 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-23 00:26:57.084419 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-11-23 00:26:57.102836 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-23 00:26:57.175953 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-11-23 00:26:57.181798 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-11-23 00:26:57.547245 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-11-23 00:26:57.552134 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-23 00:26:57.765858 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-11-23 00:26:57.771805 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-23 00:26:57.780565 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2025-11-23 00:26:57.875528 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-11-23 00:26:57.883065 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-23 00:26:57.908928 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-11-23 00:26:57.913563 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-23 00:26:57.930670 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-23 00:26:57.974572 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 119.2 MB/s eta 0:00:00
2025-11-23 00:26:57.979244 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-23 00:26:57.990569 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-11-23 00:26:58.006946 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 50.5 MB/s eta 0:00:00
2025-11-23 00:26:58.012065 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-23 00:26:58.025895 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-23 00:26:58.035173 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-23 00:26:58.062148 | controller | Building wheels for collected packages: ansible
2025-11-23 00:26:58.063176 | controller |   Building wheel for ansible (setup.py): started
2025-11-23 00:27:07.180982 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-11-23 00:27:07.197436 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=5761dea87c03d82b90ca0e3e1060b473af17eb744cb036fe44668c34401c9a9f
2025-11-23 00:27:07.197737 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-q0lo3wdx/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-23 00:27:07.270430 | controller | Successfully built ansible
2025-11-23 00:27:07.435823 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-11-23 00:27:19.857420 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-11-23 00:27:20.642303 | controller | ok: Runtime: 0:00:25.299001
2025-11-23 00:27:20.658228 | 
2025-11-23 00:27:20.658364 | TASK [Setup download-artifact-fork role]
2025-11-23 00:27:20.688415 | controller | ok
2025-11-23 00:27:20.722258 | 
2025-11-23 00:27:20.722352 | TASK [download-artifact-fork : Parse inventory response]
2025-11-23 00:27:20.780969 | controller | ok
2025-11-23 00:27:20.831708 | 
2025-11-23 00:27:20.831888 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-23 00:27:20.867466 | controller | skipping: Conditional result was False
2025-11-23 00:27:20.874562 | 
2025-11-23 00:27:20.874626 | TASK [download-artifact-fork : Parse build response]
2025-11-23 00:27:20.909176 | controller | skipping: Conditional result was False
2025-11-23 00:27:20.915965 | 
2025-11-23 00:27:20.916037 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-23 00:27:21.695134 | controller | changed
2025-11-23 00:27:21.708780 | 
2025-11-23 00:27:21.708927 | LOOP [download-artifact-fork : Download archive by type]
2025-11-23 00:27:21.747305 | controller | skipping: Conditional result was False
2025-11-23 00:27:21.747980 | 
2025-11-23 00:27:24.136848 | controller | changed: OK (20197 bytes)
2025-11-23 00:27:24.169147 | 
2025-11-23 00:27:24.169307 | TASK [deploy-artifacts : define __collections]
2025-11-23 00:27:24.203187 | controller | ok
2025-11-23 00:27:24.211718 | 
2025-11-23 00:27:24.211814 | LOOP [deploy-artifacts : Create list of collections]
2025-11-23 00:27:24.246381 | controller | skipping: Conditional result was False
2025-11-23 00:27:24.271944 | controller | ok:
2025-11-23 00:27:24.272103 | controller | {
2025-11-23 00:27:24.272156 | controller |   "branch": "main",
2025-11-23 00:27:24.272197 | controller |   "job": "build-ansible-collection",
2025-11-23 00:27:24.272236 | controller |   "metadata": {
2025-11-23 00:27:24.272273 | controller |     "type": "ansible_collection",
2025-11-23 00:27:24.272310 | controller |     "version": "5.0.0"
2025-11-23 00:27:24.272347 | controller |   },
2025-11-23 00:27:24.272383 | controller |   "name": "ansible.network",
2025-11-23 00:27:24.272418 | controller |   "project": "ansible-collections/ansible.network",
2025-11-23 00:27:24.272454 | controller |   "url": "https://fb033ac5ed1c08b4c8bc-341e065913ddc5c6a14c2fc8372c92e8.ssl.cf1.rackcdn.com/ansible/eb6aab9e8a684c078abc5881601e2afb/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-23 00:27:24.272504 | controller | }
2025-11-23 00:27:24.291972 | 
2025-11-23 00:27:24.292120 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-23 00:27:25.339701 | controller | Process install dependency map
2025-11-23 00:28:03.784882 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Starting collection install process
2025-11-23 00:28:03.885717 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-23 00:28:03.885844 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-23 00:28:05.593439 | controller | /-\|/-\|/-\|/-\|/Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-23 00:28:07.299587 | controller | -\|/-\|/-\|/-\|/-Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-23 00:28:09.740358 | controller | \|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-23 00:28:12.552023 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-23 00:28:15.176854 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-23 00:28:19.052261 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-23 00:28:22.195202 | controller | ok: Runtime: 0:00:56.624393
2025-11-23 00:28:22.206023 | 
2025-11-23 00:28:22.206083 | TASK [podman may need iptables kernel modules]
2025-11-23 00:28:23.585730 | controller | ok: Runtime: 0:00:00.005875
2025-11-23 00:28:23.649199 | 
2025-11-23 00:28:23.649329 | PLAY RECAP
2025-11-23 00:28:23.649410 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-23 00:28:23.649455 | 
2025-11-23 00:28:23.788588 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-23 00:28:23.789518 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-23 00:28:24.373492 | 
2025-11-23 00:28:24.373607 | PLAY [controller]
2025-11-23 00:28:24.396184 | 
2025-11-23 00:28:24.396282 | TASK [Copy the galaxy.yml on the controller]
2025-11-23 00:28:28.125909 | controller | changed
2025-11-23 00:28:28.138752 | 
2025-11-23 00:28:28.138912 | TASK [Load information from galaxy.yml]
2025-11-23 00:28:28.180997 | controller | ok
2025-11-23 00:28:28.193799 | 
2025-11-23 00:28:28.193952 | LOOP [Enable FIPS mode]
2025-11-23 00:28:28.221493 | 
2025-11-23 00:28:28.221731 | LOOP [ansible-test : Include branch specific variables.]
2025-11-23 00:28:28.266716 | controller | ok: "/var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-23 00:28:28.266872 | 
2025-11-23 00:28:28.281941 | 
2025-11-23 00:28:28.282139 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-23 00:28:28.336874 | controller | ok
2025-11-23 00:28:28.345062 | 
2025-11-23 00:28:28.345159 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-23 00:28:28.370318 | controller | skipping: Conditional result was False
2025-11-23 00:28:28.380737 | 
2025-11-23 00:28:28.380921 | TASK [ansible-test : Enable --retry-on-error]
2025-11-23 00:28:28.406650 | controller | skipping: Conditional result was False
2025-11-23 00:28:28.413364 | 
2025-11-23 00:28:28.413432 | TASK [ansible-test : Enable --continue-on-error]
2025-11-23 00:28:28.452867 | controller | ok
2025-11-23 00:28:28.458221 | 
2025-11-23 00:28:28.458283 | TASK [ansible-test : Enable --requirements]
2025-11-23 00:28:28.482084 | controller | skipping: Conditional result was False
2025-11-23 00:28:28.488374 | 
2025-11-23 00:28:28.488440 | TASK [ansible-test : Adjust options for unit tests]
2025-11-23 00:28:28.523171 | controller | skipping: Conditional result was False
2025-11-23 00:28:28.532617 | 
2025-11-23 00:28:28.532827 | TASK [ansible-test : Install requirements with unit tests]
2025-11-23 00:28:28.598477 | controller | ok
2025-11-23 00:28:28.605391 | 
2025-11-23 00:28:28.605457 | TASK [ansible-test : Set --skip-test]
2025-11-23 00:28:28.680522 | controller | skipping: Conditional result was False
2025-11-23 00:28:28.686714 | 
2025-11-23 00:28:28.686787 | TASK [ansible-test : Reduce verbosity]
2025-11-23 00:28:28.734511 | controller | ok
2025-11-23 00:28:28.748810 | 
2025-11-23 00:28:28.748971 | TASK [ansible-test : Setup --python option]
2025-11-23 00:28:28.777418 | controller | skipping: Conditional result was False
2025-11-23 00:28:28.787908 | 
2025-11-23 00:28:28.788038 | TASK [ansible-test : Setup --docker option]
2025-11-23 00:28:28.825191 | controller | ok
2025-11-23 00:28:28.838298 | 
2025-11-23 00:28:28.838641 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-23 00:28:29.727743 | controller | ok
2025-11-23 00:28:29.735404 | 
2025-11-23 00:28:29.735486 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-23 00:28:29.760433 | controller | skipping: Conditional result was False
2025-11-23 00:28:29.769535 | 
2025-11-23 00:28:29.769632 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-23 00:28:29.794738 | controller | skipping: Conditional result was False
2025-11-23 00:28:29.804274 | 
2025-11-23 00:28:29.804362 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-23 00:28:29.829765 | controller | skipping: Conditional result was False
2025-11-23 00:28:29.840626 | 
2025-11-23 00:28:29.840831 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-23 00:28:31.142093 | controller | ok
2025-11-23 00:28:31.154601 | 
2025-11-23 00:28:31.154762 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-23 00:28:31.214576 | controller | ok
2025-11-23 00:28:31.229271 | 
2025-11-23 00:28:31.229403 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-23 00:28:31.280096 | controller | ok
2025-11-23 00:28:31.293725 | 
2025-11-23 00:28:31.293897 | TASK [ansible-test : Setup minimum test requirements]
2025-11-23 00:28:31.349468 | controller | ok
2025-11-23 00:28:31.357451 | 
2025-11-23 00:28:31.357536 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-23 00:28:32.431956 | controller | ok
2025-11-23 00:28:32.449029 | 
2025-11-23 00:28:32.449171 | TASK [ansible-test : Install python requirements]
2025-11-23 00:28:33.726563 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-23 00:28:33.743743 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-23 00:28:33.773047 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-23 00:28:33.777882 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-23 00:28:33.786776 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-11-23 00:28:33.793651 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-23 00:28:33.804579 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-23 00:28:33.863994 | controller | Installing collected packages: pathspec, yamllint
2025-11-23 00:28:33.942116 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-23 00:28:34.316563 | controller | ok: Runtime: 0:00:00.885471
2025-11-23 00:28:34.330876 | 
2025-11-23 00:28:34.331055 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-23 00:28:35.117967 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-23 00:28:35.709001 | controller | ERROR
2025-11-23 00:28:35.709446 | controller | {
2025-11-23 00:28:35.709516 | controller |   "delta": "0:00:00.006658",
2025-11-23 00:28:35.709561 | controller |   "end": "2025-11-23 00:28:35.118173",
2025-11-23 00:28:35.709600 | controller |   "msg": "non-zero return code",
2025-11-23 00:28:35.709637 | controller |   "rc": 1,
2025-11-23 00:28:35.709719 | controller |   "start": "2025-11-23 00:28:35.111515"
2025-11-23 00:28:35.709760 | controller | }
2025-11-23 00:28:35.709812 | controller | ERROR: Ignoring Errors
2025-11-23 00:28:35.724081 | 
2025-11-23 00:28:35.724239 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-23 00:28:36.103967 | controller | skipping: Conditional result was False
2025-11-23 00:28:36.117938 | 
2025-11-23 00:28:36.118089 | TASK [ansible-test : Enable persistent connection logging]
2025-11-23 00:28:37.124706 | controller | changed: section and option added
2025-11-23 00:28:37.137473 | 
2025-11-23 00:28:37.137621 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-23 00:28:38.055885 | controller | changed: option added
2025-11-23 00:28:38.062369 | 
2025-11-23 00:28:38.062451 | TASK [ansible-test : Set ansible log path]
2025-11-23 00:28:39.255099 | controller | changed: section and option added
2025-11-23 00:28:39.269905 | 
2025-11-23 00:28:39.270024 | TASK [ansible-test : Identify the changed targets]
2025-11-23 00:28:39.654204 | controller | skipping: Conditional result was False
2025-11-23 00:28:39.664375 | 
2025-11-23 00:28:39.664474 | TASK [ansible-test : set_fact]
2025-11-23 00:28:39.710555 | controller | skipping: Conditional result was False
2025-11-23 00:28:39.720392 | 
2025-11-23 00:28:39.720486 | TASK [ansible-test : Set the targets]
2025-11-23 00:28:39.759391 | controller | ok
2025-11-23 00:28:39.768461 | 
2025-11-23 00:28:39.768551 | TASK [ansible-test : set_fact]
2025-11-23 00:28:39.794053 | controller | skipping: Conditional result was False
2025-11-23 00:28:39.837938 | 
2025-11-23 00:28:39.838081 | TASK [ansible-test : List the targets]
2025-11-23 00:28:40.201368 | controller | skipping: Conditional result was False
2025-11-23 00:28:40.208037 | 
2025-11-23 00:28:40.208114 | TASK [ansible-test : set_fact]
2025-11-23 00:28:40.222237 | controller | skipping: Conditional result was False
2025-11-23 00:28:40.229588 | 
2025-11-23 00:28:40.229653 | TASK [ansible-test : set_fact]
2025-11-23 00:28:40.253958 | controller | skipping: Conditional result was False
2025-11-23 00:28:40.260530 | 
2025-11-23 00:28:40.260595 | LOOP [ansible-test : set_fact]
2025-11-23 00:28:40.311464 | 
2025-11-23 00:28:40.311587 | TASK [ansible-test : set_fact]
2025-11-23 00:28:40.335633 | controller | skipping: Conditional result was False
2025-11-23 00:28:40.342177 | 
2025-11-23 00:28:40.342249 | TASK [ansible-test : debug]
2025-11-23 00:28:40.366433 | controller | skipping: Conditional result was False
2025-11-23 00:28:40.372963 | 
2025-11-23 00:28:40.373063 | TASK [ansible-test : debug]
2025-11-23 00:28:40.402779 | About to run: ansible-test sanity --requirements --docker -v
2025-11-23 00:28:40.410161 | 
2025-11-23 00:28:40.410228 | TASK [ansible-test : Run the test suite]
2025-11-23 00:28:41.669522 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-11-23 00:28:41.714384 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-11-23 00:28:43.240179 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-11-23 00:28:43.241701 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2025-11-23 00:28:43.241903 | controller | 5667fdb72017: Pulling fs layer
2025-11-23 00:28:43.244877 | controller | d83811f270d5: Pulling fs layer
2025-11-23 00:28:43.244911 | controller | ee671aafb583: Pulling fs layer
2025-11-23 00:28:43.244945 | controller | 7fc152dfb3a6: Pulling fs layer
2025-11-23 00:28:43.245318 | controller | 3fb967a6fd03: Pulling fs layer
2025-11-23 00:28:43.245333 | controller | b3e63023ca5f: Pulling fs layer
2025-11-23 00:28:43.245339 | controller | 653cb3e65dbf: Pulling fs layer
2025-11-23 00:28:43.245346 | controller | f4712b586375: Pulling fs layer
2025-11-23 00:28:43.245356 | controller | d696ed7812f8: Pulling fs layer
2025-11-23 00:28:43.245362 | controller | 29bf28764f14: Pulling fs layer
2025-11-23 00:28:43.245368 | controller | 47d7edb3c2b7: Pulling fs layer
2025-11-23 00:28:43.245387 | controller | 07550ad4f6e4: Pulling fs layer
2025-11-23 00:28:43.245393 | controller | 7e79526e64c5: Pulling fs layer
2025-11-23 00:28:43.245400 | controller | 4fffb32882b7: Pulling fs layer
2025-11-23 00:28:43.245406 | controller | fc7a38b06f4e: Pulling fs layer
2025-11-23 00:28:43.245415 | controller | 6d9d912e060e: Pulling fs layer
2025-11-23 00:28:43.245641 | controller | 5dd542dd28d9: Pulling fs layer
2025-11-23 00:28:43.245653 | controller | cef8beb99552: Pulling fs layer
2025-11-23 00:28:43.245660 | controller | bd5557e101ad: Pulling fs layer
2025-11-23 00:28:43.245666 | controller | e057c20fabdd: Pulling fs layer
2025-11-23 00:28:43.245674 | controller | d16059cdae2e: Pulling fs layer
2025-11-23 00:28:43.245680 | controller | 431e42d906a3: Pulling fs layer
2025-11-23 00:28:43.245687 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-11-23 00:28:43.245693 | controller | aacdb6db0c8c: Pulling fs layer
2025-11-23 00:28:43.245699 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-11-23 00:28:43.245717 | controller | 4cd343027a44: Pulling fs layer
2025-11-23 00:28:43.245723 | controller | 0b4379883f90: Pulling fs layer
2025-11-23 00:28:43.245729 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-11-23 00:28:43.245739 | controller | 57d6314bd293: Pulling fs layer
2025-11-23 00:28:43.245748 | controller | 774036afeb7f: Pulling fs layer
2025-11-23 00:28:43.245880 | controller | 54b10f0e1645: Pulling fs layer
2025-11-23 00:28:43.245891 | controller | 78cf439d9fdb: Pulling fs layer
2025-11-23 00:28:43.245897 | controller | 36e5e20969c7: Pulling fs layer
2025-11-23 00:28:43.245903 | controller | 4b19ac3f17a8: Pulling fs layer
2025-11-23 00:28:43.245909 | controller | 7fc152dfb3a6: Waiting
2025-11-23 00:28:43.245915 | controller | 3fb967a6fd03: Waiting
2025-11-23 00:28:43.245924 | controller | b3e63023ca5f: Waiting
2025-11-23 00:28:43.245930 | controller | 653cb3e65dbf: Waiting
2025-11-23 00:28:43.245936 | controller | f4712b586375: Waiting
2025-11-23 00:28:43.245952 | controller | d696ed7812f8: Waiting
2025-11-23 00:28:43.245958 | controller | 29bf28764f14: Waiting
2025-11-23 00:28:43.245964 | controller | 47d7edb3c2b7: Waiting
2025-11-23 00:28:43.245970 | controller | 07550ad4f6e4: Waiting
2025-11-23 00:28:43.245976 | controller | 7e79526e64c5: Waiting
2025-11-23 00:28:43.245981 | controller | 4fffb32882b7: Waiting
2025-11-23 00:28:43.245988 | controller | fc7a38b06f4e: Waiting
2025-11-23 00:28:43.245993 | controller | 6d9d912e060e: Waiting
2025-11-23 00:28:43.245999 | controller | 5dd542dd28d9: Waiting
2025-11-23 00:28:43.246011 | controller | cef8beb99552: Waiting
2025-11-23 00:28:43.246105 | controller | bd5557e101ad: Waiting
2025-11-23 00:28:43.246115 | controller | e057c20fabdd: Waiting
2025-11-23 00:28:43.246122 | controller | d16059cdae2e: Waiting
2025-11-23 00:28:43.246128 | controller | 431e42d906a3: Waiting
2025-11-23 00:28:43.246134 | controller | 7a0fd3b1d7c3: Waiting
2025-11-23 00:28:43.246140 | controller | aacdb6db0c8c: Waiting
2025-11-23 00:28:43.246146 | controller | 2e7f04ae8c8b: Waiting
2025-11-23 00:28:43.246152 | controller | 4cd343027a44: Waiting
2025-11-23 00:28:43.246161 | controller | 0b4379883f90: Waiting
2025-11-23 00:28:43.647809 | controller | 3d9d6d9dfc91: Waiting
2025-11-23 00:28:43.647858 | controller | 57d6314bd293: Waiting
2025-11-23 00:28:43.647868 | controller | 774036afeb7f: Waiting
2025-11-23 00:28:43.647887 | controller | 54b10f0e1645: Waiting
2025-11-23 00:28:43.647895 | controller | 78cf439d9fdb: Waiting
2025-11-23 00:28:43.647901 | controller | 36e5e20969c7: Waiting
2025-11-23 00:28:43.647907 | controller | 4b19ac3f17a8: Waiting
2025-11-23 00:28:43.647921 | controller | ee671aafb583: Verifying Checksum
2025-11-23 00:28:43.679856 | controller | ee671aafb583: Download complete
2025-11-23 00:28:43.679922 | controller | d83811f270d5: Verifying Checksum
2025-11-23 00:28:43.877552 | controller | d83811f270d5: Download complete
2025-11-23 00:28:43.877640 | controller | 5667fdb72017: Verifying Checksum
2025-11-23 00:28:44.327256 | controller | 5667fdb72017: Download complete
2025-11-23 00:28:44.327312 | controller | b3e63023ca5f: Verifying Checksum
2025-11-23 00:28:44.328184 | controller | b3e63023ca5f: Download complete
2025-11-23 00:28:44.478311 | controller | 7fc152dfb3a6: Verifying Checksum
2025-11-23 00:28:44.737706 | controller | 7fc152dfb3a6: Download complete
2025-11-23 00:28:44.737768 | controller | 653cb3e65dbf: Verifying Checksum
2025-11-23 00:28:44.738286 | controller | 653cb3e65dbf: Download complete
2025-11-23 00:28:45.132527 | controller | d696ed7812f8: Verifying Checksum
2025-11-23 00:28:45.551775 | controller | d696ed7812f8: Download complete
2025-11-23 00:28:45.551843 | controller | 29bf28764f14: Verifying Checksum
2025-11-23 00:28:45.551931 | controller | 29bf28764f14: Download complete
2025-11-23 00:28:45.945252 | controller | 47d7edb3c2b7: Verifying Checksum
2025-11-23 00:28:46.333382 | controller | 47d7edb3c2b7: Download complete
2025-11-23 00:28:46.333438 | controller | 07550ad4f6e4: Verifying Checksum
2025-11-23 00:28:46.333835 | controller | 07550ad4f6e4: Download complete
2025-11-23 00:28:46.741817 | controller | 7e79526e64c5: Verifying Checksum
2025-11-23 00:28:47.261023 | controller | 7e79526e64c5: Download complete
2025-11-23 00:28:47.261115 | controller | 5667fdb72017: Pull complete
2025-11-23 00:28:47.305989 | controller | d83811f270d5: Pull complete
2025-11-23 00:28:47.330693 | controller | ee671aafb583: Pull complete
2025-11-23 00:28:47.353009 | controller | 7fc152dfb3a6: Pull complete
2025-11-23 00:28:47.559219 | controller | 4fffb32882b7: Verifying Checksum
2025-11-23 00:28:47.931973 | controller | 4fffb32882b7: Download complete
2025-11-23 00:28:47.932044 | controller | fc7a38b06f4e: Verifying Checksum
2025-11-23 00:28:48.292556 | controller | fc7a38b06f4e: Download complete
2025-11-23 00:28:48.292639 | controller | 6d9d912e060e: Verifying Checksum
2025-11-23 00:28:48.700443 | controller | 6d9d912e060e: Download complete
2025-11-23 00:28:48.700505 | controller | 5dd542dd28d9: Verifying Checksum
2025-11-23 00:28:49.214163 | controller | 5dd542dd28d9: Download complete
2025-11-23 00:28:49.214225 | controller | 3fb967a6fd03: Verifying Checksum
2025-11-23 00:28:49.569441 | controller | 3fb967a6fd03: Download complete
2025-11-23 00:28:49.569505 | controller | bd5557e101ad: Verifying Checksum
2025-11-23 00:28:49.944510 | controller | bd5557e101ad: Download complete
2025-11-23 00:28:49.944566 | controller | e057c20fabdd: Verifying Checksum
2025-11-23 00:28:49.944695 | controller | e057c20fabdd: Download complete
2025-11-23 00:28:50.332683 | controller | d16059cdae2e: Verifying Checksum
2025-11-23 00:28:50.332959 | controller | d16059cdae2e: Download complete
2025-11-23 00:28:50.491228 | controller | 3fb967a6fd03: Pull complete
2025-11-23 00:28:50.518503 | controller | b3e63023ca5f: Pull complete
2025-11-23 00:28:50.542308 | controller | 653cb3e65dbf: Pull complete
2025-11-23 00:28:50.829161 | controller | 431e42d906a3: Verifying Checksum
2025-11-23 00:28:50.829212 | controller | 431e42d906a3: Download complete
2025-11-23 00:28:52.127711 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-11-23 00:28:52.127773 | controller | 7a0fd3b1d7c3: Download complete
2025-11-23 00:28:53.605179 | controller | aacdb6db0c8c: Verifying Checksum
2025-11-23 00:28:53.606707 | controller | aacdb6db0c8c: Download complete
2025-11-23 00:28:54.023844 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-11-23 00:28:54.023902 | controller | 2e7f04ae8c8b: Download complete
2025-11-23 00:28:54.396814 | controller | 4cd343027a44: Verifying Checksum
2025-11-23 00:28:54.396865 | controller | 4cd343027a44: Download complete
2025-11-23 00:28:54.791357 | controller | 0b4379883f90: Verifying Checksum
2025-11-23 00:28:55.192394 | controller | 0b4379883f90: Download complete
2025-11-23 00:28:55.192472 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-11-23 00:28:55.192511 | controller | 3d9d6d9dfc91: Download complete
2025-11-23 00:28:58.706384 | controller | f4712b586375: Download complete
2025-11-23 00:29:06.738442 | controller | 57d6314bd293: Verifying Checksum
2025-11-23 00:29:10.915667 | controller | 57d6314bd293: Download complete
2025-11-23 00:29:10.915752 | controller | 774036afeb7f: Verifying Checksum
2025-11-23 00:29:10.915792 | controller | 774036afeb7f: Download complete
2025-11-23 00:29:17.684663 | controller | cef8beb99552: Verifying Checksum
2025-11-23 00:29:17.684738 | controller | cef8beb99552: Download complete
2025-11-23 00:29:18.637524 | controller | f4712b586375: Pull complete
2025-11-23 00:29:18.662335 | controller | d696ed7812f8: Pull complete
2025-11-23 00:29:18.691667 | controller | 29bf28764f14: Pull complete
2025-11-23 00:29:18.739561 | controller | 47d7edb3c2b7: Pull complete
2025-11-23 00:29:18.771787 | controller | 07550ad4f6e4: Pull complete
2025-11-23 00:29:18.954531 | controller | 7e79526e64c5: Pull complete
2025-11-23 00:29:18.974671 | controller | 4fffb32882b7: Pull complete
2025-11-23 00:29:19.044574 | controller | fc7a38b06f4e: Pull complete
2025-11-23 00:29:19.074386 | controller | 6d9d912e060e: Pull complete
2025-11-23 00:29:19.121551 | controller | 5dd542dd28d9: Pull complete
2025-11-23 00:29:22.112992 | controller | 78cf439d9fdb: Verifying Checksum
2025-11-23 00:29:22.952767 | controller | 78cf439d9fdb: Download complete
2025-11-23 00:29:22.952838 | controller | 54b10f0e1645: Verifying Checksum
2025-11-23 00:29:23.985956 | controller | 54b10f0e1645: Download complete
2025-11-23 00:29:23.986017 | controller | 4b19ac3f17a8: Verifying Checksum
2025-11-23 00:29:29.529320 | controller | 4b19ac3f17a8: Download complete
2025-11-23 00:29:29.529417 | controller | 36e5e20969c7: Verifying Checksum
2025-11-23 00:29:29.529456 | controller | 36e5e20969c7: Download complete
2025-11-23 00:29:39.341807 | controller | cef8beb99552: Pull complete
2025-11-23 00:29:39.393129 | controller | bd5557e101ad: Pull complete
2025-11-23 00:29:39.437587 | controller | e057c20fabdd: Pull complete
2025-11-23 00:29:39.466714 | controller | d16059cdae2e: Pull complete
2025-11-23 00:29:41.837353 | controller | 431e42d906a3: Pull complete
2025-11-23 00:29:41.883703 | controller | 7a0fd3b1d7c3: Pull complete
2025-11-23 00:29:41.940117 | controller | aacdb6db0c8c: Pull complete
2025-11-23 00:29:41.973531 | controller | 2e7f04ae8c8b: Pull complete
2025-11-23 00:29:42.012138 | controller | 4cd343027a44: Pull complete
2025-11-23 00:29:42.062421 | controller | 0b4379883f90: Pull complete
2025-11-23 00:29:42.103615 | controller | 3d9d6d9dfc91: Pull complete
2025-11-23 00:29:47.048540 | controller | 57d6314bd293: Pull complete
2025-11-23 00:30:06.126639 | controller | 774036afeb7f: Pull complete
2025-11-23 00:30:31.714572 | controller | 54b10f0e1645: Pull complete
2025-11-23 00:30:56.007874 | controller | 78cf439d9fdb: Pull complete
2025-11-23 00:31:20.914863 | controller | 36e5e20969c7: Pull complete
2025-11-23 00:31:45.949965 | controller | 4b19ac3f17a8: Pull complete
2025-11-23 00:31:46.100972 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-11-23 00:31:46.120165 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-11-23 00:31:46.140107 | controller | quay.io/ansible/default-test-container:1.10.1
2025-11-23 00:31:46.188726 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-23 00:31:46.188814 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-23 00:31:48.070785 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-23 00:31:48.070928 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-23 00:31:48.070998 | controller | ff496a88c8ed: Pulling fs layer
2025-11-23 00:31:48.071056 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-23 00:31:48.071128 | controller | 2e5170e1f099: Pulling fs layer
2025-11-23 00:31:48.071187 | controller | 7641eb41b08c: Pulling fs layer
2025-11-23 00:31:48.071251 | controller | ad15fa9da398: Pulling fs layer
2025-11-23 00:31:48.071326 | controller | 087d91352424: Pulling fs layer
2025-11-23 00:31:48.071395 | controller | 8b92efd6a100: Pulling fs layer
2025-11-23 00:31:48.072014 | controller | 2e5170e1f099: Waiting
2025-11-23 00:31:48.072111 | controller | 7641eb41b08c: Waiting
2025-11-23 00:31:48.072230 | controller | ad15fa9da398: Waiting
2025-11-23 00:31:48.072329 | controller | 087d91352424: Waiting
2025-11-23 00:31:48.072427 | controller | 8b92efd6a100: Waiting
2025-11-23 00:31:48.904160 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-23 00:31:48.904277 | controller | 0ce83f459fe7: Download complete
2025-11-23 00:31:49.319402 | controller | ff496a88c8ed: Verifying Checksum
2025-11-23 00:31:49.390671 | controller | ff496a88c8ed: Download complete
2025-11-23 00:31:49.390729 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-23 00:31:49.392898 | controller | 04a5f4cda3ee: Download complete
2025-11-23 00:31:50.232487 | controller | 2e5170e1f099: Verifying Checksum
2025-11-23 00:31:50.232590 | controller | 2e5170e1f099: Download complete
2025-11-23 00:31:50.258719 | controller | 7641eb41b08c: Verifying Checksum
2025-11-23 00:31:50.258846 | controller | 7641eb41b08c: Download complete
2025-11-23 00:31:50.576117 | controller | ad15fa9da398: Verifying Checksum
2025-11-23 00:31:50.576248 | controller | ad15fa9da398: Download complete
2025-11-23 00:31:51.084921 | controller | 04a5f4cda3ee: Pull complete
2025-11-23 00:31:51.133631 | controller | ff496a88c8ed: Pull complete
2025-11-23 00:31:51.171739 | controller | 0ce83f459fe7: Pull complete
2025-11-23 00:31:51.182014 | controller | 8b92efd6a100: Verifying Checksum
2025-11-23 00:31:51.182121 | controller | 8b92efd6a100: Download complete
2025-11-23 00:31:51.453099 | controller | 087d91352424: Verifying Checksum
2025-11-23 00:31:51.453220 | controller | 087d91352424: Download complete
2025-11-23 00:31:52.432628 | controller | 2e5170e1f099: Pull complete
2025-11-23 00:31:52.467316 | controller | 7641eb41b08c: Pull complete
2025-11-23 00:31:52.501143 | controller | ad15fa9da398: Pull complete
2025-11-23 00:31:53.481421 | controller | 087d91352424: Pull complete
2025-11-23 00:31:53.519079 | controller | 8b92efd6a100: Pull complete
2025-11-23 00:31:53.545986 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-23 00:31:53.553376 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-23 00:31:53.564073 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-23 00:31:53.568145 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-23 00:31:57.483184 | controller | Run command: docker inspect 242adf940f5e2db199076a168d0b78af9bec4abff5fb765c94c9a4ff1d19b2b0
2025-11-23 00:31:57.683172 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-23 00:31:57.706103 | controller | Including collection: ansible.netcommon (292 files)
2025-11-23 00:31:57.706214 | controller | Including collection: ansible.network (23 files)
2025-11-23 00:31:57.718637 | controller | Including collection: ansible.utils (442 files)
2025-11-23 00:31:57.757516 | controller | Including collection: arista.eos (1022 files)
2025-11-23 00:31:57.802721 | controller | Including collection: cisco.ios (1172 files)
2025-11-23 00:31:57.846365 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-23 00:31:57.917255 | controller | Including collection: cisco.nxos (1684 files)
2025-11-23 00:31:57.958586 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-23 00:31:57.997241 | controller | Creating a payload archive containing 8378 files...
2025-11-23 00:32:00.206991 | controller | Created a 6889684 byte payload archive containing 8378 files in 2 seconds.
2025-11-23 00:32:00.211662 | controller | Assuming Docker is available on localhost.
2025-11-23 00:32:00.781588 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:1.10.1
2025-11-23 00:32:00.781676 | controller | Run command: docker exec -i cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea dd of=/root/docker.sh bs=65536
2025-11-23 00:32:00.854074 | controller | Run command: docker exec cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea /bin/bash /root/docker.sh
2025-11-23 00:32:00.943389 | controller | Run command: docker exec -i cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea dd of=/root/ansible.tgz bs=65536
2025-11-23 00:32:01.026276 | controller | Run command: docker exec cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea mkdir /root/ansible
2025-11-23 00:32:01.086733 | controller | Run command: docker exec cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea tar oxzf /root/ansible.tgz -C /root/ansible
2025-11-23 00:32:02.139009 | controller | Run command: docker exec cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-spavr5j8.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-23 00:32:02.591165 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-23 00:32:02.591476 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-23 00:32:02.591929 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-23 00:32:02.593841 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-23 00:32:02.660740 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-23 00:32:02.664007 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-11-23 00:32:02.671368 | controller | No tests applicable.
2025-11-23 00:32:02.671514 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-23 00:32:02.671804 | controller | No tests applicable.
2025-11-23 00:32:02.672129 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-23 00:32:02.755642 | controller | Running sanity test 'compile' with Python 2.6
2025-11-23 00:32:02.756007 | controller | No tests applicable.
2025-11-23 00:32:02.756408 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-23 00:32:02.939446 | controller | Running sanity test 'compile' with Python 2.7
2025-11-23 00:32:02.939731 | controller | No tests applicable.
2025-11-23 00:32:02.940148 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-23 00:32:03.051359 | controller | Running sanity test 'compile' with Python 3.5
2025-11-23 00:32:03.051723 | controller | No tests applicable.
2025-11-23 00:32:03.051874 | controller | Running sanity test 'compile' with Python 3.6
2025-11-23 00:32:03.052131 | controller | No tests applicable.
2025-11-23 00:32:03.052561 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-23 00:32:03.146310 | controller | Running sanity test 'compile' with Python 3.7
2025-11-23 00:32:03.146721 | controller | No tests applicable.
2025-11-23 00:32:03.147131 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-23 00:32:03.245516 | controller | Running sanity test 'compile' with Python 3.8
2025-11-23 00:32:03.245985 | controller | No tests applicable.
2025-11-23 00:32:03.246197 | controller | Running sanity test 'empty-init' with Python 3.6
2025-11-23 00:32:03.246487 | controller | No tests applicable.
2025-11-23 00:32:03.246780 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-11-23 00:32:03.246796 | controller | No tests applicable.
2025-11-23 00:32:03.248010 | controller | Running sanity test 'ignores'
2025-11-23 00:32:03.248045 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-11-23 00:32:04.055625 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-11-23 00:32:04.059203 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-11-23 00:32:04.060794 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.063703 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-11-23 00:32:04.065464 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.066981 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.072916 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.077674 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.079166 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.083982 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.086707 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.088347 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-11-23 00:32:04.089748 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.093492 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.100314 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.101918 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.103273 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.105898 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.107266 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.110967 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.112306 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.113829 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-11-23 00:32:04.116133 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-11-23 00:32:04.120694 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-11-23 00:32:04.124550 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-11-23 00:32:04.125918 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-11-23 00:32:04.142067 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-11-23 00:32:04.143248 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2025-11-23 00:32:04.169863 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2025-11-23 00:32:04.173751 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2025-11-23 00:32:04.177275 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2025-11-23 00:32:04.180152 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2025-11-23 00:32:04.186463 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2025-11-23 00:32:04.190357 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2025-11-23 00:32:04.194352 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2025-11-23 00:32:04.196318 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2025-11-23 00:32:04.224360 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2025-11-23 00:32:04.228662 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2025-11-23 00:32:04.231397 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2025-11-23 00:32:04.233319 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2025-11-23 00:32:04.235510 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2025-11-23 00:32:04.237426 | controller | Requirement already satisfied: mccabe==0.6.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 57)) (0.6.1)
2025-11-23 00:32:04.240416 | controller | Collecting astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-23 00:32:19.258919 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-23 00:32:34.777031 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-23 00:32:50.781188 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-23 00:33:07.801718 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-23 00:33:26.808007 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-23 00:33:41.813954 | controller |   ERROR: Could not find a version that satisfies the requirement astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54)) (from versions: none)
2025-11-23 00:33:41.918798 | controller | ERROR: No matching distribution found for astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-23 00:33:41.918879 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-11-23 00:33:41.919046 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-23 00:33:41.919196 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-23 00:33:41.959462 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-23 00:33:41.959520 | controller | Run command: docker exec cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-11-23 00:33:42.026204 | controller | Run command: docker exec -i cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea dd if=/root/output.tgz bs=65536
2025-11-23 00:33:42.100214 | controller | Run command: tar oxzf /tmp/ansible-result-sqwugijt.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-23 00:33:42.111826 | controller | Run command: docker rm -f 242adf940f5e2db199076a168d0b78af9bec4abff5fb765c94c9a4ff1d19b2b0
2025-11-23 00:33:42.340038 | controller | Run command: docker rm -f cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea
2025-11-23 00:33:43.002158 | controller | ERROR: Command "docker exec cf08282d3f776765c5e8554760ae58b8aca0f92c6ed2ca861db4eaae0293a9ea /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-spavr5j8.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-23 00:33:43.449556 | controller | ERROR
2025-11-23 00:33:43.449896 | controller | {
2025-11-23 00:33:43.449963 | controller |   "delta": "0:05:01.626958",
2025-11-23 00:33:43.450007 | controller |   "end": "2025-11-23 00:33:43.063418",
2025-11-23 00:33:43.450044 | controller |   "msg": "non-zero return code",
2025-11-23 00:33:43.450080 | controller |   "rc": 1,
2025-11-23 00:33:43.450211 | controller |   "start": "2025-11-23 00:28:41.436460"
2025-11-23 00:33:43.450257 | controller | }
failure
2025-11-23 00:33:43.459154 | 
2025-11-23 00:33:43.459292 | PLAY RECAP
2025-11-23 00:33:43.459647 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-23 00:33:43.459808 | 
2025-11-23 00:33:43.589918 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-23 00:33:43.590864 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-23 00:33:44.171655 | 
2025-11-23 00:33:44.171802 | PLAY [all]
2025-11-23 00:33:44.193885 | 
2025-11-23 00:33:44.194031 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-23 00:33:47.213175 | controller | changed: non-zero return code
2025-11-23 00:33:47.230218 | 
2025-11-23 00:33:47.230392 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-23 00:33:47.257615 | controller | skipping: Conditional result was False
2025-11-23 00:33:47.270765 | 
2025-11-23 00:33:47.270921 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-23 00:33:47.313100 | 
2025-11-23 00:33:47.313404 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-23 00:33:47.354163 | 
2025-11-23 00:33:47.354432 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-23 00:33:47.381269 | controller | skipping: Conditional result was False
2025-11-23 00:33:47.394620 | 
2025-11-23 00:33:47.394798 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-23 00:33:47.434206 | 
2025-11-23 00:33:47.434475 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-23 00:33:47.461065 | controller | skipping: Conditional result was False
2025-11-23 00:33:47.477002 | 
2025-11-23 00:33:47.477148 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-23 00:33:47.503891 | controller | skipping: Conditional result was False
2025-11-23 00:33:47.516691 | 
2025-11-23 00:33:47.516839 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-23 00:33:47.543382 | controller | skipping: Conditional result was False
2025-11-23 00:33:47.591271 | 
2025-11-23 00:33:47.591378 | PLAY RECAP
2025-11-23 00:33:47.591441 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-23 00:33:47.591469 | 
2025-11-23 00:33:47.689865 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-23 00:33:47.691471 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-23 00:33:48.345816 | 
2025-11-23 00:33:48.345948 | PLAY [all:!appliance*]
2025-11-23 00:33:48.367871 | 
2025-11-23 00:33:48.367993 | TASK [unregister the node]
2025-11-23 00:33:48.720005 | controller | skipping: Conditional result was False
2025-11-23 00:33:48.726135 | 
2025-11-23 00:33:48.726228 | TASK [include_role : fetch-output]
2025-11-23 00:33:48.757862 | controller | ok
2025-11-23 00:33:48.779579 | 
2025-11-23 00:33:48.779721 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-23 00:33:48.845393 | controller | skipping: Conditional result was False
2025-11-23 00:33:48.852389 | 
2025-11-23 00:33:48.852575 | TASK [fetch-output : Set log path for single node]
2025-11-23 00:33:48.892650 | controller | ok
2025-11-23 00:33:48.898161 | 
2025-11-23 00:33:48.898237 | LOOP [fetch-output : Ensure local output dirs]
2025-11-23 00:33:49.333316 | controller -> localhost | ok: "/var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/logs"
2025-11-23 00:33:49.622940 | controller -> localhost | changed: "/var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/artifacts"
2025-11-23 00:33:49.870926 | controller -> localhost | changed: "/var/lib/zuul/builds/9df6cb49010b4ed789019cfec1cd2958/work/docs"
2025-11-23 00:33:49.890711 | 
2025-11-23 00:33:49.890903 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-23 00:33:52.161789 | controller | changed:
2025-11-23 00:33:52.162390 | controller | .d..t...... ./
2025-11-23 00:33:52.162464 | controller | cd+++++++++ controller/
2025-11-23 00:33:52.162540 | controller | changed: All items complete
2025-11-23 00:33:52.162587 | 
2025-11-23 00:33:54.245725 | controller | changed: .d..t...... ./
2025-11-23 00:33:56.265166 | controller | changed: .d..t...... ./
2025-11-23 00:33:56.296882 | 
2025-11-23 00:33:56.297079 | TASK [include_role : fetch-output-openshift]
2025-11-23 00:33:56.324400 | controller | skipping: Conditional result was False
2025-11-23 00:33:56.338650 | 
2025-11-23 00:33:56.338847 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-23 00:33:56.381243 | controller | skipping: Conditional result was False
2025-11-23 00:33:56.397521 | controller | skipping: Conditional result was False
2025-11-23 00:33:56.462529 | 
2025-11-23 00:33:56.462655 | PLAY [localhost]
2025-11-23 00:33:56.481301 | 
2025-11-23 00:33:56.481381 | TASK [Run Zuul manifest role]
2025-11-23 00:33:56.502141 | localhost | ok
2025-11-23 00:33:56.520102 | 
2025-11-23 00:33:56.520188 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-23 00:33:56.913855 | localhost | changed
2025-11-23 00:33:56.925333 | 
2025-11-23 00:33:56.925465 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-23 00:33:56.962773 | localhost | ok
2025-11-23 00:33:56.982589 | 
2025-11-23 00:33:56.982773 | TASK [Set zuul-log-path fact]
2025-11-23 00:33:57.005517 | localhost | ok
2025-11-23 00:33:57.052918 | 
2025-11-23 00:33:57.053032 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-23 00:33:57.091928 | localhost | ok
2025-11-23 00:33:57.099989 | 
2025-11-23 00:33:57.100112 | LOOP [Run upload-logs-swift role]
2025-11-23 00:33:57.147246 | localhost | Output suppressed because no_log was given
2025-11-23 00:33:57.172778 | 
2025-11-23 00:33:57.172875 | TASK [Set zuul-log-path fact]
2025-11-23 00:33:57.197288 | localhost | skipping: Conditional result was False
2025-11-23 00:33:57.204066 | 
2025-11-23 00:33:57.204132 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-23 00:33:57.584426 | localhost -> localhost | ok: Runtime: 0:00:00.005036
2025-11-23 00:33:57.591473 | 
2025-11-23 00:33:57.591550 | TASK [upload-logs-swift : Upload logs to swift]