Uploaded image for project: 'MINA SSHD'
  1. MINA SSHD
  2. SSHD-138

Fresh git clone failed on ScpTest testWithGanymede

    XMLWordPrintableJSON

Details

    • Bug
    • Status: Resolved
    • Major
    • Resolution: Fixed
    • 0.5.0
    • 0.9.0
    • None
    • Windows Xp sp3 32 bit java 1.6.0.26

    Description

      Console log

      Testsuite: org.apache.sshd.ScpTest
      2011-07-25 11:24:27,109 [main ] INFO SecurityUtils - Trying to register BouncyCastle as a JCE provider
      2011-07-25 11:24:27,671 [main ] INFO SecurityUtils - Registration succeeded
      Log(jsch,1): Connecting to localhost port 2963
      Log(jsch,1): Connection established
      2011-07-25 11:24:28,062 [NioProcessor-2 ] INFO ServerSession - Session created...
      Log(jsch,1): Remote version string: SSH-2.0-SSHD-CORE-0.5.1-SNAPSHOT
      Log(jsch,1): Local version string: SSH-2.0-JSCH-0.1.42
      Log(jsch,1): CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
      2011-07-25 11:24:28,156 [NioProcessor-2 ] INFO ServerSession - Client version string: SSH-2.0-JSCH-0.1.42
      Log(jsch,1): aes256-ctr is not available.
      Log(jsch,1): aes192-ctr is not available.
      Log(jsch,1): aes256-cbc is not available.
      Log(jsch,1): aes192-cbc is not available.
      Log(jsch,1): arcfour256 is not available.
      Log(jsch,1): SSH_MSG_KEXINIT sent
      Log(jsch,1): SSH_MSG_KEXINIT received
      2011-07-25 11:24:28,328 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_KEXINIT
      Log(jsch,1): kex: server->client aes128-cbc hmac-md5 none
      Log(jsch,1): kex: client->server aes128-cbc hmac-md5 none
      Log(jsch,1): SSH_MSG_KEXDH_INIT sent
      Log(jsch,1): expecting SSH_MSG_KEXDH_REPLY
      2011-07-25 11:24:28,390 [NioProcessor-2 ] INFO DHG1 - Received SSH_MSG_KEXDH_INIT
      2011-07-25 11:24:28,468 [NioProcessor-2 ] INFO DHG1 - Send SSH_MSG_KEXDH_REPLY
      2011-07-25 11:24:28,468 [NioProcessor-2 ] INFO ServerSession - Send SSH_MSG_NEWKEYS
      Log(jsch,1): ssh_rsa_verify: signature true
      Log(jsch,2): Permanently added 'localhost' (RSA) to the list of known hosts.
      Log(jsch,1): SSH_MSG_NEWKEYS sent
      Log(jsch,1): SSH_MSG_NEWKEYS received
      2011-07-25 11:24:28,500 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_NEWKEYS
      Log(jsch,1): SSH_MSG_SERVICE_REQUEST sent
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_SERVICE_REQUEST 'ssh-userauth'
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Accepting user authentication request
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Authorized authentication methods: password
      Log(jsch,1): SSH_MSG_SERVICE_ACCEPT received
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Authenticating user 'sshd' with method 'none'
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Unsupported authentication method 'none'
      Log(jsch,1): Authentications that can continue: password
      Log(jsch,1): Next authentication method: password
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Authenticating user 'sshd' with method 'password'
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Authentication succeeded
      Log(jsch,1): Authentication succeeded (password).
      2011-07-25 11:24:28,718 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:28,781 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 0
      2011-07-25 11:24:28,781 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:28,781 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t target/scp/out.txt
      2011-07-25 11:24:28,906 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:28,906 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:28,906 [et/scp/out.txt]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:147)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,015 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 0
      2011-07-25 11:24:29,015 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 0
      2011-07-25 11:24:29,015 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,015 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 1
      2011-07-25 11:24:29,015 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,109 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t target/scp
      2011-07-25 11:24:29,234 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 1
      2011-07-25 11:24:29,234 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 1
      2011-07-25 11:24:29,234 [-t, target/scp]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:147)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,328 [-t, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 1
      2011-07-25 11:24:29,328 [-t, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 1
      2011-07-25 11:24:29,343 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 2
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t target
      2011-07-25 11:24:29,406 [cp, -t, target]] INFO ScpCommand - Error in scp command
      java.io.IOException: File is a directory: org.apache.sshd.server.filesystem.NativeSshFile@82764b
      at org.apache.sshd.server.command.ScpCommand.writeFile(ScpCommand.java:314)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:171)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,437 [cp, -t, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 2
      2011-07-25 11:24:29,437 [cp, -t, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 2
      2011-07-25 11:24:29,437 [cp, -t, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 2
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 2
      2011-07-25 11:24:29,437 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 3
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -f target/scp
      2011-07-25 11:24:29,468 [-f, target/scp]] INFO ScpCommand - Error in scp command
      java.io.IOException: org.apache.sshd.server.filesystem.NativeSshFile@1e4f7c2 not a regular file
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:216)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,546 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 3
      2011-07-25 11:24:29,546 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 3
      2011-07-25 11:24:29,546 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 3
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 3
      2011-07-25 11:24:29,546 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 4
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -f target/scp/out.txt
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 4
      2011-07-25 11:24:29,593 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 4
      2011-07-25 11:24:29,656 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 4
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 4
      2011-07-25 11:24:29,656 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,718 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 5
      2011-07-25 11:24:29,718 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,718 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -r -f target/scp
      2011-07-25 11:24:29,781 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 5
      2011-07-25 11:24:29,781 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 5
      2011-07-25 11:24:29,781 [-f, target/scp]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.readDir(ScpCommand.java:413)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:218)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,875 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 5
      2011-07-25 11:24:29,875 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 5
      2011-07-25 11:24:29,781 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,906 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 6
      2011-07-25 11:24:29,906 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,906 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t -r target
      Log(jsch,1): Disconnecting from localhost port 2963
      2011-07-25 11:24:30,031 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 6
      2011-07-25 11:24:30,031 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 6
      2011-07-25 11:24:30,031 [-t, -r, target]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:147)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:30,031 [NioProcessor-2 ] INFO ServerSession - Closing session
      2011-07-25 11:24:30,093 [-t, -r, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 6
      2011-07-25 11:24:30,093 [-t, -r, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 6
      2011-07-25 11:24:30,234 [NioProcessor-6 ] INFO ServerSession - Session created...
      2011-07-25 11:24:30,234 [NioProcessor-6 ] INFO ServerSession - Client version string: SSH-2.0-Ganymed Build_210
      2011-07-25 11:24:30,250 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_KEXINIT
      2011-07-25 11:24:30,515 [NioProcessor-6 ] INFO DHG14 - Received SSH_MSG_KEXDH_INIT
      2011-07-25 11:24:30,796 [NioProcessor-6 ] INFO DHG14 - Send SSH_MSG_KEXDH_REPLY
      2011-07-25 11:24:30,796 [NioProcessor-6 ] INFO ServerSession - Send SSH_MSG_NEWKEYS
      2011-07-25 11:24:31,046 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_NEWKEYS
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_SERVICE_REQUEST 'ssh-userauth'
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Accepting user authentication request
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Authorized authentication methods: password
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Authenticating user 'sshd' with method 'none'
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Unsupported authentication method 'none'
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Authenticating user 'sshd' with method 'password'
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Authentication succeeded
      2011-07-25 11:24:31,203 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:31,203 [NioProcessor-6 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Executing command: scp -t -d target/scp/gan
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:31,296 [target/scp/gan]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:31,296 [target/scp/gan]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ServerSession - Closing session
      2011-07-25 11:24:31,406 [NioProcessor-6 ] INFO ChannelSession - Closing channel 1 immediately
      Tests run: 2, Failures: 0, Errors: 1, Time elapsed: 4.719 sec

      ------------- Standard Output ---------------
      2011-07-25 11:24:27,109 [main ] INFO SecurityUtils - Trying to register BouncyCastle as a JCE provider
      2011-07-25 11:24:27,671 [main ] INFO SecurityUtils - Registration succeeded
      Log(jsch,1): Connecting to localhost port 2963
      Log(jsch,1): Connection established
      2011-07-25 11:24:28,062 [NioProcessor-2 ] INFO ServerSession - Session created...
      Log(jsch,1): Remote version string: SSH-2.0-SSHD-CORE-0.5.1-SNAPSHOT
      Log(jsch,1): Local version string: SSH-2.0-JSCH-0.1.42
      Log(jsch,1): CheckCiphers: aes256-ctr,aes192-ctr,aes128-ctr,aes256-cbc,aes192-cbc,aes128-cbc,3des-ctr,arcfour,arcfour128,arcfour256
      2011-07-25 11:24:28,156 [NioProcessor-2 ] INFO ServerSession - Client version string: SSH-2.0-JSCH-0.1.42
      Log(jsch,1): aes256-ctr is not available.
      Log(jsch,1): aes192-ctr is not available.
      Log(jsch,1): aes256-cbc is not available.
      Log(jsch,1): aes192-cbc is not available.
      Log(jsch,1): arcfour256 is not available.
      Log(jsch,1): SSH_MSG_KEXINIT sent
      Log(jsch,1): SSH_MSG_KEXINIT received
      2011-07-25 11:24:28,328 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_KEXINIT
      Log(jsch,1): kex: server->client aes128-cbc hmac-md5 none
      Log(jsch,1): kex: client->server aes128-cbc hmac-md5 none
      Log(jsch,1): SSH_MSG_KEXDH_INIT sent
      Log(jsch,1): expecting SSH_MSG_KEXDH_REPLY
      2011-07-25 11:24:28,390 [NioProcessor-2 ] INFO DHG1 - Received SSH_MSG_KEXDH_INIT
      2011-07-25 11:24:28,468 [NioProcessor-2 ] INFO DHG1 - Send SSH_MSG_KEXDH_REPLY
      2011-07-25 11:24:28,468 [NioProcessor-2 ] INFO ServerSession - Send SSH_MSG_NEWKEYS
      Log(jsch,1): ssh_rsa_verify: signature true
      Log(jsch,2): Permanently added 'localhost' (RSA) to the list of known hosts.
      Log(jsch,1): SSH_MSG_NEWKEYS sent
      Log(jsch,1): SSH_MSG_NEWKEYS received
      2011-07-25 11:24:28,500 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_NEWKEYS
      Log(jsch,1): SSH_MSG_SERVICE_REQUEST sent
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_SERVICE_REQUEST 'ssh-userauth'
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Accepting user authentication request
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Authorized authentication methods: password
      Log(jsch,1): SSH_MSG_SERVICE_ACCEPT received
      2011-07-25 11:24:28,578 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Authenticating user 'sshd' with method 'none'
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Unsupported authentication method 'none'
      Log(jsch,1): Authentications that can continue: password
      Log(jsch,1): Next authentication method: password
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Authenticating user 'sshd' with method 'password'
      2011-07-25 11:24:28,687 [NioProcessor-2 ] INFO ServerSession - Authentication succeeded
      Log(jsch,1): Authentication succeeded (password).
      2011-07-25 11:24:28,718 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:28,781 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 0
      2011-07-25 11:24:28,781 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:28,781 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t target/scp/out.txt
      2011-07-25 11:24:28,906 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:28,906 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:28,906 [et/scp/out.txt]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:147)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,015 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 0
      2011-07-25 11:24:29,015 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 0
      2011-07-25 11:24:29,015 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,015 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 1
      2011-07-25 11:24:29,015 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,109 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t target/scp
      2011-07-25 11:24:29,234 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 1
      2011-07-25 11:24:29,234 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 1
      2011-07-25 11:24:29,234 [-t, target/scp]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:147)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,328 [-t, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 1
      2011-07-25 11:24:29,328 [-t, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 1
      2011-07-25 11:24:29,343 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 2
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t target
      2011-07-25 11:24:29,406 [cp, -t, target]] INFO ScpCommand - Error in scp command
      java.io.IOException: File is a directory: org.apache.sshd.server.filesystem.NativeSshFile@82764b
      at org.apache.sshd.server.command.ScpCommand.writeFile(ScpCommand.java:314)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:171)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,437 [cp, -t, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 2
      2011-07-25 11:24:29,437 [cp, -t, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 2
      2011-07-25 11:24:29,437 [cp, -t, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 2
      2011-07-25 11:24:29,406 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 2
      2011-07-25 11:24:29,437 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 3
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -f target/scp
      2011-07-25 11:24:29,468 [-f, target/scp]] INFO ScpCommand - Error in scp command
      java.io.IOException: org.apache.sshd.server.filesystem.NativeSshFile@1e4f7c2 not a regular file
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:216)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,546 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 3
      2011-07-25 11:24:29,546 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 3
      2011-07-25 11:24:29,546 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 3
      2011-07-25 11:24:29,468 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 3
      2011-07-25 11:24:29,546 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 4
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -f target/scp/out.txt
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 4
      2011-07-25 11:24:29,593 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 4
      2011-07-25 11:24:29,656 [et/scp/out.txt]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 4
      2011-07-25 11:24:29,593 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 4
      2011-07-25 11:24:29,656 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,718 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 5
      2011-07-25 11:24:29,718 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,718 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -r -f target/scp
      2011-07-25 11:24:29,781 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 5
      2011-07-25 11:24:29,781 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 5
      2011-07-25 11:24:29,781 [-f, target/scp]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.readDir(ScpCommand.java:413)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:218)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:29,875 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 5
      2011-07-25 11:24:29,875 [-f, target/scp]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 5
      2011-07-25 11:24:29,781 [NioProcessor-2 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:29,906 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 6
      2011-07-25 11:24:29,906 [NioProcessor-2 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:29,906 [NioProcessor-2 ] INFO ChannelSession - Executing command: scp -t -r target
      Log(jsch,1): Disconnecting from localhost port 2963
      2011-07-25 11:24:30,031 [NioProcessor-2 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 6
      2011-07-25 11:24:30,031 [NioProcessor-2 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 6
      2011-07-25 11:24:30,031 [-t, -r, target]] INFO ScpCommand - Error in scp command
      java.io.IOException: Pipe closed
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:72)
      at org.apache.sshd.common.channel.ChannelPipedInputStream.read(ChannelPipedInputStream.java:58)
      at org.apache.sshd.server.command.ScpCommand.readAck(ScpCommand.java:422)
      at org.apache.sshd.server.command.ScpCommand.run(ScpCommand.java:147)
      at java.lang.Thread.run(Thread.java:662)
      2011-07-25 11:24:30,031 [NioProcessor-2 ] INFO ServerSession - Closing session
      2011-07-25 11:24:30,093 [-t, -r, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 6
      2011-07-25 11:24:30,093 [-t, -r, target]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 6
      2011-07-25 11:24:30,234 [NioProcessor-6 ] INFO ServerSession - Session created...
      2011-07-25 11:24:30,234 [NioProcessor-6 ] INFO ServerSession - Client version string: SSH-2.0-Ganymed Build_210
      2011-07-25 11:24:30,250 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_KEXINIT
      2011-07-25 11:24:30,515 [NioProcessor-6 ] INFO DHG14 - Received SSH_MSG_KEXDH_INIT
      2011-07-25 11:24:30,796 [NioProcessor-6 ] INFO DHG14 - Send SSH_MSG_KEXDH_REPLY
      2011-07-25 11:24:30,796 [NioProcessor-6 ] INFO ServerSession - Send SSH_MSG_NEWKEYS
      2011-07-25 11:24:31,046 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_NEWKEYS
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_SERVICE_REQUEST 'ssh-userauth'
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Accepting user authentication request
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Authorized authentication methods: password
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:31,093 [NioProcessor-6 ] INFO ServerSession - Authenticating user 'sshd' with method 'none'
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Unsupported authentication method 'none'
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_USERAUTH_REQUEST
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Authenticating user 'sshd' with method 'password'
      2011-07-25 11:24:31,187 [NioProcessor-6 ] INFO ServerSession - Authentication succeeded
      2011-07-25 11:24:31,203 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:31,203 [NioProcessor-6 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_REQUEST on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Received channel request: exec
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Executing command: scp -t -d target/scp/gan
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Received SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ChannelSession - Send SSH_MSG_CHANNEL_CLOSE on channel 0
      2011-07-25 11:24:31,296 [target/scp/gan]] INFO ChannelSession - Send SSH_MSG_CHANNEL_EOF on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ServerSession - Received SSH_MSG_CHANNEL_OPEN session
      2011-07-25 11:24:31,296 [target/scp/gan]] INFO ChannelSession - Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 0
      2011-07-25 11:24:31,296 [NioProcessor-6 ] INFO ServerSession - Closing session
      2011-07-25 11:24:31,406 [NioProcessor-6 ] INFO ChannelSession - Closing channel 1 immediately
      ------------- ---------------- ---------------
      Testcase: testWithGanymede(org.apache.sshd.ScpTest): Caused an ERROR
      Error during SCP transfer.
      java.io.IOException: Error during SCP transfer.
      at ch.ethz.ssh2.SCPClient.put(SCPClient.java:510)
      at ch.ethz.ssh2.SCPClient.put(SCPClient.java:466)
      at org.apache.sshd.ScpTest.testWithGanymede(ScpTest.java:171)
      Caused by: java.io.IOException: Could not open channel (The connection is being shutdown)
      at ch.ethz.ssh2.channel.ChannelManager.waitUntilChannelOpen(ChannelManager.java:123)
      at ch.ethz.ssh2.channel.ChannelManager.openSessionChannel(ChannelManager.java:568)
      at ch.ethz.ssh2.Session.<init>(Session.java:39)
      at ch.ethz.ssh2.Connection.openSession(Connection.java:965)
      at ch.ethz.ssh2.SCPClient.put(SCPClient.java:504)

      Test org.apache.sshd.ScpTest FAILED
      test:
      Deleting: D:\DOCUME~1\DENIS~1.BAR\LOCALS~1\Temp\TEST-org.apache.sshd.ScpTest.xml
      BUILD SUCCESSFUL (total time: 6 seconds)

      Attachments

        Issue Links

          depends upon

          Bug - A problem which impairs or prevents the functions of the product. SSHD-237 SSHD may send an exit-status after the channel being closed

          • Major - Major loss of function.
          • Resolved

          Activity

            People

              gnodet Guillaume Nodet
              denis.bardadym Denis Bardadym
              Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

              Dates

                Created:
                Updated:
                Resolved: