2026-07-02 07:45:17.085311 | Job console starting 2026-07-02 07:45:17.101907 | Updating git repos 2026-07-02 07:45:17.197700 | Cloning repos into workspace 2026-07-02 07:45:17.379122 | Restoring repo states 2026-07-02 07:45:17.408043 | Merging changes 2026-07-02 07:45:18.403738 | Checking out repos 2026-07-02 07:45:18.502476 | Preparing playbooks 2026-07-02 07:45:20.218379 | Running Ansible setup 2026-07-02 07:45:25.737904 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-02 07:45:27.775720 | 2026-07-02 07:45:27.780594 | PLAY [Base pre] 2026-07-02 07:45:27.812136 | 2026-07-02 07:45:27.812255 | TASK [Setup log path fact] 2026-07-02 07:45:27.840250 | debian-bookworm | ok 2026-07-02 07:45:27.865319 | 2026-07-02 07:45:27.865442 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-02 07:45:27.898310 | debian-bookworm | ok 2026-07-02 07:45:27.910895 | 2026-07-02 07:45:27.911002 | TASK [emit-job-header : Print job information] 2026-07-02 07:45:27.969656 | # Job Information 2026-07-02 07:45:27.969822 | Ansible Version: 2.16.14 2026-07-02 07:45:27.969859 | Job: python-osism-unit-tests 2026-07-02 07:45:27.969894 | Pipeline: check 2026-07-02 07:45:27.969917 | Executor: 521e9411259a 2026-07-02 07:45:27.969939 | Triggered by: https://github.com/osism/python-osism/pull/2412 2026-07-02 07:45:27.969962 | Event ID: f0aaa120-75e9-11f1-9723-5de76bd51be2 2026-07-02 07:45:27.974991 | 2026-07-02 07:45:27.975094 | LOOP [emit-job-header : Print node information] 2026-07-02 07:45:28.235425 | debian-bookworm | ok: 2026-07-02 07:45:28.235607 | debian-bookworm | # Node Information 2026-07-02 07:45:28.235669 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-02 07:45:28.235692 | debian-bookworm | Hostname: debian 2026-07-02 07:45:28.235711 | debian-bookworm | Username: zuul 2026-07-02 07:45:28.235728 | debian-bookworm | Distro: Debian 12.14 2026-07-02 07:45:28.235747 | debian-bookworm | Provider: regiocloud-a 2026-07-02 07:45:28.235764 | debian-bookworm | Region: 2026-07-02 07:45:28.235782 | debian-bookworm | Label: debian-bookworm 2026-07-02 07:45:28.235798 | debian-bookworm | Product Name: OpenStack Nova 2026-07-02 07:45:28.235814 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec3:4d65 2026-07-02 07:45:28.255407 | 2026-07-02 07:45:28.255528 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-02 07:45:29.146775 | debian-bookworm -> localhost | changed 2026-07-02 07:45:29.153545 | 2026-07-02 07:45:29.153643 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-02 07:45:31.071162 | debian-bookworm -> localhost | changed 2026-07-02 07:45:31.080003 | 2026-07-02 07:45:31.080102 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-02 07:45:31.506896 | debian-bookworm -> localhost | ok 2026-07-02 07:45:31.512502 | 2026-07-02 07:45:31.512674 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-02 07:45:31.560762 | debian-bookworm | ok 2026-07-02 07:45:31.581538 | debian-bookworm | included: /var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-02 07:45:31.610719 | 2026-07-02 07:45:31.610885 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-02 07:45:33.708365 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-02 07:45:33.708577 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/work/25318b0a455f404cb531049aee830fcf_id_rsa 2026-07-02 07:45:33.708616 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/work/25318b0a455f404cb531049aee830fcf_id_rsa.pub 2026-07-02 07:45:33.708643 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-02 07:45:33.708668 | debian-bookworm -> localhost | SHA256:A+mjTYxO9CjkR+na7UeH4UIJ+S1wtSbLA6v6sohwkJg zuul-build-sshkey 2026-07-02 07:45:33.708691 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-02 07:45:33.708722 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-02 07:45:33.708744 | debian-bookworm -> localhost | | . .. | 2026-07-02 07:45:33.708766 | debian-bookworm -> localhost | | +... . | 2026-07-02 07:45:33.708786 | debian-bookworm -> localhost | | . ==+oo | 2026-07-02 07:45:33.708806 | debian-bookworm -> localhost | |.= + X==o | 2026-07-02 07:45:33.708826 | debian-bookworm -> localhost | |E o B.XoSo | 2026-07-02 07:45:33.708849 | debian-bookworm -> localhost | | . O =.o+.. | 2026-07-02 07:45:33.708871 | debian-bookworm -> localhost | |. + + oo . | 2026-07-02 07:45:33.708891 | debian-bookworm -> localhost | |=o . . | 2026-07-02 07:45:33.708912 | debian-bookworm -> localhost | |=+. .. | 2026-07-02 07:45:33.708933 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-02 07:45:33.708979 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.052992 2026-07-02 07:45:33.715164 | 2026-07-02 07:45:33.715256 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-02 07:45:33.749368 | debian-bookworm | ok 2026-07-02 07:45:33.762009 | debian-bookworm | included: /var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-02 07:45:33.769945 | 2026-07-02 07:45:33.770031 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-02 07:45:33.783372 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:45:33.788990 | 2026-07-02 07:45:33.789079 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-02 07:45:34.531032 | debian-bookworm | changed 2026-07-02 07:45:34.536491 | 2026-07-02 07:45:34.536582 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-02 07:45:34.811046 | debian-bookworm | ok 2026-07-02 07:45:34.816661 | 2026-07-02 07:45:34.816751 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-02 07:45:35.648043 | debian-bookworm | changed 2026-07-02 07:45:35.653159 | 2026-07-02 07:45:35.653239 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-02 07:45:36.410179 | debian-bookworm | changed 2026-07-02 07:45:36.414999 | 2026-07-02 07:45:36.415084 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-02 07:45:36.437833 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:45:36.442887 | 2026-07-02 07:45:36.442979 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-02 07:45:37.112492 | debian-bookworm -> localhost | changed 2026-07-02 07:45:37.121185 | 2026-07-02 07:45:37.121283 | TASK [add-build-sshkey : Add back temp key] 2026-07-02 07:45:37.661318 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/work/25318b0a455f404cb531049aee830fcf_id_rsa (zuul-build-sshkey) 2026-07-02 07:45:37.661538 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010817 2026-07-02 07:45:37.674532 | 2026-07-02 07:45:37.674636 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-02 07:45:38.153953 | debian-bookworm | ok 2026-07-02 07:45:38.161788 | 2026-07-02 07:45:38.161892 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-02 07:45:38.195148 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:45:38.205685 | 2026-07-02 07:45:38.205803 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-02 07:45:38.670932 | debian-bookworm | ok 2026-07-02 07:45:38.696133 | 2026-07-02 07:45:38.696241 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-02 07:45:38.724723 | debian-bookworm | ok 2026-07-02 07:45:38.729130 | 2026-07-02 07:45:38.729219 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-02 07:45:39.164522 | debian-bookworm -> localhost | ok 2026-07-02 07:45:39.169717 | 2026-07-02 07:45:39.169794 | TASK [validate-host : Collect information about the host] 2026-07-02 07:45:40.575436 | debian-bookworm | ok 2026-07-02 07:45:40.590618 | 2026-07-02 07:45:40.590729 | TASK [validate-host : Sanitize hostname] 2026-07-02 07:45:40.756950 | debian-bookworm | ok 2026-07-02 07:45:40.767558 | 2026-07-02 07:45:40.767672 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-02 07:45:41.735595 | debian-bookworm -> localhost | changed 2026-07-02 07:45:41.741236 | 2026-07-02 07:45:41.741336 | TASK [validate-host : Collect information about zuul worker] 2026-07-02 07:45:42.368914 | debian-bookworm | ok 2026-07-02 07:45:42.373539 | 2026-07-02 07:45:42.373636 | TASK [validate-host : Write out all zuul information for each host] 2026-07-02 07:45:43.001290 | debian-bookworm -> localhost | changed 2026-07-02 07:45:43.010296 | 2026-07-02 07:45:43.010391 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-02 07:45:43.348189 | debian-bookworm | ok 2026-07-02 07:45:43.358341 | 2026-07-02 07:45:43.358467 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-02 07:45:44.669515 | debian-bookworm | changed: 2026-07-02 07:45:44.669891 | debian-bookworm | cd+++++++++ src/ 2026-07-02 07:45:44.669973 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-02 07:45:44.670026 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-02 07:45:44.670071 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-02 07:45:44.670163 | debian-bookworm | Containerfile 2026-07-02 07:45:44.670470 | debian-bookworm | Containerfile 2026-07-02 07:45:44.709549 | debian-bookworm | 2026-07-02 07:47:13.947775 | debian-bookworm | [DeployException]: sys.exit(cli()) 2026-07-02 07:47:13.947782 | debian-bookworm | [DeployException]: ^^^^^ 2026-07-02 07:47:13.947787 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.947792 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/cli/command.py", line 801, in cli 2026-07-02 07:47:13.947796 | debian-bookworm | [DeployException]: handler(args, state) 2026-07-02 07:47:13.947800 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.947804 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/cli/command.py", line 70, in 2026-07-02 07:47:13.947808 | debian-bookworm | cmd_install 2026-07-02 07:47:13.947813 | debian-bookworm | [DeployException]: do_install( 2026-07-02 07:47:13.947817 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.947821 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 415, 2026-07-02 07:47:13.947826 | debian-bookworm | in do_install 2026-07-02 07:47:13.947831 | debian-bookworm | [DeployException]: do_init( 2026-07-02 07:47:13.947834 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.947839 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 927, 2026-07-02 07:47:13.947843 | debian-bookworm | in do_init 2026-07-02 07:47:13.947861 | debian-bookworm | [DeployException]: handle_lockfile( 2026-07-02 07:47:13.947865 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.947869 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 270, 2026-07-02 07:47:13.947873 | debian-bookworm | in handle_lockfile 2026-07-02 07:47:13.947877 | debian-bookworm | [DeployException]: raise exceptions.DeployException 2026-07-02 07:47:13.948151 | debian-bookworm | ERROR:: Aborting deploy 2026-07-02 07:47:14.215776 | debian-bookworm | ERROR 2026-07-02 07:47:14.216038 | debian-bookworm | { 2026-07-02 07:47:14.216103 | debian-bookworm | "delta": "0:00:01.802163", 2026-07-02 07:47:14.216139 | debian-bookworm | "end": "2026-07-02 07:47:14.149161", 2026-07-02 07:47:14.216219 | debian-bookworm | "msg": "non-zero return code", 2026-07-02 07:47:14.216250 | debian-bookworm | "rc": 1, 2026-07-02 07:47:14.216277 | debian-bookworm | "start": "2026-07-02 07:47:12.346998" 2026-07-02 07:47:14.216303 | debian-bookworm | } failure 2026-07-02 07:47:14.221917 | 2026-07-02 07:47:14.222004 | PLAY RECAP 2026-07-02 07:47:14.222066 | debian-bookworm | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-07-02 07:47:14.222096 | 2026-07-02 07:47:14.376005 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/python-osism/playbooks/test-unit.yml@main] 2026-07-02 07:47:14.377975 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-02 07:47:15.185923 | 2026-07-02 07:47:15.186105 | PLAY [Base post-fetch] 2026-07-02 07:47:15.201229 | 2026-07-02 07:47:15.201372 | TASK [diagnose-network : Ensure log directory exists] 2026-07-02 07:47:15.826431 | debian-bookworm | ok 2026-07-02 07:47:15.837855 | 2026-07-02 07:47:15.838044 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-02 07:47:27.804278 | debian-bookworm | ok: Runtime: 0:00:06.114106 2026-07-02 07:47:27.814331 | 2026-07-02 07:47:27.814508 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-02 07:47:33.895306 | debian-bookworm | ok: Runtime: 0:00:00.297666 2026-07-02 07:47:33.900987 | 2026-07-02 07:47:33.901114 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-02 07:47:40.007109 | debian-bookworm | ok: Runtime: 0:00:00.073019 2026-07-02 07:47:40.019390 | 2026-07-02 07:47:40.019538 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-02 07:47:40.085796 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:47:40.099562 | 2026-07-02 07:47:40.099759 | TASK [fetch-output : Set log path for single node] 2026-07-02 07:47:40.153933 | debian-bookworm | ok 2026-07-02 07:47:40.160923 | 2026-07-02 07:47:40.161083 | LOOP [fetch-output : Ensure local output dirs] 2026-07-02 07:47:40.472746 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/work/logs" 2026-07-02 07:47:40.749937 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/work/artifacts" 2026-07-02 07:47:41.067773 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/25318b0a455f404cb531049aee830fcf/work/docs" 2026-07-02 07:47:41.081567 | 2026-07-02 07:47:41.081720 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-02 07:47:41.957880 | debian-bookworm | changed: 2026-07-02 07:47:41.958260 | debian-bookworm | .d..t...... ./ 2026-07-02 07:47:41.958327 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-02 07:47:41.958369 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-02 07:47:41.958409 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-02 07:47:41.958448 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-02 07:47:41.958488 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-02 07:47:41.958561 | debian-bookworm | changed: All items complete 2026-07-02 07:47:41.958604 | 2026-07-02 07:47:42.502091 | debian-bookworm | changed: .d..t...... ./ 2026-07-02 07:47:43.061726 | debian-bookworm | changed: .d..t...... ./ 2026-07-02 07:47:43.083829 | 2026-07-02 07:47:43.083986 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-02 07:47:43.423198 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006074 2026-07-02 07:47:43.704648 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008900 2026-07-02 07:47:43.718021 | 2026-07-02 07:47:43.718127 | PLAY RECAP 2026-07-02 07:47:43.718199 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-02 07:47:43.718226 | 2026-07-02 07:47:43.868869 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-02 07:47:43.872329 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-02 07:47:44.608263 | 2026-07-02 07:47:44.608454 | PLAY [Base post] 2026-07-02 07:47:44.622913 | 2026-07-02 07:47:44.623079 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-02 07:47:45.472919 | debian-bookworm | changed 2026-07-02 07:47:45.479628 | 2026-07-02 07:47:45.479742 | PLAY RECAP 2026-07-02 07:47:45.479805 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-02 07:47:45.479868 | 2026-07-02 07:47:45.609166 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-02 07:47:45.612699 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-02 07:47:46.423085 | 2026-07-02 07:47:46.424403 | PLAY [Base post-logs] 2026-07-02 07:47:46.435996 | 2026-07-02 07:47:46.436143 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-02 07:47:46.952809 | localhost | changed 2026-07-02 07:47:46.971634 | 2026-07-02 07:47:46.971833 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-02 07:47:47.000469 | localhost | ok 2026-07-02 07:47:47.006584 | 2026-07-02 07:47:47.006735 | TASK [Set zuul-log-path fact] 2026-07-02 07:47:47.024694 | localhost | ok 2026-07-02 07:47:47.039549 | 2026-07-02 07:47:47.039903 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-02 07:47:47.066444 | localhost | ok 2026-07-02 07:47:47.070579 | 2026-07-02 07:47:47.070715 | TASK [upload-logs : Create log directories] 2026-07-02 07:47:47.586960 | localhost | changed 2026-07-02 07:47:47.590129 | 2026-07-02 07:47:47.590296 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-02 07:47:48.096449 | localhost -> localhost | ok: Runtime: 0:00:00.007740 2026-07-02 07:47:48.109502 | 2026-07-02 07:47:48.109840 | TASK [upload-logs : Upload logs to log server] 2026-07-02 07:47:48.711497 | localhost | Output suppressed because no_log was given 2026-07-02 07:47:48.713422 | 2026-07-02 07:47:48.713535 | LOOP [upload-logs : Compress console log and json output] 2026-07-02 07:47:48.766325 | localhost | skipping: Conditional result was False 2026-07-02 07:47:48.775655 | localhost | skipping: Conditional result was False 2026-07-02 07:47:48.782089 | 2026-07-02 07:47:48.782249 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-02 07:47:48.837723 | localhost | skipping: Conditional result was False 2026-07-02 07:47:48.838462 | 2026-07-02 07:47:48.842140 | localhost | skipping: Conditional result was False 2026-07-02 07:47:48.866877 | 2026-07-02 07:47:48.867079 | LOOP [upload-logs : Upload console log and json output]