2020-04-20 20:39:06.558 7 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:06.559 7 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:06.559 7 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 20:39:08.145 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 20:39:08.145 7 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 20:39:08.145 7 ERROR rally.cli.cliutils   File "<decorator-gen-16>", line 2, in destroy
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 20:39:08.145 7 ERROR rally.cli.cliutils   File "<decorator-gen-15>", line 2, in destroy
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils     api.deployment.destroy(deployment=deployment)
2020-04-20 20:39:08.145 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 115, in destroy
2020-04-20 20:39:08.145 7 ERROR rally.cli.cliutils     deploy = objects.Deployment.get(deployment)
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils     return Deployment(env_mgr.EnvManager.get(deploy))
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils     return cls(db.env_get(uuid_or_name))
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 20:39:08.145 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:39:08.145 7 ERROR rally.cli.cliutils 
2020-04-20 20:39:09.417 9 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:09.417 9 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:09.417 9 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:12.389 11 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:12.389 11 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:12.389 11 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:15.713 14 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:15.713 14 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:15.713 14 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:16.931 17 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:16.931 17 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:16.931 17 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 20:39:18.580 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 20:39:18.580 17 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 20:39:18.580 17 ERROR rally.cli.cliutils   File "<decorator-gen-67>", line 2, in delete_verifier
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils     api.verifier.delete(verifier_id=verifier_id,
2020-04-20 20:39:18.580 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 690, in delete
2020-04-20 20:39:18.580 17 ERROR rally.cli.cliutils     verifier = self._get(verifier_id)
2020-04-20 20:39:18.580 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 664, in _get
2020-04-20 20:39:18.580 17 ERROR rally.cli.cliutils     return objects.Verifier.get(verifier_id)
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils     return cls(db.verifier_get(verifier_id))
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils     return _verifier_get(session, verifier_id)
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 20:39:18.580 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:39:18.580 17 ERROR rally.cli.cliutils 
2020-04-20 20:39:19.792 19 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:19.793 19 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:19.793 19 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:21.338 19 INFO rally.api [-] Creating verifier 'opnfv-tempest'.
2020-04-20 20:39:21.397 19 RALLYDEBUG rally.verification.manager [-] Cloning verifier repo from /src/tempest into /root/.rally/verification/verifier-f0d06209-fade-4d28-ae63-b536021e702b/repo.
2020-04-20 20:39:21.592 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'Cloning into '/root/.rally/verification/verifier-f0d06209-fade-4d28-ae63-b536021e702b/repo'...
done.
'
2020-04-20 20:39:21.602 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'heads/master
'
2020-04-20 20:39:21.622 19 RALLYDEBUG rally.verification.manager [-] Checking system-wide packages for verifier.
2020-04-20 20:39:21.667 19 RALLYDEBUG rally.plugins.verification.testr [-] Initializing testr.
2020-04-20 20:39:22.251 19 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=f0d06209-fade-4d28-ae63-b536021e702b) has been successfully created!
2020-04-20 20:39:23.512 26 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:23.513 26 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:23.513 26 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:26.967 29 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:26.967 29 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:26.967 29 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:28.451 29 INFO rally.api [-] Configuring verifier 'opnfv-tempest' (UUID=f0d06209-fade-4d28-ae63-b536021e702b) for deployment 'opnfv-rally' (UUID=97fd2235-ce26-4974-8f6b-8649778dd5f0).
2020-04-20 20:39:28.800 29 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=f0d06209-fade-4d28-ae63-b536021e702b) has been successfully configured for deployment 'opnfv-rally' (UUID=97fd2235-ce26-4974-8f6b-8649778dd5f0)!
2020-04-20 20:39:34.985 34 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:39:34.985 34 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:39:34.985 34 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:39:36.586 34 INFO rally.api [-] Starting verification (UUID=e9d04fac-99bf-45c9-8d0a-15545922a50e) for deployment 'opnfv-rally' (UUID=97fd2235-ce26-4974-8f6b-8649778dd5f0) by verifier 'opnfv-tempest' (UUID=f0d06209-fade-4d28-ae63-b536021e702b).
2020-04-20 20:39:37.037 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' from 'DEFAULT' section is not configured.
2020-04-20 20:39:37.038 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-f0d06209-fade-4d28-ae63-b536021e702b/for-deployment-97fd2235-ce26-4974-8f6b-8649778dd5f0/tempest.log' to option 'log_file'.
2020-04-20 20:39:37.038 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' is configured. log_file = /root/.rally/verification/verifier-f0d06209-fade-4d28-ae63-b536021e702b/for-deployment-97fd2235-ce26-4974-8f6b-8649778dd5f0/tempest.log
2020-04-20 20:39:37.039 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' from 'oslo_concurrency' section is not configured.
2020-04-20 20:39:37.039 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-f0d06209-fade-4d28-ae63-b536021e702b/for-deployment-97fd2235-ce26-4974-8f6b-8649778dd5f0/lock_files' to option 'lock_path'.
2020-04-20 20:39:37.039 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' is configured. lock_path = /root/.rally/verification/verifier-f0d06209-fade-4d28-ae63-b536021e702b/for-deployment-97fd2235-ce26-4974-8f6b-8649778dd5f0/lock_files
2020-04-20 20:39:37.039 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:39:37.040 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:39:37.040 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref' is already configured in Tempest config file. image_ref = d61a8e30-1a2b-47f7-a7a7-26d62b2552c7
2020-04-20 20:39:37.040 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref_alt' is already configured in Tempest config file. image_ref_alt = 205866ff-f9a1-4196-85a1-ce071835fe46
2020-04-20 20:39:37.041 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref' is already configured in Tempest config file. flavor_ref = c820413d-a223-4f95-b850-a279b786e4e4
2020-04-20 20:39:37.042 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref_alt' is already configured in Tempest config file. flavor_ref_alt = 82121ba7-06e6-49b4-a889-cd3def3ca64c
2020-04-20 20:39:37.278 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Shared networks found. 'fixed_network_name' option should be configured.
2020-04-20 20:39:37.278 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'fixed_network_name' is already configured in Tempest config file. fixed_network_name = tempest_cinder_cntt-net_94af2a25-46da-4af8-ab32-597b3164c677
2020-04-20 20:39:37.279 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' from 'orchestration' section is not configured.
2020-04-20 20:39:37.343 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:39:37.376 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] There is no flavor with the mentioned properties.
2020-04-20 20:39:37.376 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Creating flavor 'rally_verify_e9d04fac_RBzZOya8' with the following properties: RAM = 64MB, VCPUs = 1, disk = 5GB.
2020-04-20 20:39:37.406 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Flavor 'rally_verify_e9d04fac_RBzZOya8' (ID = 7478637a-71b5-4a2b-b3f5-0a0807d5ca98) has been successfully created!
2020-04-20 20:39:37.406 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '7478637a-71b5-4a2b-b3f5-0a0807d5ca98' to option 'instance_type'.
2020-04-20 20:39:37.406 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' is configured. instance_type = 7478637a-71b5-4a2b-b3f5-0a0807d5ca98
2020-04-20 20:39:37.407 34 INFO rally.task.context [-] Verification f0d06209-fade-4d28-ae63-b536021e702b | Context tempest@default setup()  finished in 0.69 sec
2020-04-20 20:39:37.408 34 INFO rally.task.context [-] Verification f0d06209-fade-4d28-ae63-b536021e702b | Context testr@default setup()  finished in 0.31 msec
2020-04-20 20:39:37.408 34 RALLYDEBUG rally.plugins.verification.testr [-] Test(s) started by the command: 'stestr run --subunit --concurrency 3 --load-list /tmp/74be6d2a-8ebd-420d-a03b-6e66f479a012'.
2020-04-20 20:39:45.872 34 INFO opnfv-tempest [-] {2} cinder_tempest_plugin.api.volume.test_volume_revert.VolumeRevertTests.test_volume_revert_to_snapshot ... success [4.197s]
2020-04-20 20:39:47.557 34 INFO opnfv-tempest [-] {1} cinder_tempest_plugin.api.volume.test_create_from_image.VolumeFromImageTest.test_create_from_image_multiple ... success [6.083s]
2020-04-20 20:39:51.063 34 INFO opnfv-tempest [-] {2} cinder_tempest_plugin.api.volume.test_volume_revert.VolumeRevertTests.test_volume_revert_to_snapshot_after_extended_negative ... success [5.192s]
2020-04-20 20:39:55.105 34 INFO opnfv-tempest [-] {0} cinder_tempest_plugin.api.volume.test_volume_backup.VolumesBackupsTest.test_backup_create_and_restore_to_an_existing_volume ... success [13.601s]
2020-04-20 20:40:06.428 34 INFO opnfv-tempest [-] {0} cinder_tempest_plugin.api.volume.test_volume_backup.VolumesBackupsTest.test_volume_snapshot_backup ... success [11.325s]
2020-04-20 20:40:13.312 34 INFO opnfv-tempest [-] {0} cinder_tempest_plugin.api.volume.test_volume_unicode.CinderUnicodeTest.test_create_delete_unicode_volume_name ... success [0.071s]
2020-04-20 20:40:16.139 34 INFO opnfv-tempest [-] {0} cinder_tempest_plugin.api.volume.test_volume_unicode.CinderUnicodeTest.test_snapshot_create_volume_description_non_ascii_code ... success [2.824s]
2020-04-20 20:40:21.066 34 INFO rally.task.context [-] Verification f0d06209-fade-4d28-ae63-b536021e702b | Context testr@default cleanup() started
2020-04-20 20:40:21.066 34 INFO rally.task.context [-] Verification f0d06209-fade-4d28-ae63-b536021e702b | Context testr@default cleanup() finished in 0.78 msec
2020-04-20 20:40:21.067 34 INFO rally.task.context [-] Verification f0d06209-fade-4d28-ae63-b536021e702b | Context tempest@default cleanup() started
2020-04-20 20:40:21.261 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Deleting flavor 'rally_verify_e9d04fac_RBzZOya8'.
2020-04-20 20:40:21.510 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Flavor 'rally_verify_e9d04fac_RBzZOya8' has been deleted.
2020-04-20 20:40:21.510 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Removing value '7478637a-71b5-4a2b-b3f5-0a0807d5ca98' of option 'instance_type' from Tempest config file.
2020-04-20 20:40:21.511 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Value '7478637a-71b5-4a2b-b3f5-0a0807d5ca98' has been removed.
2020-04-20 20:40:21.515 34 INFO rally.task.context [-] Verification f0d06209-fade-4d28-ae63-b536021e702b | Context tempest@default cleanup() finished in 0.45 sec
2020-04-20 20:40:21.534 34 INFO rally.api [-] Verification (UUID=e9d04fac-99bf-45c9-8d0a-15545922a50e) has been successfully finished for deployment 'opnfv-rally' (UUID=97fd2235-ce26-4974-8f6b-8649778dd5f0)!
2020-04-20 20:40:22.958 46 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:40:22.958 46 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:40:22.958 46 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:40:24.198 48 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:40:24.199 48 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:40:24.199 48 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:40:25.716 48 INFO rally.api [-] Building 'html' report for the following verification(s): 'e9d04fac-99bf-45c9-8d0a-15545922a50e'
2020-04-20 20:40:25.730 48 INFO rally.api [-] The report has been successfully built.
2020-04-20 20:40:27.065 50 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 20:40:27.066 50 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 20:40:27.066 50 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 20:40:28.733 50 INFO rally.api [-] Building 'junit-xml' report for the following verification(s): 'e9d04fac-99bf-45c9-8d0a-15545922a50e'
2020-04-20 20:40:28.735 50 INFO rally.api [-] The report has been successfully built.
