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,[email protected],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,[email protected],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,[email protected],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,[email protected],hmac-sha1-96,hmac-md5-96
2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO  SftpOperations                 
- JSCH -> kex: server: none,[email protected]
2013-06-13 14:46:15,765 [128.98/WIRK/out] INFO  SftpOperations                 
- JSCH -> kex: server: none,[email protected]
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

Reply via email to