It seems that bunch of requests from contrail-analytics-api VNC api client to keystone at around 12:52:40 are missing from keystone logs.
contrail-analytics-api logs --------------------------------------
Traceback (most recent call last): File "/usr/lib/python2.7/dist-packages/bottle.py", line 856, in _handle return route.call(**args) File "/usr/lib/python2.7/dist-packages/bottle.py", line 1721, in wrapper rv = callback(*a, **ka) File "/usr/lib/python2.7/dist-packages/opserver/opserver.py", line 411, in _impl self._vnc_api_client.is_role_cloud_admin(user_token): File "/usr/lib/python2.7/dist-packages/opserver/vnc_cfg_api_client.py", line 61, in is_role_cloud_admin result = self._get_user_token_info(user_token) File "/usr/lib/python2.7/dist-packages/opserver/vnc_cfg_api_client.py", line 30, in _get_user_token_info return self._vnc_api_client.obj_perms(user_token) File "/usr/lib/python2.7/dist-packages/vnc_api/vnc_api.py", line 1208, in obj_perms del self._headers['X-USER-TOKEN'] KeyError: 'X-USER-TOKEN' 10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/uves/database-node HTTP/1.1" 500 156 0.032620 10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/uves/analytics-node HTTP/1.1" 200 10437 0.037467 Traceback (most recent call last): File "/usr/lib/python2.7/dist-packages/bottle.py", line 856, in _handle return route.call(**args) File "/usr/lib/python2.7/dist-packages/bottle.py", line 1721, in wrapper rv = callback(*a, **ka) File "/usr/lib/python2.7/dist-packages/opserver/opserver.py", line 411, in _impl self._vnc_api_client.is_role_cloud_admin(user_token): File "/usr/lib/python2.7/dist-packages/opserver/vnc_cfg_api_client.py", line 61, in is_role_cloud_admin result = self._get_user_token_info(user_token) File "/usr/lib/python2.7/dist-packages/opserver/vnc_cfg_api_client.py", line 30, in _get_user_token_info return self._vnc_api_client.obj_perms(user_token) File "/usr/lib/python2.7/dist-packages/vnc_api/vnc_api.py", line 1208, in obj_perms del self._headers['X-USER-TOKEN'] KeyError: 'X-USER-TOKEN' 10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/uves/config-node HTTP/1.1" 500 156 0.037808 10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/uves/control-node HTTP/1.1" 200 4659 0.026998 10.204.217.41 - - [2016-08-25 12:52:40] "GET /analytics/uves/virtual-networks?cfilt=UveVirtualNetworkAgent HTTP/1.1" 200 243 0.015646 Traceback (most recent call last): File "/usr/lib/python2.7/dist-packages/bottle.py", line 856, in _handle return route.call(**args) File "/usr/lib/python2.7/dist-packages/bottle.py", line 1721, in wrapper rv = callback(*a, **ka) File "/usr/lib/python2.7/dist-packages/opserver/opserver.py", line 411, in _impl self._vnc_api_client.is_role_cloud_admin(user_token): File "/usr/lib/python2.7/dist-packages/opserver/vnc_cfg_api_client.py", line 61, in is_role_cloud_admin result = self._get_user_token_info(user_token) File "/usr/lib/python2.7/dist-packages/opserver/vnc_cfg_api_client.py", line 30, in _get_user_token_info return self._vnc_api_client.obj_perms(user_token) File "/usr/lib/python2.7/dist-packages/vnc_api/vnc_api.py", line 1208, in obj_perms del self._headers['X-USER-TOKEN'] KeyError: 'X-USER-TOKEN' 10.204.217.41 - - [2016-08-25 12:52:40] "GET /analytics/queries HTTP/1.1" 500 156 0.016220
keystone logs -------------------
2016-08-25 12:52:39.487 4381 INFO eventlet.wsgi.server [req-52eae439-bb84-4291-8648-9502e4bd93c0 a8f8e869d40b4ea2a5df2c2c79388d41 c683a111eeb1423b8fb24a634f525035 - default default] 10.204.217.41 - - [25/Aug/2016 12:52:39] "GET /v3/auth/tokens HTTP/1.1" 200 6486 0.100954 2016-08-25 12:53:10.135 4380 INFO keystone.common.wsgi [req-c6a89140-29cf-44e3-b740-a03702ac7805 - - - - -] POST http://10.204.217.41:35357/v2.0/tokens
It doesn't look like keystone was restarted at that time
root@nodeg1:/var/log/contrail# supervisorctl -s unix:///var/run/supervisord_openstack.sock barbican-api FATAL Exited too quickly (process log may have details) barbican-worker RUNNING pid 2541, uptime 11:22:18 cinder-api RUNNING pid 2549, uptime 11:22:18 cinder-scheduler RUNNING pid 2653, uptime 11:22:18 glance-api RUNNING pid 2546, uptime 11:22:18 glance-registry RUNNING pid 2608, uptime 11:22:18 heat-api RUNNING pid 2638, uptime 11:22:18 heat-api-cfn RUNNING pid 2592, uptime 11:22:18 heat-engine RUNNING pid 2607, uptime 11:22:18 keystone RUNNING pid 23684, uptime 10:06:32 nova-api RUNNING pid 2538, uptime 11:22:18 nova-conductor RUNNING pid 2543, uptime 11:22:18 nova-console RUNNING pid 2568, uptime 11:22:18 nova-consoleauth RUNNING pid 2553, uptime 11:22:18 nova-novncproxy RUNNING pid 2537, uptime 11:22:18 nova-scheduler RUNNING pid 2602, uptime 11:22:18 supervisor> quit root@nodeg1:/var/log/contrail# date Thu Aug 25 23:06:28 IST 2016 root@nodeg1:/var/log/contrail#
It seems that bunch of requests from contrail- analytics- api VNC api client to keystone at around 12:52:40 are missing from keystone logs.
contrail- analytics- api logs ------- ------- ------- ------- ---
-------
Traceback (most recent call last): python2. 7/dist- packages/ bottle. py", line 856, in _handle python2. 7/dist- packages/ bottle. py", line 1721, in wrapper python2. 7/dist- packages/ opserver/ opserver. py", line 411, in _impl _vnc_api_ client. is_role_ cloud_admin( user_token) : python2. 7/dist- packages/ opserver/ vnc_cfg_ api_client. py", line 61, in is_role_cloud_admin user_token_ info(user_ token) python2. 7/dist- packages/ opserver/ vnc_cfg_ api_client. py", line 30, in _get_user_ token_info api_client. obj_perms( user_token) python2. 7/dist- packages/ vnc_api/ vnc_api. py", line 1208, in obj_perms 'X-USER- TOKEN'] uves/database- node HTTP/1.1" 500 156 0.032620 uves/analytics- node HTTP/1.1" 200 10437 0.037467 python2. 7/dist- packages/ bottle. py", line 856, in _handle python2. 7/dist- packages/ bottle. py", line 1721, in wrapper python2. 7/dist- packages/ opserver/ opserver. py", line 411, in _impl _vnc_api_ client. is_role_ cloud_admin( user_token) : python2. 7/dist- packages/ opserver/ vnc_cfg_ api_client. py", line 61, in is_role_cloud_admin user_token_ info(user_ token) python2. 7/dist- packages/ opserver/ vnc_cfg_ api_client. py", line 30, in _get_user_ token_info api_client. obj_perms( user_token) python2. 7/dist- packages/ vnc_api/ vnc_api. py", line 1208, in obj_perms 'X-USER- TOKEN'] uves/config- node HTTP/1.1" 500 156 0.037808 uves/control- node HTTP/1.1" 200 4659 0.026998 uves/virtual- networks? cfilt=UveVirtua lNetworkAgent HTTP/1.1" 200 243 0.015646 python2. 7/dist- packages/ bottle. py", line 856, in _handle python2. 7/dist- packages/ bottle. py", line 1721, in wrapper python2. 7/dist- packages/ opserver/ opserver. py", line 411, in _impl _vnc_api_ client. is_role_ cloud_admin( user_token) : python2. 7/dist- packages/ opserver/ vnc_cfg_ api_client. py", line 61, in is_role_cloud_admin user_token_ info(user_ token) python2. 7/dist- packages/ opserver/ vnc_cfg_ api_client. py", line 30, in _get_user_ token_info api_client. obj_perms( user_token) python2. 7/dist- packages/ vnc_api/ vnc_api. py", line 1208, in obj_perms 'X-USER- TOKEN']
File "/usr/lib/
return route.call(**args)
File "/usr/lib/
rv = callback(*a, **ka)
File "/usr/lib/
self.
File "/usr/lib/
result = self._get_
File "/usr/lib/
return self._vnc_
File "/usr/lib/
del self._headers[
KeyError: 'X-USER-TOKEN'
10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/
10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/
Traceback (most recent call last):
File "/usr/lib/
return route.call(**args)
File "/usr/lib/
rv = callback(*a, **ka)
File "/usr/lib/
self.
File "/usr/lib/
result = self._get_
File "/usr/lib/
return self._vnc_
File "/usr/lib/
del self._headers[
KeyError: 'X-USER-TOKEN'
10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/
10.204.217.41 - - [2016-08-25 12:52:40] "POST /analytics/
10.204.217.41 - - [2016-08-25 12:52:40] "GET /analytics/
Traceback (most recent call last):
File "/usr/lib/
return route.call(**args)
File "/usr/lib/
rv = callback(*a, **ka)
File "/usr/lib/
self.
File "/usr/lib/
result = self._get_
File "/usr/lib/
return self._vnc_
File "/usr/lib/
del self._headers[
KeyError: 'X-USER-TOKEN'
10.204.217.41 - - [2016-08-25 12:52:40] "GET /analytics/queries HTTP/1.1" 500 156 0.016220
keystone logs
-------------------
2016-08-25 12:52:39.487 4381 INFO eventlet. wsgi.server [req-52eae439- bb84-4291- 8648-9502e4bd93 c0 a8f8e869d40b4ea 2a5df2c2c79388d 41 c683a111eeb1423 b8fb24a634f5250 35 - default default] 10.204.217.41 - - [25/Aug/2016 12:52:39] "GET /v3/auth/tokens HTTP/1.1" 200 6486 0.100954 common. wsgi [req-c6a89140- 29cf-44e3- b740-a03702ac78 05 - - - - -] POST http:// 10.204. 217.41: 35357/v2. 0/tokens
2016-08-25 12:53:10.135 4380 INFO keystone.
It doesn't look like keystone was restarted at that time
root@nodeg1: /var/log/ contrail# supervisorctl -s unix:// /var/run/ supervisord_ openstack. sock /var/log/ contrail# date /var/log/ contrail#
barbican-api FATAL Exited too quickly (process log may have details)
barbican-worker RUNNING pid 2541, uptime 11:22:18
cinder-api RUNNING pid 2549, uptime 11:22:18
cinder-scheduler RUNNING pid 2653, uptime 11:22:18
glance-api RUNNING pid 2546, uptime 11:22:18
glance-registry RUNNING pid 2608, uptime 11:22:18
heat-api RUNNING pid 2638, uptime 11:22:18
heat-api-cfn RUNNING pid 2592, uptime 11:22:18
heat-engine RUNNING pid 2607, uptime 11:22:18
keystone RUNNING pid 23684, uptime 10:06:32
nova-api RUNNING pid 2538, uptime 11:22:18
nova-conductor RUNNING pid 2543, uptime 11:22:18
nova-console RUNNING pid 2568, uptime 11:22:18
nova-consoleauth RUNNING pid 2553, uptime 11:22:18
nova-novncproxy RUNNING pid 2537, uptime 11:22:18
nova-scheduler RUNNING pid 2602, uptime 11:22:18
supervisor> quit
root@nodeg1:
Thu Aug 25 23:06:28 IST 2016
root@nodeg1: