Skip to content

Instantly share code, notes, and snippets.

@arohner
Created October 18, 2011 19:56
Show Gist options
  • Select an option

  • Save arohner/1296509 to your computer and use it in GitHub Desktop.

Select an option

Save arohner/1296509 to your computer and use it in GitHub Desktop.
node startup log
INFO [2011-10-18 14:53:57,803] pool-2-thread-3 - pallet.compute.jclouds - options {:image-id "us-east-1/ami-06ad526f", :location-id "us-east-1", :os-family :ubuntu, :hardware-id "m1.small", :security-groups ["www" "allow-DB"]}
DEBUG [2011-10-18 14:53:57,803] pool-2-thread-3 - jclouds.compute - >> searching params([biggest=false, fastest=false, imageName=null, imageDescription=null, imageId=us-east-1/ami-06ad526f, imagePredicate=null, imageVersion=null, location=[id=us-east-1, scope=REGION, description=us-east-1, parent=aws-ec2, iso3166Codes=[US-VA], metadata={}], minCores=0.0, minRam=0, osFamily=ubuntu, osName=null, osDescription=null, osVersion=null, osArch=null, os64Bit=null, hardwareId=m1.small])
DEBUG [2011-10-18 14:53:57,809] pool-2-thread-3 - jclouds.compute - << matched hardware(m1.small)
DEBUG [2011-10-18 14:53:57,809] pool-2-thread-3 - jclouds.compute - << matched image(us-east-1/ami-06ad526f)
DEBUG [2011-10-18 14:53:57,809] pool-2-thread-3 - jclouds.compute - >> running 1 node group(circle) location(us-east-1) image(us-east-1/ami-06ad526f) hardwareProfile(m1.small) options([groupIds=[www, allow-DB], keyPair=null, noKeyPair=false, monitoringEnabled=false, placementGroup=null, noPlacementGroup=false, subnetId=null, userData=[35, 99, 108, 111, 117, 100, 45, 99, 111, 110, 102, 105, 103, 10, 114, 101, 112, 111, 95, 117, 112, 103, 114, 97, 100, 101, 58, 32, 110, 111, 110, 101, 10], blockDeviceMappings=[], spotPrice=null, spotOptions=[formParameters={}]])
DEBUG [2011-10-18 14:53:57,809] pool-2-thread-3 - jclouds.compute - >> searching params([biggest=false, fastest=false, imageName=null, imageDescription=099720109477/ebs/ubuntu-images/ubuntu-natty-11.04-i386-server-20110426, imageId=null, imagePredicate=null, imageVersion=20110426, location=[id=us-east-1, scope=REGION, description=us-east-1, parent=aws-ec2, iso3166Codes=[US-VA], metadata={}], minCores=1.0, minRam=1740, osFamily=ubuntu, osName=null, osDescription=099720109477/ebs/ubuntu-images/ubuntu-natty-11.04-i386-server-20110426, osVersion=11.04, osArch=paravirtual, os64Bit=false, hardwareId=null])
DEBUG [2011-10-18 14:53:57,842] pool-2-thread-3 - jclouds.compute - << matched hardware(m1.small)
DEBUG [2011-10-18 14:53:57,844] pool-2-thread-3 - jclouds.compute - << matched image(us-east-1/ami-06ad526f)
WARN [2011-10-18 14:53:57,844] pool-2-thread-3 - jclouds.compute - to avoid creating temporary keys in aws-ec2, use templateOption overrideLoginCredentialWith(id_rsa)
DEBUG [2011-10-18 14:53:57,844] pool-2-thread-3 - jclouds.compute - >> creating keyPair region(us-east-1) group(circle)
DEBUG [2011-10-18 14:53:59,529] pool-2-thread-3 - jclouds.compute - << created keyPair([region=us-east-1, keyName=jclouds#circle#us-east-1#60, fingerprint=a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d, sha1OfPrivateKey=98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29, keyMaterial?=true])
DEBUG [2011-10-18 14:53:59,530] pool-2-thread-3 - jclouds.compute - >> creating securityGroup region(us-east-1) name(jclouds#circle#us-east-1)
DEBUG [2011-10-18 14:53:59,862] pool-2-thread-3 - jclouds.compute - << created securityGroup(jclouds#circle#us-east-1)
DEBUG [2011-10-18 14:53:59,862] pool-2-thread-3 - jclouds.compute - >> running 1 instance region(us-east-1) zone(null) ami(ami-06ad526f) params({InstanceType=[m1.small], SecurityGroup.1=[jclouds#circle#us-east-1], SecurityGroup.2=[www], SecurityGroup.3=[allow-DB], KeyName=[jclouds#circle#us-east-1#60], UserData=[I2Nsb3VkLWNvbmZpZwpyZXBvX3VwZ3JhZGU6IG5vbmUK]})
DEBUG [2011-10-18 14:54:00,195] pool-2-thread-3 - jclouds.compute - << started instances([region=us-east-1, name=i-18150078])
DEBUG [2011-10-18 14:54:00,469] pool-2-thread-3 - jclouds.compute - << present instances([region=us-east-1, name=i-18150078])
DEBUG [2011-10-18 14:54:00,469] pool-2-thread-3 - org.jclouds.aws.ec2.compute.functions.AWSRunningInstanceToNodeMetadata - no group parsed from i-18150078's security groups: []
DEBUG [2011-10-18 14:54:08,967] user thread 8 - jclouds.compute - >> providing images
DEBUG [2011-10-18 14:54:08,967] user thread 5 - jclouds.compute - >> providing images
DEBUG [2011-10-18 14:54:09,489] user thread 5 - jclouds.compute - << images(40)
DEBUG [2011-10-18 14:54:17,135] user thread 8 - jclouds.compute - << images(3292)
DEBUG [2011-10-18 14:54:17,605] user thread 6 - jclouds.compute - << images(3292)
DEBUG [2011-10-18 14:54:21,136] user thread 6 - jclouds.compute - >> blocking on socket [address=50.17.55.80, port=22] for 600000 MILLISECONDS
DEBUG [2011-10-18 14:54:43,311] user thread 6 - jclouds.compute - << socket [address=50.17.55.80, port=22] opened
DEBUG [2011-10-18 14:54:43,318] user thread 6 - jclouds.ssh - >> (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) acquiring Session(timeout=60000)
INFO [2011-10-18 14:54:43,319] user thread 6 - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:43,396] user thread 6 - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:43,476] user thread 6 - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:43,477] user thread 6 - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:43,477] user thread 6 - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:43,492] user thread 6 - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:43,492] user thread 6 - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
WARN [2011-10-18 14:54:43,496] user thread 6 - jclouds.ssh - << (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
com.jcraft.jsch.JSchException: Session.connect: java.io.IOException: End of IO Stream Read
at com.jcraft.jsch.Session.connect(Session.java:504)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:190)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:162)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:206)
at org.jclouds.ssh.jsch.JschSshClient.connect(JschSshClient.java:227)
at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:89)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:150)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:57)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:43,496] user thread 6 - org.jclouds.http.handlers.BackoffLimitedRetryHandler - Retry 1/7: delaying for 200 ms: (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
DEBUG [2011-10-18 14:54:43,698] user thread 6 - jclouds.ssh - >> (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) acquiring Session(timeout=60000)
INFO [2011-10-18 14:54:43,698] user thread 6 - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:43,774] user thread 6 - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:43,856] user thread 6 - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:43,856] user thread 6 - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:43,856] user thread 6 - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:43,858] user thread 6 - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:43,859] user thread 6 - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
WARN [2011-10-18 14:54:43,859] user thread 6 - jclouds.ssh - << (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
com.jcraft.jsch.JSchException: Session.connect: java.io.IOException: End of IO Stream Read
at com.jcraft.jsch.Session.connect(Session.java:504)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:190)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:162)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:206)
at org.jclouds.ssh.jsch.JschSshClient.connect(JschSshClient.java:227)
at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:89)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:150)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:57)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:43,859] user thread 6 - org.jclouds.http.handlers.BackoffLimitedRetryHandler - Retry 2/7: delaying for 800 ms: (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
DEBUG [2011-10-18 14:54:44,660] user thread 6 - jclouds.ssh - >> (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) acquiring Session(timeout=60000)
INFO [2011-10-18 14:54:44,661] user thread 6 - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:44,787] user thread 6 - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:44,888] user thread 6 - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:44,888] user thread 6 - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:44,889] user thread 6 - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:44,891] user thread 6 - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:44,895] user thread 6 - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
WARN [2011-10-18 14:54:44,896] user thread 6 - jclouds.ssh - << (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
com.jcraft.jsch.JSchException: Session.connect: java.io.IOException: End of IO Stream Read
at com.jcraft.jsch.Session.connect(Session.java:504)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:190)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:162)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:206)
at org.jclouds.ssh.jsch.JschSshClient.connect(JschSshClient.java:227)
at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:89)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:150)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:57)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:44,896] user thread 6 - org.jclouds.http.handlers.BackoffLimitedRetryHandler - Retry 3/7: delaying for 1800 ms: (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
DEBUG [2011-10-18 14:54:46,697] user thread 6 - jclouds.ssh - >> (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) acquiring Session(timeout=60000)
INFO [2011-10-18 14:54:46,697] user thread 6 - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:46,828] user thread 6 - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:46,989] user thread 6 - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:46,989] user thread 6 - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:46,989] user thread 6 - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:46,991] user thread 6 - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:46,995] user thread 6 - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
WARN [2011-10-18 14:54:46,995] user thread 6 - jclouds.ssh - << (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
com.jcraft.jsch.JSchException: Session.connect: java.io.IOException: End of IO Stream Read
at com.jcraft.jsch.Session.connect(Session.java:504)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:190)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:162)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:206)
at org.jclouds.ssh.jsch.JschSshClient.connect(JschSshClient.java:227)
at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:89)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:150)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:57)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:46,996] user thread 6 - org.jclouds.http.handlers.BackoffLimitedRetryHandler - Retry 4/7: delaying for 2000 ms: (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
DEBUG [2011-10-18 14:54:48,997] user thread 6 - jclouds.ssh - >> (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) acquiring Session(timeout=60000)
INFO [2011-10-18 14:54:48,997] user thread 6 - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:49,075] user thread 6 - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:49,178] user thread 6 - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:49,179] user thread 6 - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:49,179] user thread 6 - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:49,180] user thread 6 - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:49,186] user thread 6 - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
WARN [2011-10-18 14:54:49,186] user thread 6 - jclouds.ssh - << (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
com.jcraft.jsch.JSchException: Session.connect: java.io.IOException: End of IO Stream Read
at com.jcraft.jsch.Session.connect(Session.java:504)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:190)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:162)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:206)
at org.jclouds.ssh.jsch.JschSshClient.connect(JschSshClient.java:227)
at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:89)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:150)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:57)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:49,186] user thread 6 - org.jclouds.http.handlers.BackoffLimitedRetryHandler - Retry 5/7: delaying for 2000 ms: (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
DEBUG [2011-10-18 14:54:51,187] user thread 6 - jclouds.ssh - >> (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) acquiring Session(timeout=60000)
INFO [2011-10-18 14:54:51,188] user thread 6 - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:51,263] user thread 6 - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:51,369] user thread 6 - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:51,369] user thread 6 - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:51,369] user thread 6 - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:51,370] user thread 6 - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:51,376] user thread 6 - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
WARN [2011-10-18 14:54:51,377] user thread 6 - jclouds.ssh - << (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
com.jcraft.jsch.JSchException: Session.connect: java.io.IOException: End of IO Stream Read
at com.jcraft.jsch.Session.connect(Session.java:504)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:190)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:162)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:206)
at org.jclouds.ssh.jsch.JschSshClient.connect(JschSshClient.java:227)
at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:89)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:150)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:57)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:51,377] user thread 6 - org.jclouds.http.handlers.BackoffLimitedRetryHandler - Retry 6/7: delaying for 2000 ms: (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
DEBUG [2011-10-18 14:54:53,378] user thread 6 - jclouds.ssh - >> (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) acquiring Session(timeout=60000)
INFO [2011-10-18 14:54:53,379] user thread 6 - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:53,458] user thread 6 - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:53,559] user thread 6 - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:53,559] user thread 6 - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:53,559] user thread 6 - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:53,560] user thread 6 - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:53,567] user thread 6 - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
ERROR [2011-10-18 14:54:53,567] user thread 6 - jclouds.compute - << problem applying options to node(us-east-1/i-18150078):
org.jclouds.ssh.SshException: (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) (ubuntu:rsa[fingerprint(a0:26:23:79:41:9a:a0:0a:3a:cc:34:df:db:1c:59:6d),sha1(98:8e:3b:36:a6:3a:f0:f7:ea:5c:88:72:bf:cc:54:e7:1b:99:9c:29)]@50.17.55.80:22) error acquiring Session(timeout=60000): Session.connect: java.io.IOException: End of IO Stream Read
at org.jclouds.ssh.jsch.JschSshClient.propagate(JschSshClient.java:362)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:213)
at org.jclouds.ssh.jsch.JschSshClient.connect(JschSshClient.java:227)
at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:89)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:150)
at org.jclouds.compute.strategy.CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.call(CustomizeNodeAndAddToGoodMapOrPutExceptionIntoBadMap.java:57)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
Caused by: com.jcraft.jsch.JSchException: Session.connect: java.io.IOException: End of IO Stream Read
at com.jcraft.jsch.Session.connect(Session.java:504)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:190)
at org.jclouds.ssh.jsch.JschSshClient$1.create(JschSshClient.java:162)
at org.jclouds.ssh.jsch.JschSshClient.acquire(JschSshClient.java:206)
... 9 more
WARN [2011-10-18 14:54:53,569] pool-2-thread-3 - pallet.compute.jclouds - Failed to start 1 of 1 nodes for group :circle
WARN [2011-10-18 14:54:53,569] pool-2-thread-3 - pallet.compute.jclouds - Exception while trying to remove failed nodes for :circle
java.lang.IllegalArgumentException: No implementation of method: :id of protocol: #'pallet.node/Node found for class: com.google.common.collect.ImmutableEntry
at clojure.core$_cache_protocol_fn.invoke(core_deftype.clj:471)
at pallet.node$eval1041$fn__1086$G__1026__1091.invoke(node.clj:4)
at clojure.lang.AFn.applyToHelper(AFn.java:161)
at clojure.lang.AFn.applyTo(AFn.java:151)
at clojure.core$apply.invoke(core.clj:540)
at pallet.compute$id.doInvoke(compute.clj:33)
at clojure.lang.RestFn.invoke(RestFn.java:408)
at pallet.compute.jclouds.JcloudsService.destroy_node(jclouds.clj:496)
at pallet.compute.jclouds.JcloudsService$process_failed_start_nodes__8716$fn__8724.invoke(jclouds.clj:441)
at pallet.compute.jclouds.JcloudsService$process_failed_start_nodes__8716.invoke(jclouds.clj:440)
at pallet.compute.jclouds.JcloudsService.run_nodes(jclouds.clj:467)
at pallet.core$create_nodes.invoke(core.clj:414)
at pallet.core$adjust_node_count.invoke(core.clj:461)
at pallet.core$parallel_adjust_node_counts$p_a_n_c_future__4753$fn__4754.invoke(core.clj:484)
at clojure.lang.AFn.call(AFn.java:18)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
ERROR [2011-10-18 14:54:53,570] Swank REPL Thread - pallet.futures - Adjust node count exception: No additional nodes could be started
clojure.contrib.condition.Condition: No additional nodes could be started
at clojure.contrib.condition.Condition.fillInStackTrace(Unknown Source)
at java.lang.Throwable.<init>(Throwable.java:218)
at clojure.contrib.condition.Condition.<init>(Unknown Source)
at pallet.core$create_nodes.invoke(core.clj:418)
at pallet.core$adjust_node_count.invoke(core.clj:461)
at pallet.core$parallel_adjust_node_counts$p_a_n_c_future__4753$fn__4754.invoke(core.clj:484)
at clojure.lang.AFn.call(AFn.java:18)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:53,571] Swank REPL Thread - pallet.futures - Adjust node count exception
clojure.contrib.condition.Condition: No additional nodes could be started
at clojure.contrib.condition.Condition.fillInStackTrace(Unknown Source)
at java.lang.Throwable.<init>(Throwable.java:218)
at clojure.contrib.condition.Condition.<init>(Unknown Source)
at pallet.core$create_nodes.invoke(core.clj:418)
at pallet.core$adjust_node_count.invoke(core.clj:461)
at pallet.core$parallel_adjust_node_counts$p_a_n_c_future__4753$fn__4754.invoke(core.clj:484)
at clojure.lang.AFn.call(AFn.java:18)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:680)
DEBUG [2011-10-18 14:54:53,571] Swank REPL Thread - pallet.core - pallet version: 0.6.5-SNAPSHOT
INFO [2011-10-18 14:54:53,571] Swank REPL Thread - pallet.core - lift-nodes phases [:settings :configure], groups [:circle]
INFO [2011-10-18 14:54:53,571] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/pre-settings for :circle with 0 nodes
INFO [2011-10-18 14:54:53,572] Swank REPL Thread - pallet.core - apply-phase :settings for :circle with 0 nodes
INFO [2011-10-18 14:54:53,572] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/post-settings for :circle with 0 nodes
INFO [2011-10-18 14:54:53,572] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/pre-configure for :circle with 0 nodes
INFO [2011-10-18 14:54:53,572] Swank REPL Thread - pallet.core - apply-phase :configure for :circle with 0 nodes
INFO [2011-10-18 14:54:53,572] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/post-configure for :circle with 0 nodes
DEBUG [2011-10-18 14:54:53,576] Swank REPL Thread - pallet.core - pallet version: 0.6.5-SNAPSHOT
INFO [2011-10-18 14:54:53,576] Swank REPL Thread - pallet.core - retrieving nodes
DEBUG [2011-10-18 14:54:53,576] Swank REPL Thread - jclouds.compute - >> listing node details matching(ALWAYS_TRUE)
DEBUG [2011-10-18 14:54:54,903] Swank REPL Thread - jclouds.compute - << list(9)
INFO [2011-10-18 14:54:54,904] Swank REPL Thread - pallet.core - lift-nodes phases [:settings :phase12508], groups [:circle]
DEBUG [2011-10-18 14:54:54,904] Swank REPL Thread - pallet.core - p-f-s server environment null
DEBUG [2011-10-18 14:54:54,905] Swank REPL Thread - pallet.core - p-f-s server environment null
INFO [2011-10-18 14:54:54,905] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/pre-settings for :circle with 1 nodes
INFO [2011-10-18 14:54:54,905] pool-2-thread-3 - pallet.execute - execute-with-ssh on circle "50.17.55.80"
INFO [2011-10-18 14:54:54,905] pool-2-thread-3 - pallet.execute - Admin user circle www.id_rsa
DEBUG [2011-10-18 14:54:54,906] Swank REPL Thread - pallet.core - p-f-s server environment null
INFO [2011-10-18 14:54:54,906] Swank REPL Thread - pallet.core - apply-phase :settings for :circle with 1 nodes
INFO [2011-10-18 14:54:54,906] pool-2-thread-3 - pallet.execute - execute-with-ssh on circle "50.17.55.80"
INFO [2011-10-18 14:54:54,906] pool-2-thread-3 - pallet.execute - Admin user circle www.id_rsa
DEBUG [2011-10-18 14:54:54,906] Swank REPL Thread - pallet.core - p-f-s server environment null
INFO [2011-10-18 14:54:54,906] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/post-settings for :circle with 1 nodes
INFO [2011-10-18 14:54:54,907] pool-2-thread-3 - pallet.execute - execute-with-ssh on circle "50.17.55.80"
INFO [2011-10-18 14:54:54,907] pool-2-thread-3 - pallet.execute - Admin user circle www.id_rsa
DEBUG [2011-10-18 14:54:54,907] Swank REPL Thread - pallet.core - p-f-s server environment null
DEBUG [2011-10-18 14:54:54,908] Swank REPL Thread - pallet.core - p-f-s server environment null
INFO [2011-10-18 14:54:54,908] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/pre-phase12508 for :circle with 1 nodes
INFO [2011-10-18 14:54:54,908] pool-2-thread-3 - pallet.execute - execute-with-ssh on circle "50.17.55.80"
INFO [2011-10-18 14:54:54,908] pool-2-thread-3 - pallet.execute - Admin user circle www.id_rsa
DEBUG [2011-10-18 14:54:54,909] Swank REPL Thread - pallet.core - p-f-s server environment null
INFO [2011-10-18 14:54:54,909] Swank REPL Thread - pallet.core - apply-phase :phase12508 for :circle with 1 nodes
INFO [2011-10-18 14:54:54,909] pool-2-thread-3 - pallet.execute - execute-with-ssh on circle "50.17.55.80"
INFO [2011-10-18 14:54:54,909] pool-2-thread-3 - pallet.execute - Admin user circle www.id_rsa
DEBUG [2011-10-18 14:54:54,910] Swank REPL Thread - pallet.core - p-f-s server environment null
INFO [2011-10-18 14:54:54,910] Swank REPL Thread - pallet.core - apply-phase :pallet.phase/post-phase12508 for :circle with 1 nodes
INFO [2011-10-18 14:54:54,910] pool-2-thread-3 - pallet.execute - execute-with-ssh on circle "50.17.55.80"
INFO [2011-10-18 14:54:54,910] pool-2-thread-3 - pallet.execute - Admin user circle www.id_rsa
INFO [2011-10-18 14:54:54,913] Swank REPL Thread - clj-ssh.ssh - Connecting to 50.17.55.80 port 22
INFO [2011-10-18 14:54:54,988] Swank REPL Thread - clj-ssh.ssh - Connection established
INFO [2011-10-18 14:54:55,158] Swank REPL Thread - clj-ssh.ssh - Remote version string: SSH-2.0-OpenSSH_5.8p1 Debian-1ubuntu3
INFO [2011-10-18 14:54:55,158] Swank REPL Thread - clj-ssh.ssh - Local version string: SSH-2.0-JSCH-0.1.44
INFO [2011-10-18 14:54:55,158] Swank REPL Thread - clj-ssh.ssh - CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
INFO [2011-10-18 14:54:55,161] Swank REPL Thread - clj-ssh.ssh - SSH_MSG_KEXINIT sent
INFO [2011-10-18 14:54:55,287] Swank REPL Thread - clj-ssh.ssh - SSH_MSG_KEXINIT received
INFO [2011-10-18 14:54:55,289] Swank REPL Thread - clj-ssh.ssh - kex: server->client aes128-ctr hmac-md5 none
INFO [2011-10-18 14:54:55,289] Swank REPL Thread - clj-ssh.ssh - kex: client->server aes128-ctr hmac-md5 none
INFO [2011-10-18 14:54:55,298] Swank REPL Thread - clj-ssh.ssh - SSH_MSG_KEXDH_INIT sent
INFO [2011-10-18 14:54:55,298] Swank REPL Thread - clj-ssh.ssh - expecting SSH_MSG_KEXDH_REPLY
INFO [2011-10-18 14:54:55,603] Swank REPL Thread - clj-ssh.ssh - ssh_rsa_verify: signature true
WARN [2011-10-18 14:54:55,605] Swank REPL Thread - clj-ssh.ssh - Permanently added '50.17.55.80' (RSA) to the list of known hosts.
INFO [2011-10-18 14:54:55,606] Swank REPL Thread - clj-ssh.ssh - SSH_MSG_NEWKEYS sent
INFO [2011-10-18 14:54:55,606] Swank REPL Thread - clj-ssh.ssh - SSH_MSG_NEWKEYS received
INFO [2011-10-18 14:54:55,609] Swank REPL Thread - clj-ssh.ssh - SSH_MSG_SERVICE_REQUEST sent
INFO [2011-10-18 14:54:55,737] Swank REPL Thread - clj-ssh.ssh - SSH_MSG_SERVICE_ACCEPT received
INFO [2011-10-18 14:54:56,029] Swank REPL Thread - clj-ssh.ssh - Authentications that can continue: publickey,keyboard-interactive,password
INFO [2011-10-18 14:54:56,029] Swank REPL Thread - clj-ssh.ssh - Next authentication method: publickey
INFO [2011-10-18 14:54:56,277] Swank REPL Thread - clj-ssh.ssh - Disconnecting from 50.17.55.80 port 22
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment