OpenStack Compute (nova)

AttributeError: 'dict' object has no attribute 'name' Error While launching instance on VMWare ESXi 5.1

Asked by Srikanth Mergu on 2013-02-04

Hello Everyone,

I have done with setting up controller node on Centos 6.x
All services are running fine on the controller node, Below is the output of nova-manage service list
***********************************************************************
[root@806B6 ~]# nova-manage service list
2013-02-04 08:41:25 DEBUG nova.utils [req-f3158cc1-99b5-4745-b5c4-032e549ed615 None None] backend <module 'nova.db.sqlalchemy.api' from '/usr/lib/python2.6/site-packages/nova/db/sqlalchemy/api.pyc'> __get_backend /usr/lib/python2.6/site-packages/nova/utils.py:502
Binary Host Zone Status State Updated_At
nova-network controllernode nova enabled :-) 2013-02-04 08:41:24
nova-volume controllernode nova enabled :-) 2013-02-04 08:41:24
nova-cert controllernode nova enabled :-) 2013-02-04 08:41:24
nova-console controllernode nova enabled :-) 2013-02-04 08:41:23
nova-consoleauth controllernode nova enabled :-) 2013-02-04 08:41:24
nova-scheduler controllernode nova enabled :-) 2013-02-04 08:41:24
nova-compute controllernode nova enabled :-) 2013-02-04 08:41:24
***********************************************************************

When i am trying to launch the instance from dashboard, after networking it is going into Error state permanently.

Below is my current configuration on controller node.
nova.conf
************************************************************************
[DEFAULT]

# LOGS/STATE
debug = True
# verbose = True

# AUTHENTICATION
auth_strategy = keystone

# SCHEDULER
# compute_scheduler_driver = nova.scheduler.filter_scheduler.FilterScheduler
scheduler_driver=nova.scheduler.simple.SimpleScheduler

# VOLUMES
volume_group = nova-volumes
# volume_name_template = volume-%08x
iscsi_helper = tgtadm
root_helper = sudo

# DATABASE
sql_connection = mysql://nova:password@localhost/nova

# COMPUTE
# connection_type = vsphereapi_conn
connection_type = vmwareapi
# libvirt_type = vmwareapi
# compute_driver = vmwareapi.VMWareESXDriver
# enabled_apis=ec2,osapi_compute,osapi_volume,metadata
# vmware_vif_driver = nova.virt.vmwareapi.vif.VMwareVlanBridgeDriver
# vmware_api_retry_count = 10
# vmwareapi_host_ip = xxx.xxx.x.xx
vmwareapi_host_ip = 172.23.48.130
vmwareapi_host_username = root
vmwareapi_host_password = password
vmwareapi_wsdl_loc = http://127.0.0.1:8080/vmware/SDK/wsdl/vim25/vimService.wsdl
# console_manager = nova.console.vmrc_manager.ConsoleVMRCManager
# vmwareapi_task_poll_interval = 5.0
# network_driver = nova.network.vmwareapi_net
vmwareapi_vlan_interface = vmnic1

# libvirt_type = qemu
# connection_type = libvirt
# instance_name_template = instance-%08x
api_paste_config = /etc/nova/api-paste.ini
# allow_resize_to_same_host = True

# APIS
osapi_compute_extension = nova.api.openstack.compute.contrib.standard_extensions
ec2_dmz_host = 127.0.0.1
s3_host = 127.0.0.1

# RABBITMQ
rabbit_host = 127.0.0.1

# GLANCE
image_service = nova.image.glance.GlanceImageService
glance_api_servers = 127.0.0.1:9292

# NOVNC CONSOLE
novncproxy_base_url = http://127.0.0.1:6080/vnc_auto.html
vncserver_proxyclient_address = 127.0.0.1
vncserver_listen = 127.0.0.1

logdir = /var/log/nova
state_path = /var/lib/nova
lock_path = /var/lib/nova/tmp
volumes_dir = /etc/nova/volumes
dhcpbridge = /usr/bin/nova-dhcpbridge
injected_network_template = /usr/share/nova/interfaces.template
#libvirt_nonblocking = True
#libvirt_inject_partition = -1
iscsi_helper = tgtadm
# compute_driver = libvirt.LibvirtDriver
rpc_backend = nova.openstack.common.rpc.impl_qpid
rootwrap_config = /etc/nova/rootwrap.conf

# NETWORK
network_manager = nova.network.manager.FlatDHCPManager
force_dhcp_release = True
dhcpbridge_flagfile = /etc/nova/nova.conf
dhcpbridge = /usr/bin/nova-dhcpbridge
state_path = /var/lib/nova
# firewall_driver = nova.virt.libvirt.firewall.IptablesFirewallDriver
my_ip = 127.0.0.1
public_interface = eth0
flat_network_bridge = br100
flat_interface = eth0
# routing_source_ip = 192.168.1.1
# fixed_range = 10.0.0.0/24

[keystone_authtoken]
admin_tenant_name = service
admin_user = nova
admin_password = password
auth_host = 127.0.0.1
auth_port = 35357
auth_protocol = http
signing_dir = /tmp/keystone-signing-nova
**********************************************************************

ifconfig
**********************************************************************
[root@806B6 ~]# ifconfig
br100 Link encap:Ethernet HWaddr 00:25:90:9A:ED:72
          inet addr:192.168.100.3 Bcast:192.168.100.255 Mask:255.255.255.0
          inet6 addr: fe80::7876:c7ff:fe36:df03/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
          RX packets:39011 errors:0 dropped:0 overruns:0 frame:0
          TX packets:9674 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:4196730 (4.0 MiB) TX bytes:1852818 (1.7 MiB)

eth0 Link encap:Ethernet HWaddr 00:25:90:9A:ED:72
          inet6 addr: fe80::225:90ff:fe9a:ed72/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
          RX packets:40005 errors:0 dropped:0 overruns:0 frame:0
          TX packets:10135 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:4906693 (4.6 MiB) TX bytes:1892296 (1.8 MiB)
          Memory:df980000-dfa00000

eth1 Link encap:Ethernet HWaddr 00:25:90:9A:ED:73
          inet addr:209.XXX.X.XXX Bcast:209.188.9.175 Mask:255.255.255.248
          inet6 addr: fe80::225:90ff:fe9a:ed73/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
          RX packets:9134 errors:0 dropped:0 overruns:0 frame:0
          TX packets:3375 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:1066923 (1.0 MiB) TX bytes:1262266 (1.2 MiB)
          Memory:df900000-df980000

lo Link encap:Local Loopback
          inet addr:127.0.0.1 Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING MTU:16436 Metric:1
          RX packets:58551 errors:0 dropped:0 overruns:0 frame:0
          TX packets:58551 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:8012606 (7.6 MiB) TX bytes:8012606 (7.6 MiB)

virbr0 Link encap:Ethernet HWaddr 52:54:00:A2:A6:7A
          inet addr:192.168.122.1 Bcast:192.168.122.255 Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:472 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:0 (0.0 b) TX bytes:24218 (23.6 KiB)
************************************************************************
Also i have configured bridge network with name "br100" on vmware ESXi server.
************************************************************************

Compute Logs
XXX.XXX.X.XXX -- my controller node public IP
XXX.XXX.X.XX -- My VMWare ESXi public IP
**************************************************************************
2013-02-04 08:18:21 9750 INFO nova.compute.manager [-] Loading compute driver 'nova.virt.connection.get_connection'
2013-02-04 08:18:21 9750 WARNING nova.common.deprecated [-] Deprecated Config: Specifying virt driver via connection_type is deprecated. Use compute_driver=classname instead.
2013-02-04 08:18:22 9764 INFO nova.compute.manager [-] Loading compute driver 'nova.virt.connection.get_connection'
2013-02-04 08:18:22 9764 WARNING nova.common.deprecated [-] Deprecated Config: Specifying virt driver via connection_type is deprecated. Use compute_driver=classname instead.
2013-02-04 08:18:23 9778 INFO nova.compute.manager [-] Loading compute driver 'nova.virt.connection.get_connection'
2013-02-04 08:18:23 9778 WARNING nova.common.deprecated [-] Deprecated Config: Specifying virt driver via connection_type is deprecated. Use compute_driver=classname instead.
2013-02-04 08:18:27 9778 DEBUG nova.service [-] Full set of FLAGS: wait /usr/lib/python2.6/site-packages/nova/service.py:179
2013-02-04 08:18:27 9778 DEBUG nova.service [-] bandwidth_poll_interval : 600 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] storage_availability_zone : nova wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] num_networks : 1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ca_file : cacert.pem wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sql_connection_debug : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] compute_stats_class : nova.compute.stats.Stats wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] fixed_range : 10.0.0.0/8 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] compute_topic : compute wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] glance_port : 9292 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] glance_api_servers : ['127.0.0.1:9292'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] flat_interface : eth0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_compute_listen_port : 8774 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] user_cert_subject : /C=US/ST=California/O=OpenStack/OU=NovaDev/CN=%.16s-%.16s-%s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_ram : 51200 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_compute_listen : 0.0.0.0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] cache_images : all wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] aws_access_key_id : FLAG SET wait /usr/lib/python2.6/site-packages/nova/service.py:186
2013-02-04 08:18:27 9778 DEBUG nova.service [-] send_arp_for_ha : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] backdoor_port : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] max_age : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] network_size : 256 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] host_state_interval : 120 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] network_device_mtu : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] enable_new_services : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] my_ip : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_security_groups : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] live_migration_retry_count : 30 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_dmz_host : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] password_length : 12 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] security_group_handler : nova.network.sg.NullSecurityGroupHandler wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] public_interface : eth0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_schedule_zone : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_injected_file_path_bytes : 255 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] logdir : /var/log/nova wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_volume_listen : 0.0.0.0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sqlite_db : nova.sqlite wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_compute_extension : ['nova.api.openstack.compute.contrib.standard_extensions'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] allow_resize_to_same_host : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] reclaim_instance_interval : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] rootwrap_config : /etc/nova/rootwrap.conf wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] notification_driver : [] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_max_limit : 1000 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] enable_instance_password : FLAG SET wait /usr/lib/python2.6/site-packages/nova/service.py:186
2013-02-04 08:18:27 9778 DEBUG nova.service [-] compute_api_class : nova.compute.api.API wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] fixed_range_v6 : fd00::/48 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] dns_server : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] base_dir_name : _base wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_access_ip_network_name : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_publisher_id : 806B6.Securedservers.com wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] resume_guests_state_on_host_boot : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] dhcp_lease_time : 120 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_listen : 0.0.0.0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] logfile : /var/log/nova/compute.log wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] floating_ip_dns_manager : nova.network.dns_driver.DNSDriver wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] send_arp_for_ha_count : 3 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] snapshot_name_template : snapshot-%s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmwareapi_host_username : root wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] resize_confirm_window : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] metadata_host : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_cores : 20 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_driver : nova.quota.DbQuotaDriver wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] debug : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] use_project_ca : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_volume_workers : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] policy_file : policy.json wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] volume_topic : volume wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] dnsmasq_config_file : wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_glance_link_prefix : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmwareapi_wsdl_loc : http://127.0.0.1:8080/vmware/SDK/wsdl/vim25/vimService.wsdl wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] volume_name_template : volume-%s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] lock_path : /var/lib/nova/tmp wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] rpc_cast_timeout : 30 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] state_path : /var/lib/nova wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] until_refresh : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] flat_network_dns : 8.8.4.4 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] config_dir : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] log_config : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] use_stderr : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] connection_type : vmwareapi wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] fatal_deprecations : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] s3_port : 3333 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] logfile_mode : 0644 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_ephemeral_format : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] logging_context_format_string : %(asctime)s %(levelname)s %(name)s [%(request_id)s %(user_id)s %(project_id)s] %(instance)s%(message)s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] metadata_listen_port : 8775 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] reserved_host_disk_mb : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] isolated_hosts : [] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_name_template : instance-%08x wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_host : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_usage_audit_period : month wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] log_dir : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] cert_manager : nova.cert.manager.CertManager wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_metadata_items : 128 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] logging_debug_format_suffix : %(funcName)s %(pathname)s:%(lineno)d wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] network_host : 806B6.Securedservers.com wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] console_manager : nova.console.manager.ConsoleProxyManager wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] zombie_instance_updated_at_window : 172800 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] dhcp_domain : novalocal wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] rpc_backend : nova.openstack.common.rpc.impl_qpid wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_log_levels : ['amqplib=WARN', 'sqlalchemy=WARN', 'boto=WARN', 'suds=INFO', 'keystone=INFO', 'eventlet.wsgi.server=WARN'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] running_deleted_instance_poll_interval : 30 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_volume_extension : ['nova.api.openstack.volume.contrib.standard_extensions'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] heal_instance_info_cache_interval : 60 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sql_connection : FLAG SET wait /usr/lib/python2.6/site-packages/nova/service.py:186
2013-02-04 08:18:27 9778 DEBUG nova.service [-] console_topic : console wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instances_path : /var/lib/nova/instances wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] flat_injected : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] api_rate_limit : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] use_local_volumes : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] host : 806B6.Securedservers.com wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_dns_domain : wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] non_inheritable_image_properties : ['cache_in_nova', 'instance_uuid', 'user_id', 'image_type', 'backup_type', 'min_ram', 'min_disk'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_injected_file_content_bytes : 10240 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] running_deleted_instance_action : log wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] console_host : 806B6.Securedservers.com wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] key_file : private/cakey.pem wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] floating_range : 4.4.4.0/24 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] reboot_timeout : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] multi_host : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] glance_num_retries : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] db_backend : sqlalchemy wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] image_cache_manager_interval : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sql_retry_interval : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmwareapi_task_poll_interval : 5.0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] volume_driver : nova.volume.driver.ISCSIDriver wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] reservation_expire : 86400 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] networks_path : /var/lib/nova/networks wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] crl_file : crl.pem wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] gateway_v6 : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] monkey_patch : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] rpc_conn_pool_size : 30 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] s3_host : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_compute_workers : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] routing_source_ip : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sqlite_synchronous : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmwareapi_host_ip : 172.23.48.130 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] glance_api_insecure : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmwareapi_host_password : FLAG SET wait /usr/lib/python2.6/site-packages/nova/service.py:186
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vlan_interface : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] linuxnet_ovs_integration_bridge : br-int wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] volume_force_update_capabilities : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] scheduler_topic : scheduler wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] verbose : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] flat_network_bridge : br100 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sql_max_retries : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] linuxnet_interface_driver : nova.network.linux_net.LinuxBridgeInterfaceDriver wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_instance_type : m1.small wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] metadata_listen : 0.0.0.0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] periodic_fuzzy_delay : 60 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] metadata_workers : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] periodic_interval : 60 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vpn_key_suffix : FLAG SET wait /usr/lib/python2.6/site-packages/nova/service.py:186
2013-02-04 08:18:27 9778 DEBUG nova.service [-] use_cow_images : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_volume_listen_port : 8776 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] gateway : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] null_kernel : nokernel wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_floating_pool : nova wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] notify_on_any_change : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_key_pairs : FLAG SET wait /usr/lib/python2.6/site-packages/nova/service.py:186
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_format : [instance: %(uuid)s] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_compute_link_prefix : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] rpc_response_timeout : 60 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_notification_level : INFO wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_compute_ext_list : [] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] defer_iptables_apply : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] publish_errors : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] consoleauth_topic : consoleauth wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] service_down_time : 60 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] logging_exception_prefix : %(asctime)s %(process)d TRACE %(name)s %(instance)s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] enabled_apis : ['ec2', 'osapi_compute', 'osapi_volume', 'metadata'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] volume_api_class : nova.volume.api.API wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmwareapi_vlan_interface : vmnic1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] report_interval : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] scheduler_manager : nova.scheduler.manager.SchedulerManager wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_port : 8773 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] monkey_patch_modules : ['nova.api.ec2.cloud:nova.notifier.api.notify_decorator', 'nova.compute.api:nova.notifier.api.notify_decorator'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] rpc_thread_pool_size : 64 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_volumes : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_listen_port : 8773 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_scheme : http wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] keys_path : /var/lib/nova/keys wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vpn_image_id : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] disable_process_locking : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] allowed_rpc_exception_modules : ['nova.openstack.common.exception', 'nova.exception', 'cinder.exception'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] auto_assign_floating_ip : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] cert_topic : cert wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_floating_ips : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] fake_call : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] snapshot_same_host : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_dns_manager : nova.network.dns_driver.DNSDriver wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sql_idle_timeout : 3600 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] glance_host : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] default_image : ami-11111 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] aws_secret_access_key : FLAG SET wait /usr/lib/python2.6/site-packages/nova/service.py:186
2013-02-04 08:18:27 9778 DEBUG nova.service [-] use_ipv6 : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] log_format : %(asctime)s %(levelname)8s [%(name)s] %(message)s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] fake_network : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] metadata_port : 8775 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] force_dhcp_release : True wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] dhcpbridge_flagfile : /etc/nova/nova.conf wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_gigabytes : 1000 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] region_list : [] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] dhcpbridge : /usr/bin/nova-dhcpbridge wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vpn_start : 1000 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_workers : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] running_deleted_instance_timeout : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] network_manager : nova.network.manager.FlatDHCPManager wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] reserved_host_memory_mb : 512 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] root_helper : sudo wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmware_vif_driver : nova.virt.vmwareapi.vif.VMWareVlanBridgeDriver wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] memcached_servers : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] compute_driver : nova.virt.connection.get_connection wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_volume_ext_list : [] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] logging_default_format_string : %(asctime)s %(process)d %(levelname)s %(name)s [-] %(instance)s%(message)s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] fake_rabbit : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] log_date_format : %Y-%m-%d %H:%M:%S wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_usage_audit : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] rescue_timeout : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ca_path : /var/lib/nova/CA wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] dmz_cidr : [] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] fixed_ip_disassociate_timeout : 600 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] use_syslog : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] sql_connection_trace : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] osapi_path : /v1.1/ wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] syslog_log_facility : LOG_USER wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ec2_path : /services/Cloud wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vmwareapi_api_retry_count : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vpn_ip : 127.0.0.1 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] bindir : /usr/lib/python2.6/site-packages/bin wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] auth_strategy : keystone wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] config_file : ['/etc/nova/nova.conf'] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] network_api_class : nova.network.api.API wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] node_availability_zone : nova wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] claim_timeout_seconds : 600 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_uuid_format : [instance: %(uuid)s] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] db_driver : nova.db wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] create_unique_mac_address_attempts : 5 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] volume_manager : nova.volume.manager.VolumeManager wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] start_guests_on_host_boot : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] pybasedir : /usr/lib/python2.6/site-packages wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] vlan_start : 100 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] metadata_manager : nova.api.manager.MetadataManager wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] ipv6_backend : rfc2462 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] l3_lib : nova.network.l3.LinuxNetL3 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] isolated_images : [] wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] api_paste_config : /etc/nova/api-paste.ini wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_security_group_rules : 20 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] notify_on_state_change : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] network_driver : nova.network.linux_net wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_instances : 10 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] project_cert_subject : /C=US/ST=California/O=OpenStack/OU=NovaDev/CN=project-ca-%.16s-%s wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] policy_default_rule : default wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] use_single_default_gateway : False wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] control_exchange : nova wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] cnt_vpn_clients : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] quota_injected_files : 5 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] instance_build_timeout : 0 wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] log_file : None wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] compute_manager : nova.compute.manager.ComputeManager wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 DEBUG nova.service [-] network_topic : network wait /usr/lib/python2.6/site-packages/nova/service.py:188
2013-02-04 08:18:27 9778 AUDIT nova.service [-] Starting compute node (version 2012.2.2-1.el6)
2013-02-04 08:18:27 DEBUG nova.utils [req-f1965235-399c-4ae4-b9a4-089e93ac6019 None None] backend <module 'nova.db.sqlalchemy.api' from '/usr/lib/python2.6/site-packages/nova/db/sqlalchemy/api.pyc'> __get_backend /usr/lib/python2.6/site-packages/nova/utils.py:502
2013-02-04 08:18:28 9778 DEBUG nova.utils [-] Got semaphore "compute_resources" for method "update_available_resource"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:18:28 9778 AUDIT nova.compute.resource_tracker [-] Virt driver does not support 'get_available_resource' Compute tracking is disabled.
2013-02-04 08:18:28 9778 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
2013-02-04 08:18:28 9778 DEBUG nova.service [-] Creating Consumer connection for Service compute start /usr/lib/python2.6/site-packages/nova/service.py:414
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._publish_service_capabilities periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._poll_rescued_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Skipping ComputeManager._sync_power_states, 10 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._poll_bandwidth_usage periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 INFO nova.compute.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Updating bandwidth usage cache
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._instance_usage_audit periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager.update_available_resource periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.utils [req-ba874a89-8334-4008-81dc-c4362283259a None None] Got semaphore "compute_resources" for method "update_available_resource"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:18:33 AUDIT nova.compute.resource_tracker [req-ba874a89-8334-4008-81dc-c4362283259a None None] Virt driver does not support 'get_available_resource' Compute tracking is disabled.
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._poll_rebooting_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Skipping ComputeManager._cleanup_running_deleted_instances, 30 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._check_instance_build_time periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._heal_instance_info_cache periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._run_image_cache_manager_pass periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._reclaim_queued_deletes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 DEBUG nova.compute.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] FLAGS.reclaim_instance_interval <= 0, skipping... _reclaim_queued_deletes /usr/lib/python2.6/site-packages/nova/compute/manager.py:2790
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._report_driver_status periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:18:33 INFO nova.compute.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Updating host status
2013-02-04 08:18:33 ERROR nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Error during ComputeManager._report_driver_status:
2013-02-04 08:18:33 9778 TRACE nova.manager Traceback (most recent call last):
2013-02-04 08:18:33 9778 TRACE nova.manager File "/usr/lib/python2.6/site-packages/nova/manager.py", line 175, in periodic_tasks
2013-02-04 08:18:33 9778 TRACE nova.manager task(self, context)
2013-02-04 08:18:33 9778 TRACE nova.manager File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 2642, in _report_driver_status
2013-02-04 08:18:33 9778 TRACE nova.manager capabilities = self.driver.get_host_stats(refresh=True)
2013-02-04 08:18:33 9778 TRACE nova.manager File "/usr/lib/python2.6/site-packages/nova/virt/driver.py", line 595, in get_host_stats
2013-02-04 08:18:33 9778 TRACE nova.manager raise NotImplementedError()
2013-02-04 08:18:33 9778 TRACE nova.manager NotImplementedError
2013-02-04 08:18:33 9778 TRACE nova.manager
2013-02-04 08:18:33 DEBUG nova.manager [req-ba874a89-8334-4008-81dc-c4362283259a None None] Running periodic task ComputeManager._poll_unconfirmed_resizes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:07 9778 DEBUG nova.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_request_id': u'req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1', u'_context_quota_class': None, u'_context_project_name': u'openstack', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'id': u'4e15505e6aae4cab95a56d1d55fdee44', u'internalURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'publicURL': u'http://XXX.XXX.X.XXX:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'endpoints_links': [], u'type': u'compute', u'name': u'nova'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:9292/v1', u'region': u'RegionOne', u'id': u'9d5a156057d941ea956cb77c82365956', u'internalURL': u'http://127.0.0.1:9292/v1', u'publicURL': u'http://XXX.XXX.X.XXX:9292/v1'}], u'endpoints_links': [], u'type': u'image', u'name': u'glance'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'id': u'e96cfcc8b82048fc94aeb8472105f8b1', u'internalURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'publicURL': u'http://XXX.XXX.X.XXX:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'endpoints_links': [], u'type': u'volume', u'name': u'volume'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:8773/services/Admin', u'region': u'RegionOne', u'id': u'8540932b24bb489d92b096a20c88874b', u'internalURL': u'http://127.0.0.1:8773/services/Cloud', u'publicURL': u'http://XXX.XXX.X.XXX:8773/services/Cloud'}], u'endpoints_links': [], u'type': u'ec2', u'name': u'ec2'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:8888/', u'region': u'RegionOne', u'id': u'9eff54e7a7ab48cb824c25ba93790121', u'internalURL': u'http://127.0.0.1:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f', u'publicURL': u'http://XXX.XXX.X.XXX:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f'}], u'endpoints_links': [], u'type': u'object-store', u'name': u'swift'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:35357/v2.0', u'region': u'RegionOne', u'id': u'221bff046433460a965fa27cb5cd40a8', u'internalURL': u'http://127.0.0.1:5000/v2.0', u'publicURL': u'http://XXX.XXX.X.XXX:5000/v2.0'}], u'endpoints_links': [], u'type': u'identity', u'name': u'keystone'}], u'_context_user_name': u'admin', u'_context_auth_token': '<SANITIZED>', u'args': {u'request_spec': {u'block_device_mapping': [], u'image': {u'status': u'active', u'name': u'Ubuntu-Precise-12.04-i386', u'deleted': False, u'container_format': u'bare', u'created_at': u'2013-02-01T10:27:56.000000', u'disk_format': u'qcow2', u'updated_at': u'2013-02-01T10:27:57.000000', u'id': u'eba6a159-ebb2-412c-9f8e-2d6072761e6e', u'owner': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'min_ram': 0, u'checksum': u'0f01af9f7df6b2f477b8f5f4a39ec5d0', u'min_disk': 0, u'is_public': True, u'deleted_at': None, u'properties': {}, u'size': 229441536}, u'instance_type': {u'disabled': False, u'root_gb': 30, u'name': u'm1.small', u'flavorid': u'7', u'deleted': False, u'created_at': timestamp(1359720737.0), u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 1024, u'vcpus': 1, u'extra_specs': {}, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'deleted_at': None, u'vcpu_weight': None, u'id': 7}, u'instance_properties': {u'vm_state': u'building', u'availability_zone': None, u'launch_index': 0, u'ephemeral_gb': 0, u'instance_type_id': 7, u'user_data': None, u'vm_mode': None, u'reservation_id': u'r-08mkpccf', u'root_device_name': None, u'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'display_description': u'Test', u'key_data': None, u'power_state': 0, u'progress': 0, u'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'config_drive': u'', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'display_name': u'Test', u'config_drive_id': u'', u'root_gb': 30, u'locked': False, u'launch_time': u'2013-02-04T08:19:07Z', u'memory_mb': 1024, u'vcpus': 1, u'image_ref': u'eba6a159-ebb2-412c-9f8e-2d6072761e6e', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': {}}, u'security_group': [u'default'], u'instance_uuids': [u'24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa']}, u'requested_networks': None, u'filter_properties': {u'scheduler_hints': {}}, u'instance': {u'vm_state': u'building', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 7, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-08mkpccf', u'id': 24, u'security_groups': [{u'deleted_at': None, u'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'name': u'default', u'deleted': False, u'created_at': u'2013-02-01T12:10:04.000000', u'updated_at': None, u'rules': [], u'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'uuid': u'24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa', u'server_name': None, u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa', u'deleted': False, u'created_at': u'2013-02-04T08:19:07.000000', u'updated_at': None, u'network_info': u'[]', u'deleted_at': None, u'id': 24}, u'hostname': u'test', u'launched_on': None, u'display_description': u'Test', u'key_data': None, u'kernel_id': u'', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'launched_at': None, u'scheduled_at': u'2013-02-04T08:19:07.388569', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'deleted': False, u'key_name': None, u'updated_at': u'2013-02-04T08:19:07.413436', u'host': None, u'display_name': u'Test', u'task_state': u'scheduling', u'shutdown_terminate': False, u'architecture': None, u'root_gb': 30, u'locked': False, u'name': u'instance-00000018', u'created_at': u'2013-02-04T08:19:07.000000', u'launch_index': 0, u'metadata': [], u'memory_mb': 1024, u'instance_type': {u'disabled': False, u'root_gb': 30, u'deleted_at': None, u'name': u'm1.small', u'deleted': False, u'created_at': u'2013-02-01T12:12:17.000000', u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 1024, u'vcpus': 1, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'flavorid': u'7', u'vcpu_weight': None, u'id': 7}, u'vcpus': 1, u'image_ref': u'eba6a159-ebb2-412c-9f8e-2d6072761e6e', u'root_device_name': None, u'auto_disk_config': None, u'os_type': None, u'config_drive': u''}, u'admin_password': '<SANITIZED>', u'injected_files': [], u'is_first_time': True}, u'_context_instance_lock_checked': False, u'_context_is_admin': True, u'version': u'2.0', u'_context_project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'_context_timestamp': u'2013-02-04T08:19:07.071661', u'_context_read_deleted': u'no', u'_context_user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'method': u'run_instance', u'_context_remote_address': u'XXX.XXX.X.XXX'} _safe_log /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/common.py:195
2013-02-04 08:19:07 9778 DEBUG nova.openstack.common.rpc.amqp [-] unpacked context: {'project_name': u'openstack', 'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', 'roles': [u'admin'], 'timestamp': u'2013-02-04T08:19:07.071661', 'auth_token': '<SANITIZED>', 'remote_address': u'XXX.XXX.X.XXX', 'quota_class': None, 'is_admin': True, 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'id': u'4e15505e6aae4cab95a56d1d55fdee44', u'internalURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'type': u'compute', u'name': u'nova'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:9292/v1', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:9292/v1', u'id': u'9d5a156057d941ea956cb77c82365956', u'internalURL': u'http://127.0.0.1:9292/v1'}], u'type': u'image', u'name': u'glance'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'id': u'e96cfcc8b82048fc94aeb8472105f8b1', u'internalURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'type': u'volume', u'name': u'volume'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8773/services/Admin', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8773/services/Cloud', u'id': u'8540932b24bb489d92b096a20c88874b', u'internalURL': u'http://127.0.0.1:8773/services/Cloud'}], u'type': u'ec2', u'name': u'ec2'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8888/', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f', u'id': u'9eff54e7a7ab48cb824c25ba93790121', u'internalURL': u'http://127.0.0.1:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f'}], u'type': u'object-store', u'name': u'swift'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:35357/v2.0', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:5000/v2.0', u'id': u'221bff046433460a965fa27cb5cd40a8', u'internalURL': u'http://127.0.0.1:5000/v2.0'}], u'type': u'identity', u'name': u'keystone'}], 'request_id': u'req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1', 'instance_lock_checked': False, 'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', 'user_name': u'admin', 'read_deleted': u'no'} _safe_log /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/common.py:195
2013-02-04 08:19:07 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa" for method "do_run_instance"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:07 DEBUG nova.virt.vmwareapi.vmops [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Getting list of instances list_instances /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py:66
2013-02-04 08:19:17 DEBUG nova.virt.vmwareapi.vmops [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got total of 0 instances list_instances /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py:82
2013-02-04 08:19:17 DEBUG nova.compute.manager [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] image_id=eba6a159-ebb2-412c-9f8e-2d6072761e6e, image_size_bytes=229441536, allowed_size_bytes=32212254720 _check_image_size /usr/lib/python2.6/site-packages/nova/compute/manager.py:680
2013-02-04 08:19:17 AUDIT nova.compute.manager [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Starting instance...
2013-02-04 08:19:17 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:17 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "begin_resource_claim"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:17 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:17 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:19:17 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 83dcb5a1f3754bdda13f71ff5464e554 multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:19:17 9778 DEBUG nova.openstack.common.rpc.amqp [-] Pool creating new connection create /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:58
2013-02-04 08:19:17 9778 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
2013-02-04 08:19:19 DEBUG nova.compute.manager [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Instance network_info: |[VIF({'network': Network({'bridge': u'br100', 'subnets': [Subnet({'ips': [FixedIP({'meta': {}, 'version': 4, 'type': u'fixed', 'floating_ips': [], 'address': u'192.168.100.2'})], 'version': 4, 'meta': {u'dhcp_server': u'192.168.100.3'}, 'dns': [IP({'meta': {}, 'version': 4, 'type': u'dns', 'address': u'8.8.4.4'})], 'routes': [], 'cidr': u'192.168.100.0/24', 'gateway': IP({'meta': {}, 'version': 4, 'type': u'gateway', 'address': u'192.168.100.1'})}), Subnet({'ips': [], 'version': None, 'meta': {u'dhcp_server': u'192.168.100.3'}, 'dns': [], 'routes': [], 'cidr': None, 'gateway': IP({'meta': {}, 'version': None, 'type': u'gateway', 'address': None})})], 'meta': {u'tenant_id': None, u'multi_host': True, u'should_create_bridge': True, u'bridge_interface': u'br100'}, 'id': u'4b4eca61-0c56-4802-b4f9-32a51cc51a30', 'label': u'private'}), 'meta': {}, 'id': u'1f2bd50e-08f2-4232-be40-f77a92a437dd', 'address': u'fa:16:3e:0c:bb:79'})]| _allocate_network /usr/lib/python2.6/site-packages/nova/compute/manager.py:719
2013-02-04 08:19:19 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:19 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:29 DEBUG nova.virt.vmwareapi.vmware_images [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Getting image size for the image eba6a159-ebb2-412c-9f8e-2d6072761e6e get_vmdk_size_and_properties /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmware_images.py:149
2013-02-04 08:19:29 DEBUG nova.virt.vmwareapi.vmware_images [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Got image size of 229441536 for the image eba6a159-ebb2-412c-9f8e-2d6072761e6e get_vmdk_size_and_properties /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmware_images.py:154
2013-02-04 08:19:30 WARNING nova.virt.vmwareapi.network_utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [(ManagedObjectReference){
   value = "HaNetwork-VM Network"
   _type = "Network"
}, (ManagedObjectReference){
   value = "HaNetwork-VM LAN"
   _type = "Network"
}, (ManagedObjectReference){
   value = "HaNetwork-br100"
   _type = "Network"
}]
2013-02-04 08:19:30 ERROR nova.compute.manager [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Instance failed to spawn
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Traceback (most recent call last):
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 747, in _spawn
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] block_device_info)
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/driver.py", line 132, in spawn
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] self._vmops.spawn(context, instance, image_meta, network_info)
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py", line 195, in spawn
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] data_store_name, vif_infos, os_type)
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vm_util.py", line 45, in get_vm_create_spec
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] config_spec.name = instance.name
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] AttributeError: 'dict' object has no attribute 'name'
2013-02-04 08:19:30 9778 TRACE nova.compute.manager [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa]
2013-02-04 08:19:30 DEBUG nova.compute.manager [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Deallocating network for instance _deallocate_network /usr/lib/python2.6/site-packages/nova/compute/manager.py:773
2013-02-04 08:19:30 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:19:30 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 9ea56c10a6b643baa01c660f9c4e11de multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:19:31 DEBUG nova.compute.manager [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Retry info not present, will not reschedule _reschedule /usr/lib/python2.6/site-packages/nova/compute/manager.py:568
2013-02-04 08:19:32 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:32 DEBUG nova.utils [req-1b9cb29d-5e13-4c34-9031-6c5ef022ada1 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:32 9778 ERROR nova.openstack.common.rpc.amqp [-] Exception during message handling
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp Traceback (most recent call last):
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py", line 276, in _process_data
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp rval = self.proxy.dispatch(ctxt, version, method, **args)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/dispatcher.py", line 145, in dispatch
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp return getattr(proxyobj, method)(ctxt, **kwargs)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/exception.py", line 117, in wrapped
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp temp_level, payload)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/exception.py", line 92, in wrapped
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp return f(*args, **kw)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 175, in decorated_function
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp pass
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 161, in decorated_function
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 196, in decorated_function
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp kwargs['instance']['uuid'], e, sys.exc_info())
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 190, in decorated_function
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 830, in run_instance
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp do_run_instance()
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/utils.py", line 760, in inner
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp retval = f(*args, **kwargs)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 829, in do_run_instance
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp admin_password, is_first_time, instance)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 520, in _run_instance
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp self._set_instance_error_state(context, instance['uuid'])
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 508, in _run_instance
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp is_first_time, request_spec, filter_properties)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 494, in _run_instance
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp injected_files, admin_password)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 747, in _spawn
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp block_device_info)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/driver.py", line 132, in spawn
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp self._vmops.spawn(context, instance, image_meta, network_info)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py", line 195, in spawn
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp data_store_name, vif_infos, os_type)
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vm_util.py", line 45, in get_vm_create_spec
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp config_spec.name = instance.name
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp AttributeError: 'dict' object has no attribute 'name'
2013-02-04 08:19:32 9778 TRACE nova.openstack.common.rpc.amqp
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._publish_service_capabilities periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rescued_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Skipping ComputeManager._sync_power_states, 9 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_bandwidth_usage periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._instance_usage_audit periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager.update_available_resource periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.utils [-] Got semaphore "compute_resources" for method "update_available_resource"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:19:33 9778 AUDIT nova.compute.resource_tracker [-] Virt driver does not support 'get_available_resource' Compute tracking is disabled.
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 29 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._check_instance_build_time periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:19:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is f2f8bdb3466749798a953c513d232077 multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:19:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:19:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 462b775850cf49ea87577c759d727383 multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:19:33 9778 DEBUG nova.compute.manager [-] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Updated the info_cache for instance _heal_instance_info_cache /usr/lib/python2.6/site-packages/nova/compute/manager.py:2484
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._run_image_cache_manager_pass periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... _reclaim_queued_deletes /usr/lib/python2.6/site-packages/nova/compute/manager.py:2790
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:19:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._publish_service_capabilities periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rescued_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Skipping ComputeManager._sync_power_states, 8 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_bandwidth_usage periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._instance_usage_audit periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager.update_available_resource periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.utils [-] Got semaphore "compute_resources" for method "update_available_resource"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:20:33 9778 AUDIT nova.compute.resource_tracker [-] Virt driver does not support 'get_available_resource' Compute tracking is disabled.
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 28 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._check_instance_build_time periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:20:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 6680a9e4cf1c4a17b42c5f19d41ffacf multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:20:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:20:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is f9722c1f0c544aaf97ed46bed139e019 multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:20:33 9778 DEBUG nova.compute.manager [-] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Updated the info_cache for instance _heal_instance_info_cache /usr/lib/python2.6/site-packages/nova/compute/manager.py:2484
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._run_image_cache_manager_pass periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... _reclaim_queued_deletes /usr/lib/python2.6/site-packages/nova/compute/manager.py:2790
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:33 9778 INFO nova.compute.manager [-] Updating host status
2013-02-04 08:20:33 9778 ERROR nova.manager [-] Error during ComputeManager._report_driver_status:
2013-02-04 08:20:33 9778 TRACE nova.manager Traceback (most recent call last):
2013-02-04 08:20:33 9778 TRACE nova.manager File "/usr/lib/python2.6/site-packages/nova/manager.py", line 175, in periodic_tasks
2013-02-04 08:20:33 9778 TRACE nova.manager task(self, context)
2013-02-04 08:20:33 9778 TRACE nova.manager File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 2642, in _report_driver_status
2013-02-04 08:20:33 9778 TRACE nova.manager capabilities = self.driver.get_host_stats(refresh=True)
2013-02-04 08:20:33 9778 TRACE nova.manager File "/usr/lib/python2.6/site-packages/nova/virt/driver.py", line 595, in get_host_stats
2013-02-04 08:20:33 9778 TRACE nova.manager raise NotImplementedError()
2013-02-04 08:20:33 9778 TRACE nova.manager NotImplementedError
2013-02-04 08:20:33 9778 TRACE nova.manager
2013-02-04 08:20:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:20:44 9778 DEBUG nova.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_request_id': u'req-893ef059-e4d8-4728-8a62-d911c5a0b256', u'_context_quota_class': None, u'_context_project_name': u'openstack', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'id': u'4e15505e6aae4cab95a56d1d55fdee44', u'internalURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'publicURL': u'http://XXX.XXX.X.XXX:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'endpoints_links': [], u'type': u'compute', u'name': u'nova'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:9292/v1', u'region': u'RegionOne', u'id': u'9d5a156057d941ea956cb77c82365956', u'internalURL': u'http://127.0.0.1:9292/v1', u'publicURL': u'http://XXX.XXX.X.XXX:9292/v1'}], u'endpoints_links': [], u'type': u'image', u'name': u'glance'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'id': u'e96cfcc8b82048fc94aeb8472105f8b1', u'internalURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'publicURL': u'http://XXX.XXX.X.XXX:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'endpoints_links': [], u'type': u'volume', u'name': u'volume'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:8773/services/Admin', u'region': u'RegionOne', u'id': u'8540932b24bb489d92b096a20c88874b', u'internalURL': u'http://127.0.0.1:8773/services/Cloud', u'publicURL': u'http://XXX.XXX.X.XXX:8773/services/Cloud'}], u'endpoints_links': [], u'type': u'ec2', u'name': u'ec2'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:8888/', u'region': u'RegionOne', u'id': u'9eff54e7a7ab48cb824c25ba93790121', u'internalURL': u'http://127.0.0.1:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f', u'publicURL': u'http://XXX.XXX.X.XXX:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f'}], u'endpoints_links': [], u'type': u'object-store', u'name': u'swift'}, {u'endpoints': [{u'adminURL': u'http://127.0.0.1:35357/v2.0', u'region': u'RegionOne', u'id': u'221bff046433460a965fa27cb5cd40a8', u'internalURL': u'http://127.0.0.1:5000/v2.0', u'publicURL': u'http://XXX.XXX.X.XXX:5000/v2.0'}], u'endpoints_links': [], u'type': u'identity', u'name': u'keystone'}], u'_context_user_name': u'admin', u'_context_auth_token': '<SANITIZED>', u'args': {u'request_spec': {u'block_device_mapping': [], u'image': {u'status': u'active', u'name': u'Ubuntu-Precise-12.04-i386', u'deleted': False, u'container_format': u'bare', u'created_at': u'2013-02-01T10:27:56.000000', u'disk_format': u'qcow2', u'updated_at': u'2013-02-01T10:27:57.000000', u'id': u'eba6a159-ebb2-412c-9f8e-2d6072761e6e', u'owner': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'min_ram': 0, u'checksum': u'0f01af9f7df6b2f477b8f5f4a39ec5d0', u'min_disk': 0, u'is_public': True, u'deleted_at': None, u'properties': {}, u'size': 229441536}, u'instance_type': {u'disabled': False, u'root_gb': 20, u'name': u'm1.tiny', u'flavorid': u'6', u'deleted': False, u'created_at': timestamp(1359720718.0), u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'extra_specs': {}, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'deleted_at': None, u'vcpu_weight': None, u'id': 6}, u'instance_properties': {u'vm_state': u'building', u'availability_zone': None, u'launch_index': 0, u'ephemeral_gb': 0, u'instance_type_id': 6, u'user_data': None, u'vm_mode': None, u'reservation_id': u'r-uthh3ypx', u'root_device_name': None, u'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'display_description': u'mysql', u'key_data': None, u'power_state': 0, u'progress': 0, u'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'config_drive': u'', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'display_name': u'mysql', u'config_drive_id': u'', u'root_gb': 20, u'locked': False, u'launch_time': u'2013-02-04T08:20:44Z', u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'eba6a159-ebb2-412c-9f8e-2d6072761e6e', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': {}}, u'security_group': [u'default'], u'instance_uuids': [u'a2648a3d-b1ba-4aa5-9829-8bbd479944cb']}, u'requested_networks': None, u'filter_properties': {u'scheduler_hints': {}}, u'instance': {u'vm_state': u'building', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 6, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-uthh3ypx', u'id': 25, u'security_groups': [{u'deleted_at': None, u'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'name': u'default', u'deleted': False, u'created_at': u'2013-02-01T12:10:04.000000', u'updated_at': None, u'rules': [], u'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'uuid': u'a2648a3d-b1ba-4aa5-9829-8bbd479944cb', u'server_name': None, u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'a2648a3d-b1ba-4aa5-9829-8bbd479944cb', u'deleted': False, u'created_at': u'2013-02-04T08:20:44.000000', u'updated_at': None, u'network_info': u'[]', u'deleted_at': None, u'id': 25}, u'hostname': u'mysql', u'launched_on': None, u'display_description': u'mysql', u'key_data': None, u'kernel_id': u'', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'launched_at': None, u'scheduled_at': u'2013-02-04T08:20:44.512582', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'deleted': False, u'key_name': None, u'updated_at': u'2013-02-04T08:20:44.539335', u'host': None, u'display_name': u'mysql', u'task_state': u'scheduling', u'shutdown_terminate': False, u'architecture': None, u'root_gb': 20, u'locked': False, u'name': u'instance-00000019', u'created_at': u'2013-02-04T08:20:44.000000', u'launch_index': 0, u'metadata': [], u'memory_mb': 512, u'instance_type': {u'disabled': False, u'root_gb': 20, u'deleted_at': None, u'name': u'm1.tiny', u'deleted': False, u'created_at': u'2013-02-01T12:11:58.000000', u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'flavorid': u'6', u'vcpu_weight': None, u'id': 6}, u'vcpus': 1, u'image_ref': u'eba6a159-ebb2-412c-9f8e-2d6072761e6e', u'root_device_name': None, u'auto_disk_config': None, u'os_type': None, u'config_drive': u''}, u'admin_password': '<SANITIZED>', u'injected_files': [], u'is_first_time': True}, u'_context_instance_lock_checked': False, u'_context_is_admin': True, u'version': u'2.0', u'_context_project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', u'_context_timestamp': u'2013-02-04T08:20:44.231251', u'_context_read_deleted': u'no', u'_context_user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', u'method': u'run_instance', u'_context_remote_address': u'XXX.XXX.X.XXX'} _safe_log /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/common.py:195
2013-02-04 08:20:44 9778 DEBUG nova.openstack.common.rpc.amqp [-] unpacked context: {'project_name': u'openstack', 'user_id': u'f4814ea8af9d4d88b47858518aa2bd9d', 'roles': [u'admin'], 'timestamp': u'2013-02-04T08:20:44.231251', 'auth_token': '<SANITIZED>', 'remote_address': u'XXX.XXX.X.XXX', 'quota_class': None, 'is_admin': True, 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f', u'id': u'4e15505e6aae4cab95a56d1d55fdee44', u'internalURL': u'http://127.0.0.1:8774/v2/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'type': u'compute', u'name': u'nova'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:9292/v1', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:9292/v1', u'id': u'9d5a156057d941ea956cb77c82365956', u'internalURL': u'http://127.0.0.1:9292/v1'}], u'type': u'image', u'name': u'glance'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f', u'id': u'e96cfcc8b82048fc94aeb8472105f8b1', u'internalURL': u'http://127.0.0.1:8776/v1/f0cfac82489a4ef68cb3171e8fb4b77f'}], u'type': u'volume', u'name': u'volume'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8773/services/Admin', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8773/services/Cloud', u'id': u'8540932b24bb489d92b096a20c88874b', u'internalURL': u'http://127.0.0.1:8773/services/Cloud'}], u'type': u'ec2', u'name': u'ec2'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:8888/', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f', u'id': u'9eff54e7a7ab48cb824c25ba93790121', u'internalURL': u'http://127.0.0.1:8888/v1/AUTH_f0cfac82489a4ef68cb3171e8fb4b77f'}], u'type': u'object-store', u'name': u'swift'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://127.0.0.1:35357/v2.0', u'region': u'RegionOne', u'publicURL': u'http://XXX.XXX.X.XXX:5000/v2.0', u'id': u'221bff046433460a965fa27cb5cd40a8', u'internalURL': u'http://127.0.0.1:5000/v2.0'}], u'type': u'identity', u'name': u'keystone'}], 'request_id': u'req-893ef059-e4d8-4728-8a62-d911c5a0b256', 'instance_lock_checked': False, 'project_id': u'f0cfac82489a4ef68cb3171e8fb4b77f', 'user_name': u'admin', 'read_deleted': u'no'} _safe_log /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/common.py:195
2013-02-04 08:20:44 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "a2648a3d-b1ba-4aa5-9829-8bbd479944cb" for method "do_run_instance"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:20:44 DEBUG nova.virt.vmwareapi.vmops [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Getting list of instances list_instances /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py:66
2013-02-04 08:20:54 DEBUG nova.virt.vmwareapi.vmops [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got total of 0 instances list_instances /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py:82
2013-02-04 08:20:54 DEBUG nova.compute.manager [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] image_id=eba6a159-ebb2-412c-9f8e-2d6072761e6e, image_size_bytes=229441536, allowed_size_bytes=21474836480 _check_image_size /usr/lib/python2.6/site-packages/nova/compute/manager.py:680
2013-02-04 08:20:54 AUDIT nova.compute.manager [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Starting instance...
2013-02-04 08:20:54 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:20:54 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "begin_resource_claim"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:20:54 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:20:54 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:20:54 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 76a4c7c09fe2495a944c5ce37507b525 multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:20:56 DEBUG nova.compute.manager [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Instance network_info: |[VIF({'network': Network({'bridge': u'br100', 'subnets': [Subnet({'ips': [FixedIP({'meta': {}, 'version': 4, 'type': u'fixed', 'floating_ips': [], 'address': u'192.168.100.4'})], 'version': 4, 'meta': {u'dhcp_server': u'192.168.100.3'}, 'dns': [IP({'meta': {}, 'version': 4, 'type': u'dns', 'address': u'8.8.4.4'})], 'routes': [], 'cidr': u'192.168.100.0/24', 'gateway': IP({'meta': {}, 'version': 4, 'type': u'gateway', 'address': u'192.168.100.1'})}), Subnet({'ips': [], 'version': None, 'meta': {u'dhcp_server': u'192.168.100.3'}, 'dns': [], 'routes': [], 'cidr': None, 'gateway': IP({'meta': {}, 'version': None, 'type': u'gateway', 'address': None})})], 'meta': {u'tenant_id': None, u'multi_host': True, u'should_create_bridge': True, u'bridge_interface': u'br100'}, 'id': u'4b4eca61-0c56-4802-b4f9-32a51cc51a30', 'label': u'private'}), 'meta': {}, 'id': u'07de0fbc-a319-4428-ba6d-94feb810967a', 'address': u'fa:16:3e:78:f2:69'})]| _allocate_network /usr/lib/python2.6/site-packages/nova/compute/manager.py:719
2013-02-04 08:20:57 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:20:57 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:21:07 DEBUG nova.virt.vmwareapi.vmware_images [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Getting image size for the image eba6a159-ebb2-412c-9f8e-2d6072761e6e get_vmdk_size_and_properties /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmware_images.py:149
2013-02-04 08:21:07 DEBUG nova.virt.vmwareapi.vmware_images [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Got image size of 229441536 for the image eba6a159-ebb2-412c-9f8e-2d6072761e6e get_vmdk_size_and_properties /usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmware_images.py:154
2013-02-04 08:21:07 WARNING nova.virt.vmwareapi.network_utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [(ManagedObjectReference){
   value = "HaNetwork-VM Network"
   _type = "Network"
}, (ManagedObjectReference){
   value = "HaNetwork-VM LAN"
   _type = "Network"
}, (ManagedObjectReference){
   value = "HaNetwork-br100"
   _type = "Network"
}]
2013-02-04 08:21:08 ERROR nova.compute.manager [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Instance failed to spawn
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Traceback (most recent call last):
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 747, in _spawn
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] block_device_info)
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/driver.py", line 132, in spawn
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] self._vmops.spawn(context, instance, image_meta, network_info)
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py", line 195, in spawn
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] data_store_name, vif_infos, os_type)
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vm_util.py", line 45, in get_vm_create_spec
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] config_spec.name = instance.name
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] AttributeError: 'dict' object has no attribute 'name'
2013-02-04 08:21:08 9778 TRACE nova.compute.manager [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb]
2013-02-04 08:21:08 DEBUG nova.compute.manager [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Deallocating network for instance _deallocate_network /usr/lib/python2.6/site-packages/nova/compute/manager.py:773
2013-02-04 08:21:08 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:21:08 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 2b434ad499cf419c836ad384481056bf multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:21:09 DEBUG nova.compute.manager [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] [instance: a2648a3d-b1ba-4aa5-9829-8bbd479944cb] Retry info not present, will not reschedule _reschedule /usr/lib/python2.6/site-packages/nova/compute/manager.py:568
2013-02-04 08:21:09 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:21:09 DEBUG nova.utils [req-893ef059-e4d8-4728-8a62-d911c5a0b256 f4814ea8af9d4d88b47858518aa2bd9d f0cfac82489a4ef68cb3171e8fb4b77f] Got semaphore "compute_resources" for method "update_usage"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:21:09 9778 ERROR nova.openstack.common.rpc.amqp [-] Exception during message handling
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp Traceback (most recent call last):
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py", line 276, in _process_data
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp rval = self.proxy.dispatch(ctxt, version, method, **args)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/dispatcher.py", line 145, in dispatch
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp return getattr(proxyobj, method)(ctxt, **kwargs)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/exception.py", line 117, in wrapped
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp temp_level, payload)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/exception.py", line 92, in wrapped
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp return f(*args, **kw)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 175, in decorated_function
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp pass
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 161, in decorated_function
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 196, in decorated_function
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp kwargs['instance']['uuid'], e, sys.exc_info())
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 190, in decorated_function
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 830, in run_instance
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp do_run_instance()
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/utils.py", line 760, in inner
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp retval = f(*args, **kwargs)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 829, in do_run_instance
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp admin_password, is_first_time, instance)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 520, in _run_instance
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp self._set_instance_error_state(context, instance['uuid'])
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 508, in _run_instance
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp is_first_time, request_spec, filter_properties)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 494, in _run_instance
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp injected_files, admin_password)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 747, in _spawn
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp block_device_info)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/driver.py", line 132, in spawn
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp self._vmops.spawn(context, instance, image_meta, network_info)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vmops.py", line 195, in spawn
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp data_store_name, vif_infos, os_type)
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/virt/vmwareapi/vm_util.py", line 45, in get_vm_create_spec
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp config_spec.name = instance.name
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp AttributeError: 'dict' object has no attribute 'name'
2013-02-04 08:21:09 9778 TRACE nova.openstack.common.rpc.amqp
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._publish_service_capabilities periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rescued_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Skipping ComputeManager._sync_power_states, 7 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_bandwidth_usage periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._instance_usage_audit periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager.update_available_resource periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.utils [-] Got semaphore "compute_resources" for method "update_available_resource"... inner /usr/lib/python2.6/site-packages/nova/utils.py:721
2013-02-04 08:21:33 9778 AUDIT nova.compute.resource_tracker [-] Virt driver does not support 'get_available_resource' Compute tracking is disabled.
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 27 ticks left until next run periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._check_instance_build_time periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:21:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 026994dc761643aabaf3f840b2e7f6a4 multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:21:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:352
2013-02-04 08:21:33 9778 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is bcdfc84cda2144d9825d80565499d92a multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:355
2013-02-04 08:21:33 9778 DEBUG nova.compute.manager [-] [instance: 24a9865b-3bec-4bb5-84ef-1bf32bb8a2fa] Updated the info_cache for instance _heal_instance_info_cache /usr/lib/python2.6/site-packages/nova/compute/manager.py:2484
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._run_image_cache_manager_pass periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... _reclaim_queued_deletes /usr/lib/python2.6/site-packages/nova/compute/manager.py:2790
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2013-02-04 08:21:33 9778 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172