rendered paste body[root@fuel node-30.admin.private]# tail -100 neutron-metadata-agent.log
2015-03-27T01:52:53.855396+00:00 debug: 2015-03-27 01:52:53.850 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:52:53.850563'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:52:58.855378+00:00 debug: 2015-03-27 01:52:58.850 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:52:58.850695'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:03.855536+00:00 debug: 2015-03-27 01:53:03.851 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:03.850826'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:08.855797+00:00 debug: 2015-03-27 01:53:08.851 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:08.850993'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:13.855923+00:00 debug: 2015-03-27 01:53:13.851 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:13.851156'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:18.856060+00:00 debug: 2015-03-27 01:53:18.851 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:18.851304'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:23.856104+00:00 debug: 2015-03-27 01:53:23.851 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:23.851439'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:28.856317+00:00 debug: 2015-03-27 01:53:28.851 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:28.851548'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:33.856454+00:00 debug: 2015-03-27 01:53:33.851 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:33.851681'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:38.856740+00:00 debug: 2015-03-27 01:53:38.852 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:38.851849'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:43.856821+00:00 debug: 2015-03-27 01:53:43.852 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:43.852012'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:48.856842+00:00 debug: 2015-03-27 01:53:48.852 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:48.852141'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:53.857250+00:00 debug: 2015-03-27 01:53:53.852 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:53.852288'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:53:58.856936+00:00 debug: 2015-03-27 01:53:58.852 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:53:58.852412'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:03.857180+00:00 debug: 2015-03-27 01:54:03.852 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:03.852556'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:08.857453+00:00 debug: 2015-03-27 01:54:08.852 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:08.852688'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:13.857340+00:00 debug: 2015-03-27 01:54:13.853 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:13.852816'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:15.399143+00:00 info: 2015-03-27 01:54:15.395 22157 INFO eventlet.wsgi.server [-] (22157) accepted ''
2015-03-27T01:54:15.400927+00:00 debug: 2015-03-27 01:54:15.396 22157 DEBUG neutron.agent.metadata.agent [-] Request: GET / HTTP/1.0
Accept-Encoding: gzip, deflate
Content-Type: text/plain
Host: 169.254.169.254
User-Agent: Python-httplib2/0.8 (gzip)
X-Forwarded-For: 192.168.2.4
X-Neutron-Router-Id: fb133a70-27af-4783-9b8b-1597a69373ea __call__ /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:149
2015-03-27T01:54:15.402216+00:00 debug: 2015-03-27 01:54:15.398 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:15.403056+00:00 debug: 2015-03-27 01:54:15.399 22157 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'device_owner': ['network:router_interface', 'network:router_interface_distributed'], 'device_id': ['fb133a70-27af-4783-9b8b-1597a69373ea']}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:18.857631+00:00 debug: 2015-03-27 01:54:18.853 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:18.852951'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:20.480744+00:00 debug: 2015-03-27 01:54:20.476 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:20.481532+00:00 debug: 2015-03-27 01:54:20.477 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:20.481941+00:00 debug: 2015-03-27 01:54:20.478 22157 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'network_id': (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'), 'fixed_ips': {'ip_address': ['192.168.2.4']}}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:23.857723+00:00 debug: 2015-03-27 01:54:23.853 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:23.853091'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:25.544739+00:00 debug: 2015-03-27 01:54:25.540 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:25.554222+00:00 debug: 2015-03-27 01:54:25.550 22157 DEBUG neutron.agent.metadata.agent [-] {'status': '200', 'content-length': '98', 'content-location': u'http://192.168.0.2:8775/', 'connection': 'close', 'date': 'Fri, 27 Mar 2015 01:54:25 GMT', 'content-type': 'text/plain; charset=UTF-8'} _proxy_request /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:282
2015-03-27T01:54:25.555117+00:00 info: 2015-03-27 01:54:25.551 22157 INFO eventlet.wsgi.server [-] 192.168.2.4,<local> - - [27/Mar/2015 01:54:25] "GET / HTTP/1.1" 200 215 10.154708
2015-03-27T01:54:26.523601+00:00 info: 2015-03-27 01:54:26.519 22157 INFO eventlet.wsgi.server [-] (22157) accepted ''
2015-03-27T01:54:26.525468+00:00 debug: 2015-03-27 01:54:26.521 22157 DEBUG neutron.agent.metadata.agent [-] Request: GET / HTTP/1.0
Accept-Encoding: gzip, deflate
Content-Type: text/plain
Host: 169.254.169.254
User-Agent: Python-httplib2/0.8 (gzip)
X-Forwarded-For: 192.168.2.4
X-Neutron-Router-Id: fb133a70-27af-4783-9b8b-1597a69373ea __call__ /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:149
2015-03-27T01:54:26.526589+00:00 debug: 2015-03-27 01:54:26.522 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:26.527392+00:00 debug: 2015-03-27 01:54:26.523 22157 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'device_owner': ['network:router_interface', 'network:router_interface_distributed'], 'device_id': ['fb133a70-27af-4783-9b8b-1597a69373ea']}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:28.857958+00:00 debug: 2015-03-27 01:54:28.853 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:28.853248'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:31.606156+00:00 debug: 2015-03-27 01:54:31.601 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:31.607425+00:00 debug: 2015-03-27 01:54:31.603 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:31.608164+00:00 debug: 2015-03-27 01:54:31.604 22157 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'network_id': (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'), 'fixed_ips': {'ip_address': ['192.168.2.4']}}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:33.858025+00:00 debug: 2015-03-27 01:54:33.853 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:33.853395'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:36.676380+00:00 debug: 2015-03-27 01:54:36.672 22157 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:36.686709+00:00 debug: 2015-03-27 01:54:36.682 22157 DEBUG neutron.agent.metadata.agent [-] {'status': '200', 'content-length': '98', 'content-location': u'http://192.168.0.2:8775/', 'connection': 'close', 'date': 'Fri, 27 Mar 2015 01:54:36 GMT', 'content-type': 'text/plain; charset=UTF-8'} _proxy_request /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:282
2015-03-27T01:54:36.687538+00:00 info: 2015-03-27 01:54:36.683 22157 INFO eventlet.wsgi.server [-] 192.168.2.4,<local> - - [27/Mar/2015 01:54:36] "GET / HTTP/1.1" 200 215 10.162618
2015-03-27T01:54:38.858069+00:00 debug: 2015-03-27 01:54:38.853 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:38.853544'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:43.858296+00:00 debug: 2015-03-27 01:54:43.853 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:43.853671'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:48.050246+00:00 info: 2015-03-27 01:54:48.046 22145 INFO eventlet.wsgi.server [-] (22145) accepted ''
2015-03-27T01:54:48.052062+00:00 debug: 2015-03-27 01:54:48.047 22145 DEBUG neutron.agent.metadata.agent [-] Request: GET /2009-04-04/meta-data/ HTTP/1.0
Accept-Encoding: gzip, deflate
Content-Type: text/plain
Host: 169.254.169.254
User-Agent: Python-httplib2/0.8 (gzip)
X-Forwarded-For: 192.168.2.4
X-Neutron-Router-Id: fb133a70-27af-4783-9b8b-1597a69373ea __call__ /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:149
2015-03-27T01:54:48.053472+00:00 debug: 2015-03-27 01:54:48.049 22145 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:48.054618+00:00 debug: 2015-03-27 01:54:48.050 22145 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'device_owner': ['network:router_interface', 'network:router_interface_distributed'], 'device_id': ['fb133a70-27af-4783-9b8b-1597a69373ea']}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:48.858305+00:00 debug: 2015-03-27 01:54:48.854 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:48.853826'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:53.110602+00:00 debug: 2015-03-27 01:54:53.106 22145 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:53.111856+00:00 debug: 2015-03-27 01:54:53.107 22145 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:53.113195+00:00 debug: 2015-03-27 01:54:53.108 22145 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'network_id': (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'), 'fixed_ips': {'ip_address': ['192.168.2.4']}}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:53.858625+00:00 debug: 2015-03-27 01:54:53.854 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:53.853962'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:54:58.174494+00:00 debug: 2015-03-27 01:54:58.170 22145 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:54:58.814537+00:00 debug: 2015-03-27 01:54:58.810 22145 DEBUG neutron.agent.metadata.agent [-] {'status': '200', 'content-length': '244', 'content-location': u'http://192.168.0.2:8775/2009-04-04/meta-data/', 'connection': 'close', 'date': 'Fri, 27 Mar 2015 01:54:58 GMT', 'content-type': 'text/plain; charset=UTF-8'} _proxy_request /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:282
2015-03-27T01:54:58.815476+00:00 info: 2015-03-27 01:54:58.811 22145 INFO eventlet.wsgi.server [-] 192.168.2.4,<local> - - [27/Mar/2015 01:54:58] "GET /2009-04-04/meta-data/ HTTP/1.1" 200 362 10.763925
2015-03-27T01:54:58.858623+00:00 debug: 2015-03-27 01:54:58.854 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:54:58.854098'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:03.858467+00:00 debug: 2015-03-27 01:55:03.854 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:55:03.854237'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:07.904641+00:00 info: 2015-03-27 01:55:07.900 22122 INFO eventlet.wsgi.server [-] (22122) accepted ''
2015-03-27T01:55:07.908412+00:00 debug: 2015-03-27 01:55:07.903 22122 DEBUG neutron.agent.metadata.agent [-] Request: GET /2009-04-04/meta-data/instance-id HTTP/1.0
Accept-Encoding: gzip, deflate
Content-Type: text/plain
Host: 169.254.169.254
User-Agent: Python-httplib2/0.8 (gzip)
X-Forwarded-For: 192.168.2.4
X-Neutron-Router-Id: fb133a70-27af-4783-9b8b-1597a69373ea __call__ /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:149
2015-03-27T01:55:07.911175+00:00 debug: 2015-03-27 01:55:07.907 22122 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:55:07.912526+00:00 debug: 2015-03-27 01:55:07.908 22122 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'device_owner': ['network:router_interface', 'network:router_interface_distributed'], 'device_id': ['fb133a70-27af-4783-9b8b-1597a69373ea']}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:07.915391+00:00 info: 2015-03-27 01:55:07.911 22122 INFO oslo.messaging._drivers.impl_rabbit [-] Connecting to AMQP server on 192.168.0.11:5673
2015-03-27T01:55:07.930877+00:00 info: 2015-03-27 01:55:07.926 22122 INFO oslo.messaging._drivers.impl_rabbit [-] Connected to AMQP server on 192.168.0.11:5673
2015-03-27T01:55:07.945578+00:00 info: 2015-03-27 01:55:07.941 22122 INFO oslo.messaging._drivers.impl_rabbit [-] Connecting to AMQP server on 127.0.0.1:5673
2015-03-27T01:55:07.958692+00:00 info: 2015-03-27 01:55:07.954 22122 INFO oslo.messaging._drivers.impl_rabbit [-] Connected to AMQP server on 127.0.0.1:5673
2015-03-27T01:55:08.858968+00:00 debug: 2015-03-27 01:55:08.854 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:55:08.854355'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:13.025588+00:00 debug: 2015-03-27 01:55:13.021 22122 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_router_networks', 'fb133a70-27af-4783-9b8b-1597a69373ea')" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:55:13.026463+00:00 debug: 2015-03-27 01:55:13.022 22122 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:55:13.027290+00:00 debug: 2015-03-27 01:55:13.023 22122 DEBUG neutron.common.rpc [-] neutron.agent.metadata.agent.MetadataPluginAPI method call called with arguments (<neutron.context.ContextBase object at 0x336c5d0>, {'args': {'filters': {'network_id': (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'), 'fixed_ips': {'ip_address': ['192.168.2.4']}}}, 'namespace': None, 'method': 'get_ports'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:13.859080+00:00 debug: 2015-03-27 01:55:13.854 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:55:13.854500'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:18.091318+00:00 debug: 2015-03-27 01:55:18.087 22122 DEBUG neutron.openstack.common.lockutils [-] Got semaphore "('neutron.agent.metadata.agent.MetadataProxyHandler._get_ports_for_remote_address', '192.168.2.4', (u'66cc60b8-486b-4264-b0e6-d84ff8fa7401', u'05feef29-49ed-4c69-8c2e-23071b958d01'))" lock /usr/lib/python2.7/dist-packages/neutron/openstack/common/lockutils.py:168
2015-03-27T01:55:18.473852+00:00 debug: 2015-03-27 01:55:18.469 22122 DEBUG neutron.agent.metadata.agent [-] {'status': '200', 'content-length': '10', 'content-location': u'http://192.168.0.2:8775/2009-04-04/meta-data/instance-id', 'connection': 'close', 'date': 'Fri, 27 Mar 2015 01:55:18 GMT', 'content-type': 'text/plain; charset=UTF-8'} _proxy_request /usr/lib/python2.7/dist-packages/neutron/agent/metadata/agent.py:282
2015-03-27T01:55:18.475110+00:00 info: 2015-03-27 01:55:18.471 22122 INFO eventlet.wsgi.server [-] 192.168.2.4,<local> - - [27/Mar/2015 01:55:18] "GET /2009-04-04/meta-data/instance-id HTTP/1.1" 200 127 10.568263
2015-03-27T01:55:18.859096+00:00 debug: 2015-03-27 01:55:18.854 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:55:18.854665'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:23.859276+00:00 debug: 2015-03-27 01:55:23.855 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:55:23.854799'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
2015-03-27T01:55:28.859432+00:00 debug: 2015-03-27 01:55:28.855 22061 DEBUG neutron.common.rpc [-] neutron.agent.rpc.PluginReportStateAPI method cast called with arguments (<neutron.context.ContextBase object at 0x335fe10>, {'args': {'agent_state': {'agent_state': {'binary': 'neutron-metadata-agent', 'topic': 'N/A', 'host': 'node-30', 'agent_type': 'Metadata agent', 'configurations': {'nova_metadata_port': 8775, 'nova_metadata_ip': '192.168.0.2', 'metadata_proxy_socket': '/var/lib/neutron/metadata_proxy'}}}, 'time': '2015-03-27T01:55:28.854919'}, 'namespace': None, 'method': 'report_state'}) {} wrapper /usr/lib/python2.7/dist-packages/neutron/common/log.py:33
[root@fuel node-30.admin.private]#