2020-04-20 20:58:58.128 7 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:58:58.129 7 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:58:58.129 7 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:58:59.875 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 20:58:59.875 7 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "<decorator-gen-16>", line 2, in destroy
2020-04-20 20:58:59.875 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 20:58:59.875 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "<decorator-gen-15>", line 2, in destroy
2020-04-20 20:58:59.875 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 20:58:59.875 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/commands/deployment.py", line 154, in destroy
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils     api.deployment.destroy(deployment=deployment)
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 115, in destroy
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils     deploy = objects.Deployment.get(deployment)
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/objects/deploy.py", line 124, in get
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils     return Deployment(env_mgr.EnvManager.get(deploy))
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/env/env_mgr.py", line 212, in get
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils     return cls(db.env_get(uuid_or_name))
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/db/api.py", line 133, in wrapper
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 20:58:59.875 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 20:58:59.875 7 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils rally.exceptions.DBRecordNotFound: Record for uuid or name is opnfv-rally not found in table envs
2020-04-20 20:58:59.875 7 ERROR rally.cli.cliutils 
2020-04-20 20:59:01.346 9 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:01.346 9 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:01.347 9 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:04.627 11 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:04.627 11 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:04.627 11 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:08.094 14 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:08.095 14 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:08.095 14 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:09.260 17 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:09.260 17 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:09.260 17 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:10.798 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 20:59:10.798 17 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils   File "<decorator-gen-67>", line 2, in delete_verifier
2020-04-20 20:59:10.798 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 20:59:10.798 17 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 20:59:10.798 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 20:59:10.798 17 ERROR rally.cli.cliutils     api.verifier.delete(verifier_id=verifier_id,
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 690, in delete
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils     verifier = self._get(verifier_id)
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 664, in _get
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils     return objects.Verifier.get(verifier_id)
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/objects/verifier.py", line 69, in get
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils     return cls(db.verifier_get(verifier_id))
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/common/db/api.py", line 133, in wrapper
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 20:59:10.798 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 20:59:10.798 17 ERROR rally.cli.cliutils     return _verifier_get(session, verifier_id)
2020-04-20 20:59:10.798 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 20:59:10.798 17 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils rally.exceptions.DBRecordNotFound: Record for name or uuid is opnfv-tempest not found in table verifiers
2020-04-20 20:59:10.798 17 ERROR rally.cli.cliutils 
2020-04-20 20:59:12.030 19 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:12.031 19 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:12.031 19 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:13.676 19 INFO rally.api [-] Creating verifier 'opnfv-tempest'.
2020-04-20 20:59:13.738 19 RALLYDEBUG rally.verification.manager [-] Cloning verifier repo from /src/tempest into /root/.rally/verification/verifier-c9e3c380-8b88-45f3-bd4b-791f8258f94d/repo.
2020-04-20 20:59:13.936 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'Cloning into '/root/.rally/verification/verifier-c9e3c380-8b88-45f3-bd4b-791f8258f94d/repo'...
done.
'
2020-04-20 20:59:13.947 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'heads/master
'
2020-04-20 20:59:13.967 19 RALLYDEBUG rally.verification.manager [-] Checking system-wide packages for verifier.
2020-04-20 20:59:14.013 19 RALLYDEBUG rally.plugins.verification.testr [-] Initializing testr.
2020-04-20 20:59:14.662 19 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=c9e3c380-8b88-45f3-bd4b-791f8258f94d) has been successfully created!
2020-04-20 20:59:16.038 26 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:16.038 26 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:16.038 26 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:19.622 29 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:19.622 29 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:19.622 29 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:21.362 29 INFO rally.api [-] Configuring verifier 'opnfv-tempest' (UUID=c9e3c380-8b88-45f3-bd4b-791f8258f94d) for deployment 'opnfv-rally' (UUID=25ac6203-49ad-42a3-8c6c-de42592807a9).
2020-04-20 20:59:22.579 29 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=c9e3c380-8b88-45f3-bd4b-791f8258f94d) has been successfully configured for deployment 'opnfv-rally' (UUID=25ac6203-49ad-42a3-8c6c-de42592807a9)!
2020-04-20 20:59:30.361 34 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:59:30.362 34 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:59:30.362 34 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:59:32.049 34 INFO rally.api [-] Starting verification (UUID=67387f46-19c6-43b6-a7d2-9341a3849bbf) for deployment 'opnfv-rally' (UUID=25ac6203-49ad-42a3-8c6c-de42592807a9) by verifier 'opnfv-tempest' (UUID=c9e3c380-8b88-45f3-bd4b-791f8258f94d).
2020-04-20 20:59:32.715 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' from 'DEFAULT' section is not configured.
2020-04-20 20:59:32.715 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-c9e3c380-8b88-45f3-bd4b-791f8258f94d/for-deployment-25ac6203-49ad-42a3-8c6c-de42592807a9/tempest.log' to option 'log_file'.
2020-04-20 20:59:32.716 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' is configured. log_file = /root/.rally/verification/verifier-c9e3c380-8b88-45f3-bd4b-791f8258f94d/for-deployment-25ac6203-49ad-42a3-8c6c-de42592807a9/tempest.log
2020-04-20 20:59:32.716 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' from 'oslo_concurrency' section is not configured.
2020-04-20 20:59:32.716 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-c9e3c380-8b88-45f3-bd4b-791f8258f94d/for-deployment-25ac6203-49ad-42a3-8c6c-de42592807a9/lock_files' to option 'lock_path'.
2020-04-20 20:59:32.717 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' is configured. lock_path = /root/.rally/verification/verifier-c9e3c380-8b88-45f3-bd4b-791f8258f94d/for-deployment-25ac6203-49ad-42a3-8c6c-de42592807a9/lock_files
2020-04-20 20:59:32.717 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 20:59:32.717 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 20:59:32.718 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref' is already configured in Tempest config file. image_ref = cc3b0413-a931-4ca0-83e8-3c076c2e3d9c
2020-04-20 20:59:32.718 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref_alt' is already configured in Tempest config file. image_ref_alt = ac3f6751-92c7-4350-b0fe-2ae3710100f3
2020-04-20 20:59:32.718 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref' is already configured in Tempest config file. flavor_ref = 62534091-d03d-483e-898d-12e0f39c36e9
2020-04-20 20:59:32.719 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref_alt' is already configured in Tempest config file. flavor_ref_alt = 08adde39-f308-4cab-807c-d76a73e1ec2c
2020-04-20 20:59:33.141 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Shared networks found. 'fixed_network_name' option should be configured.
2020-04-20 20:59:33.142 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'fixed_network_name' is already configured in Tempest config file. fixed_network_name = tempest_scenario_cntt-net_546cace1-6125-4c3d-af39-c6b9e7295bff
2020-04-20 20:59:33.142 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' from 'orchestration' section is not configured.
2020-04-20 20:59:33.218 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 20:59:33.259 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] The following flavor discovered: 'rally_verify_ac54f963_I8GEghtA'. Using flavor 'rally_verify_ac54f963_I8GEghtA' (ID = fdb2d875-70ae-4e21-96a0-4e6d240fb747) for the tests.
2020-04-20 20:59:33.259 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value 'fdb2d875-70ae-4e21-96a0-4e6d240fb747' to option 'instance_type'.
2020-04-20 20:59:33.260 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' is configured. instance_type = fdb2d875-70ae-4e21-96a0-4e6d240fb747
2020-04-20 20:59:33.261 34 INFO rally.task.context [-] Verification c9e3c380-8b88-45f3-bd4b-791f8258f94d | Context tempest@default setup()  finished in 1.01 sec
2020-04-20 20:59:33.261 34 INFO rally.task.context [-] Verification c9e3c380-8b88-45f3-bd4b-791f8258f94d | Context testr@default setup()  finished in 0.28 msec
2020-04-20 20:59:33.262 34 RALLYDEBUG rally.plugins.verification.testr [-] Test(s) started by the command: 'stestr run --subunit --serial --load-list /tmp/300f7658-dd90-4a94-af13-8f4ec92b78f5'.
2020-04-20 21:02:24.108 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_minimum_basic.TestMinimumBasicScenario.test_minimum_basic_scenario ... success [159.325s]
2020-04-20 21:03:23.694 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_network_basic_ops.TestNetworkBasicOps.test_hotplug_nic ... success [52.353s]
2020-04-20 21:04:25.992 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_network_basic_ops.TestNetworkBasicOps.test_network_basic_ops ... success [62.281s]
2020-04-20 21:04:28.914 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_object_storage_basic_ops.TestObjectStorageBasicOps.test_swift_basic_ops ... success [0.270s]
2020-04-20 21:05:44.623 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_security_groups_basic_ops.TestSecurityGroupsBasicOps.test_cross_tenant_traffic ... success [71.267s]
2020-04-20 21:06:37.219 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_security_groups_basic_ops.TestSecurityGroupsBasicOps.test_in_tenant_traffic ... success [52.589s]
2020-04-20 21:07:11.340 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_server_basic_ops.TestServerBasicOps.test_server_basic_ops ... success [23.424s]
2020-04-20 21:08:05.465 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_server_multinode.TestServerMultinode.test_schedule_to_all_nodes ... success [30.325s]
2020-04-20 21:09:08.727 34 INFO opnfv-tempest [-] {0} tempest.scenario.test_volume_boot_pattern.TestVolumeBootPattern.test_image_defined_boot_from_volume ... success [34.713s]
2020-04-20 21:09:21.833 34 INFO rally.task.context [-] Verification c9e3c380-8b88-45f3-bd4b-791f8258f94d | Context testr@default cleanup() started
2020-04-20 21:09:21.833 34 INFO rally.task.context [-] Verification c9e3c380-8b88-45f3-bd4b-791f8258f94d | Context testr@default cleanup() finished in 0.66 msec
2020-04-20 21:09:21.834 34 INFO rally.task.context [-] Verification c9e3c380-8b88-45f3-bd4b-791f8258f94d | Context tempest@default cleanup() started
2020-04-20 21:09:22.070 34 INFO rally.task.context [-] Verification c9e3c380-8b88-45f3-bd4b-791f8258f94d | Context tempest@default cleanup() finished in 0.24 sec
2020-04-20 21:09:22.098 34 INFO rally.api [-] Verification (UUID=67387f46-19c6-43b6-a7d2-9341a3849bbf) has been successfully finished for deployment 'opnfv-rally' (UUID=25ac6203-49ad-42a3-8c6c-de42592807a9)!
2020-04-20 21:09:23.931 145 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 21:09:23.931 145 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 21:09:23.932 145 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 21:09:25.271 147 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 21:09:25.271 147 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 21:09:25.271 147 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 21:09:27.498 147 INFO rally.api [-] Building 'html' report for the following verification(s): '67387f46-19c6-43b6-a7d2-9341a3849bbf'
2020-04-20 21:09:27.514 147 INFO rally.api [-] The report has been successfully built.
2020-04-20 21:09:28.847 149 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 21:09:28.847 149 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 21:09:28.847 149 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 21:09:30.620 149 INFO rally.api [-] Building 'junit-xml' report for the following verification(s): '67387f46-19c6-43b6-a7d2-9341a3849bbf'
2020-04-20 21:09:30.622 149 INFO rally.api [-] The report has been successfully built.
