2025-07-04 12:51:36.501603 | Job console starting 2025-07-04 12:51:36.512528 | Updating git repos 2025-07-04 12:51:36.531319 | Cloning repos into workspace 2025-07-04 12:51:36.611514 | Restoring repo states 2025-07-04 12:51:36.620936 | Merging changes 2025-07-04 12:51:37.059024 | Checking out repos 2025-07-04 12:51:37.102408 | Preparing playbooks 2025-07-04 12:51:37.677061 | Running Ansible setup 2025-07-04 12:51:42.437605 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-04 12:51:43.202125 | 2025-07-04 12:51:43.202287 | PLAY [Base pre] 2025-07-04 12:51:43.218933 | 2025-07-04 12:51:43.219077 | TASK [Setup log path fact] 2025-07-04 12:51:43.249199 | ubuntu-noble-large | ok 2025-07-04 12:51:43.264602 | 2025-07-04 12:51:43.264743 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-04 12:51:43.304988 | ubuntu-noble-large | ok 2025-07-04 12:51:43.316387 | 2025-07-04 12:51:43.316515 | TASK [emit-job-header : Print job information] 2025-07-04 12:51:43.356522 | # Job Information 2025-07-04 12:51:43.356713 | Ansible Version: 2.16.14 2025-07-04 12:51:43.356748 | Job: openstack-ironic-images-build-osism-ironic 2025-07-04 12:51:43.356793 | Pipeline: check 2025-07-04 12:51:43.356816 | Executor: 521e9411259a 2025-07-04 12:51:43.356836 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/96 2025-07-04 12:51:43.356856 | Event ID: 7f49d9b0-58d5-11f0-8135-4a9a6c26023a 2025-07-04 12:51:43.361865 | 2025-07-04 12:51:43.361982 | LOOP [emit-job-header : Print node information] 2025-07-04 12:51:43.482510 | ubuntu-noble-large | ok: 2025-07-04 12:51:43.482788 | ubuntu-noble-large | # Node Information 2025-07-04 12:51:43.482850 | ubuntu-noble-large | Inventory Hostname: ubuntu-noble-large 2025-07-04 12:51:43.482878 | ubuntu-noble-large | Hostname: ubuntu 2025-07-04 12:51:43.482901 | ubuntu-noble-large | Username: zuul 2025-07-04 12:51:43.482921 | ubuntu-noble-large | Distro: Ubuntu 24.04 2025-07-04 12:51:43.482951 | ubuntu-noble-large | Provider: regiocloud-a 2025-07-04 12:51:43.482977 | ubuntu-noble-large | Region: 2025-07-04 12:51:43.482998 | ubuntu-noble-large | Label: ubuntu-noble-large 2025-07-04 12:51:43.483017 | ubuntu-noble-large | Product Name: OpenStack Nova 2025-07-04 12:51:43.483037 | ubuntu-noble-large | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fecd:4467 2025-07-04 12:51:43.512707 | 2025-07-04 12:51:43.512940 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-04 12:51:44.005589 | ubuntu-noble-large -> localhost | changed 2025-07-04 12:51:44.013382 | 2025-07-04 12:51:44.013507 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-04 12:51:45.156856 | ubuntu-noble-large -> localhost | changed 2025-07-04 12:51:45.172363 | 2025-07-04 12:51:45.172541 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-04 12:51:45.521114 | ubuntu-noble-large -> localhost | ok 2025-07-04 12:51:45.528979 | 2025-07-04 12:51:45.529112 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-04 12:51:45.561056 | ubuntu-noble-large | ok 2025-07-04 12:51:45.582857 | ubuntu-noble-large | included: /var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-04 12:51:45.590444 | 2025-07-04 12:51:45.590569 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-04 12:51:47.510453 | ubuntu-noble-large -> localhost | Generating public/private rsa key pair. 2025-07-04 12:51:47.510687 | ubuntu-noble-large -> localhost | Your identification has been saved in /var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/4dc740f860204934a5dee5cc92841eb5_id_rsa 2025-07-04 12:51:47.510724 | ubuntu-noble-large -> localhost | Your public key has been saved in /var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/4dc740f860204934a5dee5cc92841eb5_id_rsa.pub 2025-07-04 12:51:47.510750 | ubuntu-noble-large -> localhost | The key fingerprint is: 2025-07-04 12:51:47.510813 | ubuntu-noble-large -> localhost | SHA256:Ka93kJYX7Dw+KczTZumrjOr+lCF7vWdLZlqcD2l6E9M zuul-build-sshkey 2025-07-04 12:51:47.510980 | ubuntu-noble-large -> localhost | The key's randomart image is: 2025-07-04 12:51:47.511011 | ubuntu-noble-large -> localhost | +---[RSA 3072]----+ 2025-07-04 12:51:47.511035 | ubuntu-noble-large -> localhost | | | 2025-07-04 12:51:47.511057 | ubuntu-noble-large -> localhost | | | 2025-07-04 12:51:47.511077 | ubuntu-noble-large -> localhost | | . | 2025-07-04 12:51:47.511097 | ubuntu-noble-large -> localhost | | .o | 2025-07-04 12:51:47.511117 | ubuntu-noble-large -> localhost | | . o S= o | 2025-07-04 12:51:47.511146 | ubuntu-noble-large -> localhost | | o *=.BoE | 2025-07-04 12:51:47.511167 | ubuntu-noble-large -> localhost | | . ++o=@* | 2025-07-04 12:51:47.511187 | ubuntu-noble-large -> localhost | | o +*X#o | 2025-07-04 12:51:47.511207 | ubuntu-noble-large -> localhost | | o+o+.*%+=. | 2025-07-04 12:51:47.511226 | ubuntu-noble-large -> localhost | +----[SHA256]-----+ 2025-07-04 12:51:47.511290 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:01.416847 2025-07-04 12:51:47.518199 | 2025-07-04 12:51:47.518335 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-04 12:51:47.557684 | ubuntu-noble-large | ok 2025-07-04 12:51:47.591425 | ubuntu-noble-large | included: /var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-04 12:51:47.605284 | 2025-07-04 12:51:47.605966 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-04 12:51:47.653020 | ubuntu-noble-large | skipping: Conditional result was False 2025-07-04 12:51:47.667716 | 2025-07-04 12:51:47.667863 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-04 12:51:48.428579 | ubuntu-noble-large | changed 2025-07-04 12:51:48.437606 | 2025-07-04 12:51:48.437722 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-04 12:51:48.786757 | ubuntu-noble-large | ok 2025-07-04 12:51:48.793156 | 2025-07-04 12:51:48.793272 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-04 12:51:49.575087 | ubuntu-noble-large | changed 2025-07-04 12:51:49.580540 | 2025-07-04 12:51:49.580660 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-04 12:51:50.399568 | ubuntu-noble-large | changed 2025-07-04 12:51:50.407842 | 2025-07-04 12:51:50.407971 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-04 12:51:50.432453 | ubuntu-noble-large | skipping: Conditional result was False 2025-07-04 12:51:50.445072 | 2025-07-04 12:51:50.445284 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-04 12:51:50.961498 | ubuntu-noble-large -> localhost | changed 2025-07-04 12:51:50.972726 | 2025-07-04 12:51:50.972869 | TASK [add-build-sshkey : Add back temp key] 2025-07-04 12:51:51.343400 | ubuntu-noble-large -> localhost | Identity added: /var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/4dc740f860204934a5dee5cc92841eb5_id_rsa (zuul-build-sshkey) 2025-07-04 12:51:51.343649 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.027990 2025-07-04 12:51:51.351920 | 2025-07-04 12:51:51.352037 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-04 12:51:51.801069 | ubuntu-noble-large | ok 2025-07-04 12:51:51.813371 | 2025-07-04 12:51:51.813549 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-04 12:51:51.849422 | ubuntu-noble-large | skipping: Conditional result was False 2025-07-04 12:51:51.872548 | 2025-07-04 12:51:51.872732 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-04 12:51:52.343659 | ubuntu-noble-large | ok 2025-07-04 12:51:52.362165 | 2025-07-04 12:51:52.362303 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-04 12:51:52.394159 | ubuntu-noble-large | ok 2025-07-04 12:51:52.403322 | 2025-07-04 12:51:52.403488 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-04 12:51:52.737060 | ubuntu-noble-large -> localhost | ok 2025-07-04 12:51:52.743832 | 2025-07-04 12:51:52.743936 | TASK [validate-host : Collect information about the host] 2025-07-04 12:51:54.121025 | ubuntu-noble-large | ok 2025-07-04 12:51:54.132879 | 2025-07-04 12:51:54.132994 | TASK [validate-host : Sanitize hostname] 2025-07-04 12:51:54.334089 | ubuntu-noble-large | ok 2025-07-04 12:51:54.338820 | 2025-07-04 12:51:54.338958 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-04 12:51:54.920035 | ubuntu-noble-large -> localhost | changed 2025-07-04 12:51:54.925963 | 2025-07-04 12:51:54.926074 | TASK [validate-host : Collect information about zuul worker] 2025-07-04 12:51:55.429440 | ubuntu-noble-large | ok 2025-07-04 12:51:55.437535 | 2025-07-04 12:51:55.437692 | TASK [validate-host : Write out all zuul information for each host] 2025-07-04 12:51:56.091620 | ubuntu-noble-large -> localhost | changed 2025-07-04 12:51:56.105569 | 2025-07-04 12:51:56.105702 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-04 12:51:56.454986 | ubuntu-noble-large | ok 2025-07-04 12:51:56.463756 | 2025-07-04 12:51:56.463916 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-04 12:51:57.304579 | ubuntu-noble-large | changed: 2025-07-04 12:51:57.304817 | ubuntu-noble-large | cd+++++++++ src/ 2025-07-04 12:51:57.304862 | ubuntu-noble-large | cd+++++++++ src/github.com/ 2025-07-04 12:51:57.304887 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ 2025-07-04 12:51:57.304909 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-04 12:51:57.304930 | ubuntu-noble-large | localhost | ok: "/var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/logs" 2025-07-04 13:02:57.204719 | ubuntu-noble-large -> localhost | changed: "/var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/artifacts" 2025-07-04 13:02:57.507055 | ubuntu-noble-large -> localhost | changed: "/var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/docs" 2025-07-04 13:02:57.528794 | 2025-07-04 13:02:57.528952 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-04 13:02:58.320536 | ubuntu-noble-large | changed: 2025-07-04 13:02:58.320899 | ubuntu-noble-large | .d..t...... ./ 2025-07-04 13:02:58.320971 | ubuntu-noble-large | >f+++++++++ osism-ironic.log 2025-07-04 13:02:58.321054 | ubuntu-noble-large | changed: All items complete 2025-07-04 13:02:58.321105 | 2025-07-04 13:02:58.982207 | ubuntu-noble-large | ERROR: Item: {'dest': '/var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/artifacts', 'src': 'artifacts'} 2025-07-04 13:02:58.982708 | ubuntu-noble-large | { 2025-07-04 13:02:58.982814 | ubuntu-noble-large | "ansible_loop_var": "zj_output", 2025-07-04 13:02:58.982911 | ubuntu-noble-large | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/artifacts\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2025-07-04 13:02:58.982968 | ubuntu-noble-large | "rc": 23, 2025-07-04 13:02:58.983009 | ubuntu-noble-large | "zj_output": { 2025-07-04 13:02:58.983042 | ubuntu-noble-large | "dest": "/var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/artifacts", 2025-07-04 13:02:58.983076 | ubuntu-noble-large | "src": "artifacts" 2025-07-04 13:02:58.983110 | ubuntu-noble-large | } 2025-07-04 13:02:58.983142 | ubuntu-noble-large | } 2025-07-04 13:02:59.665960 | ubuntu-noble-large | ERROR: Item: {'dest': '/var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/docs', 'src': 'docs'} 2025-07-04 13:02:59.666192 | ubuntu-noble-large | { 2025-07-04 13:02:59.666250 | ubuntu-noble-large | "ansible_loop_var": "zj_output", 2025-07-04 13:02:59.666290 | ubuntu-noble-large | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/docs\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2025-07-04 13:02:59.666333 | ubuntu-noble-large | "rc": 23, 2025-07-04 13:02:59.666367 | ubuntu-noble-large | "zj_output": { 2025-07-04 13:02:59.666397 | ubuntu-noble-large | "dest": "/var/lib/zuul/builds/4dc740f860204934a5dee5cc92841eb5/work/docs", 2025-07-04 13:02:59.666427 | ubuntu-noble-large | "src": "docs" 2025-07-04 13:02:59.666458 | ubuntu-noble-large | } 2025-07-04 13:02:59.666487 | ubuntu-noble-large | } failure 2025-07-04 13:02:59.687468 | 2025-07-04 13:02:59.687734 | PLAY RECAP 2025-07-04 13:02:59.687827 | ubuntu-noble-large | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-07-04 13:02:59.687868 | 2025-07-04 13:02:59.826006 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-04 13:02:59.827075 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-04 13:03:00.574069 | 2025-07-04 13:03:00.574238 | PLAY [Base post] 2025-07-04 13:03:00.588211 | 2025-07-04 13:03:00.588354 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-04 13:03:01.232992 | ubuntu-noble-large | changed 2025-07-04 13:03:01.243275 | 2025-07-04 13:03:01.243435 | PLAY RECAP 2025-07-04 13:03:01.243517 | ubuntu-noble-large | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-04 13:03:01.243594 | 2025-07-04 13:03:01.367531 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-04 13:03:01.369762 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-04 13:03:02.153691 | 2025-07-04 13:03:02.153868 | PLAY [Base post-logs] 2025-07-04 13:03:02.164824 | 2025-07-04 13:03:02.164968 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-04 13:03:02.675126 | localhost | changed 2025-07-04 13:03:02.695448 | 2025-07-04 13:03:02.695735 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-04 13:03:02.724765 | localhost | ok 2025-07-04 13:03:02.732804 | 2025-07-04 13:03:02.733012 | TASK [Set zuul-log-path fact] 2025-07-04 13:03:02.753283 | localhost | ok 2025-07-04 13:03:02.770272 | 2025-07-04 13:03:02.770445 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-04 13:03:02.807604 | localhost | ok 2025-07-04 13:03:02.813683 | 2025-07-04 13:03:02.813853 | TASK [upload-logs : Create log directories] 2025-07-04 13:03:03.309228 | localhost | changed 2025-07-04 13:03:03.312183 | 2025-07-04 13:03:03.312293 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-04 13:03:03.877010 | localhost -> localhost | ok: Runtime: 0:00:00.006865 2025-07-04 13:03:03.886389 | 2025-07-04 13:03:03.886591 | TASK [upload-logs : Upload logs to log server] 2025-07-04 13:03:04.463661 | localhost | Output suppressed because no_log was given 2025-07-04 13:03:04.466893 | 2025-07-04 13:03:04.467040 | LOOP [upload-logs : Compress console log and json output] 2025-07-04 13:03:04.528658 | localhost | skipping: Conditional result was False 2025-07-04 13:03:04.534013 | localhost | skipping: Conditional result was False 2025-07-04 13:03:04.548180 | 2025-07-04 13:03:04.548431 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-04 13:03:04.595740 | localhost | skipping: Conditional result was False 2025-07-04 13:03:04.596338 | 2025-07-04 13:03:04.600043 | localhost | skipping: Conditional result was False 2025-07-04 13:03:04.613649 | 2025-07-04 13:03:04.613902 | LOOP [upload-logs : Upload console log and json output]