2020-04-20 19:05:47.122 7 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:05:47.122 7 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:05:47.122 7 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 19:05:48.742 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 19:05:48.742 7 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 19:05:48.742 7 ERROR rally.cli.cliutils   File "<decorator-gen-16>", line 2, in destroy
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 19:05:48.742 7 ERROR rally.cli.cliutils   File "<decorator-gen-15>", line 2, in destroy
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils     api.deployment.destroy(deployment=deployment)
2020-04-20 19:05:48.742 7 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 115, in destroy
2020-04-20 19:05:48.742 7 ERROR rally.cli.cliutils     deploy = objects.Deployment.get(deployment)
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils     return Deployment(env_mgr.EnvManager.get(deploy))
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils     return cls(db.env_get(uuid_or_name))
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 19:05:48.742 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:05:48.742 7 ERROR rally.cli.cliutils 
2020-04-20 19:05:50.004 9 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:05:50.004 9 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:05:50.004 9 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:05:53.167 11 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:05:53.168 11 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:05:53.168 11 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:05:56.510 14 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:05:56.510 14 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:05:56.510 14 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:05:57.618 17 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:05:57.618 17 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:05:57.619 17 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils Traceback (most recent call last):
2020-04-20 19:05:59.096 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/cli/cliutils.py", line 657, in run
2020-04-20 19:05:59.096 17 ERROR rally.cli.cliutils     ret = fn(*fn_args, **fn_kwargs)
2020-04-20 19:05:59.096 17 ERROR rally.cli.cliutils   File "<decorator-gen-67>", line 2, in delete_verifier
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils     return f(*args, **kwargs)
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils     api.verifier.delete(verifier_id=verifier_id,
2020-04-20 19:05:59.096 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 690, in delete
2020-04-20 19:05:59.096 17 ERROR rally.cli.cliutils     verifier = self._get(verifier_id)
2020-04-20 19:05:59.096 17 ERROR rally.cli.cliutils   File "/usr/lib/python3.8/site-packages/rally/api.py", line 664, in _get
2020-04-20 19:05:59.096 17 ERROR rally.cli.cliutils     return objects.Verifier.get(verifier_id)
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils     return cls(db.verifier_get(verifier_id))
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils     result = f(session, *args, **kwargs)
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils     return _verifier_get(session, verifier_id)
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils     raise exceptions.DBRecordNotFound(
2020-04-20 19:05:59.096 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:05:59.096 17 ERROR rally.cli.cliutils 
2020-04-20 19:06:00.201 19 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:06:00.201 19 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:06:00.202 19 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:06:01.638 19 INFO rally.api [-] Creating verifier 'opnfv-tempest'.
2020-04-20 19:06:01.703 19 RALLYDEBUG rally.verification.manager [-] Cloning verifier repo from /src/tempest into /root/.rally/verification/verifier-a7e885fa-0c4d-4052-8e57-13d6e66c624b/repo.
2020-04-20 19:06:01.877 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'Cloning into '/root/.rally/verification/verifier-a7e885fa-0c4d-4052-8e57-13d6e66c624b/repo'...
done.
'
2020-04-20 19:06:01.887 19 RALLYDEBUG rally.verification.utils [-] Subprocess output: 'heads/master
'
2020-04-20 19:06:01.909 19 RALLYDEBUG rally.verification.manager [-] Checking system-wide packages for verifier.
2020-04-20 19:06:01.940 19 RALLYDEBUG rally.plugins.verification.testr [-] Initializing testr.
2020-04-20 19:06:02.507 19 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=a7e885fa-0c4d-4052-8e57-13d6e66c624b) has been successfully created!
2020-04-20 19:06:03.930 26 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:06:03.930 26 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:06:03.930 26 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:06:07.430 29 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:06:07.430 29 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:06:07.430 29 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:06:09.023 29 INFO rally.api [-] Configuring verifier 'opnfv-tempest' (UUID=a7e885fa-0c4d-4052-8e57-13d6e66c624b) for deployment 'opnfv-rally' (UUID=8fac8b40-983b-498b-9170-4a00654c473d).
2020-04-20 19:06:10.063 29 INFO rally.api [-] Verifier 'opnfv-tempest' (UUID=a7e885fa-0c4d-4052-8e57-13d6e66c624b) has been successfully configured for deployment 'opnfv-rally' (UUID=8fac8b40-983b-498b-9170-4a00654c473d)!
2020-04-20 19:06:16.282 34 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:06:16.283 34 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:06:16.283 34 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:06:17.942 34 INFO rally.api [-] Starting verification (UUID=96f56697-73f3-40a0-aa16-5b4e0c1b1ec6) for deployment 'opnfv-rally' (UUID=8fac8b40-983b-498b-9170-4a00654c473d) by verifier 'opnfv-tempest' (UUID=a7e885fa-0c4d-4052-8e57-13d6e66c624b).
2020-04-20 19:06:18.459 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' from 'DEFAULT' section is not configured.
2020-04-20 19:06:18.460 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-a7e885fa-0c4d-4052-8e57-13d6e66c624b/for-deployment-8fac8b40-983b-498b-9170-4a00654c473d/tempest.log' to option 'log_file'.
2020-04-20 19:06:18.460 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'log_file' is configured. log_file = /root/.rally/verification/verifier-a7e885fa-0c4d-4052-8e57-13d6e66c624b/for-deployment-8fac8b40-983b-498b-9170-4a00654c473d/tempest.log
2020-04-20 19:06:18.460 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' from 'oslo_concurrency' section is not configured.
2020-04-20 19:06:18.461 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value '/root/.rally/verification/verifier-a7e885fa-0c4d-4052-8e57-13d6e66c624b/for-deployment-8fac8b40-983b-498b-9170-4a00654c473d/lock_files' to option 'lock_path'.
2020-04-20 19:06:18.461 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'lock_path' is configured. lock_path = /root/.rally/verification/verifier-a7e885fa-0c4d-4052-8e57-13d6e66c624b/for-deployment-8fac8b40-983b-498b-9170-4a00654c473d/lock_files
2020-04-20 19:06:18.461 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:06:18.462 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:06:18.462 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref' is already configured in Tempest config file. image_ref = 418244d4-2400-46e6-941f-df6dc5e2573b
2020-04-20 19:06:18.462 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'image_ref_alt' is already configured in Tempest config file. image_ref_alt = 4361814b-53d1-46f3-b852-cdd66a52bfca
2020-04-20 19:06:18.463 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref' is already configured in Tempest config file. flavor_ref = 0db389ba-eb16-4881-abb9-c3334abf2cf1
2020-04-20 19:06:18.463 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'flavor_ref_alt' is already configured in Tempest config file. flavor_ref_alt = 3ce35095-c795-4f2a-800f-d7d977ce7f72
2020-04-20 19:06:18.682 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Shared networks found. 'fixed_network_name' option should be configured.
2020-04-20 19:06:18.682 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'fixed_network_name' is already configured in Tempest config file. fixed_network_name = tempest_cinder-net_7bcdf1fe-2449-4355-95ef-371ef8a475a3
2020-04-20 19:06:18.683 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' from 'orchestration' section is not configured.
2020-04-20 19:06:18.756 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:06:18.825 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] The following flavor discovered: 'rally_verify_3334f681_kpDGmF69'. Using flavor 'rally_verify_3334f681_kpDGmF69' (ID = bab2efcc-3f9b-4e87-bdfb-370956c15789) for the tests.
2020-04-20 19:06:18.825 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Setting value 'bab2efcc-3f9b-4e87-bdfb-370956c15789' to option 'instance_type'.
2020-04-20 19:06:18.826 34 RALLYDEBUG rally_openstack.verification.tempest.context [-] Option 'instance_type' is configured. instance_type = bab2efcc-3f9b-4e87-bdfb-370956c15789
2020-04-20 19:06:18.827 34 INFO rally.task.context [-] Verification a7e885fa-0c4d-4052-8e57-13d6e66c624b | Context tempest@default setup()  finished in 0.74 sec
2020-04-20 19:06:18.827 34 INFO rally.task.context [-] Verification a7e885fa-0c4d-4052-8e57-13d6e66c624b | Context testr@default setup()  finished in 0.29 msec
2020-04-20 19:06:18.828 34 RALLYDEBUG rally.plugins.verification.testr [-] Test(s) started by the command: 'stestr run --subunit --concurrency 3 --load-list /tmp/b63fd69e-c0c6-4dc9-8d52-a729e898e0fb'.
2020-04-20 19:06:22.408 34 INFO opnfv-tempest [-] {2} cinder_tempest_plugin.api.volume.admin.test_consistencygroups.ConsistencyGroupsV2Test ... skip: b'Cinder consistency group feature disabled'
2020-04-20 19:06:29.062 34 INFO opnfv-tempest [-] {1} cinder_tempest_plugin.api.volume.test_create_from_image.VolumeFromImageTest.test_create_from_image_multiple ... success [5.912s]
2020-04-20 19:06:36.510 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.538s]
2020-04-20 19:06:41.980 34 INFO opnfv-tempest [-] {1} cinder_tempest_plugin.api.volume.test_volume_revert.VolumeRevertTests.test_volume_revert_to_snapshot ... success [4.097s]
2020-04-20 19:06:46.121 34 INFO opnfv-tempest [-] {1} cinder_tempest_plugin.api.volume.test_volume_revert.VolumeRevertTests.test_volume_revert_to_snapshot_after_extended_negative ... success [4.139s]
2020-04-20 19:06:48.779 34 INFO opnfv-tempest [-] {0} cinder_tempest_plugin.api.volume.test_volume_backup.VolumesBackupsTest.test_volume_snapshot_backup ... success [12.269s]
2020-04-20 19:06:55.946 34 INFO opnfv-tempest [-] {0} cinder_tempest_plugin.api.volume.test_volume_unicode.CinderUnicodeTest.test_create_delete_unicode_volume_name ... success [0.060s]
2020-04-20 19:06:59.805 34 INFO opnfv-tempest [-] {0} cinder_tempest_plugin.api.volume.test_volume_unicode.CinderUnicodeTest.test_snapshot_create_volume_description_non_ascii_code ... success [3.858s]
2020-04-20 19:07:04.754 34 INFO rally.task.context [-] Verification a7e885fa-0c4d-4052-8e57-13d6e66c624b | Context testr@default cleanup() started
2020-04-20 19:07:04.754 34 INFO rally.task.context [-] Verification a7e885fa-0c4d-4052-8e57-13d6e66c624b | Context testr@default cleanup() finished in 0.72 msec
2020-04-20 19:07:04.755 34 INFO rally.task.context [-] Verification a7e885fa-0c4d-4052-8e57-13d6e66c624b | Context tempest@default cleanup() started
2020-04-20 19:07:04.898 34 INFO rally.task.context [-] Verification a7e885fa-0c4d-4052-8e57-13d6e66c624b | Context tempest@default cleanup() finished in 0.14 sec
2020-04-20 19:07:04.917 34 INFO rally.api [-] Verification (UUID=96f56697-73f3-40a0-aa16-5b4e0c1b1ec6) has been successfully finished for deployment 'opnfv-rally' (UUID=8fac8b40-983b-498b-9170-4a00654c473d)!
2020-04-20 19:07:06.355 46 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:07:06.355 46 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:07:06.355 46 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:07:07.572 48 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:07:07.572 48 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:07:07.572 48 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:07:09.156 48 INFO rally.api [-] Building 'html' report for the following verification(s): '96f56697-73f3-40a0-aa16-5b4e0c1b1ec6'
2020-04-20 19:07:09.172 48 INFO rally.api [-] The report has been successfully built.
2020-04-20 19:07:10.496 50 RALLYDEBUG rally.api [-] INFO logs from urllib3 and requests module are hide.
2020-04-20 19:07:10.496 50 RALLYDEBUG rally.api [-] urllib3 insecure warnings are hidden.
2020-04-20 19:07:10.496 50 RALLYDEBUG rally.api [-] ERROR log from boto module is hide.
2020-04-20 19:07:12.016 50 INFO rally.api [-] Building 'junit-xml' report for the following verification(s): '96f56697-73f3-40a0-aa16-5b4e0c1b1ec6'
2020-04-20 19:07:12.017 50 INFO rally.api [-] The report has been successfully built.
