2020-04-10 04:01:39,797 - xtesting.ci.run_tests - INFO - Deployment description:

+-------------------------+---------------------------------------------------------------------------------------------------------------------------+
|         ENV VAR         |                                                           VALUE                                                           |
+-------------------------+---------------------------------------------------------------------------------------------------------------------------+
|         CI_LOOP         |                                                           daily                                                           |
|          DEBUG          |                                                            true                                                           |
|     DEPLOY_SCENARIO     |                                                   os-ovn-nofeature-noha                                                   |
|      INSTALLER_TYPE     |                                                          unknown                                                          |
|        BUILD_TAG        |                                                        0S7HBR685ZOB                                                       |
|        NODE_NAME        |                                                         lf-pod4-2                                                         |
|       TEST_DB_URL       |                                      http://testresults.opnfv.org/test/api/v1/results                                     |
|     TEST_DB_EXT_URL     |                                                                                                                           |
|     S3_ENDPOINT_URL     |                                               https://storage.googleapis.com                                              |
|        S3_DST_URL       |      s3://artifacts.opnfv.org/functest/0S7HBR685ZOB/functest-ovn-opnfv-functest-benchmarking-jerma-rally_jobs-run-30      |
|       HTTP_DST_URL      |     http://artifacts.opnfv.org/functest/0S7HBR685ZOB/functest-ovn-opnfv-functest-benchmarking-jerma-rally_jobs-run-30     |
+-------------------------+---------------------------------------------------------------------------------------------------------------------------+

2020-04-10 04:01:39,803 - xtesting.ci.run_tests - INFO - Sourcing env file /var/lib/xtesting/conf/env_file

export OS_AUTH_URL=http://172.30.12.85/identity/v3
export OS_PROJECT_NAME="demo"
export OS_USER_DOMAIN_NAME="Default"
export OS_PROJECT_DOMAIN_NAME="Default"
export OS_USERNAME="admin"
export OS_PASSWORD="secret"
export OS_REGION_NAME="RegionOne"
export OS_INTERFACE=public
export OS_IDENTITY_API_VERSION=3

2020-04-10 04:01:39,803 - xtesting.ci.run_tests - DEBUG - Test args: rally_jobs
2020-04-10 04:01:39,817 - xtesting.ci.run_tests - INFO - Loading test case 'rally_jobs'...
2020-04-10 04:01:40,633 - functest.core.tenantnetwork - DEBUG - password: 5GnI|6){_LUdyFyAZ1^vO@s84CnaP)
2020-04-10 04:01:40,792 - functest.core.tenantnetwork - DEBUG - project: Munch({'location': Munch({'cloud': 'envvars', 'region_name': None, 'zone': None, 'project': Munch({'id': 'default', 'name': None, 'domain_id': 'default', 'domain_name': None})}), 'id': 'f16c274af08d462ca4c3ab38df455bbc', 'name': 'rally_jobs-project_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'description': 'Created by OPNFV Functest: rally_jobs', 'is_enabled': True, 'is_domain': False, 'domain_id': 'default', 'properties': Munch({'tags': [], 'options': Munch({})}), 'enabled': True, 'parent_id': 'default', 'tags': [], 'options': Munch({})})
2020-04-10 04:01:40,902 - functest.core.tenantnetwork - DEBUG - user: Munch({'id': 'ae93db755bef40b39dd63ec63f4b1775', 'email': None, 'name': 'rally_jobs-user_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'username': None, 'default_project_id': None, 'domain_id': 'default', 'enabled': True, 'description': None})
2020-04-10 04:01:41,407 - functest.core.tenantnetwork - DEBUG - cloud_config {'clouds': {'envvars': {'api_timeout': None, 'verify': True, 'cacert': None, 'cert': None, 'key': None, 'application_catalog_api_version': '1', 'auth_type': 'password', 'baremetal_api_version': '1', 'container_api_version': '1', 'container_infra_api_version': '1', 'compute_api_version': '2', 'database_api_version': '1.0', 'disable_vendor_agent': {}, 'dns_api_version': '2', 'interface': 'public', 'floating_ip_source': 'neutron', 'identity_api_version': '3', 'image_api_use_tasks': False, 'image_api_version': '2', 'image_format': 'qcow2', 'key_manager_api_version': 'v1', 'message': '', 'metering_api_version': '2', 'network_api_version': '2', 'object_store_api_version': '1', 'orchestration_api_version': '1', 'secgroup_source': 'neutron', 'status': 'active', 'volume_api_version': '2', 'workflow_api_version': '2', 'auth_url': 'http://172.30.12.85/identity/v3', 'project_name': 'rally_jobs-project_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'user_domain_name': 'Default', 'project_domain_name': 'Default', 'username': 'rally_jobs-user_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'password': '5GnI|6){_LUdyFyAZ1^vO@s84CnaP)', 'region_name': 'RegionOne', 'project_id': 'f16c274af08d462ca4c3ab38df455bbc'}}}
2020-04-10 04:01:41,411 - functest.core.tenantnetwork - DEBUG - new cloud {'auth_url': 'http://172.30.12.85/identity/v3', 'project_id': 'f16c274af08d462ca4c3ab38df455bbc', 'project_name': 'rally_jobs-project_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'project_domain_name': 'Default', 'username': 'rally_jobs-user_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'user_domain_name': 'Default', 'password': '5GnI|6){_LUdyFyAZ1^vO@s84CnaP)'}
2020-04-10 04:01:41,953 - xtesting.ci.run_tests - INFO - Running test case 'rally_jobs'...
2020-04-10 04:01:43,099 - functest.core.tenantnetwork - DEBUG - network: Munch({'id': '730d8841-6308-46af-af65-fae11770940e', 'name': 'rally_jobs-net_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'tenant_id': 'f16c274af08d462ca4c3ab38df455bbc', 'admin_state_up': True, 'mtu': 1342, 'status': 'ACTIVE', 'subnets': [], 'shared': True, 'project_id': 'f16c274af08d462ca4c3ab38df455bbc', 'port_security_enabled': True, 'dns_domain': '', 'qos_policy_id': None, 'router:external': False, 'provider:network_type': 'geneve', 'provider:physical_network': None, 'provider:segmentation_id': 3, 'availability_zone_hints': [], 'is_default': False, 'availability_zones': [], 'ipv4_address_scope': None, 'ipv6_address_scope': None, 'description': '', 'tags': [], 'created_at': '2020-04-10T04:01:42Z', 'updated_at': '2020-04-10T04:01:42Z', 'revision_number': 1})
2020-04-10 04:01:45,165 - functest.core.tenantnetwork - DEBUG - subnet: Munch({'id': 'd6a5ee63-a882-4bd2-8d0c-6f4957b7e906', 'name': 'rally_jobs-subnet_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'tenant_id': 'f16c274af08d462ca4c3ab38df455bbc', 'network_id': '730d8841-6308-46af-af65-fae11770940e', 'ip_version': 4, 'subnetpool_id': None, 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'gateway_ip': '192.168.120.1', 'cidr': '192.168.120.0/24', 'allocation_pools': [{'start': '192.168.120.2', 'end': '192.168.120.254'}], 'host_routes': [], 'dns_nameservers': ['8.8.8.8'], 'description': '', 'service_types': [], 'tags': [], 'created_at': '2020-04-10T04:01:43Z', 'updated_at': '2020-04-10T04:01:43Z', 'revision_number': 0, 'project_id': 'f16c274af08d462ca4c3ab38df455bbc'})
2020-04-10 04:01:46,660 - functest.core.tenantnetwork - DEBUG - router: Munch({'id': 'ff828752-4fc5-4de8-af64-3a4b0b2075f9', 'name': 'rally_jobs-router_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'tenant_id': 'f16c274af08d462ca4c3ab38df455bbc', 'admin_state_up': True, 'status': 'ACTIVE', 'external_gateway_info': {'network_id': 'e19a0777-f8a3-47b0-a791-740ade92af84', 'external_fixed_ips': [{'subnet_id': 'effc1932-da81-4db8-8b51-37bb33f6720c', 'ip_address': '172.24.4.32'}, {'subnet_id': '8dc3cf45-5453-4e2b-94d6-d2f096de48d1', 'ip_address': '2001:db8::1c8'}], 'enable_snat': True}, 'description': '', 'routes': [], 'tags': [], 'created_at': '2020-04-10T04:01:45Z', 'updated_at': '2020-04-10T04:01:46Z', 'revision_number': 2, 'project_id': 'f16c274af08d462ca4c3ab38df455bbc'})
2020-04-10 04:01:49,971 - functest.core.singlevm - DEBUG - image: Munch({'location': Munch({'cloud': 'envvars', 'region_name': 'RegionOne', 'zone': None, 'project': Munch({'id': 'f16c274af08d462ca4c3ab38df455bbc', 'name': 'rally_jobs-project_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'domain_id': None, 'domain_name': 'Default'})}), 'created_at': '2020-04-10T04:01:49Z', 'updated_at': '2020-04-10T04:01:50Z', 'checksum': '443b7623e27ecf03dc9e01ee93f67afe', 'container_format': 'bare', 'direct_url': None, 'disk_format': 'qcow2', 'file': '/v2/images/dd9ea4b7-4c3e-43d7-b1dc-ff33babf718a/file', 'id': 'dd9ea4b7-4c3e-43d7-b1dc-ff33babf718a', 'name': 'rally_jobs-img_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'owner': 'f16c274af08d462ca4c3ab38df455bbc', 'tags': [], 'status': 'active', 'min_ram': 0, 'min_disk': 0, 'size': 12716032, 'virtual_size': 0, 'is_protected': False, 'locations': [], 'properties': {'hw_scsi_model': 'virtio-scsi', 'self': '/v2/images/dd9ea4b7-4c3e-43d7-b1dc-ff33babf718a', 'os_hash_algo': 'sha512', 'hw_disk_bus': 'scsi', 'schema': '/v2/schemas/image', 'owner_specified.shade.sha256': 'a8dd75ecffd4cdd96072d60c2237b448e0c8b2bc94d57f10fdbc8c481d9005b8', 'owner_specified.shade.object': 'images/rally_jobs-img_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'owner_specified.shade.md5': '443b7623e27ecf03dc9e01ee93f67afe', 'os_hidden': False, 'os_hash_value': '6513f21e44aa3da349f248188a44bc304a3653a04122d8fb4535423c8e1d14cd6a153f735bb0982e2161b5b5186106570c17a9e58b64dd39390617cd5a350f78'}, 'is_public': True, 'visibility': 'public', 'hw_scsi_model': 'virtio-scsi', 'self': '/v2/images/dd9ea4b7-4c3e-43d7-b1dc-ff33babf718a', 'os_hash_algo': 'sha512', 'hw_disk_bus': 'scsi', 'schema': '/v2/schemas/image', 'owner_specified.shade.sha256': 'a8dd75ecffd4cdd96072d60c2237b448e0c8b2bc94d57f10fdbc8c481d9005b8', 'owner_specified.shade.object': 'images/rally_jobs-img_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'owner_specified.shade.md5': '443b7623e27ecf03dc9e01ee93f67afe', 'os_hidden': False, 'os_hash_value': '6513f21e44aa3da349f248188a44bc304a3653a04122d8fb4535423c8e1d14cd6a153f735bb0982e2161b5b5186106570c17a9e58b64dd39390617cd5a350f78', 'protected': False, 'metadata': {'hw_scsi_model': 'virtio-scsi', 'self': '/v2/images/dd9ea4b7-4c3e-43d7-b1dc-ff33babf718a', 'os_hash_algo': 'sha512', 'hw_disk_bus': 'scsi', 'schema': '/v2/schemas/image', 'owner_specified.shade.sha256': 'a8dd75ecffd4cdd96072d60c2237b448e0c8b2bc94d57f10fdbc8c481d9005b8', 'owner_specified.shade.object': 'images/rally_jobs-img_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'owner_specified.shade.md5': '443b7623e27ecf03dc9e01ee93f67afe', 'os_hidden': False, 'os_hash_value': '6513f21e44aa3da349f248188a44bc304a3653a04122d8fb4535423c8e1d14cd6a153f735bb0982e2161b5b5186106570c17a9e58b64dd39390617cd5a350f78'}, 'created': '2020-04-10T04:01:49Z', 'updated': '2020-04-10T04:01:50Z', 'minDisk': 0, 'minRam': 0})
2020-04-10 04:01:50,030 - functest.core.singlevm - DEBUG - flavor: Munch({'location': Munch({'cloud': 'envvars', 'region_name': 'RegionOne', 'zone': None, 'project': Munch({'id': '67255c4d5f2f4aa4b05f5e7666d2adaa', 'name': 'demo', 'domain_id': None, 'domain_name': 'Default'})}), 'id': '4fe204d5-1008-49ee-9024-42cdbe196cc0', 'name': 'rally_jobs-flavor_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'is_public': True, 'is_disabled': False, 'ram': 512, 'vcpus': 1, 'disk': 1, 'ephemeral': 0, 'swap': 0, 'rxtx_factor': 1.0, 'properties': Munch({'OS-FLV-DISABLED:disabled': False, 'os-flavor-access:is_public': True, 'OS-FLV-EXT-DATA:ephemeral': 0}), 'extra_specs': Munch({}), 'OS-FLV-DISABLED:disabled': False, 'os-flavor-access:is_public': True, 'OS-FLV-EXT-DATA:ephemeral': 0})
2020-04-10 04:01:50,067 - functest.opnfv_tests.openstack.rally.rally - INFO - Creating Rally environment...
2020-04-10 04:01:56,769 - functest.opnfv_tests.openstack.rally.rally - INFO - rally deployment create --fromenv --name opnfv-rally
+--------------------------------------+----------------------------+-------------+------------------+--------+
| uuid                                 | created_at                 | name        | status           | active |
+--------------------------------------+----------------------------+-------------+------------------+--------+
| 09e58292-38bb-450d-a03a-0dea3c6be06f | 2020-04-10T04:01:56.488739 | opnfv-rally | deploy->finished |        |
+--------------------------------------+----------------------------+-------------+------------------+--------+
Using deployment: 09e58292-38bb-450d-a03a-0dea3c6be06f
~/.rally/openrc was updated

HINTS:

* To use standard OpenStack clients, set up your env by running:
	source ~/.rally/openrc
  OpenStack clients are now configured, e.g run:
	openstack image list

2020-04-10 04:02:00,527 - functest.opnfv_tests.openstack.rally.rally - INFO - rally deployment check
--------------------------------------------------------------------------------
Platform openstack:
--------------------------------------------------------------------------------

Available services:
+-------------+----------------+-----------+
| Service     | Service Type   | Status    |
+-------------+----------------+-----------+
| __unknown__ | compute_legacy | Available |
| __unknown__ | placement      | Available |
| barbican    | key-manager    | Available |
| cinder      | block-storage  | Available |
| cinder      | volumev2       | Available |
| cinder      | volumev3       | Available |
| cloud       | cloudformation | Available |
| glance      | image          | Available |
| heat        | orchestration  | Available |
| keystone    | identity       | Available |
| neutron     | network        | Available |
| nova        | compute        | Available |
| swift       | object-store   | Available |
+-------------+----------------+-----------+



2020-04-10 04:02:01,754 - functest.opnfv_tests.openstack.rally.rally - DEBUG - Validating run tests...
2020-04-10 04:02:01,871 - functest.core.singlevm - DEBUG - flavor: Munch({'location': Munch({'cloud': 'envvars', 'region_name': 'RegionOne', 'zone': None, 'project': Munch({'id': '67255c4d5f2f4aa4b05f5e7666d2adaa', 'name': 'demo', 'domain_id': None, 'domain_name': 'Default'})}), 'id': 'bf21ccbf-e7f4-443a-b0ab-b0c9a29632d2', 'name': 'rally_jobs-flavor_alt_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'is_public': True, 'is_disabled': False, 'ram': 1024, 'vcpus': 1, 'disk': 1, 'ephemeral': 0, 'swap': 0, 'rxtx_factor': 1.0, 'properties': Munch({'OS-FLV-DISABLED:disabled': False, 'os-flavor-access:is_public': True, 'OS-FLV-EXT-DATA:ephemeral': 0}), 'extra_specs': Munch({}), 'OS-FLV-DISABLED:disabled': False, 'os-flavor-access:is_public': True, 'OS-FLV-EXT-DATA:ephemeral': 0})
2020-04-10 04:02:01,961 - functest.opnfv_tests.openstack.rally.rally - DEBUG - flavor: Munch({'location': Munch({'cloud': 'envvars', 'region_name': 'RegionOne', 'zone': None, 'project': Munch({'id': '67255c4d5f2f4aa4b05f5e7666d2adaa', 'name': 'demo', 'domain_id': None, 'domain_name': 'Default'})}), 'id': 'bf21ccbf-e7f4-443a-b0ab-b0c9a29632d2', 'name': 'rally_jobs-flavor_alt_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'is_public': True, 'is_disabled': False, 'ram': 1024, 'vcpus': 1, 'disk': 1, 'ephemeral': 0, 'swap': 0, 'rxtx_factor': 1.0, 'properties': Munch({'OS-FLV-DISABLED:disabled': False, 'os-flavor-access:is_public': True, 'OS-FLV-EXT-DATA:ephemeral': 0}), 'extra_specs': Munch({}), 'OS-FLV-DISABLED:disabled': False, 'os-flavor-access:is_public': True, 'OS-FLV-EXT-DATA:ephemeral': 0})
2020-04-10 04:02:01,965 - functest.opnfv_tests.openstack.rally.rally - DEBUG - Scenario fetched from : /home/opnfv/functest/data/rally/neutron/rally-jobs/task-neutron.yaml
2020-04-10 04:02:01,966 - functest.opnfv_tests.openstack.rally.rally - DEBUG - Applying blacklist...
2020-04-10 04:02:01,983 - functest.opnfv_tests.openstack.rally.rally - DEBUG - Scenario exclusion not applied.
2020-04-10 04:02:02,404 - functest.opnfv_tests.openstack.rally.rally - INFO - Starting test scenario "neutron" ...
2020-04-10 04:02:02,404 - functest.opnfv_tests.openstack.rally.rally - DEBUG - running command: ['rally', 'task', 'start', '--tag', 'neutron', '--task', '/home/opnfv/functest/data/rally/task/var/task-neutron.yaml', '--task-args', "{'floating_network': 'public', 'image_name': 'rally_jobs-img_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'flavor_name': 'rally_jobs-flavor_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2'}"]
2020-04-10 06:02:02,440 - functest.opnfv_tests.openstack.rally.rally - ERROR - Error with run:
Traceback (most recent call last):
  File "/usr/lib/python3.7/site-packages/functest/opnfv_tests/openstack/rally/rally.py", line 691, in run
    self.run_tests(**kwargs)
  File "/usr/lib/python3.7/site-packages/functest/opnfv_tests/openstack/rally/rally.py", line 541, in run_tests
    self.run_task(test)
  File "/usr/lib/python3.7/site-packages/functest/opnfv_tests/openstack/rally/rally.py", line 432, in run_task
    stdout=subprocess.DEVNULL, stderr=subprocess.DEVNULL)
  File "/usr/lib/python3.7/subprocess.py", line 341, in call
    return p.wait(timeout=timeout)
  File "/usr/lib/python3.7/subprocess.py", line 1019, in wait
    return self._wait(timeout=timeout)
  File "/usr/lib/python3.7/subprocess.py", line 1645, in _wait
    raise TimeoutExpired(self.args, timeout)
subprocess.TimeoutExpired: Command '['rally', 'task', 'start', '--tag', 'neutron', '--task', '/home/opnfv/functest/data/rally/task/var/task-neutron.yaml', '--task-args', "{'floating_network': 'public', 'image_name': 'rally_jobs-img_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2', 'flavor_name': 'rally_jobs-flavor_3e62a7e6-ed9f-488c-8a45-b192fe1e5ad2'}"]' timed out after 7200 seconds
2020-04-10 06:02:02,477 - xtesting.ci.run_tests - INFO - Test result:

+--------------------+------------------+------------------+----------------+
|     TEST CASE      |     PROJECT      |     DURATION     |     RESULT     |
+--------------------+------------------+------------------+----------------+
|     rally_jobs     |     functest     |      120:21      |      FAIL      |
+--------------------+------------------+------------------+----------------+

2020-04-10 06:02:12,792 - xtesting.core.testcase - DEBUG - Publishing /home/opnfv/functest/results/functest.log ('text/plain', None)
