Problem registering VIM in tacker

[Miguel Angel Muñoz]

When running tacker in OPNFV XCI (Pike) and after activating the venv for tacker in controller node I try to register the VIM and this is what I get:

Unable to find key file for VIM 5ab35b51-f6c9-4072-8780-ffff5fda2d85

This is my vim.config.yaml:

auth_url: 'https://192.168.122.3:5000/v3'
username: 'admin'
password: 'opnfv-secret-password'
project_name: 'admin'
project_domain_name: 'Default'
user_domain_name: 'Default'
cert_verify: 'False'

and this the cmd I use to register:

tacker vim-register --is-default --config-file vim-config.yaml --description 'my first vim' hellovim

It seems that I don't have /etc/tacker/fernet-keys file.

Logs from tacker show that there are no permissions to create the file:

2018-02-28 10:30:48.550 2727 INFO tacker.common.config [-] Logging enabled!
2018-02-28 10:30:48.553 2727 WARNING oslo_config.cfg [-] Option "lock_path" from group "DEFAULT" is deprecated. Use option "lock_path" from group "oslo_concurrency".
2018-02-28 10:30:48.556 2727 INFO tacker.common.config [-] Config paste file: /openstack/venvs/tacker-16.0.9/etc/tacker/api-paste.ini
2018-02-28 10:30:48.769 2727 INFO tacker.api.extensions [-] Initializing extension manager.
2018-02-28 10:30:48.779 2727 INFO tacker.api.extensions [-] Loaded extension: Commonservices
2018-02-28 10:30:48.785 2727 INFO tacker.api.extensions [-] Loaded extension: NFVO
2018-02-28 10:30:48.789 2727 INFO tacker.api.extensions [-] Loaded extension: VNFM
2018-02-28 10:30:48.790 2727 INFO tacker.manager [-] Loading Plugin: vnfm
2018-02-28 10:30:48.897 2727 INFO tosca.model [-] nfv
2018-02-28 10:30:48.897 2727 INFO tosca.model [-] tosca_simple_profile_for_nfv_1_0_0.py
2018-02-28 10:30:48.931 2727 INFO tosca.model [-] nfv
2018-02-28 10:30:48.931 2727 INFO tosca.model [-] tosca_simple_profile_for_nfv_1_0_0.py
2018-02-28 10:30:49.130 2727 INFO tacker.common.driver_manager [-] Registered drivers from tacker.tacker.mgmt.drivers: ['noop', 'openwrt']
2018-02-28 10:30:49.193 2727 INFO tacker.common.driver_manager [-] Registered drivers from tacker.tacker.vnfm.drivers: ['openstack', 'noop']
2018-02-28 10:30:49.199 2727 INFO tacker.common.driver_manager [-] Registered drivers from tacker.tacker.policy.actions: ['log_and_kill', 'autoscaling', 'log', 'respawn']
2018-02-28 10:30:49.202 2727 INFO tacker.common.driver_manager [-] Registered drivers from tacker.tacker.monitor.drivers: ['http_ping', 'ping']
2018-02-28 10:30:49.205 2727 INFO tacker.common.driver_manager [-] Registered drivers from tacker.tacker.alarm_monitor.drivers: ['ceilometer']
2018-02-28 10:30:49.205 2727 INFO tacker.manager [-] Loading Plugin: nfvo
2018-02-28 10:30:49.287 2727 INFO tacker.vnfm.keystone [-] [fernet_tokens] key_repository does not appear to exist; attempting to create it
2018-02-28 10:30:49.287 2727 ERROR tacker.vnfm.keystone [-] Failed to create [fernet_tokens] key_repository: eitherit already exists or you don't have sufficientpermissions to create it: OSError: [Errno 13] Permission denied: '/etc/tacker'
2018-02-28 10:30:49.287 2727 INFO tacker.common.driver_manager [-] Registered drivers from tacker.nfvo.vim.drivers: ['openstack']
2018-02-28 10:30:49.287 2727 INFO tacker.manager [-] Loading Plugin: commonservices
2018-02-28 10:30:49.298 2727 WARNING keystonemiddleware._common.config [-] The option "__file__" in conf is not known to auth_token
2018-02-28 10:30:49.298 2727 WARNING keystonemiddleware._common.config [-] The option "here" in conf is not known to auth_token
2018-02-28 10:30:49.298 2727 WARNING keystonemiddleware.auth_token [-] AuthToken middleware is set with keystone_authtoken.service_token_roles_required set to False. This is backwards compatible but deprecated behaviour. Please set this to True.
2018-02-28 10:30:49.301 2727 WARNING oslo_config.cfg [-] Option "signing_dir" from group "keystone_authtoken" is deprecated for removal (PKI token format is no longer supported.). Its value may be silently ignored in the future.
2018-02-28 10:30:49.301 2727 WARNING keystonemiddleware.auth_token [-] signing_dir mode is 0755 instead of 0700
2018-02-28 10:30:49.306 2727 INFO tacker.service [-] Tacker service started, listening on 0.0.0.0:9890
2018-02-28 10:30:49.306 2727 INFO tacker.wsgi [-] (2727) wsgi starting up on http://0.0.0.0:9890
2018-02-28 10:30:56.373 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:30:56] "OPTIONS / HTTP/1.0" 200 250 0.001273
2018-02-28 10:31:08.374 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:31:08] "OPTIONS / HTTP/1.0" 200 250 0.000619
2018-02-28 10:31:20.377 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:31:20] "OPTIONS / HTTP/1.0" 200 250 0.000660
2018-02-28 10:31:32.380 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:31:32] "OPTIONS / HTTP/1.0" 200 250 0.000700
2018-02-28 10:31:44.383 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:31:44] "OPTIONS / HTTP/1.0" 200 250 0.000453
2018-02-28 10:31:56.385 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:31:56] "OPTIONS / HTTP/1.0" 200 250 0.000692
2018-02-28 10:32:08.388 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:32:08] "OPTIONS / HTTP/1.0" 200 250 0.000609
2018-02-28 10:32:20.390 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [28/Feb/2018 10:32:20] "OPTIONS / HTTP/1.0" 200 250 0.000655

[...]

2018-03-15 15:39:37.129 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:39:37] "OPTIONS / HTTP/1.0" 200 250 0.000596
2018-03-15 15:39:49.131 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:39:49] "OPTIONS / HTTP/1.0" 200 250 0.000859
2018-03-15 15:40:01.133 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:40:01] "OPTIONS / HTTP/1.0" 200 250 0.000718
2018-03-15 15:40:13.134 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:40:13] "OPTIONS / HTTP/1.0" 200 250 0.000429
2018-03-15 15:40:25.136 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:40:25] "OPTIONS / HTTP/1.0" 200 250 0.000782
2018-03-15 15:40:37.137 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:40:37] "OPTIONS / HTTP/1.0" 200 250 0.000779
2018-03-15 15:40:49.141 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:40:49] "OPTIONS / HTTP/1.0" 200 250 0.000905
2018-03-15 15:41:01.144 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:41:01] "OPTIONS / HTTP/1.0" 200 250 0.000712
2018-03-15 15:41:05.650 2727 WARNING tacker.nfvo.drivers.vim.openstack_driver [req-843cf8b3-8555-4edd-935a-64a4e1d8565d 27b1bf98a33446739198bc47a748207b 4eedea01f1494cf4acddca4221278a35 - - -] VIM key deletion failed for vim 796220ce-eb46-4be2-b103-e6487daeed14: OSError: [Errno 2] No such file or directory: '/etc/tacker/vim/fernet_keys/796220ce-eb46-4be2-b103-e6487daeed14'
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource [req-843cf8b3-8555-4edd-935a-64a4e1d8565d 27b1bf98a33446739198bc47a748207b 4eedea01f1494cf4acddca4221278a35 - - -] create failed: No details.: VimKeyNotFoundException: Unable to find key file for VIM 796220ce-eb46-4be2-b103-e6487daeed14
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource Traceback (most recent call last):
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/api/v1/resource.py", line 77, in resource
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource result = method(request=request, **args)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/api/v1/base.py", line 393, in create
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource obj = obj_creator(request.context, **kwargs)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/common/log.py", line 35, in wrapper
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource return method(*args, **kwargs)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/nfvo/nfvo_plugin.py", line 121, in create_vim
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource auth=vim_obj['auth_cred'])
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/oslo_utils/excutils.py", line 220, in __exit__
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource self.force_reraise()
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/oslo_utils/excutils.py", line 196, in force_reraise
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource six.reraise(self.type_, self.value, self.tb)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/nfvo/nfvo_plugin.py", line 113, in create_vim
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource vim_obj=vim_obj)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/common/driver_manager.py", line 70, in invoke
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource return getattr(driver, method_name)(**kwargs)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/common/log.py", line 35, in wrapper
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource return method(*args, **kwargs)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/nfvo/drivers/vim/openstack_driver.py", line 207, in register_vim
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource self.encode_vim_auth(context, vim_obj['id'], vim_obj['auth_cred'])
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/common/log.py", line 35, in wrapper
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource return method(*args, **kwargs)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource File "/openstack/venvs/tacker-16.0.9/lib/python2.7/site-packages/tacker/nfvo/drivers/vim/openstack_driver.py", line 287, in encode_vim_auth
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource raise nfvo.VimKeyNotFoundException(vim_id=vim_id)
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource VimKeyNotFoundException: Unable to find key file for VIM 796220ce-eb46-4be2-b103-e6487daeed14
2018-03-15 15:41:05.651 2727 ERROR tacker.api.v1.resource
2018-03-15 15:41:05.653 2727 INFO tacker.wsgi [req-843cf8b3-8555-4edd-935a-64a4e1d8565d 27b1bf98a33446739198bc47a748207b 4eedea01f1494cf4acddca4221278a35 - - -] 10.0.3.131,172.29.236.11 - - [15/Mar/2018 15:41:05] "POST /v1.0/vims.json HTTP/1.1" 500 362 0.440024
2018-03-15 15:41:13.147 2727 INFO tacker.wsgi [-] 172.29.236.11 - - [15/Mar/2018 15:41:13] "OPTIONS / HTTP/1.0" 200 250 0.000760