2025-12-23 00:06:58.149097 | Job console starting...
2025-12-23 00:06:58.160583 | Updating repositories
2025-12-23 00:06:58.531219 | Preparing job workspace
2025-12-23 00:07:02.221981 | Running Ansible setup...
2025-12-23 00:07:07.004069 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-23 00:07:07.648585 | 
2025-12-23 00:07:07.648744 | PLAY [localhost]
2025-12-23 00:07:07.658093 | 
2025-12-23 00:07:07.658196 | TASK [Gathering Facts]
2025-12-23 00:07:08.772608 | localhost | ok
2025-12-23 00:07:08.788631 | 
2025-12-23 00:07:08.788754 | TASK [Setup log path fact]
2025-12-23 00:07:08.807150 | localhost | ok
2025-12-23 00:07:08.819697 | 
2025-12-23 00:07:08.819780 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-23 00:07:08.838706 | localhost | ok
2025-12-23 00:07:08.846959 | 
2025-12-23 00:07:08.847029 | TASK [emit-job-header : Print job information]
2025-12-23 00:07:08.894137 | # Job Information
2025-12-23 00:07:08.894286 | Ansible Version: 2.15.12
2025-12-23 00:07:08.894318 | Job: ansible-test-sanity-docker-stable-2.10
2025-12-23 00:07:08.894343 | Pipeline: periodic
2025-12-23 00:07:08.894362 | Executor: ze04.softwarefactory-project.io
2025-12-23 00:07:08.894381 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-23 00:07:08.894401 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/c04/ansible/c04b1a6ee92c4a19b3fb3263cb949774/
2025-12-23 00:07:08.894420 | Event ID: 53716c5a236c4d5eab91d4bd51d64c9b
2025-12-23 00:07:08.898512 | 
2025-12-23 00:07:08.898577 | LOOP [emit-job-header : Print node information]
2025-12-23 00:07:09.011842 | localhost | ok:
2025-12-23 00:07:09.012248 | localhost | # Node Information
2025-12-23 00:07:09.012282 | localhost | Inventory Hostname: controller
2025-12-23 00:07:09.012304 | localhost | Hostname: np0005569178
2025-12-23 00:07:09.012325 | localhost | Username: zuul
2025-12-23 00:07:09.012350 | localhost | Distro: Fedora 37
2025-12-23 00:07:09.012370 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-12-23 00:07:09.012389 | localhost | Region: ca-ymq-1
2025-12-23 00:07:09.012407 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-23 00:07:09.012426 | localhost | Product Name: OpenStack Nova
2025-12-23 00:07:09.012443 | localhost | Interface IP: 162.253.55.227
2025-12-23 00:07:09.034804 | 
2025-12-23 00:07:09.035026 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-23 00:07:09.483893 | localhost -> localhost | changed
2025-12-23 00:07:09.490555 | 
2025-12-23 00:07:09.490671 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-23 00:07:10.322055 | localhost -> localhost | changed
2025-12-23 00:07:10.342803 | 
2025-12-23 00:07:10.342926 | PLAY [all:!appliance*]
2025-12-23 00:07:10.359434 | 
2025-12-23 00:07:10.359517 | TASK [include_role : start-zuul-console]
2025-12-23 00:07:10.378466 | controller | ok
2025-12-23 00:07:10.393300 | 
2025-12-23 00:07:10.393389 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-23 00:07:10.796986 | controller | ok
2025-12-23 00:07:10.823941 | 
2025-12-23 00:07:10.824146 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-23 00:07:12.056071 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-23 00:07:12.071388 | 
2025-12-23 00:07:12.071602 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-23 00:07:12.623963 | controller | skipping: Conditional result was False
2025-12-23 00:07:12.639246 | 
2025-12-23 00:07:12.639416 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-23 00:07:12.667570 | controller | skipping: Conditional result was False
2025-12-23 00:07:12.684431 | 
2025-12-23 00:07:12.684613 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-23 00:07:12.712419 | controller | skipping: Conditional result was False
2025-12-23 00:07:12.727912 | 
2025-12-23 00:07:12.728149 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-23 00:07:12.755864 | controller | skipping: Conditional result was False
2025-12-23 00:07:12.773137 | 
2025-12-23 00:07:12.773330 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-23 00:07:12.802028 | controller | skipping: Conditional result was False
2025-12-23 00:07:12.810077 | 
2025-12-23 00:07:12.810175 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-23 00:07:12.833948 | controller | skipping: Conditional result was False
2025-12-23 00:07:12.850560 | 
2025-12-23 00:07:12.850739 | TASK [Disable Fedora Modular]
2025-12-23 00:07:13.090754 | controller | changed
2025-12-23 00:07:13.102414 | 
2025-12-23 00:07:13.102571 | TASK [Enable EPEL]
2025-12-23 00:07:13.128946 | controller | skipping: Conditional result was False
2025-12-23 00:07:13.139918 | 
2025-12-23 00:07:13.140107 | TASK [Register the RHEL node]
2025-12-23 00:07:13.706749 | 
2025-12-23 00:07:13.706923 | TASK [Show the subscription-manager status]
2025-12-23 00:07:14.262996 | controller | skipping: Conditional result was False
2025-12-23 00:07:14.270041 | 
2025-12-23 00:07:14.270127 | TASK [Enable EPEL on RHEL]
2025-12-23 00:07:14.809729 | controller | skipping: Conditional result was False
2025-12-23 00:07:14.825837 | 
2025-12-23 00:07:14.826037 | TASK [Install git and tox]
2025-12-23 00:08:54.839185 | controller | changed
2025-12-23 00:08:54.848070 | 
2025-12-23 00:08:54.848172 | TASK [include_role : prepare-workspace]
2025-12-23 00:08:54.882984 | controller | ok
2025-12-23 00:08:54.913987 | 
2025-12-23 00:08:54.914125 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-23 00:08:55.167079 | controller | ok
2025-12-23 00:08:55.182988 | 
2025-12-23 00:08:55.183154 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-23 00:09:08.428534 | controller | Output suppressed because no_log was given
2025-12-23 00:09:08.440912 | 
2025-12-23 00:09:08.441042 | TASK [include_role : prepare-workspace-openshift]
2025-12-23 00:09:08.459031 | controller | skipping: Conditional result was False
2025-12-23 00:09:08.485488 | 
2025-12-23 00:09:08.485592 | PLAY [all:!appliance]
2025-12-23 00:09:08.502617 | 
2025-12-23 00:09:08.502748 | TASK [Run add-build-sshkey role (RSA)]
2025-12-23 00:09:08.523469 | controller | ok
2025-12-23 00:09:08.539351 | 
2025-12-23 00:09:08.539470 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-23 00:09:08.746974 | controller -> localhost | ok
2025-12-23 00:09:08.754223 | 
2025-12-23 00:09:08.754316 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-23 00:09:08.789300 | controller | ok
2025-12-23 00:09:08.810623 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-23 00:09:08.819097 | 
2025-12-23 00:09:08.819188 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-23 00:09:09.263257 | controller -> localhost | Generating public/private rsa key pair.
2025-12-23 00:09:09.263499 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/c04b1a6ee92c4a19b3fb3263cb949774_id_rsa.
2025-12-23 00:09:09.263539 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/c04b1a6ee92c4a19b3fb3263cb949774_id_rsa.pub.
2025-12-23 00:09:09.263568 | controller -> localhost | The key fingerprint is:
2025-12-23 00:09:09.263594 | controller -> localhost | SHA256:mOuTU9/ZTQ9DbomuKRYxlaxk6JMglQFtxamoFCFJ5JE zuul-build-sshkey
2025-12-23 00:09:09.263619 | controller -> localhost | The key's randomart image is:
2025-12-23 00:09:09.263644 | controller -> localhost | +---[RSA 2048]----+
2025-12-23 00:09:09.263668 | controller -> localhost | |+*++o=.o . .     |
2025-12-23 00:09:09.263722 | controller -> localhost | |+Eo = + o +      |
2025-12-23 00:09:09.263748 | controller -> localhost | | ..+ + + o       |
2025-12-23 00:09:09.263773 | controller -> localhost | | .. . +o+     .  |
2025-12-23 00:09:09.263796 | controller -> localhost | |..    o.So   + . |
2025-12-23 00:09:09.263830 | controller -> localhost | |.      .o   . * .|
2025-12-23 00:09:09.263861 | controller -> localhost | |      .o o o + =.|
2025-12-23 00:09:09.263887 | controller -> localhost | |     .+ o ..+ . o|
2025-12-23 00:09:09.263911 | controller -> localhost | |      .+ .o.     |
2025-12-23 00:09:09.263939 | controller -> localhost | +----[SHA256]-----+
2025-12-23 00:09:09.263997 | controller -> localhost | ok: Runtime: 0:00:00.065665
2025-12-23 00:09:09.274199 | 
2025-12-23 00:09:09.274282 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-23 00:09:09.307812 | controller | ok
2025-12-23 00:09:09.322703 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-23 00:09:09.335911 | 
2025-12-23 00:09:09.336017 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-23 00:09:09.361544 | controller | skipping: Conditional result was False
2025-12-23 00:09:09.369294 | 
2025-12-23 00:09:09.369368 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-23 00:09:09.814322 | controller | changed
2025-12-23 00:09:09.820093 | 
2025-12-23 00:09:09.820155 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-23 00:09:10.062548 | controller | ok
2025-12-23 00:09:10.077419 | 
2025-12-23 00:09:10.077579 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-23 00:09:10.738841 | controller | changed
2025-12-23 00:09:10.748845 | 
2025-12-23 00:09:10.748983 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-23 00:09:11.473392 | controller | changed
2025-12-23 00:09:11.488718 | 
2025-12-23 00:09:11.488931 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-23 00:09:11.526778 | controller | skipping: Conditional result was False
2025-12-23 00:09:11.543794 | 
2025-12-23 00:09:11.544020 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-23 00:09:11.990438 | controller -> localhost | changed
2025-12-23 00:09:12.005129 | 
2025-12-23 00:09:12.005263 | TASK [add-build-sshkey : Add back temp key]
2025-12-23 00:09:12.315565 | controller -> localhost | Identity added: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/c04b1a6ee92c4a19b3fb3263cb949774_id_rsa (zuul-build-sshkey)
2025-12-23 00:09:12.315858 | controller -> localhost | ok: Runtime: 0:00:00.008752
2025-12-23 00:09:12.323109 | 
2025-12-23 00:09:12.323180 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-23 00:09:12.732284 | controller | ok
2025-12-23 00:09:12.739991 | 
2025-12-23 00:09:12.740092 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-23 00:09:12.768046 | controller | skipping: Conditional result was False
2025-12-23 00:09:12.785729 | 
2025-12-23 00:09:12.785870 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-23 00:09:12.819932 | controller | ok
2025-12-23 00:09:12.841673 | 
2025-12-23 00:09:12.841834 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-23 00:09:13.081619 | controller -> localhost | ok
2025-12-23 00:09:13.088432 | 
2025-12-23 00:09:13.088728 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-23 00:09:13.121496 | controller | ok
2025-12-23 00:09:13.133440 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-23 00:09:13.141538 | 
2025-12-23 00:09:13.141636 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-23 00:09:13.431448 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-23 00:09:13.431735 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/c04b1a6ee92c4a19b3fb3263cb949774_id_ecdsa.
2025-12-23 00:09:13.431773 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/c04b1a6ee92c4a19b3fb3263cb949774_id_ecdsa.pub.
2025-12-23 00:09:13.431804 | controller -> localhost | The key fingerprint is:
2025-12-23 00:09:13.431825 | controller -> localhost | SHA256:plo2J+J+9zT7Y+Tt84ANXa5meiJEISbpKpmNJxq1W8A zuul-build-sshkey
2025-12-23 00:09:13.431843 | controller -> localhost | The key's randomart image is:
2025-12-23 00:09:13.431863 | controller -> localhost | +---[ECDSA 521]---+
2025-12-23 00:09:13.431882 | controller -> localhost | |       .         |
2025-12-23 00:09:13.431899 | controller -> localhost | |      o o .      |
2025-12-23 00:09:13.431916 | controller -> localhost | | .   . o . .    .|
2025-12-23 00:09:13.431932 | controller -> localhost | |  E   .   .  . o |
2025-12-23 00:09:13.431949 | controller -> localhost | | . B .  S.  . . .|
2025-12-23 00:09:13.431967 | controller -> localhost | |. B =  o  . .+ . |
2025-12-23 00:09:13.431984 | controller -> localhost | | o *. * ..oo..*  |
2025-12-23 00:09:13.432001 | controller -> localhost | |. .. =.+...o=++. |
2025-12-23 00:09:13.432019 | controller -> localhost | |   .+. . .o+o=.o.|
2025-12-23 00:09:13.432036 | controller -> localhost | +----[SHA256]-----+
2025-12-23 00:09:13.432081 | controller -> localhost | ok: Runtime: 0:00:00.009572
2025-12-23 00:09:13.439429 | 
2025-12-23 00:09:13.439500 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-23 00:09:13.461652 | controller | ok
2025-12-23 00:09:13.470040 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-23 00:09:13.480504 | 
2025-12-23 00:09:13.480587 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-23 00:09:13.495827 | controller | skipping: Conditional result was False
2025-12-23 00:09:13.503164 | 
2025-12-23 00:09:13.503242 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-23 00:09:13.848047 | controller | changed
2025-12-23 00:09:13.859754 | 
2025-12-23 00:09:13.859928 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-23 00:09:14.078193 | controller | ok
2025-12-23 00:09:14.087417 | 
2025-12-23 00:09:14.087550 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-23 00:09:14.739325 | controller | changed
2025-12-23 00:09:14.753498 | 
2025-12-23 00:09:14.753636 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-23 00:09:15.417285 | controller | changed
2025-12-23 00:09:15.430859 | 
2025-12-23 00:09:15.431048 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-23 00:09:15.458204 | controller | skipping: Conditional result was False
2025-12-23 00:09:15.505552 | 
2025-12-23 00:09:15.505720 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-23 00:09:15.757706 | controller -> localhost | changed
2025-12-23 00:09:15.776010 | 
2025-12-23 00:09:15.776168 | TASK [add-build-sshkey : Add back temp key]
2025-12-23 00:09:16.088946 | controller -> localhost | Identity added: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/c04b1a6ee92c4a19b3fb3263cb949774_id_ecdsa (zuul-build-sshkey)
2025-12-23 00:09:16.089319 | controller -> localhost | ok: Runtime: 0:00:00.009869
2025-12-23 00:09:16.106124 | 
2025-12-23 00:09:16.106321 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-23 00:09:16.329852 | controller | ok
2025-12-23 00:09:16.345897 | 
2025-12-23 00:09:16.346119 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-23 00:09:16.382850 | controller | skipping: Conditional result was False
2025-12-23 00:09:16.409170 | 
2025-12-23 00:09:16.409343 | TASK [include_role : remove-zuul-sshkey]
2025-12-23 00:09:16.436252 | controller | skipping: Conditional result was False
2025-12-23 00:09:16.449778 | 
2025-12-23 00:09:16.449913 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-23 00:09:16.716764 | controller | ok: "logs"
2025-12-23 00:09:16.717017 | controller | ok: All items complete
2025-12-23 00:09:16.717045 | 
2025-12-23 00:09:16.920310 | controller | ok: "artifacts"
2025-12-23 00:09:17.126575 | controller | ok: "docs"
2025-12-23 00:09:17.143378 | 
2025-12-23 00:09:17.143507 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-23 00:09:17.407607 | controller | changed: "logs"
2025-12-23 00:09:17.613452 | controller | changed: "artifacts"
2025-12-23 00:09:17.829775 | controller | changed: "docs"
2025-12-23 00:09:17.877613 | 
2025-12-23 00:09:17.877786 | PLAY RECAP
2025-12-23 00:09:17.877871 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-23 00:09:17.877921 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-23 00:09:17.877958 | 
2025-12-23 00:09:18.004373 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-23 00:09:18.006475 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-23 00:09:18.667640 | 
2025-12-23 00:09:18.667846 | PLAY [all]
2025-12-23 00:09:18.692624 | 
2025-12-23 00:09:18.692788 | TASK [Install binary dependencies]
2025-12-23 00:09:18.763221 | controller | ok
2025-12-23 00:09:18.783454 | 
2025-12-23 00:09:18.783571 | TASK [bindep : Include find tasks]
2025-12-23 00:09:18.824615 | controller | ok
2025-12-23 00:09:18.833225 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-23 00:09:18.840364 | 
2025-12-23 00:09:18.840437 | TASK [bindep : Look for bindep.txt]
2025-12-23 00:09:19.263808 | controller | ok
2025-12-23 00:09:19.273375 | 
2025-12-23 00:09:19.273486 | TASK [bindep : Define bindep_file fact]
2025-12-23 00:09:19.288495 | controller | skipping: Conditional result was False
2025-12-23 00:09:19.296460 | 
2025-12-23 00:09:19.296601 | TASK [bindep : Look for other-requirements.txt]
2025-12-23 00:09:19.532775 | controller | ok
2025-12-23 00:09:19.539769 | 
2025-12-23 00:09:19.539856 | TASK [bindep : Define bindep_file fact]
2025-12-23 00:09:19.584482 | controller | skipping: Conditional result was False
2025-12-23 00:09:19.594016 | 
2025-12-23 00:09:19.594159 | TASK [bindep : Look for bindep fallback file]
2025-12-23 00:09:19.624609 | controller | skipping: Conditional result was False
2025-12-23 00:09:19.633570 | 
2025-12-23 00:09:19.633703 | TASK [bindep : Define bindep_file fact]
2025-12-23 00:09:19.658759 | controller | skipping: Conditional result was False
2025-12-23 00:09:19.666025 | 
2025-12-23 00:09:19.666136 | TASK [bindep : Include bindep tasks]
2025-12-23 00:09:19.690268 | controller | skipping: Conditional result was False
2025-12-23 00:09:19.698847 | 
2025-12-23 00:09:19.698986 | TASK [bindep : Include install tasks]
2025-12-23 00:09:19.723155 | controller | skipping: Conditional result was False
2025-12-23 00:09:19.733662 | 
2025-12-23 00:09:19.733881 | LOOP [bindep : Include package tasks]
2025-12-23 00:09:19.799087 | 
2025-12-23 00:09:19.799388 | TASK [Run test-setup role]
2025-12-23 00:09:19.824234 | controller | ok
2025-12-23 00:09:19.852320 | 
2025-12-23 00:09:19.852467 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-23 00:09:20.077804 | controller | ok
2025-12-23 00:09:20.084913 | 
2025-12-23 00:09:20.085005 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-23 00:09:20.625130 | controller | skipping: Conditional result was False
2025-12-23 00:09:20.654611 | 
2025-12-23 00:09:20.654728 | PLAY RECAP
2025-12-23 00:09:20.654774 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-23 00:09:20.654803 | 
2025-12-23 00:09:20.795784 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-23 00:09:20.797208 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-23 00:09:21.402750 | 
2025-12-23 00:09:21.402925 | PLAY [controller]
2025-12-23 00:09:21.425190 | 
2025-12-23 00:09:21.425350 | TASK [Create the /root directory]
2025-12-23 00:09:21.830793 | controller | ok
2025-12-23 00:09:21.839910 | 
2025-12-23 00:09:21.840050 | TASK [Install glibc-langpack-en]
2025-12-23 00:09:25.801007 | controller | ok: Nothing to do
2025-12-23 00:09:25.815747 | 
2025-12-23 00:09:25.815902 | TASK [Ensure controller directory exists]
2025-12-23 00:09:26.023720 | controller | changed
2025-12-23 00:09:26.031378 | 
2025-12-23 00:09:26.031565 | TASK [Install container runtime]
2025-12-23 00:09:26.098071 | controller | ok
2025-12-23 00:09:26.155175 | 
2025-12-23 00:09:26.155329 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-23 00:09:26.201041 | controller | ok: "/var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-23 00:09:26.226974 | 
2025-12-23 00:09:26.227153 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-23 00:09:26.255203 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.265389 | 
2025-12-23 00:09:26.265498 | TASK [ensure-docker : Sanity check for distro install]
2025-12-23 00:09:26.290651 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.301229 | 
2025-12-23 00:09:26.301415 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-23 00:09:26.327336 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.337312 | 
2025-12-23 00:09:26.337468 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-23 00:09:26.364977 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.381384 | 
2025-12-23 00:09:26.381569 | TASK [ensure-docker : Include tasks for docker install]
2025-12-23 00:09:26.404440 | controller | ok
2025-12-23 00:09:26.413920 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-23 00:09:26.423856 | 
2025-12-23 00:09:26.423973 | TASK [ensure-docker : Not Implemented]
2025-12-23 00:09:26.438695 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.446605 | 
2025-12-23 00:09:26.446742 | TASK [Set up docker mirrors]
2025-12-23 00:09:26.466877 | controller | ok
2025-12-23 00:09:26.488387 | 
2025-12-23 00:09:26.488476 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-23 00:09:26.512728 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.519854 | 
2025-12-23 00:09:26.519943 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-23 00:09:26.544670 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.551817 | 
2025-12-23 00:09:26.551930 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-23 00:09:26.576408 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.583453 | 
2025-12-23 00:09:26.583580 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-23 00:09:26.608321 | controller | skipping: Conditional result was False
2025-12-23 00:09:26.622029 | 
2025-12-23 00:09:26.622157 | TASK [Add all repositories]
2025-12-23 00:09:26.642332 | controller | ok
2025-12-23 00:09:26.672263 | 
2025-12-23 00:09:26.672402 | LOOP [ensure-package-repositories : Install repositories]
2025-12-23 00:09:26.708709 | controller | ok: "/var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-23 00:09:26.708947 | controller | ok: All items complete
2025-12-23 00:09:26.708989 | 
2025-12-23 00:09:26.758855 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-23 00:09:26.768031 | 
2025-12-23 00:09:26.768117 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-23 00:09:27.123136 | controller | changed
2025-12-23 00:09:27.135964 | 
2025-12-23 00:09:27.136045 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-23 00:09:28.018729 | controller | changed:
2025-12-23 00:09:28.022805 | controller | {
2025-12-23 00:09:28.022881 | 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-12-23 00:09:28.022919 | controller | }
2025-12-23 00:09:28.039977 | 
2025-12-23 00:09:28.040130 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-23 00:09:28.699595 | controller | changed:
2025-12-23 00:09:28.699907 | controller | {
2025-12-23 00:09:28.699953 | 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-12-23 00:09:28.699996 | controller | }
2025-12-23 00:09:28.700096 | controller | changed: All items complete
2025-12-23 00:09:28.700143 | 
2025-12-23 00:09:28.719418 | 
2025-12-23 00:09:28.719570 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-23 00:09:28.936780 | controller | changed
2025-12-23 00:09:28.945603 | 
2025-12-23 00:09:28.945741 | LOOP [ensure-package-repositories : Add repositories]
2025-12-23 00:09:29.283789 | controller | changed:
2025-12-23 00:09:29.284015 | controller | {
2025-12-23 00:09:29.284042 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-23 00:09:29.284062 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-23 00:09:29.284079 | controller |   "gpgcheck": true,
2025-12-23 00:09:29.284111 | controller |   "name": "docker-ce-stable"
2025-12-23 00:09:29.284128 | controller | }
2025-12-23 00:09:29.303710 | 
2025-12-23 00:09:29.303856 | TASK [ensure-docker : Install pre-reqs]
2025-12-23 00:09:32.083314 | controller | ok: Nothing to do
2025-12-23 00:09:32.089990 | 
2025-12-23 00:09:32.090099 | TASK [ensure-docker : Remove packages]
2025-12-23 00:09:33.416281 | controller | ok: Nothing to do
2025-12-23 00:09:33.429998 | 
2025-12-23 00:09:33.430198 | TASK [ensure-docker : Install upstream docker using package]
2025-12-23 00:10:21.029999 | controller | changed
2025-12-23 00:10:21.045292 | 
2025-12-23 00:10:21.045474 | TASK [ensure-docker : Setup docker]
2025-12-23 00:10:21.078986 | controller | ok
2025-12-23 00:10:21.098039 | controller | included: /var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-23 00:10:21.117407 | 
2025-12-23 00:10:21.117555 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-23 00:10:21.545055 | controller | ok
2025-12-23 00:10:21.558401 | 
2025-12-23 00:10:21.558514 | TASK [ensure-docker : Add user to docker group]
2025-12-23 00:10:22.099901 | controller | changed
2025-12-23 00:10:22.106305 | 
2025-12-23 00:10:22.106404 | TASK [Update docker daemon proxy configuration]
2025-12-23 00:10:22.151478 | controller | skipping: Conditional result was False
2025-12-23 00:10:22.158169 | 
2025-12-23 00:10:22.158277 | TASK [Lower default MTU]
2025-12-23 00:10:22.177885 | controller | skipping: Conditional result was False
2025-12-23 00:10:22.188624 | 
2025-12-23 00:10:22.188793 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-23 00:10:22.568524 | controller | inactive
2025-12-23 00:10:22.761470 | controller | ok: Runtime: 0:00:00.019920
2025-12-23 00:10:22.778812 | 
2025-12-23 00:10:22.779021 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-23 00:10:23.051176 | controller | inactive
2025-12-23 00:10:23.335253 | controller | ok: Runtime: 0:00:00.010776
2025-12-23 00:10:23.347303 | 
2025-12-23 00:10:23.347458 | TASK [ensure-docker : Restart docker]
2025-12-23 00:10:23.418879 | Trigger a Docker restart
2025-12-23 00:10:23.431744 | 
2025-12-23 00:10:23.432023 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-23 00:10:23.480274 | 
2025-12-23 00:10:23.480449 | TASK [ensure-docker : Install docker-compose]
2025-12-23 00:10:23.508238 | controller | skipping: Conditional result was False
2025-12-23 00:10:23.517695 | 
2025-12-23 00:10:23.517833 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-23 00:10:23.533715 | 
2025-12-23 00:10:23.533825 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-23 00:10:24.830361 | controller | changed
2025-12-23 00:10:24.842053 | 
2025-12-23 00:10:24.842198 | TASK [ensure-docker : Assure docker service is running]
2025-12-23 00:10:26.810823 | controller | changed
2025-12-23 00:10:26.831252 | 
2025-12-23 00:10:26.831438 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-23 00:10:27.528845 | controller | ok
2025-12-23 00:10:27.542051 | 
2025-12-23 00:10:27.542182 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-23 00:10:28.087076 | controller | ok
2025-12-23 00:10:28.095414 | 
2025-12-23 00:10:28.095533 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-23 00:10:28.529354 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-23 00:10:28.631535 | controller | ok: Runtime: 0:00:00.036998
2025-12-23 00:10:28.637992 | 
2025-12-23 00:10:28.638064 | TASK [ensure-docker : Get version details]
2025-12-23 00:10:29.089109 | controller | Client: Docker Engine - Community
2025-12-23 00:10:29.089164 | controller |  Version:           24.0.7
2025-12-23 00:10:29.089172 | controller |  API version:       1.43
2025-12-23 00:10:29.089177 | controller |  Go version:        go1.20.10
2025-12-23 00:10:29.089188 | controller |  Git commit:        afdd53b
2025-12-23 00:10:29.089193 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-23 00:10:29.089198 | controller |  OS/Arch:           linux/amd64
2025-12-23 00:10:29.089203 | controller |  Context:           default
2025-12-23 00:10:29.089210 | controller |
2025-12-23 00:10:29.089215 | controller | Server: Docker Engine - Community
2025-12-23 00:10:29.089219 | controller |  Engine:
2025-12-23 00:10:29.089225 | controller |   Version:          24.0.7
2025-12-23 00:10:29.089230 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-23 00:10:29.089234 | controller |   Go version:       go1.20.10
2025-12-23 00:10:29.089238 | controller |   Git commit:       311b9ff
2025-12-23 00:10:29.089242 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-23 00:10:29.089247 | controller |   OS/Arch:          linux/amd64
2025-12-23 00:10:29.089251 | controller |   Experimental:     false
2025-12-23 00:10:29.089303 | controller |  containerd:
2025-12-23 00:10:29.089308 | controller |   Version:          1.6.25
2025-12-23 00:10:29.089312 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-23 00:10:29.089316 | controller |  runc:
2025-12-23 00:10:29.089323 | controller |   Version:          1.1.10
2025-12-23 00:10:29.091730 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-23 00:10:29.091752 | controller |  docker-init:
2025-12-23 00:10:29.091756 | controller |   Version:          0.19.0
2025-12-23 00:10:29.091760 | controller |   GitCommit:        de40ad0
2025-12-23 00:10:29.171979 | controller | ok: Runtime: 0:00:00.051115
2025-12-23 00:10:29.179072 | 
2025-12-23 00:10:29.179159 | TASK [ensure-docker : Dump installed docker details]
2025-12-23 00:10:29.212284 | {'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-12-23 00:10:29.040937', 'end': '2025-12-23 00:10:29.092052', 'delta': '0:00:00.051115', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-0059-5451-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-12-23 00:10:29.221181 | 
2025-12-23 00:10:29.221385 | TASK [Use buildset registry]
2025-12-23 00:10:29.239042 | controller | skipping: Conditional result was False
2025-12-23 00:10:29.258903 | 
2025-12-23 00:10:29.259053 | TASK [Ensure python3.8 is present]
2025-12-23 00:10:29.274386 | controller | skipping: Conditional result was False
2025-12-23 00:10:29.281139 | 
2025-12-23 00:10:29.281222 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-23 00:10:29.303995 | controller | ok
2025-12-23 00:10:29.326907 | 
2025-12-23 00:10:29.327035 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-23 00:10:31.424231 | controller | ok: Nothing to do
2025-12-23 00:10:31.434053 | 
2025-12-23 00:10:31.434234 | TASK [our-ensure-python : Also install python3-devel]
2025-12-23 00:10:45.224178 | controller | changed
2025-12-23 00:10:45.236084 | 
2025-12-23 00:10:45.236189 | TASK [Run ensure-virtualenv role]
2025-12-23 00:10:45.257177 | controller | ok
2025-12-23 00:10:45.281760 | 
2025-12-23 00:10:45.281906 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-23 00:10:45.492451 | controller | /usr/bin/virtualenv
2025-12-23 00:10:45.814993 | controller | ok: Runtime: 0:00:00.004219
2025-12-23 00:10:45.829362 | 
2025-12-23 00:10:45.829516 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-23 00:10:45.854861 | controller | skipping: Conditional result was False
2025-12-23 00:10:45.855375 | controller | changed: All items complete
2025-12-23 00:10:45.855452 | 
2025-12-23 00:10:45.870559 | 
2025-12-23 00:10:45.870710 | TASK [Find the full path of the Python interpreter]
2025-12-23 00:10:46.089856 | controller | /usr/bin/python3.8
2025-12-23 00:10:46.417384 | controller | ok
2025-12-23 00:10:46.426728 | 
2025-12-23 00:10:46.426974 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-23 00:10:47.970916 | controller | created virtual environment CPython3.8.18.final.0-64 in 779ms
2025-12-23 00:10:47.989421 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-23 00:10:47.989477 | 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-12-23 00:10:47.989490 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-23 00:10:47.989508 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-23 00:10:48.480405 | controller | changed
2025-12-23 00:10:48.487628 | 
2025-12-23 00:10:48.487732 | TASK [Set selinux package]
2025-12-23 00:10:48.519720 | controller | ok
2025-12-23 00:10:48.526402 | 
2025-12-23 00:10:48.526511 | TASK [Set selinux package (Fedora)]
2025-12-23 00:10:48.568284 | controller | ok
2025-12-23 00:10:48.573850 | 
2025-12-23 00:10:48.573919 | TASK [Install selinux into virtualenv]
2025-12-23 00:11:15.434492 | controller | Collecting selinux-please-lie-to-me
2025-12-23 00:11:27.616124 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-23 00:11:27.995288 | controller | Collecting setuptools<50.0.0
2025-12-23 00:11:28.029632 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-23 00:11:28.067158 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 24.4 MB/s eta 0:00:00
2025-12-23 00:11:28.149736 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-23 00:11:28.149957 | controller |   Attempting uninstall: setuptools
2025-12-23 00:11:28.150661 | controller |     Found existing installation: setuptools 62.6.0
2025-12-23 00:11:28.223344 | controller |     Uninstalling setuptools-62.6.0:
2025-12-23 00:11:28.232342 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-23 00:11:28.558275 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-23 00:11:39.988130 | controller |
2025-12-23 00:11:40.078866 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-23 00:11:40.078912 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-23 00:11:40.141308 | controller | ok: Runtime: 0:00:50.997101
2025-12-23 00:11:40.157203 | 
2025-12-23 00:11:40.157353 | TASK [Install pytest-forked into virtualenv]
2025-12-23 00:12:17.059918 | controller | Collecting pytest-forked
2025-12-23 00:12:29.301592 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-23 00:12:29.354614 | controller | Collecting py
2025-12-23 00:12:29.358552 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-23 00:12:29.386316 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.6 MB/s eta 0:00:00
2025-12-23 00:12:29.510115 | controller | Collecting pytest>=3.10
2025-12-23 00:12:29.515615 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-23 00:12:29.529802 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 30.7 MB/s eta 0:00:00
2025-12-23 00:12:29.590537 | controller | Collecting tomli>=1
2025-12-23 00:12:29.594167 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-23 00:12:29.630739 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-23 00:12:29.634390 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-23 00:12:29.658517 | controller | Collecting iniconfig
2025-12-23 00:12:29.663992 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-23 00:12:29.698529 | controller | Collecting pluggy<2,>=1.5
2025-12-23 00:12:29.735986 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-23 00:12:29.796665 | controller | Collecting packaging
2025-12-23 00:12:29.801454 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-23 00:12:29.808944 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.0 MB/s eta 0:00:00
2025-12-23 00:12:29.860571 | controller | Collecting typing-extensions>=4.6.0
2025-12-23 00:12:29.868693 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-23 00:12:29.877932 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 6.7 MB/s eta 0:00:00
2025-12-23 00:12:29.939372 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-23 00:12:30.343370 | 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-12-23 00:12:30.348162 | controller |
2025-12-23 00:12:30.425748 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-23 00:12:30.425803 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-23 00:12:30.823962 | controller | ok: Runtime: 0:00:49.934213
2025-12-23 00:12:30.833043 | 
2025-12-23 00:12:30.833203 | TASK [Update pip]
2025-12-23 00:12:31.355376 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-23 00:12:41.707741 | controller | Collecting pip
2025-12-23 00:12:54.083907 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-23 00:12:54.148353 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 32.0 MB/s eta 0:00:00
2025-12-23 00:12:54.220429 | controller | Installing collected packages: pip
2025-12-23 00:12:54.220614 | controller |   Attempting uninstall: pip
2025-12-23 00:12:54.221433 | controller |     Found existing installation: pip 22.2.2
2025-12-23 00:12:54.404417 | controller |     Uninstalling pip-22.2.2:
2025-12-23 00:12:54.419510 | controller |       Successfully uninstalled pip-22.2.2
2025-12-23 00:12:55.216292 | controller | Successfully installed pip-25.0.1
2025-12-23 00:12:55.393122 | controller | ok: Runtime: 0:00:24.230324
2025-12-23 00:12:55.403286 | 
2025-12-23 00:12:55.403382 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-23 00:12:55.610976 | controller | changed
2025-12-23 00:12:55.618059 | 
2025-12-23 00:12:55.618198 | TASK [Install ansible into virtualenv]
2025-12-23 00:12:56.134699 | controller | Processing ./src/github.com/ansible/ansible
2025-12-23 00:12:56.135481 | controller |   Preparing metadata (setup.py): started
2025-12-23 00:12:56.554099 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-23 00:13:18.337549 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-12-23 00:13:30.583852 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-23 00:13:30.720895 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-12-23 00:13:30.725636 | 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-12-23 00:13:31.013295 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-12-23 00:13:31.021198 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-23 00:13:31.040069 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-12-23 00:13:31.180122 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-12-23 00:13:31.198715 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-23 00:13:31.208035 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2025-12-23 00:13:31.286096 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-12-23 00:13:31.292500 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-23 00:13:31.318340 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-12-23 00:13:31.323419 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-23 00:13:31.339768 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-23 00:13:31.448081 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 42.7 MB/s eta 0:00:00
2025-12-23 00:13:31.456831 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-23 00:13:31.470884 | 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-12-23 00:13:31.491217 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 40.2 MB/s eta 0:00:00
2025-12-23 00:13:31.504813 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-23 00:13:31.525874 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-23 00:13:31.550823 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-23 00:13:31.577903 | controller | Building wheels for collected packages: ansible-base
2025-12-23 00:13:31.578453 | controller |   Building wheel for ansible-base (setup.py): started
2025-12-23 00:13:32.801560 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-12-23 00:13:32.803817 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=f292f815853b18fb141c6d4cd0e8cd780e173ab4426cb7fe9676baccdd517f11
2025-12-23 00:13:32.804033 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-4l9y994y/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-23 00:13:32.812903 | controller | Successfully built ansible-base
2025-12-23 00:13:32.845918 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-12-23 00:13:34.270139 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-12-23 00:13:34.676361 | controller | ok: Runtime: 0:00:38.513026
2025-12-23 00:13:34.684021 | 
2025-12-23 00:13:34.684128 | TASK [Setup download-artifact-fork role]
2025-12-23 00:13:34.713229 | controller | ok
2025-12-23 00:13:34.737983 | 
2025-12-23 00:13:34.738144 | TASK [download-artifact-fork : Parse inventory response]
2025-12-23 00:13:34.780631 | controller | ok
2025-12-23 00:13:34.788354 | 
2025-12-23 00:13:34.788467 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-23 00:13:34.823474 | controller | skipping: Conditional result was False
2025-12-23 00:13:34.833980 | 
2025-12-23 00:13:34.834148 | TASK [download-artifact-fork : Parse build response]
2025-12-23 00:13:34.859781 | controller | skipping: Conditional result was False
2025-12-23 00:13:34.871690 | 
2025-12-23 00:13:34.871843 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-23 00:13:35.091455 | controller | changed
2025-12-23 00:13:35.101823 | 
2025-12-23 00:13:35.101983 | LOOP [download-artifact-fork : Download archive by type]
2025-12-23 00:13:35.139700 | controller | skipping: Conditional result was False
2025-12-23 00:13:35.140071 | 
2025-12-23 00:13:36.263701 | controller | changed: OK (20204 bytes)
2025-12-23 00:13:36.291663 | 
2025-12-23 00:13:36.291846 | TASK [deploy-artifacts : define __collections]
2025-12-23 00:13:36.337193 | controller | ok
2025-12-23 00:13:36.346416 | 
2025-12-23 00:13:36.346524 | LOOP [deploy-artifacts : Create list of collections]
2025-12-23 00:13:36.388753 | controller | skipping: Conditional result was False
2025-12-23 00:13:36.400002 | controller | ok:
2025-12-23 00:13:36.400092 | controller | {
2025-12-23 00:13:36.400119 | controller |   "branch": "main",
2025-12-23 00:13:36.400138 | controller |   "job": "build-ansible-collection",
2025-12-23 00:13:36.400156 | controller |   "metadata": {
2025-12-23 00:13:36.400174 | controller |     "type": "ansible_collection",
2025-12-23 00:13:36.400191 | controller |     "version": "5.0.0"
2025-12-23 00:13:36.400361 | controller |   },
2025-12-23 00:13:36.400390 | controller |   "name": "ansible.network",
2025-12-23 00:13:36.400409 | controller |   "project": "ansible-collections/ansible.network",
2025-12-23 00:13:36.400426 | controller |   "url": "https://cffc42b423ec047ecae3-2ed27ddb18edc575e0e43f1f1ba85174.ssl.cf2.rackcdn.com/ansible/7e878503cff442f8a95c0ad0c781a041/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-23 00:13:36.400449 | controller | }
2025-12-23 00:13:36.412363 | 
2025-12-23 00:13:36.412469 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-23 00:13:37.009015 | controller | Starting galaxy collection install process
2025-12-23 00:13:37.009307 | controller | Process install dependency map
2025-12-23 00:23:01.618889 | controller | Starting collection install process
2025-12-23 00:23:01.619046 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-23 00:23:01.632923 | controller | ansible.network (5.0.0) was installed successfully
2025-12-23 00:23:01.632989 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-23 00:23:01.633121 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40951os3_oy3/tmp49ib1zw5
2025-12-23 00:23:51.917885 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-12-23 00:23:51.917994 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-23 00:23:51.918008 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40951os3_oy3/tmp49ib1zw5
2025-12-23 00:24:46.839844 | controller | ansible.utils (6.0.0) was installed successfully
2025-12-23 00:24:46.839943 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-23 00:24:46.840083 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40951os3_oy3/tmp49ib1zw5
2025-12-23 00:25:31.074222 | controller | arista.eos (12.0.0) was installed successfully
2025-12-23 00:25:31.074285 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-23 00:25:31.074298 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40951os3_oy3/tmp49ib1zw5
2025-12-23 00:26:27.189454 | controller | cisco.ios (11.2.0) was installed successfully
2025-12-23 00:26:27.190152 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-23 00:26:27.190176 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40951os3_oy3/tmp49ib1zw5
2025-12-23 00:27:31.170036 | controller | cisco.iosxr (12.1.1) was installed successfully
2025-12-23 00:27:31.170108 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-23 00:27:31.170124 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40951os3_oy3/tmp49ib1zw5
2025-12-23 00:28:33.251385 | controller | cisco.nxos (11.1.1) was installed successfully
2025-12-23 00:28:33.251501 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-23 00:28:33.251682 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40951os3_oy3/tmp49ib1zw5
2025-12-23 00:29:36.106264 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-12-23 00:29:36.585322 | controller | ok: Runtime: 0:15:59.525928
2025-12-23 00:29:36.599047 | 
2025-12-23 00:29:36.599231 | TASK [podman may need iptables kernel modules]
2025-12-23 00:29:37.138807 | controller | ok: Runtime: 0:00:00.004432
2025-12-23 00:29:37.166904 | 
2025-12-23 00:29:37.167048 | PLAY RECAP
2025-12-23 00:29:37.167126 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-23 00:29:37.167150 | 
2025-12-23 00:29:37.372295 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-23 00:29:37.374240 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-23 00:29:38.105970 | 
2025-12-23 00:29:38.106101 | PLAY [controller]
2025-12-23 00:29:38.128228 | 
2025-12-23 00:29:38.128335 | TASK [Copy the galaxy.yml on the controller]
2025-12-23 00:29:39.244661 | controller | changed
2025-12-23 00:29:39.253460 | 
2025-12-23 00:29:39.253582 | TASK [Load information from galaxy.yml]
2025-12-23 00:29:39.296927 | controller | ok
2025-12-23 00:29:39.304135 | 
2025-12-23 00:29:39.304200 | LOOP [Enable FIPS mode]
2025-12-23 00:29:39.337008 | 
2025-12-23 00:29:39.337174 | LOOP [ansible-test : Include branch specific variables.]
2025-12-23 00:29:39.397789 | controller | ok: "/var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-23 00:29:39.398129 | 
2025-12-23 00:29:39.414401 | 
2025-12-23 00:29:39.414544 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-23 00:29:39.459820 | controller | ok
2025-12-23 00:29:39.467432 | 
2025-12-23 00:29:39.467515 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-23 00:29:39.493500 | controller | skipping: Conditional result was False
2025-12-23 00:29:39.502794 | 
2025-12-23 00:29:39.502897 | TASK [ansible-test : Enable --retry-on-error]
2025-12-23 00:29:39.528114 | controller | skipping: Conditional result was False
2025-12-23 00:29:39.537143 | 
2025-12-23 00:29:39.537237 | TASK [ansible-test : Enable --continue-on-error]
2025-12-23 00:29:39.570164 | controller | ok
2025-12-23 00:29:39.578264 | 
2025-12-23 00:29:39.578357 | TASK [ansible-test : Enable --requirements]
2025-12-23 00:29:39.603587 | controller | skipping: Conditional result was False
2025-12-23 00:29:39.614147 | 
2025-12-23 00:29:39.614254 | TASK [ansible-test : Adjust options for unit tests]
2025-12-23 00:29:39.639250 | controller | skipping: Conditional result was False
2025-12-23 00:29:39.648407 | 
2025-12-23 00:29:39.648511 | TASK [ansible-test : Install requirements with unit tests]
2025-12-23 00:29:39.681726 | controller | ok
2025-12-23 00:29:39.689462 | 
2025-12-23 00:29:39.689560 | TASK [ansible-test : Set --skip-test]
2025-12-23 00:29:39.745468 | controller | skipping: Conditional result was False
2025-12-23 00:29:39.759239 | 
2025-12-23 00:29:39.759405 | TASK [ansible-test : Reduce verbosity]
2025-12-23 00:29:39.800895 | controller | ok
2025-12-23 00:29:39.816656 | 
2025-12-23 00:29:39.816863 | TASK [ansible-test : Setup --python option]
2025-12-23 00:29:39.835178 | controller | skipping: Conditional result was False
2025-12-23 00:29:39.881468 | 
2025-12-23 00:29:39.881621 | TASK [ansible-test : Setup --docker option]
2025-12-23 00:29:39.913370 | controller | ok
2025-12-23 00:29:39.918923 | 
2025-12-23 00:29:39.918987 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-23 00:29:40.474407 | controller | ok
2025-12-23 00:29:40.487195 | 
2025-12-23 00:29:40.487331 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-23 00:29:40.504447 | controller | skipping: Conditional result was False
2025-12-23 00:29:40.519084 | 
2025-12-23 00:29:40.519241 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-23 00:29:40.535398 | controller | skipping: Conditional result was False
2025-12-23 00:29:40.551401 | 
2025-12-23 00:29:40.551549 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-23 00:29:40.567836 | controller | skipping: Conditional result was False
2025-12-23 00:29:40.582285 | 
2025-12-23 00:29:40.582434 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-23 00:29:41.071312 | controller | ok
2025-12-23 00:29:41.084430 | 
2025-12-23 00:29:41.084569 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-23 00:29:41.134494 | controller | ok
2025-12-23 00:29:41.150101 | 
2025-12-23 00:29:41.150243 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-23 00:29:41.206555 | controller | ok
2025-12-23 00:29:41.214821 | 
2025-12-23 00:29:41.214912 | TASK [ansible-test : Setup minimum test requirements]
2025-12-23 00:29:41.258509 | controller | ok
2025-12-23 00:29:41.266361 | 
2025-12-23 00:29:41.266451 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-23 00:29:41.957003 | controller | ok
2025-12-23 00:29:41.972666 | 
2025-12-23 00:29:41.972853 | TASK [ansible-test : Install python requirements]
2025-12-23 00:29:55.966195 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-23 00:30:08.218777 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-23 00:30:08.247521 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-23 00:30:08.253509 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-23 00:30:08.261961 | 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-12-23 00:30:08.271604 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-23 00:30:08.289348 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-23 00:30:08.329555 | controller | Installing collected packages: pathspec, yamllint
2025-12-23 00:30:08.396573 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-23 00:30:08.536893 | controller | ok: Runtime: 0:00:25.787488
2025-12-23 00:30:08.553412 | 
2025-12-23 00:30:08.553583 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-23 00:30:08.811049 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-23 00:30:09.091133 | controller | ERROR
2025-12-23 00:30:09.091378 | controller | {
2025-12-23 00:30:09.091421 | controller |   "delta": "0:00:00.005096",
2025-12-23 00:30:09.091448 | controller |   "end": "2025-12-23 00:30:08.811200",
2025-12-23 00:30:09.091468 | controller |   "msg": "non-zero return code",
2025-12-23 00:30:09.091486 | controller |   "rc": 1,
2025-12-23 00:30:09.091504 | controller |   "start": "2025-12-23 00:30:08.806104"
2025-12-23 00:30:09.091521 | controller | }
2025-12-23 00:30:09.091545 | controller | ERROR: Ignoring Errors
2025-12-23 00:30:09.097962 | 
2025-12-23 00:30:09.098044 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-23 00:30:09.641293 | controller | skipping: Conditional result was False
2025-12-23 00:30:09.656560 | 
2025-12-23 00:30:09.656788 | TASK [ansible-test : Enable persistent connection logging]
2025-12-23 00:30:10.180822 | controller | changed: section and option added
2025-12-23 00:30:10.196261 | 
2025-12-23 00:30:10.196455 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-23 00:30:10.887253 | controller | changed: option added
2025-12-23 00:30:10.896613 | 
2025-12-23 00:30:10.896750 | TASK [ansible-test : Set ansible log path]
2025-12-23 00:30:11.443915 | controller | changed: section and option added
2025-12-23 00:30:11.450535 | 
2025-12-23 00:30:11.450612 | TASK [ansible-test : Identify the changed targets]
2025-12-23 00:30:11.984422 | controller | skipping: Conditional result was False
2025-12-23 00:30:11.999825 | 
2025-12-23 00:30:11.999982 | TASK [ansible-test : set_fact]
2025-12-23 00:30:12.058160 | controller | skipping: Conditional result was False
2025-12-23 00:30:12.073049 | 
2025-12-23 00:30:12.073198 | TASK [ansible-test : Set the targets]
2025-12-23 00:30:12.122797 | controller | ok
2025-12-23 00:30:12.135400 | 
2025-12-23 00:30:12.135550 | TASK [ansible-test : set_fact]
2025-12-23 00:30:12.162323 | controller | skipping: Conditional result was False
2025-12-23 00:30:12.175537 | 
2025-12-23 00:30:12.175757 | TASK [ansible-test : List the targets]
2025-12-23 00:30:12.753462 | controller | skipping: Conditional result was False
2025-12-23 00:30:12.764278 | 
2025-12-23 00:30:12.764390 | TASK [ansible-test : set_fact]
2025-12-23 00:30:12.789493 | controller | skipping: Conditional result was False
2025-12-23 00:30:12.799006 | 
2025-12-23 00:30:12.799096 | TASK [ansible-test : set_fact]
2025-12-23 00:30:12.824891 | controller | skipping: Conditional result was False
2025-12-23 00:30:12.834321 | 
2025-12-23 00:30:12.834411 | LOOP [ansible-test : set_fact]
2025-12-23 00:30:12.905529 | 
2025-12-23 00:30:12.905839 | TASK [ansible-test : set_fact]
2025-12-23 00:30:12.932462 | controller | skipping: Conditional result was False
2025-12-23 00:30:12.941476 | 
2025-12-23 00:30:12.941568 | TASK [ansible-test : debug]
2025-12-23 00:30:12.966979 | controller | skipping: Conditional result was False
2025-12-23 00:30:12.980115 | 
2025-12-23 00:30:12.980253 | TASK [ansible-test : debug]
2025-12-23 00:30:13.021042 | About to run: ansible-test sanity --requirements --docker -v
2025-12-23 00:30:13.033063 | 
2025-12-23 00:30:13.033154 | TASK [ansible-test : Run the test suite]
2025-12-23 00:30:14.156737 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-12-23 00:30:14.198770 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-12-23 00:30:16.107220 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-12-23 00:30:16.107300 | controller | 171857c49d0f: Pulling fs layer
2025-12-23 00:30:16.107332 | controller | 419640447d26: Pulling fs layer
2025-12-23 00:30:16.107337 | controller | 61e52f862619: Pulling fs layer
2025-12-23 00:30:16.107342 | controller | 851de0426e26: Pulling fs layer
2025-12-23 00:30:16.107349 | controller | 37425716ea5b: Pulling fs layer
2025-12-23 00:30:16.107355 | controller | cd9b0c439616: Pulling fs layer
2025-12-23 00:30:16.107360 | controller | d4612dbd8855: Pulling fs layer
2025-12-23 00:30:16.107364 | controller | 554699364730: Pulling fs layer
2025-12-23 00:30:16.107368 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-12-23 00:30:16.107372 | controller | 5f2e4724adea: Pulling fs layer
2025-12-23 00:30:16.107377 | controller | a02d9a95ccb8: Pulling fs layer
2025-12-23 00:30:16.107381 | controller | 989f4ce370cb: Pulling fs layer
2025-12-23 00:30:16.107385 | controller | 0d7f52cdf9df: Pulling fs layer
2025-12-23 00:30:16.107389 | controller | c423742351d5: Pulling fs layer
2025-12-23 00:30:16.107394 | controller | 109236341d21: Pulling fs layer
2025-12-23 00:30:16.107398 | controller | 07a2f61c96b8: Pulling fs layer
2025-12-23 00:30:16.107402 | controller | 041f122f27ae: Pulling fs layer
2025-12-23 00:30:16.107406 | controller | 851de0426e26: Waiting
2025-12-23 00:30:16.107411 | controller | 37425716ea5b: Waiting
2025-12-23 00:30:16.107417 | controller | cd9b0c439616: Waiting
2025-12-23 00:30:16.107421 | controller | 554699364730: Waiting
2025-12-23 00:30:16.107426 | controller | d4612dbd8855: Waiting
2025-12-23 00:30:16.107433 | controller | 1ff15d4bdb3e: Waiting
2025-12-23 00:30:16.107574 | controller | 5f2e4724adea: Waiting
2025-12-23 00:30:16.107610 | controller | a02d9a95ccb8: Waiting
2025-12-23 00:30:16.107617 | controller | 989f4ce370cb: Waiting
2025-12-23 00:30:16.107622 | controller | 0d7f52cdf9df: Waiting
2025-12-23 00:30:16.107626 | controller | c423742351d5: Waiting
2025-12-23 00:30:16.107639 | controller | 109236341d21: Waiting
2025-12-23 00:30:16.107648 | controller | 07a2f61c96b8: Waiting
2025-12-23 00:30:16.107652 | controller | ca6f5098b12e: Pulling fs layer
2025-12-23 00:30:16.107656 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-12-23 00:30:16.107661 | controller | 18a0b750727a: Pulling fs layer
2025-12-23 00:30:16.107665 | controller | 17f2f596716c: Pulling fs layer
2025-12-23 00:30:16.107674 | controller | f35a9998a9bd: Pulling fs layer
2025-12-23 00:30:16.107678 | controller | 041f122f27ae: Waiting
2025-12-23 00:30:16.107682 | controller | ca6f5098b12e: Waiting
2025-12-23 00:30:16.107689 | controller | 1eb3e7b2afd9: Waiting
2025-12-23 00:30:16.107693 | controller | 18a0b750727a: Waiting
2025-12-23 00:30:16.107699 | controller | 17f2f596716c: Waiting
2025-12-23 00:30:16.107870 | controller | fdfc10566405: Pulling fs layer
2025-12-23 00:30:16.107975 | controller | 1ff1c442c5fe: Pulling fs layer
2025-12-23 00:30:16.107984 | controller | a3443c4a17ed: Pulling fs layer
2025-12-23 00:30:16.107988 | controller | 0edcf9fb2b15: Pulling fs layer
2025-12-23 00:30:16.107992 | controller | 5af43b69e845: Pulling fs layer
2025-12-23 00:30:16.107997 | controller | 1651773effb2: Pulling fs layer
2025-12-23 00:30:16.108001 | controller | f35a9998a9bd: Waiting
2025-12-23 00:30:16.108005 | controller | fdfc10566405: Waiting
2025-12-23 00:30:16.108009 | controller | 1ff1c442c5fe: Waiting
2025-12-23 00:30:16.108016 | controller | a3443c4a17ed: Waiting
2025-12-23 00:30:16.108020 | controller | 0edcf9fb2b15: Waiting
2025-12-23 00:30:16.108024 | controller | 5af43b69e845: Waiting
2025-12-23 00:30:16.108047 | controller | 79e23c5c0fe4: Pulling fs layer
2025-12-23 00:30:16.926051 | controller | e5058b1faaa6: Pulling fs layer
2025-12-23 00:30:16.926084 | controller | 846775490569: Pulling fs layer
2025-12-23 00:30:16.926091 | controller | 1651773effb2: Waiting
2025-12-23 00:30:16.926098 | controller | 79e23c5c0fe4: Waiting
2025-12-23 00:30:16.926104 | controller | e5058b1faaa6: Waiting
2025-12-23 00:30:16.926111 | controller | 846775490569: Waiting
2025-12-23 00:30:16.926123 | controller | 419640447d26: Verifying Checksum
2025-12-23 00:30:16.997196 | controller | 419640447d26: Download complete
2025-12-23 00:30:16.997215 | controller | 61e52f862619: Verifying Checksum
2025-12-23 00:30:17.525131 | controller | 61e52f862619: Download complete
2025-12-23 00:30:17.525171 | controller | 171857c49d0f: Verifying Checksum
2025-12-23 00:30:18.078548 | controller | 171857c49d0f: Download complete
2025-12-23 00:30:18.078590 | controller | 851de0426e26: Verifying Checksum
2025-12-23 00:30:18.890514 | controller | 851de0426e26: Download complete
2025-12-23 00:30:18.890553 | controller | cd9b0c439616: Verifying Checksum
2025-12-23 00:30:19.145791 | controller | cd9b0c439616: Download complete
2025-12-23 00:30:19.145832 | controller | d4612dbd8855: Verifying Checksum
2025-12-23 00:30:20.148930 | controller | d4612dbd8855: Download complete
2025-12-23 00:30:20.148974 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-12-23 00:30:20.239282 | controller | 1ff15d4bdb3e: Download complete
2025-12-23 00:30:20.239322 | controller | 171857c49d0f: Pull complete
2025-12-23 00:30:20.268553 | controller | 419640447d26: Pull complete
2025-12-23 00:30:20.317632 | controller | 61e52f862619: Pull complete
2025-12-23 00:30:20.339147 | controller | 851de0426e26: Pull complete
2025-12-23 00:30:21.373507 | controller | 5f2e4724adea: Verifying Checksum
2025-12-23 00:30:22.528156 | controller | 5f2e4724adea: Download complete
2025-12-23 00:30:22.528200 | controller | a02d9a95ccb8: Verifying Checksum
2025-12-23 00:30:22.528253 | controller | a02d9a95ccb8: Download complete
2025-12-23 00:30:22.620586 | controller | 554699364730: Verifying Checksum
2025-12-23 00:30:23.427985 | controller | 554699364730: Download complete
2025-12-23 00:30:23.428034 | controller | 989f4ce370cb: Verifying Checksum
2025-12-23 00:30:23.428133 | controller | 989f4ce370cb: Download complete
2025-12-23 00:30:23.606655 | controller | 0d7f52cdf9df: Download complete
2025-12-23 00:30:23.927378 | controller | 37425716ea5b: Verifying Checksum
2025-12-23 00:30:24.279496 | controller | 37425716ea5b: Download complete
2025-12-23 00:30:24.279549 | controller | c423742351d5: Verifying Checksum
2025-12-23 00:30:24.279668 | controller | c423742351d5: Download complete
2025-12-23 00:30:24.947992 | controller | 07a2f61c96b8: Verifying Checksum
2025-12-23 00:30:24.948332 | controller | 07a2f61c96b8: Download complete
2025-12-23 00:30:25.741107 | controller | 041f122f27ae: Verifying Checksum
2025-12-23 00:30:25.741203 | controller | 041f122f27ae: Download complete
2025-12-23 00:30:26.261620 | controller | ca6f5098b12e: Verifying Checksum
2025-12-23 00:30:26.261697 | controller | ca6f5098b12e: Download complete
2025-12-23 00:30:26.679126 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-12-23 00:30:26.679219 | controller | 1eb3e7b2afd9: Download complete
2025-12-23 00:30:26.798850 | controller | 109236341d21: Verifying Checksum
2025-12-23 00:30:26.798999 | controller | 109236341d21: Download complete
2025-12-23 00:30:27.499596 | controller | 18a0b750727a: Verifying Checksum
2025-12-23 00:30:27.719648 | controller | 18a0b750727a: Download complete
2025-12-23 00:30:27.719678 | controller | f35a9998a9bd: Verifying Checksum
2025-12-23 00:30:27.720390 | controller | f35a9998a9bd: Download complete
2025-12-23 00:30:27.788824 | controller | 17f2f596716c: Verifying Checksum
2025-12-23 00:30:28.509048 | controller | 17f2f596716c: Download complete
2025-12-23 00:30:28.509090 | controller | fdfc10566405: Verifying Checksum
2025-12-23 00:30:28.915819 | controller | fdfc10566405: Download complete
2025-12-23 00:30:28.915878 | controller | 1ff1c442c5fe: Verifying Checksum
2025-12-23 00:30:29.648923 | controller | 1ff1c442c5fe: Download complete
2025-12-23 00:30:29.648960 | controller | a3443c4a17ed: Verifying Checksum
2025-12-23 00:30:30.460050 | controller | a3443c4a17ed: Download complete
2025-12-23 00:30:30.460130 | controller | 5af43b69e845: Verifying Checksum
2025-12-23 00:30:30.460169 | controller | 5af43b69e845: Download complete
2025-12-23 00:30:30.492962 | controller | 0edcf9fb2b15: Verifying Checksum
2025-12-23 00:30:30.493044 | controller | 0edcf9fb2b15: Download complete
2025-12-23 00:30:31.619032 | controller | 1651773effb2: Verifying Checksum
2025-12-23 00:30:31.619131 | controller | 1651773effb2: Download complete
2025-12-23 00:30:31.876952 | controller | 79e23c5c0fe4: Verifying Checksum
2025-12-23 00:30:31.877088 | controller | 79e23c5c0fe4: Download complete
2025-12-23 00:30:32.125140 | controller | e5058b1faaa6: Verifying Checksum
2025-12-23 00:30:32.125687 | controller | e5058b1faaa6: Download complete
2025-12-23 00:30:33.725723 | controller | 846775490569: Verifying Checksum
2025-12-23 00:30:33.725801 | controller | 846775490569: Download complete
2025-12-23 00:30:37.271788 | controller | 37425716ea5b: Pull complete
2025-12-23 00:30:37.293864 | controller | cd9b0c439616: Pull complete
2025-12-23 00:30:37.314708 | controller | d4612dbd8855: Pull complete
2025-12-23 00:30:40.487792 | controller | 554699364730: Pull complete
2025-12-23 00:30:40.520057 | controller | 1ff15d4bdb3e: Pull complete
2025-12-23 00:30:40.539299 | controller | 5f2e4724adea: Pull complete
2025-12-23 00:30:40.575792 | controller | a02d9a95ccb8: Pull complete
2025-12-23 00:30:40.622367 | controller | 989f4ce370cb: Pull complete
2025-12-23 00:30:40.665680 | controller | 0d7f52cdf9df: Pull complete
2025-12-23 00:30:40.694009 | controller | c423742351d5: Pull complete
2025-12-23 00:30:42.001877 | controller | 109236341d21: Pull complete
2025-12-23 00:30:42.026805 | controller | 07a2f61c96b8: Pull complete
2025-12-23 00:30:42.049589 | controller | 041f122f27ae: Pull complete
2025-12-23 00:30:42.153810 | controller | ca6f5098b12e: Pull complete
2025-12-23 00:30:42.195236 | controller | 1eb3e7b2afd9: Pull complete
2025-12-23 00:30:42.232718 | controller | 18a0b750727a: Pull complete
2025-12-23 00:30:42.259616 | controller | 17f2f596716c: Pull complete
2025-12-23 00:30:42.280900 | controller | f35a9998a9bd: Pull complete
2025-12-23 00:30:42.306042 | controller | fdfc10566405: Pull complete
2025-12-23 00:30:42.337046 | controller | 1ff1c442c5fe: Pull complete
2025-12-23 00:30:43.255220 | controller | a3443c4a17ed: Pull complete
2025-12-23 00:30:44.295749 | controller | 0edcf9fb2b15: Pull complete
2025-12-23 00:30:45.826607 | controller | 5af43b69e845: Pull complete
2025-12-23 00:30:47.444475 | controller | 1651773effb2: Pull complete
2025-12-23 00:30:48.979302 | controller | 79e23c5c0fe4: Pull complete
2025-12-23 00:30:50.222501 | controller | e5058b1faaa6: Pull complete
2025-12-23 00:30:51.771901 | controller | 846775490569: Pull complete
2025-12-23 00:30:51.790582 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-12-23 00:30:51.794732 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-12-23 00:30:51.800789 | controller | quay.io/ansible/default-test-container:2.9.0
2025-12-23 00:30:51.803874 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-23 00:30:51.822637 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-23 00:30:53.597860 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-23 00:30:53.598033 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-23 00:30:53.600242 | controller | ff496a88c8ed: Pulling fs layer
2025-12-23 00:30:53.600336 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-23 00:30:53.600406 | controller | 2e5170e1f099: Pulling fs layer
2025-12-23 00:30:53.600511 | controller | 7641eb41b08c: Pulling fs layer
2025-12-23 00:30:53.600607 | controller | ad15fa9da398: Pulling fs layer
2025-12-23 00:30:53.600690 | controller | 087d91352424: Pulling fs layer
2025-12-23 00:30:53.600763 | controller | 8b92efd6a100: Pulling fs layer
2025-12-23 00:30:53.600856 | controller | 7641eb41b08c: Waiting
2025-12-23 00:30:53.600972 | controller | ad15fa9da398: Waiting
2025-12-23 00:30:53.601058 | controller | 087d91352424: Waiting
2025-12-23 00:30:53.601132 | controller | 8b92efd6a100: Waiting
2025-12-23 00:30:53.601223 | controller | 2e5170e1f099: Waiting
2025-12-23 00:30:54.694724 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-23 00:30:54.954893 | controller | 0ce83f459fe7: Download complete
2025-12-23 00:30:54.954955 | controller | ff496a88c8ed: Download complete
2025-12-23 00:30:55.357056 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-23 00:30:55.357167 | controller | 04a5f4cda3ee: Download complete
2025-12-23 00:30:56.128131 | controller | 7641eb41b08c: Verifying Checksum
2025-12-23 00:30:56.131057 | controller | 7641eb41b08c: Download complete
2025-12-23 00:30:56.722123 | controller | ad15fa9da398: Verifying Checksum
2025-12-23 00:30:56.722195 | controller | ad15fa9da398: Download complete
2025-12-23 00:30:56.829432 | controller | 2e5170e1f099: Verifying Checksum
2025-12-23 00:30:57.659334 | controller | 2e5170e1f099: Download complete
2025-12-23 00:30:57.659422 | controller | 087d91352424: Verifying Checksum
2025-12-23 00:30:57.659470 | controller | 087d91352424: Download complete
2025-12-23 00:30:57.911235 | controller | 8b92efd6a100: Verifying Checksum
2025-12-23 00:30:57.911299 | controller | 8b92efd6a100: Download complete
2025-12-23 00:30:57.933837 | controller | 04a5f4cda3ee: Pull complete
2025-12-23 00:30:57.957436 | controller | ff496a88c8ed: Pull complete
2025-12-23 00:30:57.980593 | controller | 0ce83f459fe7: Pull complete
2025-12-23 00:30:59.389534 | controller | 2e5170e1f099: Pull complete
2025-12-23 00:30:59.440702 | controller | 7641eb41b08c: Pull complete
2025-12-23 00:30:59.461904 | controller | ad15fa9da398: Pull complete
2025-12-23 00:31:00.062788 | controller | 087d91352424: Pull complete
2025-12-23 00:31:00.096184 | controller | 8b92efd6a100: Pull complete
2025-12-23 00:31:00.144422 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-23 00:31:00.154069 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-23 00:31:00.177044 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-23 00:31:00.181559 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-23 00:31:03.594899 | controller | Run command: docker inspect 0977438b8599dabae843fdab34f1f33d8ddf466eef9f6d45405335fe6bebf0c8
2025-12-23 00:31:03.637958 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-23 00:31:03.652047 | controller | Including collection: ansible.netcommon (292 files)
2025-12-23 00:31:03.661714 | controller | Including collection: ansible.network (23 files)
2025-12-23 00:31:03.661733 | controller | Including collection: ansible.utils (442 files)
2025-12-23 00:31:03.686319 | controller | Including collection: arista.eos (1022 files)
2025-12-23 00:31:03.713966 | controller | Including collection: cisco.ios (1174 files)
2025-12-23 00:31:03.740060 | controller | Including collection: cisco.iosxr (1044 files)
2025-12-23 00:31:03.783844 | controller | Including collection: cisco.nxos (1682 files)
2025-12-23 00:31:03.808604 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-23 00:31:03.834831 | controller | Creating a payload archive containing 7368 files...
2025-12-23 00:31:05.316938 | controller | Created a 5993140 byte payload archive containing 7368 files in 1 seconds.
2025-12-23 00:31:05.318035 | controller | Assuming Docker is available on localhost.
2025-12-23 00:31:05.318350 | 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:2.9.0
2025-12-23 00:31:05.853158 | controller | Run command: docker exec -i beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 dd of=/root/docker.sh bs=65536
2025-12-23 00:31:05.908246 | controller | Run command: docker exec beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 /bin/bash /root/docker.sh
2025-12-23 00:31:05.956353 | controller | Run command: docker exec -i beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 dd of=/root/test.tgz bs=65536
2025-12-23 00:31:06.026184 | controller | Run command: docker exec beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 tar oxzf /root/test.tgz -C /root
2025-12-23 00:31:06.709099 | controller | Run command: docker exec beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-lyxa9diq.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-23 00:31:06.979402 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-23 00:31:06.979588 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-23 00:31:06.979817 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-23 00:31:06.981244 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-23 00:31:06.981808 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-12-23 00:31:06.986559 | controller | No tests applicable.
2025-12-23 00:31:06.986771 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-23 00:31:06.986784 | controller | No tests applicable.
2025-12-23 00:31:06.986868 | controller | Running sanity test 'changelog' with Python 3.6
2025-12-23 00:31:06.987320 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2025-12-23 00:31:07.575792 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-12-23 00:31:07.954607 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-23 00:31:07.994316 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2025-12-23 00:31:07.995650 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-23 00:31:08.030664 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-23 00:31:08.030719 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-12-23 00:31:08.030949 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-23 00:31:08.031186 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-23 00:31:08.056243 | controller | Run command: docker exec beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-12-23 00:31:08.119922 | controller | Run command: docker exec -i beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 dd if=/root/output.tgz bs=65536
2025-12-23 00:31:08.189992 | controller | Run command: tar oxzf /tmp/ansible-result-7436z4ha.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-23 00:31:08.199071 | controller | Run command: docker rm -f 0977438b8599dabae843fdab34f1f33d8ddf466eef9f6d45405335fe6bebf0c8
2025-12-23 00:31:09.084497 | controller | Run command: docker rm -f beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5
2025-12-23 00:31:09.498455 | controller | ERROR: Command "docker exec beae4e4854d1e421ebb4d925ebc5da3c46165a79fb403d7b670bb8d8a7b158f5 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-lyxa9diq.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-23 00:31:09.629997 | controller | ERROR
2025-12-23 00:31:09.630238 | controller | {
2025-12-23 00:31:09.630343 | controller |   "delta": "0:00:55.727299",
2025-12-23 00:31:09.630392 | controller |   "end": "2025-12-23 00:31:09.517902",
2025-12-23 00:31:09.630433 | controller |   "msg": "non-zero return code",
2025-12-23 00:31:09.630472 | controller |   "rc": 1,
2025-12-23 00:31:09.630513 | controller |   "start": "2025-12-23 00:30:13.790603"
2025-12-23 00:31:09.630551 | controller | }
failure
2025-12-23 00:31:09.637431 | 
2025-12-23 00:31:09.637540 | PLAY RECAP
2025-12-23 00:31:09.637628 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-23 00:31:09.638856 | 
2025-12-23 00:31:09.807596 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-23 00:31:09.808587 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-23 00:31:10.429844 | 
2025-12-23 00:31:10.429980 | PLAY [all]
2025-12-23 00:31:10.453936 | 
2025-12-23 00:31:10.454062 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-23 00:31:10.901218 | controller | changed: non-zero return code
2025-12-23 00:31:10.914427 | 
2025-12-23 00:31:10.914600 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-23 00:31:10.941751 | controller | skipping: Conditional result was False
2025-12-23 00:31:10.950731 | 
2025-12-23 00:31:10.950836 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-23 00:31:10.981926 | 
2025-12-23 00:31:10.982090 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-23 00:31:11.002430 | 
2025-12-23 00:31:11.002591 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-23 00:31:11.016784 | controller | skipping: Conditional result was False
2025-12-23 00:31:11.028528 | 
2025-12-23 00:31:11.028653 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-23 00:31:11.066802 | 
2025-12-23 00:31:11.067079 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-23 00:31:11.083668 | controller | skipping: Conditional result was False
2025-12-23 00:31:11.095894 | 
2025-12-23 00:31:11.096038 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-23 00:31:11.111814 | controller | skipping: Conditional result was False
2025-12-23 00:31:11.120618 | 
2025-12-23 00:31:11.120760 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-23 00:31:11.141398 | controller | skipping: Conditional result was False
2025-12-23 00:31:11.179509 | 
2025-12-23 00:31:11.179643 | PLAY RECAP
2025-12-23 00:31:11.179727 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-23 00:31:11.179767 | 
2025-12-23 00:31:11.318610 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-23 00:31:11.319516 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-23 00:31:11.962236 | 
2025-12-23 00:31:11.962364 | PLAY [all:!appliance*]
2025-12-23 00:31:11.996480 | 
2025-12-23 00:31:11.996638 | TASK [unregister the node]
2025-12-23 00:31:12.531069 | controller | skipping: Conditional result was False
2025-12-23 00:31:12.537488 | 
2025-12-23 00:31:12.537583 | TASK [include_role : fetch-output]
2025-12-23 00:31:12.587904 | controller | ok
2025-12-23 00:31:12.612128 | 
2025-12-23 00:31:12.612252 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-23 00:31:12.687188 | controller | skipping: Conditional result was False
2025-12-23 00:31:12.693862 | 
2025-12-23 00:31:12.693951 | TASK [fetch-output : Set log path for single node]
2025-12-23 00:31:12.734701 | controller | ok
2025-12-23 00:31:12.740286 | 
2025-12-23 00:31:12.740371 | LOOP [fetch-output : Ensure local output dirs]
2025-12-23 00:31:13.139857 | controller -> localhost | ok: "/var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/logs"
2025-12-23 00:31:13.361160 | controller -> localhost | changed: "/var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/artifacts"
2025-12-23 00:31:13.593300 | controller -> localhost | changed: "/var/lib/zuul/builds/c04b1a6ee92c4a19b3fb3263cb949774/work/docs"
2025-12-23 00:31:13.606473 | 
2025-12-23 00:31:13.606596 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-23 00:31:14.373148 | controller | changed:
2025-12-23 00:31:14.373346 | controller | .d..t...... ./
2025-12-23 00:31:14.373374 | controller | cd+++++++++ controller/
2025-12-23 00:31:14.373407 | controller | changed: All items complete
2025-12-23 00:31:14.373427 | 
2025-12-23 00:31:14.961484 | controller | changed: .d..t...... ./
2025-12-23 00:31:15.508909 | controller | changed: .d..t...... ./
2025-12-23 00:31:15.527203 | 
2025-12-23 00:31:15.527311 | TASK [include_role : fetch-output-openshift]
2025-12-23 00:31:15.567227 | controller | skipping: Conditional result was False
2025-12-23 00:31:15.573637 | 
2025-12-23 00:31:15.573719 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-23 00:31:15.608254 | controller | skipping: Conditional result was False
2025-12-23 00:31:15.622147 | controller | skipping: Conditional result was False
2025-12-23 00:31:15.655661 | 
2025-12-23 00:31:15.655735 | PLAY [localhost]
2025-12-23 00:31:15.672037 | 
2025-12-23 00:31:15.672098 | TASK [Run Zuul manifest role]
2025-12-23 00:31:15.690022 | localhost | ok
2025-12-23 00:31:15.737525 | 
2025-12-23 00:31:15.737633 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-23 00:31:16.080845 | localhost | changed
2025-12-23 00:31:16.085601 | 
2025-12-23 00:31:16.085673 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-23 00:31:16.135252 | localhost | ok
2025-12-23 00:31:16.143124 | 
2025-12-23 00:31:16.143185 | TASK [Set zuul-log-path fact]
2025-12-23 00:31:16.162653 | localhost | ok
2025-12-23 00:31:16.177199 | 
2025-12-23 00:31:16.177275 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-23 00:31:16.215625 | localhost | ok
2025-12-23 00:31:16.224099 | 
2025-12-23 00:31:16.224166 | LOOP [Run upload-logs-swift role]
2025-12-23 00:31:16.269578 | localhost | Output suppressed because no_log was given
2025-12-23 00:31:16.295850 | 
2025-12-23 00:31:16.295954 | TASK [Set zuul-log-path fact]
2025-12-23 00:31:16.331287 | localhost | skipping: Conditional result was False
2025-12-23 00:31:16.338156 | 
2025-12-23 00:31:16.338266 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-23 00:31:16.753146 | localhost -> localhost | ok: Runtime: 0:00:00.005049
2025-12-23 00:31:16.796476 | 
2025-12-23 00:31:16.796626 | TASK [upload-logs-swift : Upload logs to swift]