Doing "euca-run-instances ami-595fe7fc -k nova_key -t m1.tiny" , the instance is stuck in "building nova_key(Ras, stocky) 0" - logs seems fine, any hint?
Thanks,
$ euca-describe-instances
RESERVATION r-ir2svb20 Ras default
INSTANCE i-00000004 ami-595fe7fc 10.0.0.2 10.0.0.2 building nova_key (Ras, stocky) 0 m1.tiny 2011-05-17T14:40:33Z nova
RESERVATION r-7dadcvps Ras default
INSTANCE i-00000002 ami-595fe7fc scheduling nova_key (Ras, None) 0 m1.tiny 2011-05-17T05:31:55Z unknown zone
RESERVATION r-v9bnwoxr Ras default
INSTANCE i-00000001 ami-595fe7fc scheduling mykey (Ras, None) 0 m1.tiny 2011-05-17T05:29:53Z unknown zone
RESERVATION r-wogqc75e Ras default
INSTANCE i-00000003 ami-595fe7fc scheduling nova_key (Ras, None) 0 m1.tiny 2011-05-17T05:54:21Z unknown zone
api.log -
2011-05-17 07:40:33,596 DEBUG nova.rpc [-] received {u'_context_request_id': u'JANVAYDFOHF7JSV-KNXC', u'_context_read_deleted': False, u'args': {u'instance_id': 4, u'injected_files': None, u'availability_zone': None}, u'_context_is_admin': True, u'_context_timestamp': u'2011-05-17T14:40:33Z', u'_context_user': u'fredy', u'method': u'run_instance', u'_context_project': u'Ras', u'_context_remote_address': u'192.168.0.14'} from (pid=2394) _receive /home/fred/openstack/nova/nova/rpc.py:177
2011-05-17 07:40:33,596 DEBUG nova.rpc [-] unpacked context: {'timestamp': u'2011-05-17T14:40:33Z', 'remote_address': u'192.168.0.14', 'project': u'Ras', 'is_admin': True, 'user': u'fredy', 'request_id': u'JANVAYDFOHF7JSV-KNXC', 'read_deleted': False} from (pid=2394) _unpack_context /home/fred/openstack/nova/nova/rpc.py:350
2011-05-17 07:40:33,643 AUDIT nova.compute.manager [JANVAYDFOHF7JSV-KNXC fredy Ras] instance 4: starting...
2011-05-17 07:40:33,743 DEBUG nova.rpc [-] Making asynchronous call on network ... from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:370
2011-05-17 07:40:33,743 DEBUG nova.rpc [-] MSG_ID is 50414ef869074031ae9d8331745fd9ab from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:373
2011-05-17 07:40:34,027 DEBUG nova.rpc [-] Making asynchronous call on network.stocky ... from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:370
2011-05-17 07:40:34,027 DEBUG nova.rpc [-] MSG_ID is 82a45bfab7714519b1f316e80e6d825c from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:373
2011-05-17 07:40:34,493 DEBUG nova.virt.libvirt_conn [-] instance instance-00000004: starting toXML method from (pid=2394) to_xml /home/fred/openstack/nova/nova/virt/libvirt_conn.py:1039
2011-05-17 07:40:34,593 DEBUG nova.virt.libvirt_conn [-] instance instance-00000004: finished toXML method from (pid=2394) to_xml /home/fred/openstack/nova/nova/virt/libvirt_conn.py:1042
2011-05-17 07:40:34,608 INFO nova [-] called setup_basic_filtering in nwfilter
2011-05-17 07:40:34,609 INFO nova [-] ensuring static filters
2011-05-17 07:40:34,673 DEBUG nova.utils [-] Attempting to grab semaphore "iptables" for method "apply"... from (pid=2394) inner /home/fred/openstack/nova/nova/utils.py:576
2011-05-17 07:40:34,673 DEBUG nova.utils [-] Attempting to grab file lock "iptables" for method "apply"... from (pid=2394) inner /home/fred/openstack/nova/nova/utils.py:581
2011-05-17 07:40:34,677 DEBUG nova.utils [-] Running cmd (subprocess): sudo iptables-save -t filter from (pid=2394) execute /home/fred/openstack/nova/nova/utils.py:141
2011-05-17 07:41:14,984 INFO nova.compute.manager [-] Found instance 'instance-00000004' in DB but no VM. State=9, so assuming spawn is in progress.
2011-05-17 07:42:15,007 INFO nova.compute.manager [-] Updating host status
Compute.log:
2011-05-17 07:40:14,923 INFO nova.compute.manager [-] Updating host status
2011-05-17 07:40:33,596 DEBUG nova.rpc [-] received {u'_context_request_id': u'JANVAYDFOHF7JSV-KNXC', u'_context_read_deleted': False, u'args': {u'instance_id': 4, u'injected_files': None, u'availability_zone': None}, u'_context_is_admin': True, u'_context_timestamp': u'2011-05-17T14:40:33Z', u'_context_user': u'fredy', u'method': u'run_instance', u'_context_project': u'Ras', u'_context_remote_address': u'192.168.0.14'} from (pid=2394) _receive /home/fred/openstack/nova/nova/rpc.py:177
2011-05-17 07:40:33,596 DEBUG nova.rpc [-] unpacked context: {'timestamp': u'2011-05-17T14:40:33Z', 'remote_address': u'192.168.0.14', 'project': u'Ras', 'is_admin': True, 'user': u'fredy', 'request_id': u'JANVAYDFOHF7JSV-KNXC', 'read_deleted': False} from (pid=2394) _unpack_context /home/fred/openstack/nova/nova/rpc.py:350
2011-05-17 07:40:33,643 AUDIT nova.compute.manager [JANVAYDFOHF7JSV-KNXC fredy Ras] instance 4: starting...
2011-05-17 07:40:33,743 DEBUG nova.rpc [-] Making asynchronous call on network ... from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:370
2011-05-17 07:40:33,743 DEBUG nova.rpc [-] MSG_ID is 50414ef869074031ae9d8331745fd9ab from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:373
2011-05-17 07:40:34,027 DEBUG nova.rpc [-] Making asynchronous call on network.stocky ... from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:370
2011-05-17 07:40:34,027 DEBUG nova.rpc [-] MSG_ID is 82a45bfab7714519b1f316e80e6d825c from (pid=2394) call /home/fred/openstack/nova/nova/rpc.py:373
2011-05-17 07:40:34,493 DEBUG nova.virt.libvirt_conn [-] instance instance-00000004: starting toXML method from (pid=2394) to_xml /home/fred/openstack/nova/nova/virt/libvirt_conn.py:1039
2011-05-17 07:40:34,593 DEBUG nova.virt.libvirt_conn [-] instance instance-00000004: finished toXML method from (pid=2394) to_xml /home/fred/openstack/nova/nova/virt/libvirt_conn.py:1042
2011-05-17 07:40:34,608 INFO nova [-] called setup_basic_filtering in nwfilter
2011-05-17 07:40:34,609 INFO nova [-] ensuring static filters
2011-05-17 07:40:34,673 DEBUG nova.utils [-] Attempting to grab semaphore "iptables" for method "apply"... from (pid=2394) inner /home/fred/openstack/nova/nova/utils.py:576
2011-05-17 07:40:34,673 DEBUG nova.utils [-] Attempting to grab file lock "iptables" for method "apply"... from (pid=2394) inner /home/fred/openstack/nova/nova/utils.py:581
2011-05-17 07:40:34,677 DEBUG nova.utils [-] Running cmd (subprocess): sudo iptables-save -t filter from (pid=2394) execute /home/fred/openstack/nova/nova/utils.py:141
2011-05-17 07:41:14,984 INFO nova.compute.manager [-] Found instance 'instance-00000004' in DB but no VM. State=9, so assuming spawn is in progress.
scheduler.log:
2011-05-17 07:40:13,953 DEBUG nova [-] Updating zone cache from db. from (pid=2402) ping /home/fred/openstack/nova/nova/scheduler/zone_manager.py:163
2011-05-17 07:40:33,440 DEBUG nova.rpc [-] received {u'_context_request_id': u'JANVAYDFOHF7JSV-KNXC', u'_context_read_deleted': False, u'args': {u'topic': u'compute', u'instance_id': 4, u'injected_files': None, u'availability_zone': None}, u'_context_is_admin': True, u'_context_timestamp': u'2011-05-17T14:40:33Z', u'_context_user': u'fredy', u'method': u'run_instance', u'_context_project': u'Ras', u'_context_remote_address': u'192.168.0.14'} from (pid=2402) _receive /home/fred/openstack/nova/nova/rpc.py:177
2011-05-17 07:40:33,440 DEBUG nova.rpc [-] unpacked context: {'timestamp': u'2011-05-17T14:40:33Z', 'remote_address': u'192.168.0.14', 'project': u'Ras', 'is_admin': True, 'user': u'fredy', 'request_id': u'JANVAYDFOHF7JSV-KNXC', 'read_deleted': False} from (pid=2402) _unpack_context /home/fred/openstack/nova/nova/rpc.py:350
2011-05-17 07:40:33,464 DEBUG nova.rpc [-] Making asynchronous cast on compute.stocky... from (pid=2402) cast /home/fred/openstack/nova/nova/rpc.py:411
2011-05-17 07:40:33,544 DEBUG nova.scheduler.manager [-] Casting to compute stocky for run_instance from (pid=2402) _schedule /home/fred/openstack/nova/nova/scheduler/manager.py:90
2011-05-17 07:41:13,958 DEBUG nova [-] Updating zone cache from db. from (pid=2402) ping /home/fred/openstack/nova/nova/scheduler/zone_manager.py:163
2011-05-17 07:42:13,964 DEBUG nova [-] Updating zone cache from db. from (pid=2402) ping /home/fred/openstack/nova/nova/scheduler/zone_manager.py:163
network.log:
2011-05-17 07:40:33,923 DEBUG nova.rpc [-] received {u'_msg_id': u'50414ef869074031ae9d8331745fd9ab', u'_context_read_deleted': False, u'_context_request_id': u'JANVAYDFOHF7JSV-KNXC', u'args': {u'network_id': 1}, u'_context_is_admin': True, u'_context_timestamp': u'2011-05-17T14:40:33Z', u'_context_user': u'fredy', u'method': u'set_network_host', u'_context_project': u'Ras', u'_context_remote_address': u'192.168.0.14'} from (pid=2403) _receive /home/fred/openstack/nova/nova/rpc.py:177
2011-05-17 07:40:33,924 DEBUG nova.rpc [-] unpacked context: {'timestamp': u'2011-05-17T14:40:33Z', 'remote_address': u'192.168.0.14', 'project': u'Ras', 'is_admin': True, 'user': u'fredy', 'request_id': u'JANVAYDFOHF7JSV-KNXC', 'read_deleted': False} from (pid=2403) _unpack_context /home/fred/openstack/nova/nova/rpc.py:350
2011-05-17 07:40:33,930 DEBUG nova.network.manager [JANVAYDFOHF7JSV-KNXC fredy Ras] setting network host from (pid=2403) set_network_host /home/fred/openstack/nova/nova/network/manager.py:161
2011-05-17 07:40:34,247 DEBUG nova.rpc [-] received {u'_msg_id': u'82a45bfab7714519b1f316e80e6d825c', u'_context_read_deleted': False, u'_context_request_id': u'JANVAYDFOHF7JSV-KNXC', u'args': {u'instance_id': 4, u'vpn': False}, u'_context_is_admin': True, u'_context_timestamp': u'2011-05-17T14:40:33Z', u'_context_user': u'fredy', u'method': u'allocate_fixed_ip', u'_context_project': u'Ras', u'_context_remote_address': u'192.168.0.14'} from (pid=2403) _receive /home/fred/openstack/nova/nova/rpc.py:177
2011-05-17 07:40:34,248 DEBUG nova.rpc [-] unpacked context: {'timestamp': u'2011-05-17T14:40:33Z', 'remote_address': u'192.168.0.14', 'project': u'Ras', 'is_admin': True, 'user': u'fredy', 'request_id': u'JANVAYDFOHF7JSV-KNXC', 'read_deleted': False} from (pid=2403) _unpack_context /home/fred/openstack/nova/nova/rpc.py:350
nova.conf:
--network_manager=nova.network.manager.FlatManager
--use_ipv6=false
--dhcpbridge_flagfile=/etc/nova/nova.conf
--dhcpbridge=nova-dhcpbridge
--verbose
--s3_host=192.168.0.14
--rabbit_host=1192.168.0.14
--cc_host=192.168.0.14
--ec2_url=http://192.168.0.14:8773/services/Cloud
--fixed_range=10.0.0.0/12
--network_size=24
--routing_source_ip=192.168.0.14
--verbose
--sql_connection=mysql://nova:notnova@localhost/nova
--lock_path=/tmp