2026-05-18 16:37:35.623970 | Job console starting 2026-05-18 16:37:35.639925 | Updating git repos 2026-05-18 16:37:35.684569 | Cloning repos into workspace 2026-05-18 16:37:35.732624 | Restoring repo states 2026-05-18 16:37:35.758964 | Merging changes 2026-05-18 16:37:36.388988 | Checking out repos 2026-05-18 16:37:36.442821 | Preparing playbooks 2026-05-18 16:37:37.243753 | Running Ansible setup 2026-05-18 16:37:41.901508 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 16:37:42.742066 | 2026-05-18 16:37:42.742236 | PLAY [Base pre] 2026-05-18 16:37:42.760949 | 2026-05-18 16:37:42.761104 | TASK [Setup log path fact] 2026-05-18 16:37:42.803277 | debian-bookworm | ok 2026-05-18 16:37:42.821311 | 2026-05-18 16:37:42.821474 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 16:37:42.852341 | debian-bookworm | ok 2026-05-18 16:37:42.868972 | 2026-05-18 16:37:42.869116 | TASK [emit-job-header : Print job information] 2026-05-18 16:37:42.919670 | # Job Information 2026-05-18 16:37:42.919898 | Ansible Version: 2.16.14 2026-05-18 16:37:42.919937 | Job: python-osism-test-setup 2026-05-18 16:37:42.919971 | Pipeline: check 2026-05-18 16:37:42.919995 | Executor: 521e9411259a 2026-05-18 16:37:42.920016 | Triggered by: https://github.com/osism/python-osism/pull/2279 2026-05-18 16:37:42.920042 | Event ID: c0033d00-52d7-11f1-9968-99d020f86c26 2026-05-18 16:37:42.928926 | 2026-05-18 16:37:42.929065 | LOOP [emit-job-header : Print node information] 2026-05-18 16:37:43.154120 | debian-bookworm | ok: 2026-05-18 16:37:43.154385 | debian-bookworm | # Node Information 2026-05-18 16:37:43.154422 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 16:37:43.154450 | debian-bookworm | Hostname: debian 2026-05-18 16:37:43.154474 | debian-bookworm | Username: zuul 2026-05-18 16:37:43.154497 | debian-bookworm | Distro: Debian 12.14 2026-05-18 16:37:43.154525 | debian-bookworm | Provider: regiocloud-a 2026-05-18 16:37:43.154548 | debian-bookworm | Region: 2026-05-18 16:37:43.154572 | debian-bookworm | Label: debian-bookworm 2026-05-18 16:37:43.154595 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 16:37:43.154617 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe00:a8ce 2026-05-18 16:37:43.186007 | 2026-05-18 16:37:43.186161 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 16:37:43.741358 | debian-bookworm -> localhost | changed 2026-05-18 16:37:43.759157 | 2026-05-18 16:37:43.759405 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 16:37:45.150709 | debian-bookworm -> localhost | changed 2026-05-18 16:37:45.178736 | 2026-05-18 16:37:45.178967 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 16:37:45.592084 | debian-bookworm -> localhost | ok 2026-05-18 16:37:45.621886 | 2026-05-18 16:37:45.622040 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 16:37:45.654747 | debian-bookworm | ok 2026-05-18 16:37:45.671212 | debian-bookworm | included: /var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 16:37:45.677883 | 2026-05-18 16:37:45.677999 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 16:37:47.784735 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 16:37:47.784969 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/work/c0c8024896d84ec497e204e90d08158c_id_rsa 2026-05-18 16:37:47.785008 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/work/c0c8024896d84ec497e204e90d08158c_id_rsa.pub 2026-05-18 16:37:47.785035 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 16:37:47.785058 | debian-bookworm -> localhost | SHA256:araM+Ej+8CRkzwQntjc9SGaydpEdt4zhPHisX+7+R9U zuul-build-sshkey 2026-05-18 16:37:47.785081 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 16:37:47.785115 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 16:37:47.785138 | debian-bookworm -> localhost | | o . | 2026-05-18 16:37:47.785160 | debian-bookworm -> localhost | | B * . | 2026-05-18 16:37:47.785181 | debian-bookworm -> localhost | | = O O o . | 2026-05-18 16:37:47.785202 | debian-bookworm -> localhost | | . @ * . . E| 2026-05-18 16:37:47.785222 | debian-bookworm -> localhost | | * B o S . | 2026-05-18 16:37:47.785248 | debian-bookworm -> localhost | | + * o = . | 2026-05-18 16:37:47.785268 | debian-bookworm -> localhost | | + + = . . | 2026-05-18 16:37:47.785288 | debian-bookworm -> localhost | | o B = o . | 2026-05-18 16:37:47.785308 | debian-bookworm -> localhost | | +o= o.o... | 2026-05-18 16:37:47.785329 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 16:37:47.785385 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.508637 2026-05-18 16:37:47.792413 | 2026-05-18 16:37:47.792540 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 16:37:47.845464 | debian-bookworm | ok 2026-05-18 16:37:47.858570 | debian-bookworm | included: /var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 16:37:47.868669 | 2026-05-18 16:37:47.868815 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 16:37:47.892863 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:37:47.899350 | 2026-05-18 16:37:47.899483 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 16:37:48.650471 | debian-bookworm | changed 2026-05-18 16:37:48.658476 | 2026-05-18 16:37:48.658599 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 16:37:48.919765 | debian-bookworm | ok 2026-05-18 16:37:48.926124 | 2026-05-18 16:37:48.926249 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 16:37:49.583063 | debian-bookworm | changed 2026-05-18 16:37:49.591943 | 2026-05-18 16:37:49.592075 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 16:37:50.240919 | debian-bookworm | changed 2026-05-18 16:37:50.246608 | 2026-05-18 16:37:50.246737 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 16:37:50.285535 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:37:50.293075 | 2026-05-18 16:37:50.293209 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 16:37:50.932888 | debian-bookworm -> localhost | changed 2026-05-18 16:37:50.945966 | 2026-05-18 16:37:50.946109 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 16:37:51.508972 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/work/c0c8024896d84ec497e204e90d08158c_id_rsa (zuul-build-sshkey) 2026-05-18 16:37:51.509219 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030953 2026-05-18 16:37:51.518515 | 2026-05-18 16:37:51.518639 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 16:37:51.976252 | debian-bookworm | ok 2026-05-18 16:37:51.981934 | 2026-05-18 16:37:51.982061 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 16:37:52.026317 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:37:52.038050 | 2026-05-18 16:37:52.038196 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 16:37:52.492947 | debian-bookworm | ok 2026-05-18 16:37:52.504481 | 2026-05-18 16:37:52.504616 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 16:37:52.533935 | debian-bookworm | ok 2026-05-18 16:37:52.539112 | 2026-05-18 16:37:52.539225 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 16:37:52.819555 | debian-bookworm -> localhost | ok 2026-05-18 16:37:52.837446 | 2026-05-18 16:37:52.837610 | TASK [validate-host : Collect information about the host] 2026-05-18 16:37:54.016307 | debian-bookworm | ok 2026-05-18 16:37:54.026784 | 2026-05-18 16:37:54.026977 | TASK [validate-host : Sanitize hostname] 2026-05-18 16:37:54.137718 | debian-bookworm | ok 2026-05-18 16:37:54.179878 | 2026-05-18 16:37:54.180032 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 16:37:55.135414 | debian-bookworm -> localhost | changed 2026-05-18 16:37:55.145576 | 2026-05-18 16:37:55.145795 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 16:37:55.589937 | debian-bookworm | ok 2026-05-18 16:37:55.597816 | 2026-05-18 16:37:55.597967 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 16:37:56.175882 | debian-bookworm -> localhost | changed 2026-05-18 16:37:56.185888 | 2026-05-18 16:37:56.186009 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 16:37:56.477601 | debian-bookworm | ok 2026-05-18 16:37:56.484639 | 2026-05-18 16:37:56.484801 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 16:37:57.623387 | debian-bookworm | changed: 2026-05-18 16:37:57.623617 | debian-bookworm | cd+++++++++ src/ 2026-05-18 16:37:57.623657 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 16:37:57.623724 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 16:37:57.623748 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-18 16:37:57.623769 | debian-bookworm | Containerfile 2026-05-18 16:37:57.623899 | debian-bookworm | Containerfile 2026-05-18 16:37:57.641666 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/work/logs" 2026-05-18 16:41:35.794558 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/work/artifacts" 2026-05-18 16:41:36.080926 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/c0c8024896d84ec497e204e90d08158c/work/docs" 2026-05-18 16:41:36.103050 | 2026-05-18 16:41:36.103191 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 16:41:36.936013 | debian-bookworm | changed: 2026-05-18 16:41:36.936346 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 16:41:36.936409 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 16:41:36.936464 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 16:41:36.936546 | debian-bookworm | changed: All items complete 2026-05-18 16:41:36.936592 | 2026-05-18 16:41:37.517600 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 16:41:38.127388 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 16:41:38.155356 | 2026-05-18 16:41:38.155543 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 16:41:38.697704 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010824 2026-05-18 16:41:38.984293 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008315 2026-05-18 16:41:39.002477 | 2026-05-18 16:41:39.002588 | PLAY RECAP 2026-05-18 16:41:39.002644 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-18 16:41:39.002669 | 2026-05-18 16:41:39.126800 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 16:41:39.127720 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 16:41:39.920589 | 2026-05-18 16:41:39.920813 | PLAY [Base post] 2026-05-18 16:41:39.936036 | 2026-05-18 16:41:39.936190 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 16:41:40.740026 | debian-bookworm | changed 2026-05-18 16:41:40.748344 | 2026-05-18 16:41:40.748472 | PLAY RECAP 2026-05-18 16:41:40.748543 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 16:41:40.748617 | 2026-05-18 16:41:40.870867 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 16:41:40.872165 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 16:41:41.683442 | 2026-05-18 16:41:41.683621 | PLAY [Base post-logs] 2026-05-18 16:41:41.694916 | 2026-05-18 16:41:41.695063 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 16:41:42.171437 | localhost | changed 2026-05-18 16:41:42.187504 | 2026-05-18 16:41:42.187695 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 16:41:42.214756 | localhost | ok 2026-05-18 16:41:42.219462 | 2026-05-18 16:41:42.219601 | TASK [Set zuul-log-path fact] 2026-05-18 16:41:42.246535 | localhost | ok 2026-05-18 16:41:42.257119 | 2026-05-18 16:41:42.257245 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 16:41:42.297593 | localhost | ok 2026-05-18 16:41:42.309289 | 2026-05-18 16:41:42.309517 | TASK [upload-logs : Create log directories] 2026-05-18 16:41:42.854065 | localhost | changed 2026-05-18 16:41:42.860327 | 2026-05-18 16:41:42.860483 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 16:41:43.396474 | localhost -> localhost | ok: Runtime: 0:00:00.007921 2026-05-18 16:41:43.401023 | 2026-05-18 16:41:43.401150 | TASK [upload-logs : Upload logs to log server] 2026-05-18 16:41:43.984263 | localhost | Output suppressed because no_log was given 2026-05-18 16:41:43.988137 | 2026-05-18 16:41:43.988312 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 16:41:44.044203 | localhost | skipping: Conditional result was False 2026-05-18 16:41:44.049529 | localhost | skipping: Conditional result was False 2026-05-18 16:41:44.062250 | 2026-05-18 16:41:44.062479 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 16:41:44.118697 | localhost | skipping: Conditional result was False 2026-05-18 16:41:44.119794 | 2026-05-18 16:41:44.122642 | localhost | skipping: Conditional result was False 2026-05-18 16:41:44.136405 | 2026-05-18 16:41:44.136657 | LOOP [upload-logs : Upload console log and json output]