2025-01-31 05:18:12.857407 | Job console starting... 2025-01-31 05:18:12.884217 | Updating repositories 2025-01-31 05:18:12.912707 | Preparing job workspace 2025-01-31 05:18:14.595989 | Running Ansible setup... 2025-01-31 05:18:20.765171 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-31 05:18:21.671246 | 2025-01-31 05:18:21.671429 | PLAY [Base pre] 2025-01-31 05:18:21.713984 | 2025-01-31 05:18:21.714159 | TASK [Setup log path fact] 2025-01-31 05:18:21.760789 | ubuntu-noble | ok 2025-01-31 05:18:21.797285 | 2025-01-31 05:18:21.797450 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-31 05:18:21.859554 | ubuntu-noble | ok 2025-01-31 05:18:21.869887 | 2025-01-31 05:18:21.870017 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-31 05:18:21.941890 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:21.949632 | 2025-01-31 05:18:21.949752 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-31 05:18:22.004430 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:22.012798 | 2025-01-31 05:18:22.012964 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-31 05:18:22.062410 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:22.069452 | 2025-01-31 05:18:22.069569 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-31 05:18:22.093529 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:22.100455 | 2025-01-31 05:18:22.100568 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-31 05:18:22.155194 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:22.169491 | 2025-01-31 05:18:22.169609 | TASK [emit-job-header : Print job information] 2025-01-31 05:18:22.282997 | # Job Information 2025-01-31 05:18:22.283159 | Ansible Version: 2.15.3 2025-01-31 05:18:22.283190 | Job: mypy 2025-01-31 05:18:22.283219 | Pipeline: check 2025-01-31 05:18:22.283239 | Executor: 7d211f194f6a 2025-01-31 05:18:22.283257 | Triggered by: https://github.com/osism/openstack-simple-stress/pull/154 2025-01-31 05:18:22.283274 | Event ID: bfb76060-df92-11ef-9723-9bf9c7177453 2025-01-31 05:18:22.289587 | 2025-01-31 05:18:22.289679 | LOOP [emit-job-header : Print node information] 2025-01-31 05:18:22.585890 | ubuntu-noble | ok: 2025-01-31 05:18:22.586101 | ubuntu-noble | # Node Information 2025-01-31 05:18:22.586135 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-31 05:18:22.586159 | ubuntu-noble | Hostname: ubuntu 2025-01-31 05:18:22.586181 | ubuntu-noble | Username: zuul 2025-01-31 05:18:22.586202 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-31 05:18:22.586221 | ubuntu-noble | Provider: regiocloud-a 2025-01-31 05:18:22.586240 | ubuntu-noble | Label: ubuntu-noble 2025-01-31 05:18:22.586259 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-31 05:18:22.586279 | ubuntu-noble | Interface IP: 81.163.192.148 2025-01-31 05:18:22.614318 | 2025-01-31 05:18:22.614465 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-31 05:18:23.545495 | ubuntu-noble -> localhost | changed 2025-01-31 05:18:23.566156 | 2025-01-31 05:18:23.566300 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-31 05:18:25.372187 | ubuntu-noble -> localhost | changed 2025-01-31 05:18:25.383474 | 2025-01-31 05:18:25.383560 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-31 05:18:25.819456 | ubuntu-noble -> localhost | ok 2025-01-31 05:18:25.827322 | 2025-01-31 05:18:25.827419 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-31 05:18:25.857696 | ubuntu-noble | ok 2025-01-31 05:18:25.873750 | ubuntu-noble | included: /var/lib/zuul/builds/16cffcb8bdce4ec09c46cbdd7508cdbc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-31 05:18:25.881447 | 2025-01-31 05:18:25.881538 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-31 05:18:26.580316 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-31 05:18:26.580522 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/16cffcb8bdce4ec09c46cbdd7508cdbc/work/16cffcb8bdce4ec09c46cbdd7508cdbc_id_rsa 2025-01-31 05:18:26.580559 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/16cffcb8bdce4ec09c46cbdd7508cdbc/work/16cffcb8bdce4ec09c46cbdd7508cdbc_id_rsa.pub 2025-01-31 05:18:26.580584 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-31 05:18:26.580606 | ubuntu-noble -> localhost | SHA256:yBZYfE2VuVrZfW7AofIOz/WLvfrqcxh/gwv9gA8msCM zuul-build-sshkey 2025-01-31 05:18:26.580627 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-31 05:18:26.580651 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-31 05:18:26.580672 | ubuntu-noble -> localhost | | .. o...o | 2025-01-31 05:18:26.580692 | ubuntu-noble -> localhost | | o. . . o . | 2025-01-31 05:18:26.580712 | ubuntu-noble -> localhost | | . .. * o | 2025-01-31 05:18:26.580731 | ubuntu-noble -> localhost | | . o . = + o| 2025-01-31 05:18:26.580749 | ubuntu-noble -> localhost | | +.S = o.| 2025-01-31 05:18:26.580768 | ubuntu-noble -> localhost | | . o o +.. o| 2025-01-31 05:18:26.580787 | ubuntu-noble -> localhost | | E o . X +=o | 2025-01-31 05:18:26.580806 | ubuntu-noble -> localhost | | . . o B+=+o| 2025-01-31 05:18:26.580826 | ubuntu-noble -> localhost | | .BB*=| 2025-01-31 05:18:26.580844 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-31 05:18:26.580889 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.191034 2025-01-31 05:18:26.588287 | 2025-01-31 05:18:26.588406 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-31 05:18:26.638465 | ubuntu-noble | ok 2025-01-31 05:18:26.661081 | ubuntu-noble | included: /var/lib/zuul/builds/16cffcb8bdce4ec09c46cbdd7508cdbc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-31 05:18:26.681036 | 2025-01-31 05:18:26.681145 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-31 05:18:26.715108 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:26.722291 | 2025-01-31 05:18:26.722383 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-31 05:18:27.478365 | ubuntu-noble | changed 2025-01-31 05:18:27.486133 | 2025-01-31 05:18:27.486226 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-31 05:18:27.780211 | ubuntu-noble | ok 2025-01-31 05:18:27.790024 | 2025-01-31 05:18:27.790133 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-31 05:18:28.495200 | ubuntu-noble | changed 2025-01-31 05:18:28.504218 | 2025-01-31 05:18:28.504321 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-31 05:18:29.223152 | ubuntu-noble | changed 2025-01-31 05:18:29.232059 | 2025-01-31 05:18:29.232182 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-31 05:18:29.266754 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:29.277365 | 2025-01-31 05:18:29.277483 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-31 05:18:29.685207 | ubuntu-noble -> localhost | changed 2025-01-31 05:18:29.699269 | 2025-01-31 05:18:29.699369 | TASK [add-build-sshkey : Add back temp key] 2025-01-31 05:18:30.305524 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/16cffcb8bdce4ec09c46cbdd7508cdbc/work/16cffcb8bdce4ec09c46cbdd7508cdbc_id_rsa (zuul-build-sshkey) 2025-01-31 05:18:30.305764 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.024652 2025-01-31 05:18:30.313376 | 2025-01-31 05:18:30.313504 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-31 05:18:30.781753 | ubuntu-noble | ok 2025-01-31 05:18:30.789258 | 2025-01-31 05:18:30.789385 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-31 05:18:30.825503 | ubuntu-noble | skipping: Conditional result was False 2025-01-31 05:18:30.850356 | 2025-01-31 05:18:30.850501 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-31 05:18:31.433485 | ubuntu-noble | ok 2025-01-31 05:18:31.463155 | 2025-01-31 05:18:31.463305 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-31 05:18:31.521812 | ubuntu-noble | ok 2025-01-31 05:18:31.531293 | 2025-01-31 05:18:31.531444 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-31 05:18:31.947112 | ubuntu-noble -> localhost | ok 2025-01-31 05:18:31.959216 | 2025-01-31 05:18:31.959365 | TASK [validate-host : Collect information about the host] 2025-01-31 05:18:33.152598 | ubuntu-noble | ok 2025-01-31 05:18:33.169198 | 2025-01-31 05:18:33.169348 | TASK [validate-host : Sanitize hostname] 2025-01-31 05:18:33.247785 | ubuntu-noble | ok 2025-01-31 05:18:33.257522 | 2025-01-31 05:18:33.257669 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-31 05:18:33.848709 | ubuntu-noble -> localhost | changed 2025-01-31 05:18:33.856129 | 2025-01-31 05:18:33.856251 | TASK [validate-host : Collect information about zuul worker] 2025-01-31 05:18:34.380845 | ubuntu-noble | ok 2025-01-31 05:18:34.387744 | 2025-01-31 05:18:34.387880 | TASK [validate-host : Write out all zuul information for each host] 2025-01-31 05:18:35.281855 | ubuntu-noble -> localhost | changed 2025-01-31 05:18:35.296133 | 2025-01-31 05:18:35.296439 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-31 05:18:35.605213 | ubuntu-noble | ok 2025-01-31 05:18:35.617109 | 2025-01-31 05:18:35.617247 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-31 05:18:36.681619 | ubuntu-noble | changed: 2025-01-31 05:18:36.681822 | ubuntu-noble | cd+++++++++ src/ 2025-01-31 05:18:36.681856 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-31 05:18:36.681880 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-31 05:18:36.681902 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-simple-stress/ 2025-01-31 05:18:36.681944 | ubuntu-noble | =2 in /tmp/venv/lib/python3.12/site-packages (from types-requests) (2.3.0) 2025-01-31 05:19:30.271631 | ubuntu-noble | Downloading types_PyYAML-6.0.12.20241230-py3-none-any.whl (20 kB) 2025-01-31 05:19:30.298094 | ubuntu-noble | Downloading types_decorator-5.1.8.20250121-py3-none-any.whl (8.1 kB) 2025-01-31 05:19:30.321805 | ubuntu-noble | Downloading types_requests-2.32.0.20241016-py3-none-any.whl (15 kB) 2025-01-31 05:19:30.345813 | ubuntu-noble | Downloading types_simplejson-3.19.0.20241221-py3-none-any.whl (10 kB) 2025-01-31 05:19:30.592078 | ubuntu-noble | Installing collected packages: types-simplejson, types-requests, types-PyYAML, types-decorator 2025-01-31 05:19:30.735026 | ubuntu-noble | Successfully installed types-PyYAML-6.0.12.20241230 types-decorator-5.1.8.20250121 types-requests-2.32.0.20241016 types-simplejson-3.19.0.20241221 2025-01-31 05:19:34.432087 | ubuntu-noble | Installing missing stub packages: 2025-01-31 05:19:34.443384 | ubuntu-noble | /tmp/venv/bin/python3 -m pip install types-PyYAML types-decorator types-requests types-simplejson 2025-01-31 05:19:34.443420 | ubuntu-noble | 2025-01-31 05:19:34.443476 | ubuntu-noble | 2025-01-31 05:19:34.443508 | ubuntu-noble | Success: no issues found in 5 source files 2025-01-31 05:19:34.491200 | ubuntu-noble | ok 2025-01-31 05:19:34.532582 | 2025-01-31 05:19:34.532696 | PLAY RECAP 2025-01-31 05:19:34.532760 | ubuntu-noble | ok: 11 changed: 7 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2025-01-31 05:19:34.532788 | 2025-01-31 05:19:34.657958 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/mypy/run.yaml@main] 2025-01-31 05:19:34.660553 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-31 05:19:35.401876 | 2025-01-31 05:19:35.402078 | PLAY [Base post] 2025-01-31 05:19:35.428702 | 2025-01-31 05:19:35.428868 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-31 05:19:36.011651 | ubuntu-noble | changed 2025-01-31 05:19:36.047134 | 2025-01-31 05:19:36.047269 | PLAY RECAP 2025-01-31 05:19:36.047336 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-31 05:19:36.047398 | 2025-01-31 05:19:36.161073 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-31 05:19:36.168053 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-31 05:19:36.936698 | 2025-01-31 05:19:36.936871 | PLAY [Base post-logs] 2025-01-31 05:19:36.953087 | 2025-01-31 05:19:36.953228 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-31 05:19:37.434874 | localhost | changed 2025-01-31 05:19:37.441511 | 2025-01-31 05:19:37.441709 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-31 05:19:37.474619 | localhost | ok 2025-01-31 05:19:37.484907 | 2025-01-31 05:19:37.485078 | TASK [Set zuul-log-path fact] 2025-01-31 05:19:37.504722 | localhost | ok 2025-01-31 05:19:37.518725 | 2025-01-31 05:19:37.518847 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-31 05:19:37.571177 | localhost | ok 2025-01-31 05:19:37.577494 | 2025-01-31 05:19:37.577666 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-31 05:19:37.614273 | localhost | skipping: Conditional result was False 2025-01-31 05:19:37.623754 | 2025-01-31 05:19:37.624084 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-31 05:19:37.665750 | localhost | skipping: Conditional result was False 2025-01-31 05:19:37.677152 | 2025-01-31 05:19:37.677406 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-31 05:19:37.704829 | localhost | skipping: Conditional result was False 2025-01-31 05:19:37.714049 | 2025-01-31 05:19:37.714248 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-31 05:19:37.740686 | localhost | skipping: Conditional result was False 2025-01-31 05:19:37.750765 | 2025-01-31 05:19:37.750975 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-31 05:19:37.778244 | localhost | skipping: Conditional result was False 2025-01-31 05:19:37.784846 | 2025-01-31 05:19:37.784973 | TASK [upload-logs : Create log directories] 2025-01-31 05:19:38.330525 | localhost | changed 2025-01-31 05:19:38.339228 | 2025-01-31 05:19:38.339387 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-31 05:19:38.890915 | localhost -> localhost | ok: Runtime: 0:00:00.007355 2025-01-31 05:19:38.904032 | 2025-01-31 05:19:38.904268 | TASK [upload-logs : Upload logs to log server] 2025-01-31 05:19:39.507530 | localhost | Output suppressed because no_log was given 2025-01-31 05:19:39.510817 | 2025-01-31 05:19:39.510950 | LOOP [upload-logs : Compress console log and json output] 2025-01-31 05:19:39.588976 | localhost | skipping: Conditional result was False 2025-01-31 05:19:39.603344 | localhost | skipping: Conditional result was False 2025-01-31 05:19:39.619659 | 2025-01-31 05:19:39.619853 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-31 05:19:39.685678 | localhost | skipping: Conditional result was False 2025-01-31 05:19:39.686065 | 2025-01-31 05:19:39.698314 | localhost | skipping: Conditional result was False 2025-01-31 05:19:39.714509 | 2025-01-31 05:19:39.714686 | LOOP [upload-logs : Upload console log and json output]