Return-Path: X-Original-To: apmail-camel-issues-archive@minotaur.apache.org Delivered-To: apmail-camel-issues-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 0544E10D99 for ; Thu, 13 Jun 2013 12:51:25 +0000 (UTC) Received: (qmail 19348 invoked by uid 500); 13 Jun 2013 12:51:24 -0000 Delivered-To: apmail-camel-issues-archive@camel.apache.org Received: (qmail 19130 invoked by uid 500); 13 Jun 2013 12:51:22 -0000 Mailing-List: contact issues-help@camel.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@camel.apache.org Delivered-To: mailing list issues@camel.apache.org Received: (qmail 19044 invoked by uid 99); 13 Jun 2013 12:51:20 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 13 Jun 2013 12:51:20 +0000 Date: Thu, 13 Jun 2013 12:51:20 +0000 (UTC) From: "Patrick Wiederkehr (JIRA)" To: issues@camel.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Created] (CAMEL-6454) sftp interprets password wrong MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 Patrick Wiederkehr created CAMEL-6454: ----------------------------------------- Summary: sftp interprets password wrong Key: CAMEL-6454 URL: https://issues.apache.org/jira/browse/CAMEL-6454 Project: Camel Issue Type: Bug Components: camel-ftp Affects Versions: 2.11.0 Environment: Windows Reporter: Patrick Wiederkehr Tried to get a route running with a + in the password option. + gets interpreted as [space] always get authentication errors. 013-06-13 14:46:15,675 [128.98/WIRK/out] TRACE SftpOperations - Session isn't connected, trying to recreate and connect. 2013-06-13 14:46:15,675 [128.98/WIRK/out] DEBUG SftpOperations - Using StrickHostKeyChecking: no 2013-06-13 14:46:15,675 [128.98/WIRK/out] TRACE SftpOperations - Connecting use connectTimeout: 10000 ... 2013-06-13 14:46:15,675 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Connecting to XXX port 22 2013-06-13 14:46:15,705 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Connection established 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Remote version string: SSH-2.0-OpenSSH_4.2 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Local version string: SSH-2.0-JSCH-0.1.49 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> aes256-ctr is not available. 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> aes192-ctr is not available. 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> aes256-cbc is not available. 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> aes192-cbc is not available. 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> arcfour256 is not available. 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> CheckKexes: diffie-hellman-group14-sha1 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> diffie-hellman-group14-sha1 is not available. 2013-06-13 14:46:15,735 [128.98/WIRK/out] INFO SftpOperations - JSCH -> SSH_MSG_KEXINIT sent 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> SSH_MSG_KEXINIT received 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: ssh-rsa,ssh-dss 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: none,zlib@openssh.com 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: none,zlib@openssh.com 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server: 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: diffie-hellman-group1-sha1,diffie-hellman-group-exchange-sha1 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: ssh-rsa,ssh-dss 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: aes128-ctr,aes128-cbc,3des-ctr,3des-cbc,blowfish-cbc 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: aes128-ctr,aes128-cbc,3des-ctr,3des-cbc,blowfish-cbc 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: hmac-md5,hmac-sha1,hmac-sha2-256,hmac-sha1-96,hmac-md5-96 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: hmac-md5,hmac-sha1,hmac-sha2-256,hmac-sha1-96,hmac-md5-96 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: none 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: none 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client: 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: server->client aes128-ctr hmac-md5 none 2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO SftpOperations - JSCH -> kex: client->server aes128-ctr hmac-md5 none 2013-06-13 14:46:15,775 [128.98/WIRK/out] INFO SftpOperations - JSCH -> SSH_MSG_KEXDH_INIT sent 2013-06-13 14:46:15,775 [128.98/WIRK/out] INFO SftpOperations - JSCH -> expecting SSH_MSG_KEXDH_REPLY 2013-06-13 14:46:15,805 [128.98/WIRK/out] INFO SftpOperations - JSCH -> ssh_rsa_verify: signature true 2013-06-13 14:46:15,805 [128.98/WIRK/out] WARN SftpOperations - JSCH -> Permanently added 'XXX' (RSA) to the list of known hosts. 2013-06-13 14:46:15,805 [128.98/WIRK/out] INFO SftpOperations - JSCH -> SSH_MSG_NEWKEYS sent 2013-06-13 14:46:15,805 [128.98/WIRK/out] INFO SftpOperations - JSCH -> SSH_MSG_NEWKEYS received 2013-06-13 14:46:15,805 [128.98/WIRK/out] INFO SftpOperations - JSCH -> SSH_MSG_SERVICE_REQUEST sent 2013-06-13 14:46:15,825 [128.98/WIRK/out] INFO SftpOperations - JSCH -> SSH_MSG_SERVICE_ACCEPT received 2013-06-13 14:46:15,865 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Authentications that can continue: publickey,keyboard-interactive,password 2013-06-13 14:46:15,865 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Next authentication method: publickey 2013-06-13 14:46:15,865 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Authentications that can continue: keyboard-interactive,password 2013-06-13 14:46:15,865 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Next authentication method: keyboard-interactive 2013-06-13 14:46:16,085 [/src/reschedule] DEBUG FileConsumer - Took 0.000 seconds to poll: src\reschedule 2013-06-13 14:46:16,595 [/src/reschedule] DEBUG FileConsumer - Took 0.000 seconds to poll: src\reschedule 2013-06-13 14:46:16,886 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Authentications that can continue: password 2013-06-13 14:46:16,886 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Next authentication method: password 2013-06-13 14:46:16,886 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Login trials exceeds 6 2013-06-13 14:46:16,886 [128.98/WIRK/out] INFO SftpOperations - JSCH -> Disconnecting from XXX port 22 2013-06-13 14:46:16,886 [128.98/WIRK/out] TRACE SftpOperations - Cannot connect due: Cannot connect to sftp://blckche@XXX:22 2013-06-13 14:46:16,886 [128.98/WIRK/out] WARN ilePollingConsumerPollStrategy - Trying to recover by disconnecting from remote server forcing a re-connect at next poll: sftp://blckche@XXX:22 2013-06-13 14:46:16,896 [128.98/WIRK/out] DEBUG DeadLetterChannel - Failed delivery for (MessageId: ID-loyalty4-win7-53562-1371127559742-0-1 on ExchangeId: ID-loyalty4-win7-53562-1371127559742-0-2). On delivery attempt: 0 caught: org.apache.camel.component.file.GenericFileOperationFailedException: Cannot connect to sftp://blckche@XXX:22 2013-06-13 14:46:16,896 [128.98/WIRK/out] DEBUG SendProcessor - >>>> Endpoint[direct://error] Exchange[Message: Consumer Consumer[sftp://blckche@XXX/WIRK/out?binary=true&consumer.bridgeErrorHandler=true&delay=3600000&include=.*%5C.DAT&noop=true&password=******&readLock=rename&separator=UNIX&stepwise=false&throwExceptionOnConnectFailed=true] failed polling endpoint: Endpoint[sftp://blckche@XXX/WIRK/out?binary=true&consumer.bridgeErrorHandler=true&delay=3600000&include=.*%5C.DAT&noop=true&password=******&readLock=rename&separator=UNIX&stepwise=false&throwExceptionOnConnectFailed=true]. Will try again at next poll] 2013-06-13 14:46:16,896 [128.98/WIRK/out] ERROR route2 - Unrecoverable Error occured 2013-06-13 14:46:16,896 [128.98/WIRK/out] DEBUG SendProcessor - >>>> Endpoint[log://ch.loyalty.retailo.gcactivator?level=ERROR&multiline=true&showAll=true] Exchange[Message: Consumer Consumer[sftp://blckche@XXX/WIRK/out?binary=true&consumer.bridgeErrorHandler=true&delay=3600000&include=.*%5C.DAT&noop=true&password=******&readLock=rename&separator=UNIX&stepwise=false&throwExceptionOnConnectFailed=true] failed polling endpoint: Endpoint[sftp://blckche@XXX/WIRK/out?binary=true&consumer.bridgeErrorHandler=true&delay=3600000&include=.*%5C.DAT&noop=true&password=******&readLock=rename&separator=UNIX&stepwise=false&throwExceptionOnConnectFailed=true]. Will try again at next poll] 2013-06-13 14:46:16,906 [128.98/WIRK/out] ERROR gcactivator - Exchange[ , Id:ID-loyalty4-win7-53562-1371127559742-0-2 , ExchangePattern:InOnly , Properties:{CamelToEndpoint=log://ch.loyalty.retailo.gcactivator?level=ERROR&multiline=true&showAll=true, CamelCreatedTimestamp=Thu Jun 13 14:46:16 CEST 2013, CamelFailureRouteId=route1, CamelExceptionCaught=org.apache.camel.component.file.GenericFileOperationFailedException: Cannot connect to sftp://blckche@XXX:22} , Headers:{} , BodyType:String , Body:Consumer Consumer[sftp://blckche@XXX/WIRK/out?binary=true&consumer.bridgeErrorHandler=true&delay=3600000&include=.*%5C.DAT&noop=true&password=******&readLock=rename&separator=UNIX&stepwise=false&throwExceptionOnConnectFailed=true] failed polling endpoint: Endpoint[sftp://blckche@XXX/WIRK/out?binary=true&consumer.bridgeErrorHandler=true&delay=3600000&include=.*%5C.DAT&noop=true&password=******&readLock=rename&separator=UNIX&stepwise=false&throwExceptionOnConnectFailed=true]. Will try again at next poll , CaughtExceptionType:org.apache.camel.component.file.GenericFileOperationFailedException, CaughtExceptionMessage:Cannot connect to sftp://blckche@XXX:22, StackTrace:org.apache.camel.component.file.GenericFileOperationFailedException: Cannot connect to sftp://blckche@XXX:22 at org.apache.camel.component.file.remote.SftpOperations.connect(SftpOperations.java:126) at org.apache.camel.component.file.remote.RemoteFileConsumer.connectIfNecessary(RemoteFileConsumer.java:147) at org.apache.camel.component.file.remote.RemoteFileConsumer.recoverableConnectIfNecessary(RemoteFileConsumer.java:138) at org.apache.camel.component.file.remote.RemoteFileConsumer.prePollCheck(RemoteFileConsumer.java:54) at org.apache.camel.component.file.GenericFileConsumer.poll(GenericFileConsumer.java:104) at org.apache.camel.impl.ScheduledPollConsumer.doRun(ScheduledPollConsumer.java:141) at org.apache.camel.impl.ScheduledPollConsumer.run(ScheduledPollConsumer.java:91) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317) at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204) 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: com.jcraft.jsch.JSchException: Auth fail at com.jcraft.jsch.Session.connect(Session.java:491) at org.apache.camel.component.file.remote.SftpOperations.connect(SftpOperations.java:98) ... 15 more -- This message is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrators For more information on JIRA, see: http://www.atlassian.com/software/jira