Hi, I'm trying to launch a CentOS 6.2 AMI (ami-e792cba2) on EC2, but the instance fails to start claiming that it exhausted all available authentication methods. If I create the instance through the AWS site directly, I can login with the "ec2-user" user and can sudo bash. I tried some of the suggestions on https://issues.apache.org/jira/browse/WHIRR-378but to no avail.. tried several combinations of whirr.bootstrap-user and whirr.cluster-user.
Attached you can find the recipe file used (script-ec2.properties) and the output I get (whirr.log) The custom service I use is attached as "whirr-script-service-1.0.jar". It allows to launch some scripts with some params during the bootstrap and the config phases. It makes it easy to quickly try out setups... Thanks in advance for any insights into this issue Sincerely, Gerrit
whirr-script-service-1.0.jar
Description: application/java-archive
Starting cluster on aws-ec2 using identity AKIAJ7KALZSWM3Y5H3DQ 2012-02-20 23:12:38,674 INFO [com.up_nxt.whirr.service.script.ScriptHandler] (main) Role = 'alexandria-server' 2012-02-20 23:12:38,682 INFO [com.up_nxt.whirr.service.script.ScriptHandler] (main) Looking for script key 'whirr.script.alexandria-server.startup' 2012-02-20 23:12:38,683 INFO [com.up_nxt.whirr.service.script.ScriptHandler] (main) Adding statement 'install_alexandria_server' 2012-02-20 23:12:38,683 INFO [org.apache.whirr.actions.BootstrapClusterAction] (main) Bootstrapping cluster 2012-02-20 23:12:38,685 INFO [org.apache.whirr.compute.BootstrapTemplate] (main) Configuring template 2012-02-20 23:12:39,177 INFO [org.apache.whirr.compute.NodeStarter] (pool-3-thread-2) Starting 1 node(s) with roles [script:alexandria-server] 2012-02-20 23:13:32,476 INFO [net.schmizz.sshj.common.SecurityUtils] (user thread 2) Trying to register BouncyCastle as a JCE provider 2012-02-20 23:13:32,695 INFO [net.schmizz.sshj.common.SecurityUtils] (user thread 2) Registration succeeded 2012-02-20 23:13:32,759 WARN [net.schmizz.sshj.DefaultConfig] (user thread 2) Disabling high-strength ciphers: cipher strengths apparently limited by JCE policy 2012-02-20 23:13:32,963 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Client identity string: SSH-2.0-SSHJ_0_7 2012-02-20 23:13:33,132 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Server identity string: SSH-2.0-OpenSSH_5.3 2012-02-20 23:13:33,132 INFO [net.schmizz.sshj.transport.KeyExchanger] (user thread 2) Sending SSH_MSG_KEXINIT 2012-02-20 23:13:33,291 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_KEXINIT 2012-02-20 23:13:33,347 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Sending SSH_MSG_KEXDH_INIT 2012-02-20 23:13:33,521 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received kex followup data 2012-02-20 23:13:33,522 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Received SSH_MSG_KEXDH_REPLY 2012-02-20 23:13:33,575 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Sending SSH_MSG_NEWKEYS 2012-02-20 23:13:33,575 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_NEWKEYS 2012-02-20 23:13:33,578 INFO [net.schmizz.sshj.SSHClient] (user thread 2) Key exchange took 0.446 seconds 2012-02-20 23:13:33,934 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Setting active service to ssh-userauth 2012-02-20 23:13:33,935 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) Trying `publickey` auth... 2012-02-20 23:13:35,035 ERROR [net.schmizz.concurrent.Promise] (user thread 2) <<authenticated>> woke to: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed 2012-02-20 23:13:35,035 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) `publickey` auth failed 2012-02-20 23:13:35,037 INFO [jclouds.ssh] (user thread 2) << (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (attempt 1 of 7): Exhausted available authentication methods 2012-02-20 23:13:35,240 WARN [net.schmizz.sshj.DefaultConfig] (user thread 2) Disabling high-strength ciphers: cipher strengths apparently limited by JCE policy 2012-02-20 23:13:35,397 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Client identity string: SSH-2.0-SSHJ_0_7 2012-02-20 23:13:35,565 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Server identity string: SSH-2.0-OpenSSH_5.3 2012-02-20 23:13:35,565 INFO [net.schmizz.sshj.transport.KeyExchanger] (user thread 2) Sending SSH_MSG_KEXINIT 2012-02-20 23:13:35,723 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_KEXINIT 2012-02-20 23:13:35,769 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Sending SSH_MSG_KEXDH_INIT 2012-02-20 23:13:35,932 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received kex followup data 2012-02-20 23:13:35,932 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Received SSH_MSG_KEXDH_REPLY 2012-02-20 23:13:35,976 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Sending SSH_MSG_NEWKEYS 2012-02-20 23:13:35,976 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_NEWKEYS 2012-02-20 23:13:35,978 INFO [net.schmizz.sshj.SSHClient] (user thread 2) Key exchange took 0.413 seconds 2012-02-20 23:13:36,331 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Setting active service to ssh-userauth 2012-02-20 23:13:36,331 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) Trying `publickey` auth... 2012-02-20 23:13:36,496 ERROR [net.schmizz.concurrent.Promise] (user thread 2) <<authenticated>> woke to: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed 2012-02-20 23:13:36,496 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) `publickey` auth failed 2012-02-20 23:13:36,496 INFO [jclouds.ssh] (user thread 2) << (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (attempt 2 of 7): Exhausted available authentication methods 2012-02-20 23:13:37,302 WARN [net.schmizz.sshj.DefaultConfig] (user thread 2) Disabling high-strength ciphers: cipher strengths apparently limited by JCE policy 2012-02-20 23:13:37,462 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Client identity string: SSH-2.0-SSHJ_0_7 2012-02-20 23:13:37,629 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Server identity string: SSH-2.0-OpenSSH_5.3 2012-02-20 23:13:37,629 INFO [net.schmizz.sshj.transport.KeyExchanger] (user thread 2) Sending SSH_MSG_KEXINIT 2012-02-20 23:13:37,788 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_KEXINIT 2012-02-20 23:13:37,832 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Sending SSH_MSG_KEXDH_INIT 2012-02-20 23:13:38,025 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received kex followup data 2012-02-20 23:13:38,025 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Received SSH_MSG_KEXDH_REPLY 2012-02-20 23:13:38,074 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Sending SSH_MSG_NEWKEYS 2012-02-20 23:13:38,074 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_NEWKEYS 2012-02-20 23:13:38,075 INFO [net.schmizz.sshj.SSHClient] (user thread 2) Key exchange took 0.446 seconds 2012-02-20 23:13:38,450 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Setting active service to ssh-userauth 2012-02-20 23:13:38,450 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) Trying `publickey` auth... 2012-02-20 23:13:38,613 ERROR [net.schmizz.concurrent.Promise] (user thread 2) <<authenticated>> woke to: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed 2012-02-20 23:13:38,613 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) `publickey` auth failed 2012-02-20 23:13:38,613 INFO [jclouds.ssh] (user thread 2) << (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (attempt 3 of 7): Exhausted available authentication methods 2012-02-20 23:13:40,416 WARN [net.schmizz.sshj.DefaultConfig] (user thread 2) Disabling high-strength ciphers: cipher strengths apparently limited by JCE policy 2012-02-20 23:13:40,578 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Client identity string: SSH-2.0-SSHJ_0_7 2012-02-20 23:13:40,746 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Server identity string: SSH-2.0-OpenSSH_5.3 2012-02-20 23:13:40,746 INFO [net.schmizz.sshj.transport.KeyExchanger] (user thread 2) Sending SSH_MSG_KEXINIT 2012-02-20 23:13:40,904 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_KEXINIT 2012-02-20 23:13:40,942 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Sending SSH_MSG_KEXDH_INIT 2012-02-20 23:13:41,111 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received kex followup data 2012-02-20 23:13:41,111 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Received SSH_MSG_KEXDH_REPLY 2012-02-20 23:13:41,148 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Sending SSH_MSG_NEWKEYS 2012-02-20 23:13:41,148 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_NEWKEYS 2012-02-20 23:13:41,149 INFO [net.schmizz.sshj.SSHClient] (user thread 2) Key exchange took 0.403 seconds 2012-02-20 23:13:41,504 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Setting active service to ssh-userauth 2012-02-20 23:13:41,504 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) Trying `publickey` auth... 2012-02-20 23:13:41,668 ERROR [net.schmizz.concurrent.Promise] (user thread 2) <<authenticated>> woke to: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed 2012-02-20 23:13:41,668 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) `publickey` auth failed 2012-02-20 23:13:41,668 INFO [jclouds.ssh] (user thread 2) << (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (attempt 4 of 7): Exhausted available authentication methods 2012-02-20 23:13:43,669 WARN [net.schmizz.sshj.DefaultConfig] (user thread 2) Disabling high-strength ciphers: cipher strengths apparently limited by JCE policy 2012-02-20 23:13:43,828 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Client identity string: SSH-2.0-SSHJ_0_7 2012-02-20 23:13:43,993 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Server identity string: SSH-2.0-OpenSSH_5.3 2012-02-20 23:13:43,993 INFO [net.schmizz.sshj.transport.KeyExchanger] (user thread 2) Sending SSH_MSG_KEXINIT 2012-02-20 23:13:44,154 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_KEXINIT 2012-02-20 23:13:44,196 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Sending SSH_MSG_KEXDH_INIT 2012-02-20 23:13:44,360 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received kex followup data 2012-02-20 23:13:44,360 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Received SSH_MSG_KEXDH_REPLY 2012-02-20 23:13:44,397 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Sending SSH_MSG_NEWKEYS 2012-02-20 23:13:44,397 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_NEWKEYS 2012-02-20 23:13:44,397 INFO [net.schmizz.sshj.SSHClient] (user thread 2) Key exchange took 0.404 seconds 2012-02-20 23:13:44,750 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Setting active service to ssh-userauth 2012-02-20 23:13:44,750 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) Trying `publickey` auth... 2012-02-20 23:13:44,915 ERROR [net.schmizz.concurrent.Promise] (user thread 2) <<authenticated>> woke to: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed 2012-02-20 23:13:44,915 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) `publickey` auth failed 2012-02-20 23:13:44,915 INFO [jclouds.ssh] (user thread 2) << (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (attempt 5 of 7): Exhausted available authentication methods 2012-02-20 23:13:46,916 WARN [net.schmizz.sshj.DefaultConfig] (user thread 2) Disabling high-strength ciphers: cipher strengths apparently limited by JCE policy 2012-02-20 23:13:47,077 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Client identity string: SSH-2.0-SSHJ_0_7 2012-02-20 23:13:47,246 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Server identity string: SSH-2.0-OpenSSH_5.3 2012-02-20 23:13:47,246 INFO [net.schmizz.sshj.transport.KeyExchanger] (user thread 2) Sending SSH_MSG_KEXINIT 2012-02-20 23:13:47,402 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_KEXINIT 2012-02-20 23:13:47,445 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Sending SSH_MSG_KEXDH_INIT 2012-02-20 23:13:47,612 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received kex followup data 2012-02-20 23:13:47,612 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Received SSH_MSG_KEXDH_REPLY 2012-02-20 23:13:47,651 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Sending SSH_MSG_NEWKEYS 2012-02-20 23:13:47,651 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_NEWKEYS 2012-02-20 23:13:47,652 INFO [net.schmizz.sshj.SSHClient] (user thread 2) Key exchange took 0.405 seconds 2012-02-20 23:13:48,005 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Setting active service to ssh-userauth 2012-02-20 23:13:48,005 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) Trying `publickey` auth... 2012-02-20 23:13:48,170 ERROR [net.schmizz.concurrent.Promise] (user thread 2) <<authenticated>> woke to: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed 2012-02-20 23:13:48,170 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) `publickey` auth failed 2012-02-20 23:13:48,170 INFO [jclouds.ssh] (user thread 2) << (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (attempt 6 of 7): Exhausted available authentication methods 2012-02-20 23:13:50,172 WARN [net.schmizz.sshj.DefaultConfig] (user thread 2) Disabling high-strength ciphers: cipher strengths apparently limited by JCE policy 2012-02-20 23:13:50,331 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Client identity string: SSH-2.0-SSHJ_0_7 2012-02-20 23:13:50,503 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Server identity string: SSH-2.0-OpenSSH_5.3 2012-02-20 23:13:50,503 INFO [net.schmizz.sshj.transport.KeyExchanger] (user thread 2) Sending SSH_MSG_KEXINIT 2012-02-20 23:13:50,659 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_KEXINIT 2012-02-20 23:13:50,697 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Sending SSH_MSG_KEXDH_INIT 2012-02-20 23:13:50,866 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received kex followup data 2012-02-20 23:13:50,867 INFO [net.schmizz.sshj.transport.kex.DHG14] (reader) Received SSH_MSG_KEXDH_REPLY 2012-02-20 23:13:50,906 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Sending SSH_MSG_NEWKEYS 2012-02-20 23:13:50,906 INFO [net.schmizz.sshj.transport.KeyExchanger] (reader) Received SSH_MSG_NEWKEYS 2012-02-20 23:13:50,907 INFO [net.schmizz.sshj.SSHClient] (user thread 2) Key exchange took 0.404 seconds 2012-02-20 23:13:51,259 INFO [net.schmizz.sshj.transport.TransportImpl] (user thread 2) Setting active service to ssh-userauth 2012-02-20 23:13:51,260 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) Trying `publickey` auth... 2012-02-20 23:13:51,424 ERROR [net.schmizz.concurrent.Promise] (user thread 2) <<authenticated>> woke to: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed 2012-02-20 23:13:51,424 INFO [net.schmizz.sshj.userauth.UserAuthImpl] (user thread 2) `publickey` auth failed 2012-02-20 23:13:51,427 ERROR [jclouds.ssh] (user thread 2) << (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (out of retries - max 7): Exhausted available authentication methods net.schmizz.sshj.userauth.UserAuthException: Exhausted available authentication methods at net.schmizz.sshj.userauth.UserAuthImpl.authenticate(UserAuthImpl.java:114) at net.schmizz.sshj.SSHClient.auth(SSHClient.java:204) at net.schmizz.sshj.SSHClient.authPublickey(SSHClient.java:304) at net.schmizz.sshj.SSHClient.authPublickey(SSHClient.java:323) at org.jclouds.sshj.SshjSshClient$1.create(SshjSshClient.java:205) at org.jclouds.sshj.SshjSshClient$1.create(SshjSshClient.java:177) at org.jclouds.sshj.SshjSshClient.acquire(SshjSshClient.java:226) at org.jclouds.sshj.SshjSshClient.connect(SshjSshClient.java:255) at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:90) 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:662) Caused by: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed at net.schmizz.sshj.userauth.UserAuthImpl.handle(UserAuthImpl.java:157) at net.schmizz.sshj.transport.TransportImpl.handle(TransportImpl.java:474) at net.schmizz.sshj.transport.Decoder.decode(Decoder.java:127) at net.schmizz.sshj.transport.Decoder.received(Decoder.java:195) at net.schmizz.sshj.transport.Reader.run(Reader.java:72) 2012-02-20 23:13:51,432 ERROR [jclouds.compute] (user thread 2) << problem applying options to node(us-west-1/i-3d02307a): org.jclouds.rest.AuthorizationException: (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) (root:rsa[fingerprint(d6:d6:63:28:ff:20:9e:fb:d8:19:27:11:a6:77:70:bb),sha1(65:e1:7e:37:57:f1:ca:d2:6d:10:4a:f8:05:e7:91:f5:9f:40:c2:68)]@184.169.240.54:22) error acquiring SSHClient(timeout=60000) (out of retries - max 7): Exhausted available authentication methods at org.jclouds.sshj.SshjSshClient.propagate(SshjSshClient.java:414) at org.jclouds.sshj.SshjSshClient.acquire(SshjSshClient.java:236) at org.jclouds.sshj.SshjSshClient.connect(SshjSshClient.java:255) at org.jclouds.compute.callables.RunScriptOnNodeAsInitScriptUsingSsh.call(RunScriptOnNodeAsInitScriptUsingSsh.java:90) 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:662) Caused by: net.schmizz.sshj.userauth.UserAuthException: Exhausted available authentication methods at net.schmizz.sshj.userauth.UserAuthImpl.authenticate(UserAuthImpl.java:114) at net.schmizz.sshj.SSHClient.auth(SSHClient.java:204) at net.schmizz.sshj.SSHClient.authPublickey(SSHClient.java:304) at net.schmizz.sshj.SSHClient.authPublickey(SSHClient.java:323) at org.jclouds.sshj.SshjSshClient$1.create(SshjSshClient.java:205) at org.jclouds.sshj.SshjSshClient$1.create(SshjSshClient.java:177) at org.jclouds.sshj.SshjSshClient.acquire(SshjSshClient.java:226) ... 9 more Caused by: net.schmizz.sshj.userauth.UserAuthException: publickey auth failed at net.schmizz.sshj.userauth.UserAuthImpl.handle(UserAuthImpl.java:157) at net.schmizz.sshj.transport.TransportImpl.handle(TransportImpl.java:474) at net.schmizz.sshj.transport.Decoder.decode(Decoder.java:127) at net.schmizz.sshj.transport.Decoder.received(Decoder.java:195) at net.schmizz.sshj.transport.Reader.run(Reader.java:72) 2012-02-20 23:13:51,446 INFO [org.apache.whirr.compute.NodeStarter] (pool-3-thread-3) Starting 1 node(s) with roles [script:alexandria-server] 2012-02-20 23:13:51,447 ERROR [org.apache.whirr.compute.StartupProcess] (pool-3-thread-1) Unexpected error while starting 1 nodes, minimum 1 nodes for [script:alexandria-server] of cluster upnxt-gge-test java.util.concurrent.ExecutionException: java.util.NoSuchElementException: no image matched predicate: And(locationEqualsOrChildOf(us-west-1),And(osFamily(centos),osDescription(081293161529/CentOS-6.2-x86_64-virtastic-120216-2),osVersion(),os64Bit(true),osArch(paravirtual)),imageVersion(2),imageDescription(CentOS-6.2-x86_64-virtastic-120216-2)) at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:222) at java.util.concurrent.FutureTask.get(FutureTask.java:83) at org.apache.whirr.compute.StartupProcess.waitForOutcomes(StartupProcess.java:129) at org.apache.whirr.compute.StartupProcess.call(StartupProcess.java:82) at org.apache.whirr.compute.StartupProcess.call(StartupProcess.java:40) 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:662) Caused by: java.util.NoSuchElementException: no image matched predicate: And(locationEqualsOrChildOf(us-west-1),And(osFamily(centos),osDescription(081293161529/CentOS-6.2-x86_64-virtastic-120216-2),osVersion(),os64Bit(true),osArch(paravirtual)),imageVersion(2),imageDescription(CentOS-6.2-x86_64-virtastic-120216-2)) at org.jclouds.compute.domain.internal.TemplateBuilderImpl.throwNoSuchElementExceptionAfterLoggingImageIds(TemplateBuilderImpl.java:620) at org.jclouds.compute.domain.internal.TemplateBuilderImpl.build(TemplateBuilderImpl.java:608) at org.jclouds.ec2.compute.strategy.EC2CreateNodesInGroupThenAddToSet.execute(EC2CreateNodesInGroupThenAddToSet.java:135) at org.jclouds.compute.internal.BaseComputeService.createNodesInGroup(BaseComputeService.java:199) at org.jclouds.aws.ec2.compute.AWSEC2ComputeService.createNodesInGroup(AWSEC2ComputeService.java:130) at org.apache.whirr.compute.NodeStarter.call(NodeStarter.java:55) at org.apache.whirr.compute.NodeStarter.call(NodeStarter.java:30) ... 5 more 2012-02-20 23:13:51,448 INFO [org.apache.whirr.compute.StartupProcess] (pool-3-thread-1) Destroying failed nodes [us-west-1/i-3d02307a] 2012-02-20 23:13:56,249 ERROR [net.schmizz.sshj.transport.TransportImpl] (reader) Dying because - net.schmizz.sshj.transport.TransportException: Broken transport; encountered EOF 2012-02-20 23:13:56,251 ERROR [net.schmizz.sshj.transport.TransportImpl] (reader) Dying because - net.schmizz.sshj.transport.TransportException: Broken transport; encountered EOF 2012-02-20 23:13:56,252 ERROR [net.schmizz.sshj.transport.TransportImpl] (reader) Dying because - net.schmizz.sshj.transport.TransportException: Broken transport; encountered EOF 2012-02-20 23:13:56,253 ERROR [net.schmizz.sshj.transport.TransportImpl] (reader) Dying because - net.schmizz.sshj.transport.TransportException: Broken transport; encountered EOF 2012-02-20 23:13:56,254 ERROR [net.schmizz.sshj.transport.TransportImpl] (reader) Dying because - net.schmizz.sshj.transport.TransportException: Broken transport; encountered EOF 2012-02-20 23:13:56,255 ERROR [net.schmizz.sshj.transport.TransportImpl] (reader) Dying because - net.schmizz.sshj.transport.TransportException: Broken transport; encountered EOF 2012-02-20 23:13:56,256 INFO [net.schmizz.sshj.transport.TransportImpl] (reader) Setting active service to null-service 2012-02-20 23:13:56,258 ERROR [net.schmizz.sshj.transport.TransportImpl] (reader) Dying because - net.schmizz.sshj.transport.TransportException: Broken transport; encountered EOF 2012-02-20 23:13:56,258 INFO [net.schmizz.sshj.transport.TransportImpl] (reader) Setting active service to null-service 2012-02-20 23:13:56,258 INFO [net.schmizz.sshj.transport.TransportImpl] (reader) Setting active service to null-service 2012-02-20 23:13:56,259 INFO [net.schmizz.sshj.transport.TransportImpl] (reader) Setting active service to null-service 2012-02-20 23:13:56,259 INFO [net.schmizz.sshj.transport.TransportImpl] (reader) Setting active service to null-service 2012-02-20 23:13:56,259 INFO [net.schmizz.sshj.transport.TransportImpl] (reader) Setting active service to null-service 2012-02-20 23:13:56,260 INFO [net.schmizz.sshj.transport.TransportImpl] (reader) Setting active service to null-service 2012-02-20 23:14:18,541 INFO [org.apache.whirr.compute.StartupProcess] (pool-3-thread-1) Destroyed failed nodes [us-west-1/i-3d02307a] 2012-02-20 23:14:18,542 ERROR [org.apache.whirr.ClusterController] (main) Unable to start the cluster. Terminating all nodes. java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: Too many instance failed while bootstrapping! 0 successfully started instances while 1 instances failed at org.apache.whirr.actions.BootstrapClusterAction.doAction(BootstrapClusterAction.java:129) at org.apache.whirr.actions.ScriptBasedClusterAction.execute(ScriptBasedClusterAction.java:124) at org.apache.whirr.ClusterController.bootstrapCluster(ClusterController.java:135) at org.apache.whirr.ClusterController.launchCluster(ClusterController.java:111) at org.apache.whirr.cli.command.LaunchClusterCommand.run(LaunchClusterCommand.java:70) at org.apache.whirr.cli.command.LaunchClusterCommand.run(LaunchClusterCommand.java:60) at org.apache.whirr.cli.Main.run(Main.java:69) at org.apache.whirr.cli.Main.main(Main.java:102) Caused by: java.util.concurrent.ExecutionException: java.io.IOException: Too many instance failed while bootstrapping! 0 successfully started instances while 1 instances failed at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:222) at java.util.concurrent.FutureTask.get(FutureTask.java:83) at org.apache.whirr.actions.BootstrapClusterAction.doAction(BootstrapClusterAction.java:124) ... 7 more Caused by: java.io.IOException: Too many instance failed while bootstrapping! 0 successfully started instances while 1 instances failed at org.apache.whirr.compute.StartupProcess.call(StartupProcess.java:92) at org.apache.whirr.compute.StartupProcess.call(StartupProcess.java:40) 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:662) 2012-02-20 23:14:18,544 INFO [org.apache.whirr.state.ClusterStateStore] (main) Unable to load cluster state, assuming it has no running nodes. java.io.FileNotFoundException: /home/gerrit/.whirr/upnxt-gge-test/instances (No such file or directory) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:120) at com.google.common.io.Files$1.getInput(Files.java:105) at com.google.common.io.Files$1.getInput(Files.java:102) at com.google.common.io.CharStreams$2.getInput(CharStreams.java:91) at com.google.common.io.CharStreams$2.getInput(CharStreams.java:88) at com.google.common.io.CharStreams.readLines(CharStreams.java:307) at com.google.common.io.Files.readLines(Files.java:527) at org.apache.whirr.state.FileClusterStateStore.load(FileClusterStateStore.java:55) at org.apache.whirr.state.ClusterStateStore.tryLoadOrEmpty(ClusterStateStore.java:58) at org.apache.whirr.ClusterController.destroyCluster(ClusterController.java:219) at org.apache.whirr.ClusterController.launchCluster(ClusterController.java:119) at org.apache.whirr.cli.command.LaunchClusterCommand.run(LaunchClusterCommand.java:70) at org.apache.whirr.cli.command.LaunchClusterCommand.run(LaunchClusterCommand.java:60) at org.apache.whirr.cli.Main.run(Main.java:69) at org.apache.whirr.cli.Main.main(Main.java:102) 2012-02-20 23:14:18,550 INFO [com.up_nxt.whirr.service.script.ScriptHandler] (main) Role = 'alexandria-server' 2012-02-20 23:14:18,550 INFO [org.apache.whirr.actions.ScriptBasedClusterAction] (main) Finished running destroy phase scripts on all cluster instances 2012-02-20 23:14:18,551 INFO [org.apache.whirr.actions.DestroyClusterAction] (main) Destroying upnxt-gge-test cluster 2012-02-20 23:14:20,016 INFO [org.apache.whirr.actions.DestroyClusterAction] (main) Cluster upnxt-gge-test destroyed Exception in thread "main" java.lang.RuntimeException: java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: Too many instance failed while bootstrapping! 0 successfully started instances while 1 instances failed at org.apache.whirr.ClusterController.launchCluster(ClusterController.java:126) at org.apache.whirr.cli.command.LaunchClusterCommand.run(LaunchClusterCommand.java:70) at org.apache.whirr.cli.command.LaunchClusterCommand.run(LaunchClusterCommand.java:60) at org.apache.whirr.cli.Main.run(Main.java:69) at org.apache.whirr.cli.Main.main(Main.java:102) Caused by: java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: Too many instance failed while bootstrapping! 0 successfully started instances while 1 instances failed at org.apache.whirr.actions.BootstrapClusterAction.doAction(BootstrapClusterAction.java:129) at org.apache.whirr.actions.ScriptBasedClusterAction.execute(ScriptBasedClusterAction.java:124) at org.apache.whirr.ClusterController.bootstrapCluster(ClusterController.java:135) at org.apache.whirr.ClusterController.launchCluster(ClusterController.java:111) ... 4 more Caused by: java.util.concurrent.ExecutionException: java.io.IOException: Too many instance failed while bootstrapping! 0 successfully started instances while 1 instances failed at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:222) at java.util.concurrent.FutureTask.get(FutureTask.java:83) at org.apache.whirr.actions.BootstrapClusterAction.doAction(BootstrapClusterAction.java:124) ... 7 more Caused by: java.io.IOException: Too many instance failed while bootstrapping! 0 successfully started instances while 1 instances failed at org.apache.whirr.compute.StartupProcess.call(StartupProcess.java:92) at org.apache.whirr.compute.StartupProcess.call(StartupProcess.java:40) 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:662)
