Can't import instance

[Doug]

I am unable to import an instance into my local Scalr installation running on Eucalyptus.  I've got the instance running, with Scalarizr 0.7.90 installed on it as well.

Using Sclar, I begin the import process, and then run the generated scalarizr command on my instance.  Scalarizr appears to stop at "Message 'Hello' delivered".  The Scalr web UI does successfully move to step 2 after receiving the message from scalarizr, but then terminates with this message:

"Snapshot creation failed. Reason: . Bundle task status changed to: failed"

On the instance, there is nothing much in the scalarizr.log file:

2011-09-20 13:10:37,510 - INFO - scalarizr - [pid: 4110] Starting scalarizr 0.7.90

2011-09-20 13:10:37,661 - INFO - scalarizr.messaging.p2p.consumer - Building message consumer server on 0.0.0.0:8013

2011-09-20 13:10:37,679 - INFO - scalarizr.handlers.lifecycle - Server will be imported into Scalr

2011-09-20 13:10:37,711 - INFO - scalarizr.snmp.agent - [pid: 4120] Starting SNMP server on 0.0.0.0:8014

2011-09-20 13:10:37,937 - INFO - scalarizr.messaging.p2p.producer - Message 'Hello' delivered (message_id: fa6362cb-126f-41f2-9be0-33aab74b8e94)

2011-09-20 13:16:43,640 - INFO - scalarizr - [pid: 4110] Stopping scalarizr 0.7.90

2011-09-20 13:16:43,640 - INFO - scalarizr - [pid: 4120] SNMP process terminated

2011-09-20 13:16:44,716 - INFO - scalarizr - [pid: 4110] Scalarizr terminated

The 'Stopping' is generated by my CTRL-C breaking out of scalarizr after the bundle task fails.  I've got port 8013 open in my default security group, and am now stuck.

Thanks in advance,

Doug

[Doug]

I set the logging level to DEBUG, and got more info in the logs, but nothing that I can see that is helpful:

-bash-3.2# 2011-09-20 18:15:07,882 - INFO - scalarizr - [pid: 1865] Starting scalarizr 0.7.91

2011-09-20 18:15:07,882 - DEBUG - scalarizr.util.filetool - Writing file /var/run/scalarizr.pid

2011-09-20 18:15:07,883 - DEBUG - scalarizr.util.filetool - File /var/run/scalarizr.pid does not exist. Trying to create.

Starting import process...

Don't terminate Scalarizr until Scalr will create the new role

2011-09-20 18:15:07,883 - DEBUG - scalarizr.config - Writing file /etc/scalr/private.d/.state

2011-09-20 18:15:07,883 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=False)

2011-09-20 18:15:07,884 - DEBUG - scalarizr.config - Loading main configuration

2011-09-20 18:15:07,884 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/private.d/config.ini

2011-09-20 18:15:07,884 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/config.ini

2011-09-20 18:15:07,885 - DEBUG - scalarizr.config - Loading platform configuration

2011-09-20 18:15:07,885 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/eucalyptus.ini

2011-09-20 18:15:07,886 - DEBUG - scalarizr.config - Loading behaviours configuration

2011-09-20 18:15:07,886 - DEBUG - scalarizr.config - Loading handlers configuration

2011-09-20 18:15:07,886 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/hooks.ini

2011-09-20 18:15:07,886 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/script_executor.ini

2011-09-20 18:15:07,887 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/ip_list_builder.ini

2011-09-20 18:15:07,888 - DEBUG - scalarizr.config - Updating configuration file /etc/scalr/private.d/config.ini

2011-09-20 18:15:07,889 - DEBUG - scalarizr.config - Updating configuration file /etc/scalr/public.d/config.ini

2011-09-20 18:15:07,890 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=True)

2011-09-20 18:15:07,890 - DEBUG - scalarizr.config - Loading main configuration

2011-09-20 18:15:07,890 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/private.d/config.ini

2011-09-20 18:15:07,891 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/config.ini

2011-09-20 18:15:07,891 - DEBUG - scalarizr.config - Loading platform configuration

2011-09-20 18:15:07,892 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/eucalyptus.ini

2011-09-20 18:15:07,892 - DEBUG - scalarizr.config - Loading behaviours configuration

2011-09-20 18:15:07,892 - DEBUG - scalarizr.config - Loading handlers configuration

2011-09-20 18:15:07,893 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/hooks.ini

2011-09-20 18:15:07,893 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/script_executor.ini

2011-09-20 18:15:07,893 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/ip_list_builder.ini

2011-09-20 18:15:07,894 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.deploy

2011-09-20 18:15:07,897 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.deploy

2011-09-20 18:15:07,897 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.hooks

2011-09-20 18:15:07,897 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.hooks

2011-09-20 18:15:07,898 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.script_executor

2011-09-20 18:15:07,898 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.script_executor

2011-09-20 18:15:07,898 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ec2.lifecycle

2011-09-20 18:15:07,899 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ec2.lifecycle

2011-09-20 18:15:07,899 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ip_list_builder

2011-09-20 18:15:07,910 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ip_list_builder

2011-09-20 18:15:07,910 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.euca.rebundle

2011-09-20 18:15:07,933 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.euca.rebundle

2011-09-20 18:15:07,934 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ec2.ebs

2011-09-20 18:15:07,934 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ec2.ebs

2011-09-20 18:15:07,935 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.lifecycle

2011-09-20 18:15:07,935 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.lifecycle

2011-09-20 18:15:07,936 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ssh_auth_keys

2011-09-20 18:15:07,936 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ssh_auth_keys

2011-09-20 18:15:07,936 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=True)

2011-09-20 18:15:07,937 - DEBUG - scalarizr.config - Loading main configuration

2011-09-20 18:15:07,937 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/private.d/config.ini

2011-09-20 18:15:07,937 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/config.ini

2011-09-20 18:15:07,938 - DEBUG - scalarizr.config - Loading platform configuration

2011-09-20 18:15:07,938 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/eucalyptus.ini

2011-09-20 18:15:07,939 - DEBUG - scalarizr.config - Loading behaviours configuration

2011-09-20 18:15:07,939 - DEBUG - scalarizr.config - Loading handlers configuration

2011-09-20 18:15:07,939 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/hooks.ini

2011-09-20 18:15:07,939 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/script_executor.ini

2011-09-20 18:15:07,940 - DEBUG - scalarizr.config - Reading configuration file /etc/scalr/public.d/ip_list_builder.ini

2011-09-20 18:15:07,940 - DEBUG - scalarizr - Initialize platform

2011-09-20 18:15:07,959 - DEBUG - scalarizr.config - Reading file /etc/scalr/private.d/.state

2011-09-20 18:15:07,960 - DEBUG - scalarizr.config - Reading file /etc/scalr/private.d/.state

2011-09-20 18:15:07,960 - DEBUG - scalarizr.util.filetool - Reading file /etc/scalr/private.d/.scalr-version

2011-09-20 18:15:07,960 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=False)

2011-09-20 18:15:07,961 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=False)

2011-09-20 18:15:07,961 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.deploy

2011-09-20 18:15:07,962 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.deploy

2011-09-20 18:15:07,962 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.hooks

2011-09-20 18:15:07,962 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.hooks

2011-09-20 18:15:07,963 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.script_executor

2011-09-20 18:15:07,963 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.script_executor

2011-09-20 18:15:07,963 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ec2.lifecycle

2011-09-20 18:15:07,963 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ec2.lifecycle

2011-09-20 18:15:07,964 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ip_list_builder

2011-09-20 18:15:07,964 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ip_list_builder

2011-09-20 18:15:07,964 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.euca.rebundle

2011-09-20 18:15:07,965 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.euca.rebundle

2011-09-20 18:15:07,965 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ec2.ebs

2011-09-20 18:15:07,965 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ec2.ebs

2011-09-20 18:15:07,965 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.lifecycle

2011-09-20 18:15:07,966 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.lifecycle

2011-09-20 18:15:07,966 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ssh_auth_keys

2011-09-20 18:15:07,966 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ssh_auth_keys

2011-09-20 18:15:07,967 - DEBUG - scalarizr - Got scalr url: 'http://scalr.webheadsba.lco.gtn'

2011-09-20 18:15:07,967 - DEBUG - scalarizr - Initialize QueryEnv client

2011-09-20 18:15:07,967 - DEBUG - scalarizr - Initialize messaging

2011-09-20 18:15:07,968 - DEBUG - scalarizr - Initialize message handlers

2011-09-20 18:15:07,969 - DEBUG - scalarizr.handlers - Initialize message listener

2011-09-20 18:15:07,969 - DEBUG - scalarizr.handlers - Gathered _accept_kwargs: {'behaviour': [], 'dist': ('CentOS', '5.7', 'Final'), 'os': ('Linux', 'euca-172-16-7-100.eucalyptus.webheadsba.lco.gtn', '2.6.27.21-0.1-xen', '#1 SMP 2009-03-31 14:50:44 +0200', 'x86_64', 'x86_64'), 'platform': 'eucalyptus'}

2011-09-20 18:15:07,969 - DEBUG - scalarizr.handlers - Collecting handlers chain

2011-09-20 18:15:07,971 - DEBUG - scalarizr.messaging.p2p - Add rotate messages table task for periodical executor

2011-09-20 18:15:07,971 - DEBUG - scalarizr.config - Reading file /etc/scalr/private.d/.state

2011-09-20 18:15:07,972 - DEBUG - scalarizr.handlers - Collected handlers chain: [<scalarizr.handlers.deploy.DeploymentHandler object at 0x1022b10>, <scalarizr.handlers.hooks.HooksHandler object at 0x1022b90>, <scalarizr.handlers.script_executor.ScriptExecutor object at 0x1022c10>, <scalarizr.handlers.ec2.lifecycle.Ec2LifeCycleHandler object at 0x1022d10>, <scalarizr.handlers.ip_list_builder.IpListBuilder object at 0x102c250>, <scalarizr.handlers.euca.rebundle.EucaRebundleHandler object at 0x102c290>, <scalarizr.handlers.ec2.ebs.EbsHandler object at 0x102c2d0>, <scalarizr.handlers.lifecycle.LifeCycleHandler object at 0x102c350>, <scalarizr.handlers.ssh_auth_keys.SSHKeys object at 0x102c7d0>]

2011-09-20 18:15:07,972 - DEBUG - scalarizr - Schedule SNMP process

2011-09-20 18:15:07,984 - DEBUG - scalarizr.util - system: hostname euca-172-16-7-100.eucalyptus.webheadsba.lco.gtn

2011-09-20 18:15:07,992 - DEBUG - scalarizr.util.filetool - Reading file /root/.ssh/authorized_keys

2011-09-20 18:15:08,004 - DEBUG - scalarizr.util - system: ('mount', '-a')

2011-09-20 18:15:08,011 - DEBUG - scalarizr.handlers.ssh_auth_keys - Found <_sre.SRE_Pattern object at 0x1083960>

2011-09-20 18:15:08,011 - DEBUG - scalarizr.handlers.ssh_auth_keys - Found <_sre.SRE_Pattern object at 0x1085580>

2011-09-20 18:15:08,012 - DEBUG - scalarizr.handlers.ssh_auth_keys - Found <_sre.SRE_Pattern object at 0x1085fd0>

2011-09-20 18:15:08,012 - DEBUG - scalarizr.handlers.hooks - Hook on 'init'() {}

2011-09-20 18:15:08,027 - INFO - scalarizr.messaging.p2p.consumer - Building message consumer server on 0.0.0.0:8013

2011-09-20 18:15:08,049 - DEBUG - scalarizr.util.PeriodicalExecutor - Executing task Rotate messages sqlite table

2011-09-20 18:15:08,050 - DEBUG - scalarizr.util - Caught: 'thread._local' object has no attribute 'current'

2011-09-20 18:15:08,050 - DEBUG - scalarizr.util - Creating new object...

2011-09-20 18:15:08,051 - DEBUG - scalarizr.util - Created <scalarizr.util._SqliteConnection object at 0x102cad0>

2011-09-20 18:15:08,051 - DEBUG - scalarizr.util - Added weakref <weakref at 0xfe7f18; to '_SqliteConnection' at 0x102cad0>

2011-09-20 18:15:08,051 - DEBUG - scalarizr - Open SQLite database (file: /etc/scalr/private.d/db.sqlite)

2011-09-20 18:15:08,050 - DEBUG - scalarizr.messaging.p2p.consumer - Starting message consumer http://0.0.0.0:8013

2011-09-20 18:15:08,053 - DEBUG - scalarizr.messaging.p2p.consumer - Starting message handler

2011-09-20 18:15:08,053 - DEBUG - scalarizr.util - Caught: 'thread._local' object has no attribute 'current'

2011-09-20 18:15:08,053 - DEBUG - scalarizr.util - Creating new object...

2011-09-20 18:15:08,054 - DEBUG - scalarizr.util - Created <scalarizr.util._SqliteConnection object at 0x102cc10>

2011-09-20 18:15:08,054 - DEBUG - scalarizr.util - Added weakref <weakref at 0x102a3c0; to '_SqliteConnection' at 0x102cc10>

2011-09-20 18:15:08,054 - DEBUG - scalarizr - Open SQLite database (file: /etc/scalr/private.d/db.sqlite)

2011-09-20 18:15:08,055 - INFO - scalarizr.handlers.lifecycle - Server will be imported into Scalr

2011-09-20 18:15:08,092 - DEBUG - scalarizr.handlers.hooks - Hook on 'before_hello'(<scalarizr.messaging.p2p.P2pMessage object at 0x102cc90>,) {}

2011-09-20 18:15:08,098 - INFO - scalarizr.snmp.agent - [pid: 1874] Starting SNMP server on 0.0.0.0:8014

2011-09-20 18:15:08,134 - DEBUG - scalarizr.messaging.p2p.producer - Sending message 'Hello' into queue 'control'

2011-09-20 18:15:08,135 - DEBUG - scalarizr.util - Caught: 'thread._local' object has no attribute 'current'

2011-09-20 18:15:08,135 - DEBUG - scalarizr.util - Creating new object...

2011-09-20 18:15:08,135 - DEBUG - scalarizr.util - Created <scalarizr.util._SqliteConnection object at 0x102ce10>

2011-09-20 18:15:08,135 - DEBUG - scalarizr.util - Added weakref <weakref at 0x102a680; to '_SqliteConnection' at 0x102ce10>

2011-09-20 18:15:08,135 - DEBUG - scalarizr - Open SQLite database (file: /etc/scalr/private.d/db.sqlite)

2011-09-20 18:15:08,137 - DEBUG - scalarizr.messaging.p2p - Commiting put_outgoing

2011-09-20 18:15:08,143 - DEBUG - scalarizr.messaging.p2p - Commited put_outgoing

2011-09-20 18:15:08,144 - DEBUG - scalarizr.messaging.p2p.producer - Delivering message 'Hello' <?xml version="1.0" ?><message id="3bbb4629-665d-4b77-adad-71683ce80418" name="Hello"><meta><szr_version>0.7.91</szr_version><server_id>015e0a5b-b988-48b9-b52a-e502ca9d49d2</server_id></meta><body><aws_avail_zone>cluster00</aws_avail_zone><local_ip>10.1.0.130</local_ip><aws_ami_id>emi-92570EED</aws_ami_id><aws_instance_type>c1.medium</aws_instance_type><behaviour/><aws_instance_id>i-2DF40657</aws_instance_id><role_name>Base</role_name><architecture>x86_64</architecture><remote_ip>172.16.7.100</remote_ip></body></message>

2011-09-20 18:15:08,144 - DEBUG - scalarizr.messaging.p2p.security - Encrypting message

2011-09-20 18:15:08,176 - DEBUG - scalarizr.config - Reading file /etc/scalr/private.d/.state

2011-09-20 18:15:08,258 - DEBUG - scalarizr.snmp.agent - Starting transport dispatcher

2011-09-20 18:15:08,259 - DEBUG - scalarizr.snmp.agent - Run transport dispatcher

2011-09-20 18:15:08,273 - INFO - scalarizr.messaging.p2p.producer - Message 'Hello' delivered (message_id: 3bbb4629-665d-4b77-adad-71683ce80418)

2011-09-20 18:15:08,275 - DEBUG - scalarizr.handlers.hooks - Hook on 'hello'() {}

2011-09-20 18:15:08,275 - DEBUG - scalarizr.handlers.hooks - Hook on 'start'() {}

2011-09-20 18:15:08,275 - DEBUG - scalarizr.config - Reading file /etc/scalr/private.d/.state