2026-05-12 20:55:43.166473 | Job console starting 2026-05-12 20:55:43.181799 | Updating git repos 2026-05-12 20:55:43.206873 | Cloning repos into workspace 2026-05-12 20:55:43.302571 | Restoring repo states 2026-05-12 20:55:43.322422 | Merging changes 2026-05-12 20:55:44.024275 | Checking out repos 2026-05-12 20:55:44.090491 | Preparing playbooks 2026-05-12 20:55:44.939986 | Running Ansible setup 2026-05-12 20:55:49.467918 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-12 20:55:50.413030 | 2026-05-12 20:55:50.413253 | PLAY [Base pre] 2026-05-12 20:55:50.435464 | 2026-05-12 20:55:50.435623 | TASK [Setup log path fact] 2026-05-12 20:55:50.481485 | debian-bookworm | ok 2026-05-12 20:55:50.499862 | 2026-05-12 20:55:50.500029 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 20:55:50.530615 | debian-bookworm | ok 2026-05-12 20:55:50.541245 | 2026-05-12 20:55:50.541379 | TASK [emit-job-header : Print job information] 2026-05-12 20:55:50.581519 | # Job Information 2026-05-12 20:55:50.581718 | Ansible Version: 2.16.14 2026-05-12 20:55:50.582199 | Job: container-image-cfg-cookiecutter-build 2026-05-12 20:55:50.582254 | Pipeline: check 2026-05-12 20:55:50.582280 | Executor: 521e9411259a 2026-05-12 20:55:50.582302 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/832 2026-05-12 20:55:50.582324 | Event ID: cf54cd60-4e44-11f1-9998-998b52c5c424 2026-05-12 20:55:50.587586 | 2026-05-12 20:55:50.587715 | LOOP [emit-job-header : Print node information] 2026-05-12 20:55:50.778730 | debian-bookworm | ok: 2026-05-12 20:55:50.779057 | debian-bookworm | # Node Information 2026-05-12 20:55:50.779114 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-12 20:55:50.779140 | debian-bookworm | Hostname: debian 2026-05-12 20:55:50.779162 | debian-bookworm | Username: zuul 2026-05-12 20:55:50.779183 | debian-bookworm | Distro: Debian 12.13 2026-05-12 20:55:50.779207 | debian-bookworm | Provider: regiocloud-a 2026-05-12 20:55:50.779227 | debian-bookworm | Region: 2026-05-12 20:55:50.779248 | debian-bookworm | Label: debian-bookworm 2026-05-12 20:55:50.779267 | debian-bookworm | Product Name: OpenStack Nova 2026-05-12 20:55:50.779287 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feff:31fe 2026-05-12 20:55:50.803450 | 2026-05-12 20:55:50.803612 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-12 20:55:51.388721 | debian-bookworm -> localhost | changed 2026-05-12 20:55:51.399506 | 2026-05-12 20:55:51.399658 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-12 20:55:52.565376 | debian-bookworm -> localhost | changed 2026-05-12 20:55:52.576717 | 2026-05-12 20:55:52.576859 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-12 20:55:52.879906 | debian-bookworm -> localhost | ok 2026-05-12 20:55:52.889200 | 2026-05-12 20:55:52.889348 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-12 20:55:52.923422 | debian-bookworm | ok 2026-05-12 20:55:52.940344 | debian-bookworm | included: /var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-12 20:55:52.948167 | 2026-05-12 20:55:52.948340 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-12 20:55:54.682206 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-12 20:55:54.682473 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/work/29433d8ee1764c75a846bb3b69686025_id_rsa 2026-05-12 20:55:54.682513 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/work/29433d8ee1764c75a846bb3b69686025_id_rsa.pub 2026-05-12 20:55:54.682539 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-12 20:55:54.682563 | debian-bookworm -> localhost | SHA256:gYGqs2do8jjdoHLqFYwZmDa1urWA36OiyCKhkARyOjY zuul-build-sshkey 2026-05-12 20:55:54.682596 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-12 20:55:54.682619 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-12 20:55:54.682641 | debian-bookworm -> localhost | | . .. | 2026-05-12 20:55:54.682663 | debian-bookworm -> localhost | |+.o o o | 2026-05-12 20:55:54.682683 | debian-bookworm -> localhost | |=* o . . | 2026-05-12 20:55:54.682704 | debian-bookworm -> localhost | |=EO . | 2026-05-12 20:55:54.682723 | debian-bookworm -> localhost | |+O.+ S | 2026-05-12 20:55:54.682746 | debian-bookworm -> localhost | |*.=.o | 2026-05-12 20:55:54.682767 | debian-bookworm -> localhost | |oO.=o | 2026-05-12 20:55:54.682787 | debian-bookworm -> localhost | |#o*... | 2026-05-12 20:55:54.682808 | debian-bookworm -> localhost | |&@o | 2026-05-12 20:55:54.682862 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-12 20:55:54.682925 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.180572 2026-05-12 20:55:54.689917 | 2026-05-12 20:55:54.690043 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-12 20:55:54.719192 | debian-bookworm | ok 2026-05-12 20:55:54.728801 | debian-bookworm | included: /var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-12 20:55:54.739494 | 2026-05-12 20:55:54.739617 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-12 20:55:54.763862 | debian-bookworm | skipping: Conditional result was False 2026-05-12 20:55:54.770357 | 2026-05-12 20:55:54.770491 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-12 20:55:55.509614 | debian-bookworm | changed 2026-05-12 20:55:55.515930 | 2026-05-12 20:55:55.516085 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-12 20:55:55.801002 | debian-bookworm | ok 2026-05-12 20:55:55.816394 | 2026-05-12 20:55:55.816538 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-12 20:55:56.638553 | debian-bookworm | changed 2026-05-12 20:55:56.662663 | 2026-05-12 20:55:56.662825 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-12 20:55:57.389238 | debian-bookworm | changed 2026-05-12 20:55:57.403934 | 2026-05-12 20:55:57.404186 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-12 20:55:57.451094 | debian-bookworm | skipping: Conditional result was False 2026-05-12 20:55:57.458348 | 2026-05-12 20:55:57.458495 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-12 20:55:58.168033 | debian-bookworm -> localhost | changed 2026-05-12 20:55:58.180053 | 2026-05-12 20:55:58.180213 | TASK [add-build-sshkey : Add back temp key] 2026-05-12 20:55:58.445099 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/work/29433d8ee1764c75a846bb3b69686025_id_rsa (zuul-build-sshkey) 2026-05-12 20:55:58.445305 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009443 2026-05-12 20:55:58.463820 | 2026-05-12 20:55:58.463938 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-12 20:55:58.825795 | debian-bookworm | ok 2026-05-12 20:55:58.831090 | 2026-05-12 20:55:58.831183 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-12 20:55:58.865265 | debian-bookworm | skipping: Conditional result was False 2026-05-12 20:55:58.875419 | 2026-05-12 20:55:58.875524 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-12 20:55:59.345479 | debian-bookworm | ok 2026-05-12 20:55:59.356568 | 2026-05-12 20:55:59.356676 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-12 20:55:59.406431 | debian-bookworm | ok 2026-05-12 20:55:59.412417 | 2026-05-12 20:55:59.418900 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-12 20:55:59.873357 | debian-bookworm -> localhost | ok 2026-05-12 20:55:59.880309 | 2026-05-12 20:55:59.880412 | TASK [validate-host : Collect information about the host] 2026-05-12 20:56:00.996099 | debian-bookworm | ok 2026-05-12 20:56:01.009863 | 2026-05-12 20:56:01.009975 | TASK [validate-host : Sanitize hostname] 2026-05-12 20:56:01.136035 | debian-bookworm | ok 2026-05-12 20:56:01.180076 | 2026-05-12 20:56:01.180191 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-12 20:56:01.802201 | debian-bookworm -> localhost | changed 2026-05-12 20:56:01.809190 | 2026-05-12 20:56:01.809298 | TASK [validate-host : Collect information about zuul worker] 2026-05-12 20:56:02.224879 | debian-bookworm | ok 2026-05-12 20:56:02.232043 | 2026-05-12 20:56:02.232179 | TASK [validate-host : Write out all zuul information for each host] 2026-05-12 20:56:02.774959 | debian-bookworm -> localhost | changed 2026-05-12 20:56:02.802371 | 2026-05-12 20:56:02.802595 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-12 20:56:03.072976 | debian-bookworm | ok 2026-05-12 20:56:03.082818 | 2026-05-12 20:56:03.082963 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-12 20:56:03.967753 | debian-bookworm | changed: 2026-05-12 20:56:03.968028 | debian-bookworm | cd+++++++++ src/ 2026-05-12 20:56:03.968121 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-12 20:56:03.968158 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-12 20:56:03.968190 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-05-12 20:56:03.968219 | debian-bookworm | Containerfile 2026-05-12 20:56:03.968404 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/work/logs" 2026-05-12 20:58:43.729606 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/work/artifacts" 2026-05-12 20:58:44.010347 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/29433d8ee1764c75a846bb3b69686025/work/docs" 2026-05-12 20:58:44.026415 | 2026-05-12 20:58:44.026554 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-12 20:58:44.821229 | debian-bookworm | changed: 2026-05-12 20:58:44.821497 | debian-bookworm | .d..t...... ./ 2026-05-12 20:58:44.821537 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-12 20:58:44.821569 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-12 20:58:44.821596 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-12 20:58:44.821638 | debian-bookworm | changed: All items complete 2026-05-12 20:58:44.821661 | 2026-05-12 20:58:45.410664 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 20:58:46.031559 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 20:58:46.070725 | 2026-05-12 20:58:46.071094 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-12 20:58:46.588600 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011109 2026-05-12 20:58:46.899522 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010818 2026-05-12 20:58:46.918395 | 2026-05-12 20:58:46.918540 | PLAY RECAP 2026-05-12 20:58:46.918622 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-12 20:58:46.918663 | 2026-05-12 20:58:47.051422 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-12 20:58:47.053840 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 20:58:47.866176 | 2026-05-12 20:58:47.866374 | PLAY [Base post] 2026-05-12 20:58:47.880938 | 2026-05-12 20:58:47.881109 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-12 20:58:48.713698 | debian-bookworm | changed 2026-05-12 20:58:48.723427 | 2026-05-12 20:58:48.723577 | PLAY RECAP 2026-05-12 20:58:48.723670 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-12 20:58:48.723749 | 2026-05-12 20:58:48.852393 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 20:58:48.854699 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-12 20:58:49.656702 | 2026-05-12 20:58:49.656926 | PLAY [Base post-logs] 2026-05-12 20:58:49.668434 | 2026-05-12 20:58:49.668579 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-12 20:58:50.175578 | localhost | changed 2026-05-12 20:58:50.194617 | 2026-05-12 20:58:50.194885 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-12 20:58:50.232142 | localhost | ok 2026-05-12 20:58:50.236994 | 2026-05-12 20:58:50.237136 | TASK [Set zuul-log-path fact] 2026-05-12 20:58:50.253633 | localhost | ok 2026-05-12 20:58:50.263619 | 2026-05-12 20:58:50.263738 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 20:58:50.299590 | localhost | ok 2026-05-12 20:58:50.303898 | 2026-05-12 20:58:50.304021 | TASK [upload-logs : Create log directories] 2026-05-12 20:58:50.829606 | localhost | changed 2026-05-12 20:58:50.833019 | 2026-05-12 20:58:50.833135 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-12 20:58:51.379977 | localhost -> localhost | ok: Runtime: 0:00:00.007402 2026-05-12 20:58:51.384384 | 2026-05-12 20:58:51.384513 | TASK [upload-logs : Upload logs to log server] 2026-05-12 20:58:51.973893 | localhost | Output suppressed because no_log was given 2026-05-12 20:58:51.978100 | 2026-05-12 20:58:51.978297 | LOOP [upload-logs : Compress console log and json output] 2026-05-12 20:58:52.041139 | localhost | skipping: Conditional result was False 2026-05-12 20:58:52.045806 | localhost | skipping: Conditional result was False 2026-05-12 20:58:52.053402 | 2026-05-12 20:58:52.053632 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-12 20:58:52.102652 | localhost | skipping: Conditional result was False 2026-05-12 20:58:52.103410 | 2026-05-12 20:58:52.106508 | localhost | skipping: Conditional result was False 2026-05-12 20:58:52.116354 | 2026-05-12 20:58:52.116577 | LOOP [upload-logs : Upload console log and json output]