SS-MBP:~ sinsub$ cat .starcluster/logs/crash-report-9647.txt ---------- CRASH DETAILS ---------- COMMAND: starcluster start -x -u ec2-user t1-micro-trial-cluster 2012-02-26 14:39:03,678 PID: 9647 config.py:551 - DEBUG - Loading config 2012-02-26 14:39:03,678 PID: 9647 config.py:118 - DEBUG - Loading file: /Users/sinsub/.starcluster/config 2012-02-26 14:39:03,681 PID: 9647 awsutils.py:54 - DEBUG - creating self._conn w/ connection_authenticator kwargs = {'proxy_user': None, 'proxy_pass': None, 'proxy_port': None, 'proxy': None, 'is_secure': True, 'path': '/', 'region': None, 'port': None} 2012-02-26 14:39:04,197 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {} 2012-02-26 14:39:04,197 PID: 9647 cluster.py:673 - DEBUG - adding node i-b84074dd to self._nodes list 2012-02-26 14:39:04,198 PID: 9647 cluster.py:673 - DEBUG - adding node i-ba4074df to self._nodes list 2012-02-26 14:39:04,198 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:39:04,198 PID: 9647 cluster.py:513 - INFO - Validating existing instances... 2012-02-26 14:39:04,198 PID: 9647 cluster.py:909 - DEBUG - Launch map: node001 (ami: ami-31814f58, type: t1.micro)... 2012-02-26 14:39:04,198 PID: 9647 cluster.py:1515 - INFO - Validating cluster template settings... 2012-02-26 14:39:04,576 PID: 9647 cluster.py:909 - DEBUG - Launch map: node001 (ami: ami-31814f58, type: t1.micro)... 2012-02-26 14:39:04,576 PID: 9647 cluster.py:1530 - INFO - Cluster template settings are valid 2012-02-26 14:39:04,577 PID: 9647 cluster.py:1406 - INFO - Starting cluster... 2012-02-26 14:39:04,645 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {u'i-ba4074df': , u'i-b84074dd': } 2012-02-26 14:39:04,646 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-b84074dd in self._nodes 2012-02-26 14:39:04,646 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-ba4074df in self._nodes 2012-02-26 14:39:04,646 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:39:04,646 PID: 9647 cluster.py:1412 - INFO - Starting stopped node: master 2012-02-26 14:39:04,848 PID: 9647 cluster.py:1412 - INFO - Starting stopped node: node001 2012-02-26 14:39:05,285 PID: 9647 cluster.py:1218 - INFO - Waiting for cluster to come up... (updating every 30s) 2012-02-26 14:39:05,459 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {u'i-ba4074df': , u'i-b84074dd': } 2012-02-26 14:39:05,459 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-b84074dd in self._nodes 2012-02-26 14:39:05,459 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-ba4074df in self._nodes 2012-02-26 14:39:05,459 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:39:05,459 PID: 9647 cluster.py:1176 - INFO - Waiting for all nodes to be in a 'running' state... 2012-02-26 14:39:05,533 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {u'i-ba4074df': , u'i-b84074dd': } 2012-02-26 14:39:05,533 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-b84074dd in self._nodes 2012-02-26 14:39:05,533 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-ba4074df in self._nodes 2012-02-26 14:39:05,534 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:39:35,643 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {u'i-ba4074df': , u'i-b84074dd': } 2012-02-26 14:39:35,644 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-b84074dd in self._nodes 2012-02-26 14:39:35,644 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-ba4074df in self._nodes 2012-02-26 14:39:35,644 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:40:05,721 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {u'i-ba4074df': , u'i-b84074dd': } 2012-02-26 14:40:05,721 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-b84074dd in self._nodes 2012-02-26 14:40:05,722 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-ba4074df in self._nodes 2012-02-26 14:40:05,722 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:40:05,722 PID: 9647 cluster.py:1194 - INFO - Waiting for SSH to come up on all nodes... 2012-02-26 14:40:05,797 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {u'i-ba4074df': , u'i-b84074dd': } 2012-02-26 14:40:05,797 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-b84074dd in self._nodes 2012-02-26 14:40:05,797 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-ba4074df in self._nodes 2012-02-26 14:40:05,797 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:40:05,879 PID: 9647 ssh.py:75 - DEBUG - loading private key /Users/sinsub/.ssh/mykey.rsa 2012-02-26 14:40:05,880 PID: 9647 ssh.py:160 - DEBUG - Using private key /Users/sinsub/.ssh/mykey.rsa (rsa) 2012-02-26 14:40:05,880 PID: 9647 ssh.py:97 - DEBUG - connecting to host ec2-50-16-8-10.compute-1.amazonaws.com on port 22 as user root 2012-02-26 14:40:07,356 PID: 9647 ssh.py:75 - DEBUG - loading private key /Users/sinsub/.ssh/mykey.rsa 2012-02-26 14:40:07,357 PID: 9647 ssh.py:160 - DEBUG - Using private key /Users/sinsub/.ssh/mykey.rsa (rsa) 2012-02-26 14:40:07,357 PID: 9647 ssh.py:97 - DEBUG - connecting to host ec2-23-20-111-97.compute-1.amazonaws.com on port 22 as user root 2012-02-26 14:40:08,642 PID: 9647 utils.py:89 - INFO - Waiting for cluster to come up took 1.056 mins 2012-02-26 14:40:08,642 PID: 9647 cluster.py:1433 - INFO - The master node is ec2-50-16-8-10.compute-1.amazonaws.com 2012-02-26 14:40:08,642 PID: 9647 cluster.py:1434 - INFO - Setting up the cluster... 2012-02-26 14:40:08,708 PID: 9647 cluster.py:665 - DEBUG - existing nodes: {u'i-ba4074df': , u'i-b84074dd': } 2012-02-26 14:40:08,708 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-b84074dd in self._nodes 2012-02-26 14:40:08,709 PID: 9647 cluster.py:668 - DEBUG - updating existing node i-ba4074df in self._nodes 2012-02-26 14:40:08,709 PID: 9647 cluster.py:681 - DEBUG - returning self._nodes = [, ] 2012-02-26 14:40:08,709 PID: 9647 clustersetup.py:94 - INFO - Configuring hostnames... 2012-02-26 14:40:08,713 PID: 9647 threadpool.py:135 - DEBUG - unfinished_tasks = 2 2012-02-26 14:40:08,714 PID: 9647 ssh.py:179 - DEBUG - creating sftp connection 2012-02-26 14:40:08,714 PID: 9647 ssh.py:179 - DEBUG - creating sftp connection 2012-02-26 14:40:09,715 PID: 9647 threadpool.py:123 - INFO - Shutting down threads... 2012-02-26 14:40:09,720 PID: 9647 threadpool.py:135 - DEBUG - unfinished_tasks = 6 2012-02-26 14:40:10,722 PID: 9647 cli.py:266 - DEBUG - error occurred in job (id=node001): Garbage packet received Traceback (most recent call last): File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/threadpool.py", line 31, in run job.run() File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/threadpool.py", line 58, in run r = self.method(*self.args, **self.kwargs) File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/node.py", line 678, in set_hostname hostname_file = self.ssh.remote_file("/etc/hostname", "w") File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/ssh.py", line 290, in remote_file rfile = self.sftp.open(file, mode) File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/ssh.py", line 180, in sftp self._sftp = paramiko.SFTPClient.from_transport(self.transport) File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp_client.py", line 106, in from_transport return cls(chan) File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp_client.py", line 87, in __init__ server_version = self._send_version() File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp.py", line 108, in _send_version t, data = self._read_packet() File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp.py", line 179, in _read_packet raise SFTPError('Garbage packet received') SFTPError: Garbage packet received error occurred in job (id=master): Garbage packet received Traceback (most recent call last): File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/threadpool.py", line 31, in run job.run() File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/threadpool.py", line 58, in run r = self.method(*self.args, **self.kwargs) File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/node.py", line 678, in set_hostname hostname_file = self.ssh.remote_file("/etc/hostname", "w") File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/ssh.py", line 290, in remote_file rfile = self.sftp.open(file, mode) File "/Library/Python/2.7/site-packages/StarCluster-0.93.1-py2.7.egg/starcluster/ssh.py", line 180, in sftp self._sftp = paramiko.SFTPClient.from_transport(self.transport) File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp_client.py", line 106, in from_transport return cls(chan) File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp_client.py", line 87, in __init__ server_version = self._send_version() File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp.py", line 108, in _send_version t, data = self._read_packet() File "build/bdist.macosx-10.7-intel/egg/paramiko/sftp.py", line 179, in _read_packet raise SFTPError('Garbage packet received') SFTPError: Garbage packet received ---------- SYSTEM INFO ---------- StarCluster: 0.93.1 Python: 2.7.1 (r271:86832, Jun 16 2011, 16:59:05) [GCC 4.2.1 (Based on Apple Inc. build 5658) (LLVM build 2335.15.00)] Platform: Darwin-11.3.0-x86_64-i386-64bit boto: 2.0 paramiko: 1.7.7.1 (George) Crypto: 2.5 jinja2: 2.5.5 decorator: 3.3.1