2025-03-26 16:39:28.841476 | Job console starting
2025-03-26 16:39:28.848859 | Updating git repos
2025-03-26 16:39:29.953629 | Cloning repos into workspace
2025-03-26 16:39:30.430895 | Restoring repo states
2025-03-26 16:39:30.475291 | Merging changes
2025-03-26 16:39:31.852533 | Checking out repos
2025-03-26 16:39:31.944411 | Preparing playbooks
2025-03-26 16:39:33.309018 | Running Ansible setup
2025-03-26 16:39:35.828334 | PRE-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2025-03-26 16:39:37.153886 | 
2025-03-26 16:39:37.154127 | PLAY [Create job header and inventory]
2025-03-26 16:39:37.177465 | 
2025-03-26 16:39:37.177773 | TASK [Gathering Facts]
2025-03-26 16:39:38.760178 | localhost | ok
2025-03-26 16:39:38.788617 | 
2025-03-26 16:39:38.788839 | TASK [Setup log path fact]
2025-03-26 16:39:38.822999 | localhost | ok
2025-03-26 16:39:38.848188 | 
2025-03-26 16:39:38.848459 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-03-26 16:39:38.898047 | localhost | ok
2025-03-26 16:39:38.911843 | 
2025-03-26 16:39:38.912058 | TASK [emit-job-header : Print job information]
2025-03-26 16:39:38.975516 | # Job Information
2025-03-26 16:39:38.975759 | Ansible Version: 2.16.14
2025-03-26 16:39:38.975836 | Job: opendev-promote-python
2025-03-26 16:39:38.975897 | Pipeline: promote
2025-03-26 16:39:38.976004 | Executor: ze09.opendev.org
2025-03-26 16:39:38.976094 | Triggered by: https://review.opendev.org/945274
2025-03-26 16:39:38.976158 | Event ID: dcfc9d2b97f74dd8aba81c4cee7671df
2025-03-26 16:39:38.983178 | 
2025-03-26 16:39:38.983442 | LOOP [emit-job-header : Print node information]
2025-03-26 16:39:39.034548 | 
2025-03-26 16:39:39.034880 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-03-26 16:39:39.751122 | localhost -> localhost | changed
2025-03-26 16:39:39.758929 | 
2025-03-26 16:39:39.759156 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-03-26 16:39:41.336858 | localhost -> localhost | changed
2025-03-26 16:39:41.351795 | 
2025-03-26 16:39:41.352016 | PLAY [Setup Zuul environment]
2025-03-26 16:39:41.353269 | 
2025-03-26 16:39:41.353395 | PLAY [Configure unbound]
2025-03-26 16:39:41.354899 | 
2025-03-26 16:39:41.355027 | PLAY [Prepare workspace and configure mirrors]
2025-03-26 16:39:41.355239 | 
2025-03-26 16:39:41.355305 | PLAY RECAP
2025-03-26 16:39:41.355404 | localhost | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-03-26 16:39:41.355461 | 
2025-03-26 16:39:41.571286 | PRE-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2025-03-26 16:39:41.572821 | RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/artifacts/promote.yaml@master]
2025-03-26 16:39:42.908091 | 
2025-03-26 16:39:42.908315 | PLAY [localhost]
2025-03-26 16:39:42.928594 | 
2025-03-26 16:39:42.928853 | TASK [Check execution context]
2025-03-26 16:39:42.966693 | localhost | skipping: Conditional result was False
2025-03-26 16:39:42.973268 | 
2025-03-26 16:39:42.973449 | TASK [Create artifact staging directory]
2025-03-26 16:39:43.612780 | localhost | changed
2025-03-26 16:39:43.619658 | 
2025-03-26 16:39:43.619797 | TASK [Download artifacts]
2025-03-26 16:39:43.648463 | localhost | ok
2025-03-26 16:39:43.683543 | 
2025-03-26 16:39:43.683746 | TASK [download-artifact : Query Zuul API for artifact information]
2025-03-26 16:39:44.839450 | localhost | ok: OK (1890 bytes)
2025-03-26 16:39:44.866897 | 
2025-03-26 16:39:44.867207 | TASK [download-artifact : Parse build response]
2025-03-26 16:39:44.925311 | localhost | ok
2025-03-26 16:39:44.934966 | 
2025-03-26 16:39:44.935165 | LOOP [download-artifact : Download archive by type]
2025-03-26 16:39:44.969456 | localhost | skipping: Conditional result was False
2025-03-26 16:39:45.022670 | localhost | skipping: Conditional result was False
2025-03-26 16:39:47.201650 | localhost | changed: OK (unknown bytes)
2025-03-26 16:39:48.735150 | localhost | changed: OK (21201480 bytes)
2025-03-26 16:39:48.762326 | 
2025-03-26 16:39:48.762486 | TASK [Find artifacts]
2025-03-26 16:39:49.416098 | localhost | ok: All paths examined
2025-03-26 16:39:49.424099 | 
2025-03-26 16:39:49.424325 | TASK [Set target directory]
2025-03-26 16:39:49.470425 | localhost | ok
2025-03-26 16:39:49.481274 | 
2025-03-26 16:39:49.481532 | TASK [Set target path]
2025-03-26 16:39:49.526836 | localhost | ok
2025-03-26 16:39:49.534138 | 
2025-03-26 16:39:49.534371 | TASK [Adjust target path]
2025-03-26 16:39:49.570050 | localhost | skipping: Conditional result was False
2025-03-26 16:39:49.581913 | 
2025-03-26 16:39:49.582166 | TASK [Get an AFS token]
2025-03-26 16:39:49.615015 | localhost | ok
2025-03-26 16:39:49.651568 | 
2025-03-26 16:39:49.651877 | TASK [create-afs-token : Create AFS keytab tempfile]
2025-03-26 16:39:50.335646 | localhost | changed
2025-03-26 16:39:50.344161 | 
2025-03-26 16:39:50.344370 | TASK [create-afs-token : Create (base64 decode) AFS keytab from secret]
2025-03-26 16:39:51.595994 | localhost | changed
2025-03-26 16:39:51.603537 | 
2025-03-26 16:39:51.603692 | TASK [create-afs-token : Obtain ticket for Kerberos]
2025-03-26 16:39:52.444376 | localhost | ok: Runtime: 0:00:00.133912
2025-03-26 16:39:52.454196 | 
2025-03-26 16:39:52.454440 | TASK [create-afs-token : Delete AFS keytab tempfile]
2025-03-26 16:39:52.855721 | localhost | changed
2025-03-26 16:39:52.864753 | 
2025-03-26 16:39:52.864907 | TASK [create-afs-token : Obtain authentication token for AFS]
2025-03-26 16:39:53.497176 | localhost | ok: Runtime: 0:00:00.153303
2025-03-26 16:39:53.515970 | 
2025-03-26 16:39:53.516122 | TASK [Ensure target directory exists]
2025-03-26 16:39:54.196280 | localhost | ok
2025-03-26 16:39:54.204908 | 
2025-03-26 16:39:54.205071 | LOOP [Upload to AFS]
2025-03-26 16:39:57.232343 | localhost | ok: Item: Runtime: 0:00:02.502921
2025-03-26 16:39:57.232932 | localhost | changed: All items complete
2025-03-26 16:39:57.233047 | 
2025-03-26 16:39:59.142124 | localhost | ok: Item: Runtime: 0:00:01.462470
2025-03-26 16:39:59.152924 | 
2025-03-26 16:39:59.153102 | TASK [Destroy AFS token]
2025-03-26 16:39:59.183863 | localhost | ok
2025-03-26 16:39:59.221304 | 
2025-03-26 16:39:59.221513 | TASK [destroy-afs-token : Destroy AFS tokens]
2025-03-26 16:39:59.694388 | localhost | ok: Runtime: 0:00:00.009523
2025-03-26 16:39:59.704576 | 
2025-03-26 16:39:59.704811 | TASK [destroy-afs-token : Destroy Kerberos tokens]
2025-03-26 16:40:00.129041 | localhost | ok: Runtime: 0:00:00.008164
2025-03-26 16:40:00.138915 | 
2025-03-26 16:40:00.139085 | PLAY RECAP
2025-03-26 16:40:00.139209 | localhost | ok: 16 changed: 10 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-03-26 16:40:00.139272 | 
2025-03-26 16:40:00.341639 | RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/artifacts/promote.yaml@master]
2025-03-26 16:40:00.342630 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2025-03-26 16:40:01.711133 | 
2025-03-26 16:40:01.711346 | PLAY [all]
2025-03-26 16:40:01.711555 | 
2025-03-26 16:40:01.711634 | PLAY RECAP
2025-03-26 16:40:01.711709 | 
2025-03-26 16:40:01.849933 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2025-03-26 16:40:01.850699 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2025-03-26 16:40:03.154911 | 
2025-03-26 16:40:03.155116 | PLAY [all]
2025-03-26 16:40:03.155443 | 
2025-03-26 16:40:03.155525 | PLAY RECAP
2025-03-26 16:40:03.155601 | 
2025-03-26 16:40:03.306441 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2025-03-26 16:40:03.307627 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post-logs.yaml@master]
2025-03-26 16:40:04.572738 | 
2025-03-26 16:40:04.573048 | PLAY [localhost]
2025-03-26 16:40:04.597657 | 
2025-03-26 16:40:04.597991 | TASK [Include Zuul manifest role]
2025-03-26 16:40:04.626089 | localhost | ok
2025-03-26 16:40:04.653373 | 
2025-03-26 16:40:04.653586 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-03-26 16:40:05.227153 | localhost | changed
2025-03-26 16:40:05.246893 | 
2025-03-26 16:40:05.247097 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-03-26 16:40:05.289311 | localhost | ok
2025-03-26 16:40:05.302109 | 
2025-03-26 16:40:05.302284 | TASK [Generate bulk log download script]
2025-03-26 16:40:05.326026 | localhost | ok
2025-03-26 16:40:05.355443 | 
2025-03-26 16:40:05.355867 | TASK [local-log-download : Check API endpoint is defined]
2025-03-26 16:40:05.407797 | localhost | ok: All assertions passed
2025-03-26 16:40:05.417588 | 
2025-03-26 16:40:05.417855 | TASK [local-log-download : Create download script]
2025-03-26 16:40:06.562776 | localhost -> localhost | changed
2025-03-26 16:40:06.577041 | 
2025-03-26 16:40:06.577207 | LOOP [Select random swift provider for logs upload]
2025-03-26 16:40:06.613253 | localhost | ok: "ovh_gra"
2025-03-26 16:40:06.634027 | 
2025-03-26 16:40:06.634218 | TASK [Upload swift logs to ovh_gra]
2025-03-26 16:40:06.666803 | localhost | Output suppressed because no_log was given
2025-03-26 16:40:06.721291 | 
2025-03-26 16:40:06.721476 | TASK [Set zuul-log-path fact]
2025-03-26 16:40:06.763646 | localhost | ok
2025-03-26 16:40:06.784315 | 
2025-03-26 16:40:06.784505 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-03-26 16:40:06.826686 | localhost | ok
2025-03-26 16:40:06.839760 | 
2025-03-26 16:40:06.839913 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-03-26 16:40:07.567417 | localhost -> localhost | ok: Runtime: 0:00:00.005877
2025-03-26 16:40:07.580127 | 
2025-03-26 16:40:07.580270 | TASK [upload-logs-swift : Upload logs to swift]