2025-07-23 08:35:05.601611 | Job console starting 2025-07-23 08:35:05.620712 | Updating git repos 2025-07-23 08:35:05.647390 | Cloning repos into workspace 2025-07-23 08:35:05.696410 | Restoring repo states 2025-07-23 08:35:05.706952 | Merging changes 2025-07-23 08:35:05.706985 | Checking out repos 2025-07-23 08:35:05.768188 | Preparing playbooks 2025-07-23 08:35:06.419759 | Running Ansible setup 2025-07-23 08:35:12.574058 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-23 08:35:13.368442 | 2025-07-23 08:35:13.368730 | PLAY [Base pre] 2025-07-23 08:35:13.387091 | 2025-07-23 08:35:13.387257 | TASK [Setup log path fact] 2025-07-23 08:35:13.417754 | noble | ok 2025-07-23 08:35:13.435491 | 2025-07-23 08:35:13.435659 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-23 08:35:13.483177 | noble | ok 2025-07-23 08:35:13.498314 | 2025-07-23 08:35:13.498467 | TASK [emit-job-header : Print job information] 2025-07-23 08:35:13.556508 | # Job Information 2025-07-23 08:35:13.556845 | Ansible Version: 2.16.14 2025-07-23 08:35:13.556912 | Job: openstack-ironic-images-publish-osism-ipa 2025-07-23 08:35:13.556970 | Pipeline: post 2025-07-23 08:35:13.557012 | Executor: 521e9411259a 2025-07-23 08:35:13.557050 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/770671468d638339efe5ae17ad636d6e24395424 2025-07-23 08:35:13.557090 | Event ID: 8e743e34-679f-11f0-9246-71d4556a2bd1 2025-07-23 08:35:13.567639 | 2025-07-23 08:35:13.567793 | LOOP [emit-job-header : Print node information] 2025-07-23 08:35:13.692681 | noble | ok: 2025-07-23 08:35:13.692919 | noble | # Node Information 2025-07-23 08:35:13.692958 | noble | Inventory Hostname: noble 2025-07-23 08:35:13.692989 | noble | Hostname: ubuntu 2025-07-23 08:35:13.693011 | noble | Username: zuul 2025-07-23 08:35:13.693032 | noble | Distro: Ubuntu 24.04 2025-07-23 08:35:13.693055 | noble | Provider: regiocloud-a 2025-07-23 08:35:13.693076 | noble | Region: 2025-07-23 08:35:13.693098 | noble | Label: ubuntu-noble-uefi 2025-07-23 08:35:13.693118 | noble | Product Name: OpenStack Nova 2025-07-23 08:35:13.693137 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedf:defd 2025-07-23 08:35:13.716160 | 2025-07-23 08:35:13.716336 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-23 08:35:14.268713 | noble -> localhost | changed 2025-07-23 08:35:14.277186 | 2025-07-23 08:35:14.277323 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-23 08:35:15.368395 | noble -> localhost | changed 2025-07-23 08:35:15.383961 | 2025-07-23 08:35:15.384113 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-23 08:35:15.661615 | noble -> localhost | ok 2025-07-23 08:35:15.669022 | 2025-07-23 08:35:15.669171 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-23 08:35:15.699156 | noble | ok 2025-07-23 08:35:15.715889 | noble | included: /var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-23 08:35:15.724182 | 2025-07-23 08:35:15.724301 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-23 08:35:17.403234 | noble -> localhost | Generating public/private rsa key pair. 2025-07-23 08:35:17.403491 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/work/96044bf66970488ab5a709acb557f719_id_rsa 2025-07-23 08:35:17.403531 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/work/96044bf66970488ab5a709acb557f719_id_rsa.pub 2025-07-23 08:35:17.403558 | noble -> localhost | The key fingerprint is: 2025-07-23 08:35:17.403583 | noble -> localhost | SHA256:alJ3gluf3DjqPh53pljWOSknw0yG8ereosJeInV6z/Y zuul-build-sshkey 2025-07-23 08:35:17.403605 | noble -> localhost | The key's randomart image is: 2025-07-23 08:35:17.403627 | noble -> localhost | +---[RSA 3072]----+ 2025-07-23 08:35:17.403648 | noble -> localhost | | | 2025-07-23 08:35:17.403684 | noble -> localhost | | | 2025-07-23 08:35:17.403706 | noble -> localhost | | . | 2025-07-23 08:35:17.403725 | noble -> localhost | | . + | 2025-07-23 08:35:17.403744 | noble -> localhost | | . + S = | 2025-07-23 08:35:17.403771 | noble -> localhost | | . + = @ = o | 2025-07-23 08:35:17.403792 | noble -> localhost | | ..+ * o / X | 2025-07-23 08:35:17.403827 | noble -> localhost | | .o* ++O X . | 2025-07-23 08:35:17.403849 | noble -> localhost | | ....B@+E | 2025-07-23 08:35:17.403869 | noble -> localhost | +----[SHA256]-----+ 2025-07-23 08:35:17.403926 | noble -> localhost | ok: Runtime: 0:00:01.158570 2025-07-23 08:35:17.411967 | 2025-07-23 08:35:17.412120 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-23 08:35:17.433781 | noble | ok 2025-07-23 08:35:17.444539 | noble | included: /var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-23 08:35:17.454545 | 2025-07-23 08:35:17.454708 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-23 08:35:17.478989 | noble | skipping: Conditional result was False 2025-07-23 08:35:17.488193 | 2025-07-23 08:35:17.488338 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-23 08:35:18.135792 | noble | changed 2025-07-23 08:35:18.142302 | 2025-07-23 08:35:18.142418 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-23 08:35:18.476809 | noble | ok 2025-07-23 08:35:18.483658 | 2025-07-23 08:35:18.483787 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-23 08:35:19.234558 | noble | changed 2025-07-23 08:35:19.242353 | 2025-07-23 08:35:19.242491 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-23 08:35:20.016000 | noble | changed 2025-07-23 08:35:20.026108 | 2025-07-23 08:35:20.026260 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-23 08:35:20.051122 | noble | skipping: Conditional result was False 2025-07-23 08:35:20.061525 | 2025-07-23 08:35:20.061658 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-23 08:35:20.542924 | noble -> localhost | changed 2025-07-23 08:35:20.562097 | 2025-07-23 08:35:20.562244 | TASK [add-build-sshkey : Add back temp key] 2025-07-23 08:35:20.951987 | noble -> localhost | Identity added: /var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/work/96044bf66970488ab5a709acb557f719_id_rsa (zuul-build-sshkey) 2025-07-23 08:35:20.952343 | noble -> localhost | ok: Runtime: 0:00:00.023861 2025-07-23 08:35:20.962435 | 2025-07-23 08:35:20.962594 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-23 08:35:21.464682 | noble | ok 2025-07-23 08:35:21.476423 | 2025-07-23 08:35:21.476605 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-23 08:35:21.511561 | noble | skipping: Conditional result was False 2025-07-23 08:35:21.569784 | 2025-07-23 08:35:21.569978 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-23 08:35:22.061349 | noble | ok 2025-07-23 08:35:22.074214 | 2025-07-23 08:35:22.074499 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-23 08:35:22.129263 | noble | ok 2025-07-23 08:35:22.139962 | 2025-07-23 08:35:22.140123 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-23 08:35:22.458954 | noble -> localhost | ok 2025-07-23 08:35:22.468246 | 2025-07-23 08:35:22.468367 | TASK [validate-host : Collect information about the host] 2025-07-23 08:35:24.981675 | noble | ok 2025-07-23 08:35:24.995837 | 2025-07-23 08:35:24.995982 | TASK [validate-host : Sanitize hostname] 2025-07-23 08:35:25.057138 | noble | ok 2025-07-23 08:35:25.063005 | 2025-07-23 08:35:25.063128 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-23 08:35:25.655592 | noble -> localhost | changed 2025-07-23 08:35:25.662275 | 2025-07-23 08:35:25.662399 | TASK [validate-host : Collect information about zuul worker] 2025-07-23 08:35:26.151052 | noble | ok 2025-07-23 08:35:26.156600 | 2025-07-23 08:35:26.156723 | TASK [validate-host : Write out all zuul information for each host] 2025-07-23 08:35:26.731642 | noble -> localhost | changed 2025-07-23 08:35:26.751062 | 2025-07-23 08:35:26.751204 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-23 08:35:27.092079 | noble | ok 2025-07-23 08:35:27.104007 | 2025-07-23 08:35:27.104169 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-23 08:35:28.033602 | noble | changed: 2025-07-23 08:35:28.033911 | noble | cd+++++++++ src/ 2025-07-23 08:35:28.033968 | noble | cd+++++++++ src/github.com/ 2025-07-23 08:35:28.034007 | noble | cd+++++++++ src/github.com/osism/ 2025-07-23 08:35:28.034040 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-23 08:35:28.034073 | noble | localhost | ok: "/var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/work/logs" 2025-07-23 08:42:31.659131 | noble -> localhost | changed: "/var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/work/artifacts" 2025-07-23 08:42:31.942478 | noble -> localhost | changed: "/var/lib/zuul/builds/96044bf66970488ab5a709acb557f719/work/docs" 2025-07-23 08:42:31.970257 | 2025-07-23 08:42:31.970447 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-23 08:42:33.296040 | noble | changed: 2025-07-23 08:42:33.296446 | noble | .d..t...... ./ 2025-07-23 08:42:33.296517 | noble | >f+++++++++ osism-ipa.log 2025-07-23 08:42:33.296621 | noble | changed: All items complete 2025-07-23 08:42:33.296683 | 2025-07-23 08:42:33.979520 | noble | changed: .d..t...... ./ 2025-07-23 08:42:34.654696 | noble | changed: .d..t...... ./ 2025-07-23 08:42:34.689900 | 2025-07-23 08:42:34.690049 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-23 08:42:34.726288 | noble | skipping: Conditional result was False 2025-07-23 08:42:34.729577 | noble | skipping: Conditional result was False 2025-07-23 08:42:34.746334 | 2025-07-23 08:42:34.746438 | PLAY RECAP 2025-07-23 08:42:34.746504 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-07-23 08:42:34.746536 | 2025-07-23 08:42:34.868994 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-23 08:42:34.869985 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-23 08:42:35.587852 | 2025-07-23 08:42:35.588001 | PLAY [Base post] 2025-07-23 08:42:35.602427 | 2025-07-23 08:42:35.602596 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-23 08:42:36.252176 | noble | changed 2025-07-23 08:42:36.263240 | 2025-07-23 08:42:36.263368 | PLAY RECAP 2025-07-23 08:42:36.263451 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-23 08:42:36.263528 | 2025-07-23 08:42:36.379581 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-23 08:42:36.380581 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-23 08:42:37.167973 | 2025-07-23 08:42:37.168146 | PLAY [Base post-logs] 2025-07-23 08:42:37.178877 | 2025-07-23 08:42:37.179020 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-23 08:42:37.633450 | localhost | changed 2025-07-23 08:42:37.650276 | 2025-07-23 08:42:37.650477 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-23 08:42:37.688177 | localhost | ok 2025-07-23 08:42:37.694403 | 2025-07-23 08:42:37.694636 | TASK [Set zuul-log-path fact] 2025-07-23 08:42:37.711720 | localhost | ok 2025-07-23 08:42:37.722215 | 2025-07-23 08:42:37.722340 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-23 08:42:37.759094 | localhost | ok 2025-07-23 08:42:37.765319 | 2025-07-23 08:42:37.765493 | TASK [upload-logs : Create log directories] 2025-07-23 08:42:38.269906 | localhost | changed 2025-07-23 08:42:38.272795 | 2025-07-23 08:42:38.272911 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-23 08:42:38.762903 | localhost -> localhost | ok: Runtime: 0:00:00.006762 2025-07-23 08:42:38.767419 | 2025-07-23 08:42:38.767559 | TASK [upload-logs : Upload logs to log server] 2025-07-23 08:42:39.344351 | localhost | Output suppressed because no_log was given 2025-07-23 08:42:39.348700 | 2025-07-23 08:42:39.348933 | LOOP [upload-logs : Compress console log and json output] 2025-07-23 08:42:39.407627 | localhost | skipping: Conditional result was False 2025-07-23 08:42:39.412407 | localhost | skipping: Conditional result was False 2025-07-23 08:42:39.420220 | 2025-07-23 08:42:39.420446 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-23 08:42:39.475729 | localhost | skipping: Conditional result was False 2025-07-23 08:42:39.476328 | 2025-07-23 08:42:39.479906 | localhost | skipping: Conditional result was False 2025-07-23 08:42:39.492371 | 2025-07-23 08:42:39.492607 | LOOP [upload-logs : Upload console log and json output]