2020-04-20 19:34:58.497 7 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:34:58.497 7 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:34:58.498 7 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils [-] Unexpected exception in CLI: rally.exceptions.DBRecordNotFound: Record for uuid or name is opnfv-rally not found in table envs
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "<decorator-gen-16>", line 2, in destroy
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/envutils.py", line 135, in default_from_global
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "<decorator-gen-15>", line 2, in destroy
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/plugins/__init__.py", line 59, in ensure_plugins_are_loaded
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/commands/deployment.py", line 154, in destroy
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     api.deployment.destroy(deployment=deployment)
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 115, in destroy
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     deploy = objects.Deployment.get(deployment)
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/objects/deploy.py", line 124, in get
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     return Deployment(env_mgr.EnvManager.get(deploy))
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/env/env_mgr.py", line 212, in get
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     return cls(db.env_get(uuid_or_name))
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/db/api.py", line 133, in wrapper
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/db/api.py", line 518, in env_get
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils rally.exceptions.DBRecordNotFound: Record for uuid or name is opnfv-rally not found in table envs
2020-04-20 19:35:00.764 7 ERROR rally.cli.cliutils 
2020-04-20 19:35:02.227 9 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:02.227 9 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:02.227 9 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:05.518 11 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:05.518 11 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:05.518 11 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:09.548 14 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:09.549 14 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:09.549 14 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:10.851 17 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:10.851 17 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:10.851 17 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils [-] Unexpected exception in CLI: rally.exceptions.DBRecordNotFound: Record for name or uuid is opnfv-tempest not found in table verifiers
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "<decorator-gen-67>", line 2, in delete_verifier
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/plugins/__init__.py", line 59, in ensure_plugins_are_loaded
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/commands/verify.py", line 234, in delete_verifier
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     api.verifier.delete(verifier_id=verifier_id,
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 690, in delete
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     verifier = self._get(verifier_id)
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 664, in _get
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     return objects.Verifier.get(verifier_id)
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/objects/verifier.py", line 69, in get
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     return cls(db.verifier_get(verifier_id))
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/db/api.py", line 133, in wrapper
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/db/api.py", line 667, in verifier_get
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     return _verifier_get(session, verifier_id)
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/db/api.py", line 676, in _verifier_get
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils rally.exceptions.DBRecordNotFound: Record for name or uuid is opnfv-tempest not found in table verifiers
2020-04-20 19:35:12.441 17 ERROR rally.cli.cliutils 
2020-04-20 19:35:13.855 19 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:13.855 19 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:13.855 19 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:15.618 19 INFO rally.api [-] Creating verifier 'opnfv-tempest'.
2020-04-20 19:35:15.737 19 RALLYDEBUG rally.verification.manager [-] Cloning verifier repo from /src/tempest into /root/.rally/verification/verifier-5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24/repo.
2020-04-20 19:35:16.210 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'Cloning into '/root/.rally/verification/verifier-5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24/repo'...
done.
'
2020-04-20 19:35:16.223 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'heads/master
'
2020-04-20 19:35:16.244 19 RALLYDEBUG rally.verification.manager [-] Checking system-wide packages for verifier.
2020-04-20 19:35:16.284 19 RALLYDEBUG rally.plugins.verification.testr [-] Initializing testr.
2020-04-20 19:35:17.047 19 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24) has been successfully created!
2020-04-20 19:35:18.646 26 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:18.647 26 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:18.647 26 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:22.448 29 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:22.448 29 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:22.448 29 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:24.061 29 INFO rally.api [-] Configuring verifier 'opnfv-tempest' (UUID=5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24) for deployment 'opnfv-rally' (UUID=9d42947e-405b-44f1-8b63-e0d6caab477a).
2020-04-20 19:35:25.472 29 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24) has been successfully configured for deployment 'opnfv-rally' (UUID=9d42947e-405b-44f1-8b63-e0d6caab477a)!
2020-04-20 19:35:33.329 34 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:35:33.330 34 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:35:33.330 34 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:35:35.154 34 INFO rally.api [-] Starting verification (UUID=22146985-b60f-4e3a-9a43-c964ff4286a9) for deployment 'opnfv-rally' (UUID=9d42947e-405b-44f1-8b63-e0d6caab477a) by verifier 'opnfv-tempest' (UUID=5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24).
2020-04-20 19:35:35.760 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' from 'DEFAULT' section is not configured.
2020-04-20 19:35:35.761 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24/for-deployment-9d42947e-405b-44f1-8b63-e0d6caab477a/tempest.log' to option 'log_file'.
2020-04-20 19:35:35.761 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' is configured. log_file = /root/.rally/verification/verifier-5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24/for-deployment-9d42947e-405b-44f1-8b63-e0d6caab477a/tempest.log
2020-04-20 19:35:35.762 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' from 'oslo_concurrency' section is not configured.
2020-04-20 19:35:35.762 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24/for-deployment-9d42947e-405b-44f1-8b63-e0d6caab477a/lock_files' to option 'lock_path'.
2020-04-20 19:35:35.762 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' is configured. lock_path = /root/.rally/verification/verifier-5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24/for-deployment-9d42947e-405b-44f1-8b63-e0d6caab477a/lock_files
2020-04-20 19:35:35.763 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'img_dir' is already configured in Tempest config file. img_dir = /home/opnfv/functest/images
2020-04-20 19:35:35.763 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'img_file' is already configured in Tempest config file. img_file = cirros-0.4.0-x86_64-disk.img
2020-04-20 19:35:35.763 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref' is already configured in Tempest config file. image_ref = b609b7ea-d45c-4c70-ba4e-6e1e5ff3393f
2020-04-20 19:35:35.763 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref_alt' is already configured in Tempest config file. image_ref_alt = 1f27255a-87cf-4fe1-a791-d9288d71867f
2020-04-20 19:35:35.764 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref' is already configured in Tempest config file. flavor_ref = 8f7e6ec6-d476-479a-823f-0f769525a7fe
2020-04-20 19:35:35.764 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref_alt' is already configured in Tempest config file. flavor_ref_alt = f50c5c98-613d-493c-a3f2-71eb1c30597f
2020-04-20 19:35:36.057 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Shared networks found. 'fixed_network_name' option should be configured.
2020-04-20 19:35:36.058 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'fixed_network_name' is already configured in Tempest config file. fixed_network_name = tempest_scenario-net_d7d09613-c2a0-41d2-a40b-c35bd5d00d4d
2020-04-20 19:35:36.058 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' from 'orchestration' section is not configured.
2020-04-20 19:35:36.142 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Trying to discover a flavor with the following properties: RAM = 64MB, VCPUs = 1, disk >= 5GiB.
2020-04-20 19:35:36.173 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] There is no flavor with the mentioned properties.
2020-04-20 19:35:36.174 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Creating flavor 'rally_verify_22146985_WvLltM46' with the following properties: RAM = 64MB, VCPUs = 1, disk = 5GB.
2020-04-20 19:35:36.209 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Flavor 'rally_verify_22146985_WvLltM46' (ID = 5992d6e8-b053-4076-885d-646b41510a0b) has been successfully created!
2020-04-20 19:35:36.210 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '5992d6e8-b053-4076-885d-646b41510a0b' to option 'instance_type'.
2020-04-20 19:35:36.210 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' is configured. instance_type = 5992d6e8-b053-4076-885d-646b41510a0b
2020-04-20 19:35:36.211 34 INFO rally.task.context [-] Verification 5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24 | Context tempest@default setup()  finished in 0.88 sec
2020-04-20 19:35:36.212 34 INFO rally.task.context [-] Verification 5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24 | Context testr@default setup()  finished in 0.32 msec
2020-04-20 19:35:36.212 34 RALLYDEBUG rally.plugins.verification.testr [-] Test(s) started by the command: 'stestr run --subunit --serial --load-list /tmp/7db3323c-1a2b-4e73-a1b6-15c6e93ce6eb'.
2020-04-20 19:38:33.680 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_minimum_basic.TestMinimumBasicScenario.test_minimum_basic_scenario ... success [164.968s]
2020-04-20 19:39:29.307 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_network_basic_ops.TestNetworkBasicOps.test_hotplug_nic ... success [48.719s]
2020-04-20 19:40:28.102 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_network_basic_ops.TestNetworkBasicOps.test_network_basic_ops ... success [58.776s]
2020-04-20 19:40:31.570 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_object_storage_basic_ops.TestObjectStorageBasicOps.test_swift_basic_ops ... success [0.260s]
2020-04-20 19:41:51.382 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_security_groups_basic_ops.TestSecurityGroupsBasicOps.test_cross_tenant_traffic ... success [75.652s]
2020-04-20 19:42:50.035 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_security_groups_basic_ops.TestSecurityGroupsBasicOps.test_in_tenant_traffic ... success [58.641s]
2020-04-20 19:43:24.480 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_server_basic_ops.TestServerBasicOps.test_server_basic_ops ... success [23.802s]
2020-04-20 19:44:14.805 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_server_multinode.TestServerMultinode.test_schedule_to_all_nodes ... success [26.087s]
2020-04-20 19:44:44.022 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_volume_boot_pattern.TestVolumeBootPattern.test_boot_server_from_encrypted_volume_luks ... skip [0.000s]: b'Encrypted volume attach is not supported'
2020-04-20 19:45:18.344 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_volume_boot_pattern.TestVolumeBootPattern.test_image_defined_boot_from_volume ... success [34.310s]
2020-04-20 19:45:30.954 34 INFO rally.task.context [-] Verification 5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24 | Context testr@default cleanup() started
2020-04-20 19:45:30.955 34 INFO rally.task.context [-] Verification 5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24 | Context testr@default cleanup() finished in 0.67 msec
2020-04-20 19:45:30.955 34 INFO rally.task.context [-] Verification 5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24 | Context tempest@default cleanup() started
2020-04-20 19:45:31.143 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Deleting flavor 'rally_verify_22146985_WvLltM46'.
2020-04-20 19:45:31.309 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Flavor 'rally_verify_22146985_WvLltM46' has been deleted.
2020-04-20 19:45:31.310 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Removing value '5992d6e8-b053-4076-885d-646b41510a0b' of option 'instance_type' from Tempest config file.
2020-04-20 19:45:31.310 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Value '5992d6e8-b053-4076-885d-646b41510a0b' has been removed.
2020-04-20 19:45:31.314 34 INFO rally.task.context [-] Verification 5d6d5e12-f6fa-400c-82f4-c9d8a42d4d24 | Context tempest@default cleanup() finished in 0.36 sec
2020-04-20 19:45:31.335 34 INFO rally.api [-] Verification (UUID=22146985-b60f-4e3a-9a43-c964ff4286a9) has been successfully finished for deployment 'opnfv-rally' (UUID=9d42947e-405b-44f1-8b63-e0d6caab477a)!
2020-04-20 19:45:32.754 144 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:45:32.755 144 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:45:32.755 144 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:45:33.895 146 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:45:33.895 146 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:45:33.895 146 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:45:35.608 146 INFO rally.api [-] Building 'html' report for the following verification(s): '22146985-b60f-4e3a-9a43-c964ff4286a9'
2020-04-20 19:45:35.632 146 INFO rally.api [-] The report has been successfully built.
2020-04-20 19:45:36.960 148 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:45:36.960 148 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:45:36.960 148 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:45:38.469 148 INFO rally.api [-] Building 'junit-xml' report for the following verification(s): '22146985-b60f-4e3a-9a43-c964ff4286a9'
2020-04-20 19:45:38.470 148 INFO rally.api [-] The report has been successfully built.
