openstack team mailing list archive

[Zhang Zhenhua <zhenhua2000@xxxxxxxxx>]

Hi Experts,

After install the Essex release, nova-network.log often shows 'Unable to
release <private ip> because vif doesn't exist'. New instance cannot be
created due to this issue.

2012-05-23 09:07:43 ERROR nova.network.manager
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Unable to release 192.168.4.36 because
vif doesn't exist.

Any clues?
Thanks!

2012-05-23 09:07:42 DEBUG nova.rpc.amqp [-] received {u'_context_roles':
[u'anotherrole', u'Member', u'admin'], u'_msg_id':
u'e41350f4da46452392877e297d6287eb', u'_context_read_deleted': u'no',
u'_context_request_id': u'req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d',
u'args': {u'instance_id': 5, u'instance_uuid':
u'c063f783-f4fd-4fcb-a2b0-38ccd0f10c50', u'host': u'Essex-233',
u'project_id': u'44431b6ced994b0ca70a5bdcd5993b43', u'rxtx_factor': 1.0},
u'_context_auth_token': '<SANITIZED>', u'_context_is_admin': True,
u'_context_project_id': u'44431b6ced994b0ca70a5bdcd5993b43',
u'_context_timestamp': u'2012-05-23T13:07:42.589802', u'_context_user_id':
u'd5783c018aaa4a27b0130e343234b59e', u'method': u'get_instance_nw_info',
u'_context_remote_address': u'10.74.25.232'} from (pid=3573) _safe_log
/usr/lib/python2.7/dist-packages/nova/rpc/common.py:160
2012-05-23 09:07:42 DEBUG nova.rpc.amqp
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] unpacked context: {'user_id':
u'd5783c018aaa4a27b0130e343234b59e', 'roles': [u'anotherrole', u'Member',
u'admin'], 'timestamp': '2012-05-23T13:07:42.589802', 'auth_token':
'<SANITIZED>', 'remote_address': u'10.74.25.232', 'is_admin': True,
'request_id': u'req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d', 'project_id':
u'44431b6ced994b0ca70a5bdcd5993b43', 'read_deleted': u'no'} from (pid=3573)
_safe_log /usr/lib/python2.7/dist-packages/nova/rpc/common.py:160
2012-05-23 09:07:42 DEBUG nova.rpc.amqp [-] received {u'_context_roles':
[u'anotherrole', u'Member', u'admin'], u'_context_request_id':
u'req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d', u'_context_read_deleted':
u'yes', u'args': {u'host': u'Essex-232', u'address': u'192.168.4.36'},
u'_context_auth_token': '<SANITIZED>', u'_context_is_admin': True,
u'_context_project_id': u'44431b6ced994b0ca70a5bdcd5993b43',
u'_context_timestamp': u'2012-05-23T13:07:42.589802', u'_context_user_id':
u'd5783c018aaa4a27b0130e343234b59e', u'method': u'deallocate_fixed_ip',
u'_context_remote_address': u'10.74.25.232'} from (pid=3573) _safe_log
/usr/lib/python2.7/dist-packages/nova/rpc/common.py:160
2012-05-23 09:07:42 DEBUG nova.rpc.amqp
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] unpacked context: {'user_id':
u'd5783c018aaa4a27b0130e343234b59e', 'roles': [u'anotherrole', u'Member',
u'admin'], 'timestamp': '2012-05-23T13:07:42.589802', 'auth_token':
'<SANITIZED>', 'remote_address': u'10.74.25.232', 'is_admin': True,
'request_id': u'req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d', 'project_id':
u'44431b6ced994b0ca70a5bdcd5993b43', 'read_deleted': u'yes'} from
(pid=3573) _safe_log /usr/lib/python2.7/dist-packages/nova/rpc/common.py:160
2012-05-23 09:07:42 DEBUG nova.utils
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Attempting to grab semaphore "get_dhcp"
for method "_get_dhcp_ip"... from (pid=3573) inner
/usr/lib/python2.7/dist-packages/nova/utils.py:927
2012-05-23 09:07:42 DEBUG nova.utils
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Got semaphore "get_dhcp" for method
"_get_dhcp_ip"... from (pid=3573) inner
/usr/lib/python2.7/dist-packages/nova/utils.py:931
2012-05-23 09:07:42 DEBUG nova.utils
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Attempting to grab semaphore
"dnsmasq_start" for method "restart_dhcp"... from (pid=3573) inner
/usr/lib/python2.7/dist-packages/nova/utils.py:927
2012-05-23 09:07:42 DEBUG nova.utils
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Got semaphore "dnsmasq_start" for method
"restart_dhcp"... from (pid=3573) inner
/usr/lib/python2.7/dist-packages/nova/utils.py:931
2012-05-23 09:07:42 DEBUG nova.utils
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Running cmd (subprocess): cat
/proc/3490/cmdline from (pid=3573) execute
/usr/lib/python2.7/dist-packages/nova/utils.py:219
2012-05-23 09:07:43 DEBUG nova.utils
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Running cmd (subprocess): sudo
nova-rootwrap kill -HUP 3490 from (pid=3573) execute
/usr/lib/python2.7/dist-packages/nova/utils.py:219
2012-05-23 09:07:43 ERROR nova.network.manager
[req-ee622237-4a08-4d43-bf18-5ae93d7f3b9d d5783c018aaa4a27b0130e343234b59e
44431b6ced994b0ca70a5bdcd5993b43] Unable to release 192.168.4.36 because
vif doesn't exist.
2012-05-23 09:08:42 DEBUG nova.manager [-] Running periodic task
FlatDHCPManager._publish_service_capabilities from (pid=3573)
periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152