2026-05-27 03:13:50.432083 | Job console starting 2026-05-27 03:13:50.448444 | Updating git repos 2026-05-27 03:13:50.489938 | Cloning repos into workspace 2026-05-27 03:13:50.582356 | Restoring repo states 2026-05-27 03:13:50.598323 | Merging changes 2026-05-27 03:13:50.598349 | Checking out repos 2026-05-27 03:13:50.635220 | Preparing playbooks 2026-05-27 03:13:51.801295 | Running Ansible setup 2026-05-27 03:13:58.148183 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-27 03:13:59.711111 | 2026-05-27 03:13:59.711273 | PLAY [Base pre] 2026-05-27 03:13:59.749751 | 2026-05-27 03:13:59.749996 | TASK [Setup log path fact] 2026-05-27 03:13:59.776858 | debian-bookworm | ok 2026-05-27 03:13:59.819193 | 2026-05-27 03:13:59.819348 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-27 03:13:59.874902 | debian-bookworm | ok 2026-05-27 03:13:59.895354 | 2026-05-27 03:13:59.895499 | TASK [emit-job-header : Print job information] 2026-05-27 03:13:59.950268 | # Job Information 2026-05-27 03:13:59.950452 | Ansible Version: 2.16.14 2026-05-27 03:13:59.950487 | Job: python-black 2026-05-27 03:13:59.950552 | Pipeline: periodic-daily 2026-05-27 03:13:59.950576 | Executor: 521e9411259a 2026-05-27 03:13:59.950597 | Triggered by: https://github.com/osism/node-image 2026-05-27 03:13:59.950621 | Event ID: 4a16d10806614574bcb4e957776b13f9 2026-05-27 03:13:59.957665 | 2026-05-27 03:13:59.957791 | LOOP [emit-job-header : Print node information] 2026-05-27 03:14:00.146602 | debian-bookworm | ok: 2026-05-27 03:14:00.146761 | debian-bookworm | # Node Information 2026-05-27 03:14:00.146787 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-27 03:14:00.146807 | debian-bookworm | Hostname: debian 2026-05-27 03:14:00.146826 | debian-bookworm | Username: zuul 2026-05-27 03:14:00.146906 | debian-bookworm | Distro: Debian 12.14 2026-05-27 03:14:00.146927 | debian-bookworm | Provider: regiocloud-a 2026-05-27 03:14:00.146945 | debian-bookworm | Region: 2026-05-27 03:14:00.146962 | debian-bookworm | Label: debian-bookworm 2026-05-27 03:14:00.146979 | debian-bookworm | Product Name: OpenStack Nova 2026-05-27 03:14:00.146995 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5d:658f 2026-05-27 03:14:00.169406 | 2026-05-27 03:14:00.169570 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-27 03:14:00.718320 | debian-bookworm -> localhost | changed 2026-05-27 03:14:00.725992 | 2026-05-27 03:14:00.726117 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-27 03:14:02.125376 | debian-bookworm -> localhost | changed 2026-05-27 03:14:02.155647 | 2026-05-27 03:14:02.155814 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-27 03:14:02.746219 | debian-bookworm -> localhost | ok 2026-05-27 03:14:02.758055 | 2026-05-27 03:14:02.758725 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-27 03:14:02.825449 | debian-bookworm | ok 2026-05-27 03:14:02.870348 | debian-bookworm | included: /var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-27 03:14:02.891061 | 2026-05-27 03:14:02.891165 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-27 03:14:05.172071 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-27 03:14:05.172627 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/work/d50b59502b8048638d1217c9be3760bc_id_rsa 2026-05-27 03:14:05.172688 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/work/d50b59502b8048638d1217c9be3760bc_id_rsa.pub 2026-05-27 03:14:05.172762 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-27 03:14:05.172793 | debian-bookworm -> localhost | SHA256:KkV//E30m1ABfj4VBf4yOhv/cNiyOGSVwf8VBq62xhs zuul-build-sshkey 2026-05-27 03:14:05.172829 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-27 03:14:05.172864 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-27 03:14:05.172898 | debian-bookworm -> localhost | | +++o| 2026-05-27 03:14:05.172923 | debian-bookworm -> localhost | | o.ooo| 2026-05-27 03:14:05.172946 | debian-bookworm -> localhost | | . o+*o| 2026-05-27 03:14:05.172968 | debian-bookworm -> localhost | | . . . ..*o+| 2026-05-27 03:14:05.172989 | debian-bookworm -> localhost | | . S oo o+o=| 2026-05-27 03:14:05.173016 | debian-bookworm -> localhost | | . . .o.++.==| 2026-05-27 03:14:05.173038 | debian-bookworm -> localhost | | . . E= =oo| 2026-05-27 03:14:05.173060 | debian-bookworm -> localhost | | . . +* = | 2026-05-27 03:14:05.173081 | debian-bookworm -> localhost | | .o.o..| 2026-05-27 03:14:05.173102 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-27 03:14:05.173163 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.976024 2026-05-27 03:14:05.180811 | 2026-05-27 03:14:05.180907 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-27 03:14:05.228456 | debian-bookworm | ok 2026-05-27 03:14:05.240339 | debian-bookworm | included: /var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-27 03:14:05.306512 | 2026-05-27 03:14:05.306655 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-27 03:14:05.341683 | debian-bookworm | skipping: Conditional result was False 2026-05-27 03:14:05.347784 | 2026-05-27 03:14:05.347893 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-27 03:14:06.256004 | debian-bookworm | changed 2026-05-27 03:14:06.261349 | 2026-05-27 03:14:06.261434 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-27 03:14:06.551511 | debian-bookworm | ok 2026-05-27 03:14:06.557281 | 2026-05-27 03:14:06.557376 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-27 03:14:07.184453 | debian-bookworm | changed 2026-05-27 03:14:07.192327 | 2026-05-27 03:14:07.192433 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-27 03:14:07.823990 | debian-bookworm | changed 2026-05-27 03:14:07.848249 | 2026-05-27 03:14:07.848381 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-27 03:14:07.897024 | debian-bookworm | skipping: Conditional result was False 2026-05-27 03:14:07.905209 | 2026-05-27 03:14:07.905353 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-27 03:14:08.949581 | debian-bookworm -> localhost | changed 2026-05-27 03:14:08.964709 | 2026-05-27 03:14:08.967370 | TASK [add-build-sshkey : Add back temp key] 2026-05-27 03:14:09.510641 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/work/d50b59502b8048638d1217c9be3760bc_id_rsa (zuul-build-sshkey) 2026-05-27 03:14:09.511190 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012215 2026-05-27 03:14:09.525098 | 2026-05-27 03:14:09.525233 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-27 03:14:09.980185 | debian-bookworm | ok 2026-05-27 03:14:09.985704 | 2026-05-27 03:14:09.985798 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-27 03:14:10.041095 | debian-bookworm | skipping: Conditional result was False 2026-05-27 03:14:10.051976 | 2026-05-27 03:14:10.052095 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-27 03:14:10.486715 | debian-bookworm | ok 2026-05-27 03:14:10.513176 | 2026-05-27 03:14:10.513311 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-27 03:14:10.554340 | debian-bookworm | ok 2026-05-27 03:14:10.559932 | 2026-05-27 03:14:10.560057 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-27 03:14:11.075352 | debian-bookworm -> localhost | ok 2026-05-27 03:14:11.087782 | 2026-05-27 03:14:11.087913 | TASK [validate-host : Collect information about the host] 2026-05-27 03:14:12.308528 | debian-bookworm | ok 2026-05-27 03:14:12.321533 | 2026-05-27 03:14:12.321646 | TASK [validate-host : Sanitize hostname] 2026-05-27 03:14:12.412285 | debian-bookworm | ok 2026-05-27 03:14:12.455897 | 2026-05-27 03:14:12.456020 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-27 03:14:13.044747 | debian-bookworm -> localhost | changed 2026-05-27 03:14:13.049410 | 2026-05-27 03:14:13.049485 | TASK [validate-host : Collect information about zuul worker] 2026-05-27 03:14:13.546308 | debian-bookworm | ok 2026-05-27 03:14:13.550211 | 2026-05-27 03:14:13.550291 | TASK [validate-host : Write out all zuul information for each host] 2026-05-27 03:14:14.263965 | debian-bookworm -> localhost | changed 2026-05-27 03:14:14.271741 | 2026-05-27 03:14:14.271820 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-27 03:14:14.582595 | debian-bookworm | ok 2026-05-27 03:14:14.587069 | 2026-05-27 03:14:14.587149 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-27 03:14:16.385836 | debian-bookworm | changed: 2026-05-27 03:14:16.386010 | debian-bookworm | cd+++++++++ src/ 2026-05-27 03:14:16.386045 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-27 03:14:16.386070 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-27 03:14:16.386092 | debian-bookworm | cd+++++++++ src/github.com/osism/node-image/ 2026-05-27 03:14:16.386114 | debian-bookworm | Containerfile 2026-05-27 03:14:16.386248 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/work/logs" 2026-05-27 03:14:42.945822 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/work/artifacts" 2026-05-27 03:14:43.183603 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d50b59502b8048638d1217c9be3760bc/work/docs" 2026-05-27 03:14:43.197057 | 2026-05-27 03:14:43.197181 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-27 03:14:43.990076 | debian-bookworm | changed: 2026-05-27 03:14:43.990499 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-27 03:14:43.990546 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-27 03:14:43.990577 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-27 03:14:43.990632 | debian-bookworm | changed: All items complete 2026-05-27 03:14:43.990654 | 2026-05-27 03:14:44.748939 | debian-bookworm | changed: .d..t...... ./ 2026-05-27 03:14:45.392188 | debian-bookworm | changed: .d..t...... ./ 2026-05-27 03:14:45.409451 | 2026-05-27 03:14:45.409656 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-27 03:14:45.448660 | debian-bookworm | skipping: Conditional result was False 2026-05-27 03:14:45.453100 | debian-bookworm | skipping: Conditional result was False 2026-05-27 03:14:45.470159 | 2026-05-27 03:14:45.470293 | PLAY RECAP 2026-05-27 03:14:45.470350 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-27 03:14:45.470375 | 2026-05-27 03:14:45.975804 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-27 03:14:45.976785 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-27 03:14:47.345219 | 2026-05-27 03:14:47.345462 | PLAY [Base post] 2026-05-27 03:14:47.380560 | 2026-05-27 03:14:47.380710 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-27 03:14:48.145892 | debian-bookworm | changed 2026-05-27 03:14:48.160070 | 2026-05-27 03:14:48.160175 | PLAY RECAP 2026-05-27 03:14:48.160234 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-27 03:14:48.160305 | 2026-05-27 03:14:48.276122 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-27 03:14:48.276927 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-27 03:14:49.172535 | 2026-05-27 03:14:49.172689 | PLAY [Base post-logs] 2026-05-27 03:14:49.193056 | 2026-05-27 03:14:49.193198 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-27 03:14:49.812764 | localhost | changed 2026-05-27 03:14:49.829325 | 2026-05-27 03:14:49.829512 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-27 03:14:49.877421 | localhost | ok 2026-05-27 03:14:49.880445 | 2026-05-27 03:14:49.880544 | TASK [Set zuul-log-path fact] 2026-05-27 03:14:49.920569 | localhost | ok 2026-05-27 03:14:49.937922 | 2026-05-27 03:14:49.938046 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-27 03:14:50.030644 | localhost | ok 2026-05-27 03:14:50.036632 | 2026-05-27 03:14:50.036734 | TASK [upload-logs : Create log directories] 2026-05-27 03:14:50.758495 | localhost | changed 2026-05-27 03:14:50.761010 | 2026-05-27 03:14:50.761095 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-27 03:14:51.496735 | localhost -> localhost | ok: Runtime: 0:00:00.030685 2026-05-27 03:14:51.500406 | 2026-05-27 03:14:51.500533 | TASK [upload-logs : Upload logs to log server] 2026-05-27 03:14:52.083805 | localhost | Output suppressed because no_log was given 2026-05-27 03:14:52.085173 | 2026-05-27 03:14:52.085249 | LOOP [upload-logs : Compress console log and json output] 2026-05-27 03:14:52.135656 | localhost | skipping: Conditional result was False 2026-05-27 03:14:52.140094 | localhost | skipping: Conditional result was False 2026-05-27 03:14:52.152436 | 2026-05-27 03:14:52.152605 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-27 03:14:52.223262 | localhost | skipping: Conditional result was False 2026-05-27 03:14:52.223467 | 2026-05-27 03:14:52.224194 | localhost | skipping: Conditional result was False 2026-05-27 03:14:52.236925 | 2026-05-27 03:14:52.237056 | LOOP [upload-logs : Upload console log and json output]