2026-05-03 09:32:43.497880 | Job console starting 2026-05-03 09:32:43.547507 | Updating git repos 2026-05-03 09:32:43.644348 | Cloning repos into workspace 2026-05-03 09:32:43.744740 | Restoring repo states 2026-05-03 09:32:43.775384 | Merging changes 2026-05-03 09:32:44.639550 | Checking out repos 2026-05-03 09:32:44.787781 | Preparing playbooks 2026-05-03 09:32:46.825927 | Running Ansible setup 2026-05-03 09:34:11.955203 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-03 09:34:12.776682 | 2026-05-03 09:34:12.776886 | PLAY [Base pre] 2026-05-03 09:34:12.794742 | 2026-05-03 09:34:12.794906 | TASK [Setup log path fact] 2026-05-03 09:34:12.831514 | debian-bookworm | ok 2026-05-03 09:34:12.851449 | 2026-05-03 09:34:12.851608 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-03 09:34:12.881737 | debian-bookworm | ok 2026-05-03 09:34:12.893833 | 2026-05-03 09:34:12.893950 | TASK [emit-job-header : Print job information] 2026-05-03 09:34:12.932906 | # Job Information 2026-05-03 09:34:12.933086 | Ansible Version: 2.16.14 2026-05-03 09:34:12.933121 | Job: python-osism-unit-tests 2026-05-03 09:34:12.933155 | Pipeline: check 2026-05-03 09:34:12.933179 | Executor: 521e9411259a 2026-05-03 09:34:12.933201 | Triggered by: https://github.com/osism/python-osism/pull/2246 2026-05-03 09:34:12.933224 | Event ID: 029f8ad0-46d3-11f1-8902-6077c39c7005 2026-05-03 09:34:12.938019 | 2026-05-03 09:34:12.938128 | LOOP [emit-job-header : Print node information] 2026-05-03 09:34:13.051143 | debian-bookworm | ok: 2026-05-03 09:34:13.051425 | debian-bookworm | # Node Information 2026-05-03 09:34:13.051465 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-03 09:34:13.051490 | debian-bookworm | Hostname: debian 2026-05-03 09:34:13.051512 | debian-bookworm | Username: zuul 2026-05-03 09:34:13.051532 | debian-bookworm | Distro: Debian 12.13 2026-05-03 09:34:13.051556 | debian-bookworm | Provider: regiocloud-a 2026-05-03 09:34:13.051576 | debian-bookworm | Region: 2026-05-03 09:34:13.051597 | debian-bookworm | Label: debian-bookworm 2026-05-03 09:34:13.051617 | debian-bookworm | Product Name: OpenStack Nova 2026-05-03 09:34:13.051636 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe07:2f2d 2026-05-03 09:34:13.080421 | 2026-05-03 09:34:13.080603 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-03 09:34:13.604967 | debian-bookworm -> localhost | changed 2026-05-03 09:34:13.621618 | 2026-05-03 09:34:13.621818 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-03 09:34:14.726678 | debian-bookworm -> localhost | changed 2026-05-03 09:34:14.746860 | 2026-05-03 09:34:14.747020 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-03 09:34:15.029368 | debian-bookworm -> localhost | ok 2026-05-03 09:34:15.049763 | 2026-05-03 09:34:15.050099 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-03 09:34:15.081066 | debian-bookworm | ok 2026-05-03 09:34:15.099353 | debian-bookworm | included: /var/lib/zuul/builds/09abc755669943e081824664110c69f8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-03 09:34:15.105749 | 2026-05-03 09:34:15.105856 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-03 09:34:16.691624 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-03 09:34:16.692104 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/09abc755669943e081824664110c69f8/work/09abc755669943e081824664110c69f8_id_rsa 2026-05-03 09:34:16.692178 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/09abc755669943e081824664110c69f8/work/09abc755669943e081824664110c69f8_id_rsa.pub 2026-05-03 09:34:16.692208 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-03 09:34:16.692241 | debian-bookworm -> localhost | SHA256:KIn4mmeSpLMCyh6/ObNwdGKo48QrAw0T1fAHmrk79qc zuul-build-sshkey 2026-05-03 09:34:16.692277 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-03 09:34:16.692319 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-03 09:34:16.692342 | debian-bookworm -> localhost | | .oo. | 2026-05-03 09:34:16.692367 | debian-bookworm -> localhost | |. =.. | 2026-05-03 09:34:16.692400 | debian-bookworm -> localhost | | .+ . . | 2026-05-03 09:34:16.692422 | debian-bookworm -> localhost | |o..o o . | 2026-05-03 09:34:16.692451 | debian-bookworm -> localhost | |.=o+o.. S | 2026-05-03 09:34:16.692492 | debian-bookworm -> localhost | |=o+.o. | 2026-05-03 09:34:16.692528 | debian-bookworm -> localhost | |X*=. | 2026-05-03 09:34:16.692556 | debian-bookworm -> localhost | |%*O=. . | 2026-05-03 09:34:16.692587 | debian-bookworm -> localhost | |B@ =Eo | 2026-05-03 09:34:16.692609 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-03 09:34:16.692710 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.041818 2026-05-03 09:34:16.703003 | 2026-05-03 09:34:16.703188 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-03 09:34:16.743236 | debian-bookworm | ok 2026-05-03 09:34:16.759731 | debian-bookworm | included: /var/lib/zuul/builds/09abc755669943e081824664110c69f8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-03 09:34:16.769934 | 2026-05-03 09:34:16.770059 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-03 09:34:16.794466 | debian-bookworm | skipping: Conditional result was False 2026-05-03 09:34:16.801921 | 2026-05-03 09:34:16.802036 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-03 09:34:17.508222 | debian-bookworm | changed 2026-05-03 09:34:17.516110 | 2026-05-03 09:34:17.516236 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-03 09:34:17.797503 | debian-bookworm | ok 2026-05-03 09:34:17.808611 | 2026-05-03 09:34:17.808800 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-03 09:34:18.540752 | debian-bookworm | changed 2026-05-03 09:34:18.550635 | 2026-05-03 09:34:18.550792 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-03 09:34:19.275485 | debian-bookworm | changed 2026-05-03 09:34:19.288339 | 2026-05-03 09:34:19.288560 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-03 09:34:19.324205 | debian-bookworm | skipping: Conditional result was False 2026-05-03 09:34:19.338025 | 2026-05-03 09:34:19.338217 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-03 09:34:19.813530 | debian-bookworm -> localhost | changed 2026-05-03 09:34:19.836746 | 2026-05-03 09:34:19.836939 | TASK [add-build-sshkey : Add back temp key] 2026-05-03 09:34:20.229661 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/09abc755669943e081824664110c69f8/work/09abc755669943e081824664110c69f8_id_rsa (zuul-build-sshkey) 2026-05-03 09:34:20.230250 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020510 2026-05-03 09:34:20.246445 | 2026-05-03 09:34:20.246597 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-03 09:34:20.680939 | debian-bookworm | ok 2026-05-03 09:34:20.688397 | 2026-05-03 09:34:20.688603 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-03 09:34:20.713538 | debian-bookworm | skipping: Conditional result was False 2026-05-03 09:34:20.724338 | 2026-05-03 09:34:20.724450 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-03 09:34:21.180090 | debian-bookworm | ok 2026-05-03 09:34:21.194426 | 2026-05-03 09:34:21.194563 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-03 09:34:21.240641 | debian-bookworm | ok 2026-05-03 09:34:21.248435 | 2026-05-03 09:34:21.248569 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-03 09:34:21.580435 | debian-bookworm -> localhost | ok 2026-05-03 09:34:21.595630 | 2026-05-03 09:34:21.595878 | TASK [validate-host : Collect information about the host] 2026-05-03 09:35:02.792822 | debian-bookworm | ok 2026-05-03 09:35:02.809172 | 2026-05-03 09:35:02.809317 | TASK [validate-host : Sanitize hostname] 2026-05-03 09:35:02.936936 | debian-bookworm | ok 2026-05-03 09:35:02.978397 | 2026-05-03 09:35:02.978529 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-03 09:35:03.559695 | debian-bookworm -> localhost | changed 2026-05-03 09:35:03.565928 | 2026-05-03 09:35:03.566048 | TASK [validate-host : Collect information about zuul worker] 2026-05-03 09:35:04.009945 | debian-bookworm | ok 2026-05-03 09:35:04.015053 | 2026-05-03 09:35:04.015168 | TASK [validate-host : Write out all zuul information for each host] 2026-05-03 09:35:04.668499 | debian-bookworm -> localhost | changed 2026-05-03 09:35:04.678812 | 2026-05-03 09:35:04.678973 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-03 09:35:04.953907 | debian-bookworm | ok 2026-05-03 09:35:04.960861 | 2026-05-03 09:35:04.960981 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-03 09:35:06.058938 | debian-bookworm | changed: 2026-05-03 09:35:06.059170 | debian-bookworm | cd+++++++++ src/ 2026-05-03 09:35:06.059214 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-03 09:35:06.059248 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-03 09:35:06.059280 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-03 09:35:06.059311 | debian-bookworm | Containerfile 2026-05-03 09:35:06.059490 | debian-bookworm | Containerfile 2026-05-03 09:35:06.077393 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/09abc755669943e081824664110c69f8/work/logs" 2026-05-03 09:39:24.394121 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/09abc755669943e081824664110c69f8/work/artifacts" 2026-05-03 09:39:24.677028 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/09abc755669943e081824664110c69f8/work/docs" 2026-05-03 09:39:24.700206 | 2026-05-03 09:39:24.700503 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-03 09:39:25.502392 | debian-bookworm | changed: 2026-05-03 09:39:25.502780 | debian-bookworm | .d..t...... ./ 2026-05-03 09:39:25.502881 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-03 09:39:25.502936 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-03 09:39:25.502979 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-03 09:39:25.503042 | debian-bookworm | changed: All items complete 2026-05-03 09:39:25.503078 | 2026-05-03 09:39:26.079565 | debian-bookworm | changed: .d..t...... ./ 2026-05-03 09:39:26.686632 | debian-bookworm | changed: .d..t...... ./ 2026-05-03 09:39:26.716333 | 2026-05-03 09:39:26.716547 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-03 09:39:27.251079 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011323 2026-05-03 09:39:27.566529 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009942 2026-05-03 09:39:27.586330 | 2026-05-03 09:39:27.586477 | PLAY RECAP 2026-05-03 09:39:27.586559 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-03 09:39:27.586601 | 2026-05-03 09:39:27.730598 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-03 09:39:27.731811 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-03 09:39:28.474412 | 2026-05-03 09:39:28.474586 | PLAY [Base post] 2026-05-03 09:39:28.488912 | 2026-05-03 09:39:28.489067 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-03 09:39:29.075881 | debian-bookworm | changed 2026-05-03 09:39:29.085758 | 2026-05-03 09:39:29.085896 | PLAY RECAP 2026-05-03 09:39:29.085973 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-03 09:39:29.086053 | 2026-05-03 09:39:29.206708 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-03 09:39:29.208270 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-03 09:39:29.980545 | 2026-05-03 09:39:29.980757 | PLAY [Base post-logs] 2026-05-03 09:39:29.991876 | 2026-05-03 09:39:29.992031 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-03 09:39:30.498353 | localhost | changed 2026-05-03 09:39:30.517276 | 2026-05-03 09:39:30.517460 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-03 09:39:30.545893 | localhost | ok 2026-05-03 09:39:30.551077 | 2026-05-03 09:39:30.551226 | TASK [Set zuul-log-path fact] 2026-05-03 09:39:30.568152 | localhost | ok 2026-05-03 09:39:30.578933 | 2026-05-03 09:39:30.579060 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-03 09:39:30.615519 | localhost | ok 2026-05-03 09:39:30.621369 | 2026-05-03 09:39:30.621519 | TASK [upload-logs : Create log directories] 2026-05-03 09:39:31.148266 | localhost | changed 2026-05-03 09:39:31.152313 | 2026-05-03 09:39:31.152454 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-03 09:39:31.651665 | localhost -> localhost | ok: Runtime: 0:00:00.007110 2026-05-03 09:39:31.664162 | 2026-05-03 09:39:31.664340 | TASK [upload-logs : Upload logs to log server] 2026-05-03 09:39:32.244754 | localhost | Output suppressed because no_log was given 2026-05-03 09:39:32.248871 | 2026-05-03 09:39:32.249052 | LOOP [upload-logs : Compress console log and json output] 2026-05-03 09:39:32.307140 | localhost | skipping: Conditional result was False 2026-05-03 09:39:32.312163 | localhost | skipping: Conditional result was False 2026-05-03 09:39:32.324400 | 2026-05-03 09:39:32.324655 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-03 09:39:32.374077 | localhost | skipping: Conditional result was False 2026-05-03 09:39:32.374707 | 2026-05-03 09:39:32.377477 | localhost | skipping: Conditional result was False 2026-05-03 09:39:32.392190 | 2026-05-03 09:39:32.392459 | LOOP [upload-logs : Upload console log and json output]