2025-03-12 16:31:46.627405 | Job console starting... 2025-03-12 16:31:46.652028 | Updating repositories 2025-03-12 16:31:46.698085 | Preparing job workspace 2025-03-12 16:31:48.755106 | Running Ansible setup... 2025-03-12 16:31:54.277670 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-12 16:31:55.334137 | 2025-03-12 16:31:55.334280 | PLAY [Base pre] 2025-03-12 16:31:55.377477 | 2025-03-12 16:31:55.377598 | TASK [Setup log path fact] 2025-03-12 16:31:55.400359 | ubuntu-noble | ok 2025-03-12 16:31:55.426713 | 2025-03-12 16:31:55.426835 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-12 16:31:55.467951 | ubuntu-noble | ok 2025-03-12 16:31:55.477109 | 2025-03-12 16:31:55.477207 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-12 16:31:55.511296 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.519157 | 2025-03-12 16:31:55.519266 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-12 16:31:55.576676 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.584918 | 2025-03-12 16:31:55.585016 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-12 16:31:55.631950 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.640884 | 2025-03-12 16:31:55.640981 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-12 16:31:55.695618 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.703154 | 2025-03-12 16:31:55.703349 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-12 16:31:55.729839 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.751191 | 2025-03-12 16:31:55.751303 | TASK [emit-job-header : Print job information] 2025-03-12 16:31:55.858930 | # Job Information 2025-03-12 16:31:55.859085 | Ansible Version: 2.15.3 2025-03-12 16:31:55.859118 | Job: flake8 2025-03-12 16:31:55.859147 | Pipeline: check 2025-03-12 16:31:55.859168 | Executor: 7d211f194f6a 2025-03-12 16:31:55.859187 | Triggered by: https://github.com/osism/testbed/pull/2631 2025-03-12 16:31:55.859206 | Event ID: 736883a0-ff5f-11ef-8ab1-25b48a284636 2025-03-12 16:31:55.867949 | 2025-03-12 16:31:55.868048 | LOOP [emit-job-header : Print node information] 2025-03-12 16:31:56.077522 | ubuntu-noble | ok: 2025-03-12 16:31:56.077806 | ubuntu-noble | # Node Information 2025-03-12 16:31:56.077888 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-12 16:31:56.078033 | ubuntu-noble | Hostname: ubuntu 2025-03-12 16:31:56.078064 | ubuntu-noble | Username: zuul 2025-03-12 16:31:56.078087 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-12 16:31:56.078108 | ubuntu-noble | Provider: regiocloud-a 2025-03-12 16:31:56.078166 | ubuntu-noble | Label: ubuntu-noble 2025-03-12 16:31:56.078188 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-12 16:31:56.078209 | ubuntu-noble | Interface IP: 81.163.192.101 2025-03-12 16:31:56.110528 | 2025-03-12 16:31:56.110977 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-12 16:31:56.836317 | ubuntu-noble -> localhost | changed 2025-03-12 16:31:56.846131 | 2025-03-12 16:31:56.846244 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-12 16:31:58.421102 | ubuntu-noble -> localhost | changed 2025-03-12 16:31:58.450343 | 2025-03-12 16:31:58.450454 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-12 16:31:58.753506 | ubuntu-noble -> localhost | ok 2025-03-12 16:31:58.764445 | 2025-03-12 16:31:58.764542 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-12 16:31:58.809977 | ubuntu-noble | ok 2025-03-12 16:31:58.834019 | ubuntu-noble | included: /var/lib/zuul/builds/b3b44d73e6194a028fc226f8d1b65c0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-12 16:31:58.845267 | 2025-03-12 16:31:58.845373 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-12 16:31:59.666030 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-12 16:31:59.666215 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/b3b44d73e6194a028fc226f8d1b65c0d/work/b3b44d73e6194a028fc226f8d1b65c0d_id_rsa 2025-03-12 16:31:59.666267 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/b3b44d73e6194a028fc226f8d1b65c0d/work/b3b44d73e6194a028fc226f8d1b65c0d_id_rsa.pub 2025-03-12 16:31:59.666292 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-12 16:31:59.666314 | ubuntu-noble -> localhost | SHA256:G4JVcMWqNgXwJqs38TGjZtcmX3owtrL58Ki4/qlDAY4 zuul-build-sshkey 2025-03-12 16:31:59.666335 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-12 16:31:59.666360 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-12 16:31:59.666381 | ubuntu-noble -> localhost | | ....oo. | 2025-03-12 16:31:59.666400 | ubuntu-noble -> localhost | |. ..o . | 2025-03-12 16:31:59.666419 | ubuntu-noble -> localhost | |o. . +. . | 2025-03-12 16:31:59.666438 | ubuntu-noble -> localhost | |E.. * o | 2025-03-12 16:31:59.666456 | ubuntu-noble -> localhost | | .+ =oS | 2025-03-12 16:31:59.666474 | ubuntu-noble -> localhost | | .. ++*+o | 2025-03-12 16:31:59.666493 | ubuntu-noble -> localhost | | .. *.=o++. | 2025-03-12 16:31:59.666512 | ubuntu-noble -> localhost | | .= +.O.o. | 2025-03-12 16:31:59.666531 | ubuntu-noble -> localhost | | .==+.++=. | 2025-03-12 16:31:59.666550 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-12 16:31:59.666599 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.150113 2025-03-12 16:31:59.675179 | 2025-03-12 16:31:59.675283 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-12 16:31:59.705248 | ubuntu-noble | ok 2025-03-12 16:31:59.716248 | ubuntu-noble | included: /var/lib/zuul/builds/b3b44d73e6194a028fc226f8d1b65c0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-12 16:31:59.725825 | 2025-03-12 16:31:59.725904 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-12 16:31:59.749899 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:59.757096 | 2025-03-12 16:31:59.757182 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-12 16:32:00.560838 | ubuntu-noble | changed 2025-03-12 16:32:00.567369 | 2025-03-12 16:32:00.567453 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-12 16:32:00.925858 | ubuntu-noble | ok 2025-03-12 16:32:00.937001 | 2025-03-12 16:32:00.937200 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-12 16:32:01.783923 | ubuntu-noble | changed 2025-03-12 16:32:01.800957 | 2025-03-12 16:32:01.801055 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-12 16:32:02.610145 | ubuntu-noble | changed 2025-03-12 16:32:02.618744 | 2025-03-12 16:32:02.618849 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-12 16:32:02.642570 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:32:02.649874 | 2025-03-12 16:32:02.649960 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-12 16:32:03.220145 | ubuntu-noble -> localhost | changed 2025-03-12 16:32:03.270604 | 2025-03-12 16:32:03.270800 | TASK [add-build-sshkey : Add back temp key] 2025-03-12 16:32:03.849391 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/b3b44d73e6194a028fc226f8d1b65c0d/work/b3b44d73e6194a028fc226f8d1b65c0d_id_rsa (zuul-build-sshkey) 2025-03-12 16:32:03.849802 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013114 2025-03-12 16:32:03.856923 | 2025-03-12 16:32:03.857016 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-12 16:32:04.329695 | ubuntu-noble | ok 2025-03-12 16:32:04.336517 | 2025-03-12 16:32:04.336618 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-12 16:32:04.368700 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:32:04.392625 | 2025-03-12 16:32:04.392857 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-12 16:32:04.856295 | ubuntu-noble | ok 2025-03-12 16:32:04.876617 | 2025-03-12 16:32:04.876881 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-12 16:32:04.930098 | ubuntu-noble | ok 2025-03-12 16:32:04.940875 | 2025-03-12 16:32:04.941070 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-12 16:32:05.337497 | ubuntu-noble -> localhost | ok 2025-03-12 16:32:05.351534 | 2025-03-12 16:32:05.351669 | TASK [validate-host : Collect information about the host] 2025-03-12 16:32:06.756348 | ubuntu-noble | ok 2025-03-12 16:32:06.799403 | 2025-03-12 16:32:06.799550 | TASK [validate-host : Sanitize hostname] 2025-03-12 16:32:06.924485 | ubuntu-noble | ok 2025-03-12 16:32:06.943027 | 2025-03-12 16:32:06.943297 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-12 16:32:07.905858 | ubuntu-noble -> localhost | changed 2025-03-12 16:32:07.914022 | 2025-03-12 16:32:07.914146 | TASK [validate-host : Collect information about zuul worker] 2025-03-12 16:32:08.447901 | ubuntu-noble | ok 2025-03-12 16:32:08.455730 | 2025-03-12 16:32:08.455860 | TASK [validate-host : Write out all zuul information for each host] 2025-03-12 16:32:09.122110 | ubuntu-noble -> localhost | changed 2025-03-12 16:32:09.135357 | 2025-03-12 16:32:09.135481 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-12 16:32:09.495752 | ubuntu-noble | ok 2025-03-12 16:32:09.504750 | 2025-03-12 16:32:09.504875 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-12 16:32:11.109319 | ubuntu-noble | changed: 2025-03-12 16:32:11.109520 | ubuntu-noble | cd+++++++++ src/ 2025-03-12 16:32:11.109554 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-12 16:32:11.109579 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-12 16:32:11.109600 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-12 16:32:11.109621 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.003839 2025-03-12 16:32:48.518499 | 2025-03-12 16:32:48.518643 | TASK [upload-logs : Upload logs to log server] 2025-03-12 16:32:49.101904 | localhost | Output suppressed because no_log was given 2025-03-12 16:32:49.107475 | 2025-03-12 16:32:49.107608 | LOOP [upload-logs : Compress console log and json output] 2025-03-12 16:32:49.181560 | localhost | skipping: Conditional result was False 2025-03-12 16:32:49.201139 | localhost | skipping: Conditional result was False 2025-03-12 16:32:49.208958 | 2025-03-12 16:32:49.209089 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-12 16:32:49.301328 | localhost | skipping: Conditional result was False 2025-03-12 16:32:49.301641 | 2025-03-12 16:32:49.315989 | localhost | skipping: Conditional result was False 2025-03-12 16:32:49.328674 | 2025-03-12 16:32:49.328859 | LOOP [upload-logs : Upload console log and json output]