2024-05-28 11:01:36.199385 | Job console starting... 2024-05-28 11:01:36.204615 | Updating repositories 2024-05-28 11:01:36.213536 | Preparing job workspace 2024-05-28 11:01:40.549583 | Running Ansible setup... 2024-05-28 11:01:43.439889 | RUN START: [trusted : gerrit/zuul-config/playbooks/start-manila-ci.yaml@master] 2024-05-28 11:01:43.906882 | 2024-05-28 11:01:43.906976 | PLAY [Start Manila CI testing on remote GitLab runner] 2024-05-28 11:01:43.917535 | 2024-05-28 11:01:43.917624 | TASK [Run GitLab trigger script] 2024-05-28 13:10:09.321775 | localhost | Defaulting to user installation because normal site-packages is not writeable 2024-05-28 13:10:09.321884 | localhost | Collecting real-easypy (from -r requirements.txt (line 3)) 2024-05-28 13:10:09.321921 | localhost | Cloning https://github.com/vast-data/easypy.git (to revision 94f28aa) to /var/lib/zuul/builds/6e4c36f1f06e4372a0691b1ef4d0ed0b/work/tmp/pip-install-gzje8wvh/real-easypy_5de19542a6a24e17a9cc9be66f2a6a7b 2024-05-28 13:10:09.321951 | localhost | Running command git clone --filter=blob:none --quiet https://github.com/vast-data/easypy.git /var/lib/zuul/builds/6e4c36f1f06e4372a0691b1ef4d0ed0b/work/tmp/pip-install-gzje8wvh/real-easypy_5de19542a6a24e17a9cc9be66f2a6a7b 2024-05-28 13:10:09.321992 | localhost | WARNING: Did not find branch or tag '94f28aa', assuming revision or ref. 2024-05-28 13:10:09.322016 | localhost | Running command git checkout -q 94f28aa 2024-05-28 13:10:09.322038 | localhost | Resolved https://github.com/vast-data/easypy.git to commit 94f28aa 2024-05-28 13:10:09.322061 | localhost | Preparing metadata (setup.py): started 2024-05-28 13:10:09.322082 | localhost | Preparing metadata (setup.py): finished with status 'done' 2024-05-28 13:10:09.322101 | localhost | Collecting python-gitlab==4.5.0 (from -r requirements.txt (line 1)) 2024-05-28 13:10:09.322119 | localhost | Downloading python_gitlab-4.5.0-py3-none-any.whl.metadata (8.2 kB) 2024-05-28 13:10:09.322137 | localhost | Collecting boto3==1.28.10 (from -r requirements.txt (line 2)) 2024-05-28 13:10:09.322155 | localhost | Downloading boto3-1.28.10-py3-none-any.whl.metadata (6.6 kB) 2024-05-28 13:10:09.322172 | localhost | Requirement already satisfied: requests>=2.25.0 in /usr/local/lib/python3.11/site-packages (from python-gitlab==4.5.0->-r requirements.txt (line 1)) (2.31.0) 2024-05-28 13:10:09.322190 | localhost | Collecting requests-toolbelt>=0.10.1 (from python-gitlab==4.5.0->-r requirements.txt (line 1)) 2024-05-28 13:10:09.322209 | localhost | Downloading requests_toolbelt-1.0.0-py2.py3-none-any.whl.metadata (14 kB) 2024-05-28 13:10:09.322227 | localhost | Collecting botocore<1.32.0,>=1.31.10 (from boto3==1.28.10->-r requirements.txt (line 2)) 2024-05-28 13:10:09.322244 | localhost | Downloading botocore-1.31.85-py3-none-any.whl.metadata (6.1 kB) 2024-05-28 13:10:09.322262 | localhost | Requirement already satisfied: jmespath<2.0.0,>=0.7.1 in /usr/local/lib/python3.11/site-packages (from boto3==1.28.10->-r requirements.txt (line 2)) (1.0.1) 2024-05-28 13:10:09.322279 | localhost | Collecting s3transfer<0.7.0,>=0.6.0 (from boto3==1.28.10->-r requirements.txt (line 2)) 2024-05-28 13:10:09.322296 | localhost | Downloading s3transfer-0.6.2-py3-none-any.whl.metadata (1.8 kB) 2024-05-28 13:10:09.322314 | localhost | Requirement already satisfied: python-dateutil<3.0.0,>=2.1 in /usr/local/lib/python3.11/site-packages (from botocore<1.32.0,>=1.31.10->boto3==1.28.10->-r requirements.txt (line 2)) (2.9.0.post0) 2024-05-28 13:10:09.322331 | localhost | Requirement already satisfied: urllib3<2.1,>=1.25.4 in /usr/local/lib/python3.11/site-packages (from botocore<1.32.0,>=1.31.10->boto3==1.28.10->-r requirements.txt (line 2)) (1.26.18) 2024-05-28 13:10:09.322349 | localhost | Requirement already satisfied: charset-normalizer<4,>=2 in /usr/local/lib/python3.11/site-packages (from requests>=2.25.0->python-gitlab==4.5.0->-r requirements.txt (line 1)) (3.3.2) 2024-05-28 13:10:09.322366 | localhost | Requirement already satisfied: idna<4,>=2.5 in /usr/local/lib/python3.11/site-packages (from requests>=2.25.0->python-gitlab==4.5.0->-r requirements.txt (line 1)) (3.7) 2024-05-28 13:10:09.322390 | localhost | Requirement already satisfied: certifi>=2017.4.17 in /usr/local/lib/python3.11/site-packages (from requests>=2.25.0->python-gitlab==4.5.0->-r requirements.txt (line 1)) (2024.2.2) 2024-05-28 13:10:09.322409 | localhost | Requirement already satisfied: six>=1.5 in /usr/local/lib/python3.11/site-packages (from python-dateutil<3.0.0,>=2.1->botocore<1.32.0,>=1.31.10->boto3==1.28.10->-r requirements.txt (line 2)) (1.16.0) 2024-05-28 13:10:09.322426 | localhost | Downloading python_gitlab-4.5.0-py3-none-any.whl (139 kB) 2024-05-28 13:10:09.322444 | localhost | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 139.3/139.3 kB 1.7 MB/s eta 0:00:00 2024-05-28 13:10:09.322463 | localhost | Downloading boto3-1.28.10-py3-none-any.whl (135 kB) 2024-05-28 13:10:09.322482 | localhost | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 135.8/135.8 kB 5.0 MB/s eta 0:00:00 2024-05-28 13:10:09.322500 | localhost | Downloading botocore-1.31.85-py3-none-any.whl (11.3 MB) 2024-05-28 13:10:09.322517 | localhost | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 11.3/11.3 MB 7.8 MB/s eta 0:00:00 2024-05-28 13:10:09.322535 | localhost | Downloading requests_toolbelt-1.0.0-py2.py3-none-any.whl (54 kB) 2024-05-28 13:10:09.322552 | localhost | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 54.5/54.5 kB 3.9 MB/s eta 0:00:00 2024-05-28 13:10:09.322570 | localhost | Downloading s3transfer-0.6.2-py3-none-any.whl (79 kB) 2024-05-28 13:10:09.322587 | localhost | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 79.8/79.8 kB 3.7 MB/s eta 0:00:00 2024-05-28 13:10:09.322605 | localhost | Building wheels for collected packages: real-easypy 2024-05-28 13:10:09.322622 | localhost | Building wheel for real-easypy (setup.py): started 2024-05-28 13:10:09.322639 | localhost | Building wheel for real-easypy (setup.py): finished with status 'done' 2024-05-28 13:10:09.322656 | localhost | Created wheel for real-easypy: filename=real_easypy-0.4.3-py3-none-any.whl size=151760 sha256=9711dd835db527126e82d4099fd82da3ce0281f6dec67c67f0e4dbbe79b1b10e 2024-05-28 13:10:09.322673 | localhost | Stored in directory: /var/lib/zuul/builds/6e4c36f1f06e4372a0691b1ef4d0ed0b/work/tmp/pip-ephem-wheel-cache-5z_0ekzd/wheels/c9/39/3a/e885f87d386b416792e2c006fd66e348489cd283272cf0d9e5 2024-05-28 13:10:09.322690 | localhost | Successfully built real-easypy 2024-05-28 13:10:09.322707 | localhost | Installing collected packages: real-easypy, requests-toolbelt, botocore, s3transfer, python-gitlab, boto3 2024-05-28 13:10:09.322724 | localhost | WARNING: The scripts ezcolorize and eziplog are installed in '/var/lib/zuul/builds/6e4c36f1f06e4372a0691b1ef4d0ed0b/work/.local/bin' which is not on PATH. 2024-05-28 13:10:09.322741 | localhost | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2024-05-28 13:10:09.322758 | localhost | WARNING: The script gitlab is installed in '/var/lib/zuul/builds/6e4c36f1f06e4372a0691b1ef4d0ed0b/work/.local/bin' which is not on PATH. 2024-05-28 13:10:09.322774 | localhost | Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location. 2024-05-28 13:10:09.322791 | localhost | Successfully installed boto3-1.28.10 botocore-1.31.85 python-gitlab-4.5.0 real-easypy-0.4.3 requests-toolbelt-1.0.0 s3transfer-0.6.2 2024-05-28 13:10:09.322808 | localhost | WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv 2024-05-28 13:10:09.322829 | localhost | => 2024-05-28 13:10:09.322847 | localhost | {'before_sha': '0000000000000000000000000000000000000000', 2024-05-28 13:10:09.322863 | localhost | 'committed_at': None, 2024-05-28 13:10:09.322881 | localhost | 'coverage': None, 2024-05-28 13:10:09.322898 | localhost | 'created_at': '2024-05-28T11:02:05.593Z', 2024-05-28 13:10:09.322915 | localhost | 'detailed_status': {'details_path': '/dev/orion/-/pipelines/1343237', 2024-05-28 13:10:09.322932 | localhost | 'favicon': '/assets/ci_favicons/favicon_status_created-4b975aa976d24e5a3ea7cd9a5713e6ce2cd9afd08b910415e96675de35f64955.png', 2024-05-28 13:10:09.322949 | localhost | 'group': 'created', 2024-05-28 13:10:09.322967 | localhost | 'has_details': False, 2024-05-28 13:10:09.322983 | localhost | 'icon': 'status_created', 2024-05-28 13:10:09.323000 | localhost | 'illustration': None, 2024-05-28 13:10:09.323018 | localhost | 'label': 'created', 2024-05-28 13:10:09.323035 | localhost | 'text': 'created', 2024-05-28 13:10:09.323051 | localhost | 'tooltip': 'created'}, 2024-05-28 13:10:09.323068 | localhost | 'duration': None, 2024-05-28 13:10:09.323085 | localhost | 'finished_at': None, 2024-05-28 13:10:09.323102 | localhost | 'id': 1343237, 2024-05-28 13:10:09.323119 | localhost | 'iid': 1393062, 2024-05-28 13:10:09.323136 | localhost | 'project_id': 3, 2024-05-28 13:10:09.323153 | localhost | 'queued_duration': None, 2024-05-28 13:10:09.323170 | localhost | 'ref': 'MANILA_TEMPEST/vlad/devstack', 2024-05-28 13:10:09.323187 | localhost | 'sha': '161c42aec6886da7696b7533963ee4f0131d0caf', 2024-05-28 13:10:09.323203 | localhost | 'source': 'trigger', 2024-05-28 13:10:09.323219 | localhost | 'started_at': None, 2024-05-28 13:10:09.323236 | localhost | 'status': 'created', 2024-05-28 13:10:09.323252 | localhost | 'tag': False, 2024-05-28 13:10:09.323269 | localhost | 'updated_at': '2024-05-28T11:02:05.593Z', 2024-05-28 13:10:09.323285 | localhost | 'user': {'avatar_url': 'https://git.vastdata.com/uploads/-/system/user/avatar/121/avatar.png', 2024-05-28 13:10:09.323302 | localhost | 'id': 121, 2024-05-28 13:10:09.323318 | localhost | 'name': 'Ofer Koren', 2024-05-28 13:10:09.323335 | localhost | 'state': 'active', 2024-05-28 13:10:09.323351 | localhost | 'username': 'ofer.koren', 2024-05-28 13:10:09.323368 | localhost | 'web_url': 'https://git.vastdata.com/ofer.koren'}, 2024-05-28 13:10:09.323384 | localhost | 'web_url': 'https://git.vastdata.com/dev/orion/-/pipelines/1343237', 2024-05-28 13:10:09.323401 | localhost | 'yaml_errors': None} 2024-05-28 13:10:09.323437 | localhost | ok: Runtime: 2:08:25.104709 2024-05-28 13:10:09.331570 | 2024-05-28 13:10:09.331625 | PLAY RECAP 2024-05-28 13:10:09.331657 | localhost | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2024-05-28 13:10:09.331677 | 2024-05-28 13:10:09.398587 | RUN END RESULT_NORMAL: [trusted : gerrit/zuul-config/playbooks/start-manila-ci.yaml@master] 2024-05-28 13:10:09.399214 | POST-RUN START: [trusted : gerrit/zuul-config/playbooks/upload-logs-s3.yaml@master] 2024-05-28 13:10:09.833043 | 2024-05-28 13:10:09.833131 | PLAY [localhost] 2024-05-28 13:10:09.842621 | 2024-05-28 13:10:09.842699 | TASK [Remove job-output.json] 2024-05-28 13:10:10.091687 | localhost | changed 2024-05-28 13:10:10.101645 | 2024-05-28 13:10:10.101779 | PLAY [localhost] 2024-05-28 13:10:10.118662 | 2024-05-28 13:10:10.118830 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2024-05-28 13:10:10.358887 | localhost | changed 2024-05-28 13:10:10.361736 | 2024-05-28 13:10:10.361798 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2024-05-28 13:10:10.377886 | localhost | ok 2024-05-28 13:10:10.383925 | 2024-05-28 13:10:10.384030 | TASK [Set zuul-log-path fact] 2024-05-28 13:10:10.406904 | localhost | skipping: Conditional result was False 2024-05-28 13:10:10.409938 | 2024-05-28 13:10:10.410001 | TASK [upload-logs-s3 : Debug log_root] 2024-05-28 13:10:10.445002 | log_root /var/lib/zuul/builds/6e4c36f1f06e4372a0691b1ef4d0ed0b/work/logs 2024-05-28 13:10:10.447950 | 2024-05-28 13:10:10.448017 | TASK [upload-logs-s3 : Ensure logs are readable before uploading] 2024-05-28 13:10:10.737429 | localhost -> localhost | ok: Runtime: 0:00:00.002380 2024-05-28 13:10:10.740580 | 2024-05-28 13:10:10.740641 | TASK [upload-logs-s3 : Upload logs to S3]