You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@mina.apache.org by Matthieu Riou <ma...@offthelip.org> on 2009/09/13 03:48:49 UTC
[sshd] Can't execute a session with the OpenSSH client
Hi,
I'm trying to connect to the Mina SSHD server using the Linux OpenSSH
client (exact version 5.1p1-5ubuntu1). The handshake seems to be
working and I get to the shell but then the communication is one-way
only. What I type is sent to the server but the reply is lost
somewhere in between. I've tried both the process shell and the echo
shell, the symptoms are identical. Things seem to be working better
when I use the client shipped with SSHD but I'd really like to use the
"standard" client.
I've pasted below the logs both on the server side and the client side
(with -v) using the echo shell (with a couple additional println),
hope that helps. Otherwise let me know if I should try something else.
Thanks,
Matthieu
===============
Server Logs
$ sudo java -cp
sshd-0.2.0/sshd-core/target/sshd-core-0.2.0.jar:mina-2.0.0-M6/dist/mina-core-2.0.0-M6.jar:mina-2.0.0-M6/lib/slf4j-api-1.5.2.jar:slf4j-1.5.2/slf4j-jdk14-1.5.2.jar:bcprov-jdk16-143.jar
org.apache.sshd.SshServer
Sep 12, 2009 6:35:08 PM
org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration run
INFO: Trying to register BouncyCastle as a JCE provider
Sep 12, 2009 6:35:08 PM
org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration run
INFO: Registration succeeded
Sep 12, 2009 6:37:45 PM org.apache.sshd.server.session.ServerSession <init>
INFO: Session created...
Sep 12, 2009 6:37:45 PM
org.apache.sshd.common.keyprovider.FileKeyPairProvider loadKeys
INFO: Unable to read key /etc/ssh_host_dsa_key:
java.io.FileNotFoundException: /etc/ssh_host_dsa_key (No such file or
directory)
Sep 12, 2009 6:37:45 PM org.apache.sshd.server.session.ServerSession
readIdentification
INFO: Client version string: SSH-2.0-OpenSSH_5.1p1 Debian-5ubuntu1
Sep 12, 2009 6:37:45 PM org.apache.sshd.server.session.ServerSession
handleMessage
INFO: Received SSH_MSG_KEXINIT
Sep 12, 2009 6:37:45 PM org.apache.sshd.server.kex.AbstractDHGServer next
INFO: Received SSH_MSG_KEXDH_INIT
Sep 12, 2009 6:37:46 PM
org.apache.sshd.common.keyprovider.FileKeyPairProvider loadKeys
INFO: Unable to read key /etc/ssh_host_dsa_key:
java.io.FileNotFoundException: /etc/ssh_host_dsa_key (No such file or
directory)
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.kex.AbstractDHGServer next
INFO: Send SSH_MSG_KEXDH_REPLY
Sep 12, 2009 6:37:46 PM org.apache.sshd.common.session.AbstractSession
sendNewKeys
INFO: Send SSH_MSG_NEWKEYS
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
handleMessage
INFO: Received SSH_MSG_NEWKEYS
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
handleMessage
INFO: Received SSH_MSG_SERVICE_REQUEST 'ssh-userauth'
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Accepting user authentication request
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Authorized authentication methods: password,publickey
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
handleMessage
INFO: Received SSH_MSG_USERAUTH_REQUEST
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Authenticating user 'foo' with method 'none'
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Unsupported authentication method 'none'
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
handleMessage
INFO: Received SSH_MSG_USERAUTH_REQUEST
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Authenticating user 'foo' with method 'publickey'
Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Authentication failed: No PublickeyAuthenticator configured
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession
handleMessage
INFO: Received SSH_MSG_USERAUTH_REQUEST
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Authenticating user 'foo' with method 'password'
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession userAuth
INFO: Authentication succeeded
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession channelOpen
INFO: Received SSH_MSG_CHANNEL_OPEN session
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
handleRequest
INFO: Received SSH_MSG_CHANNEL_REQUEST on channel 0
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
handleRequest
INFO: Received channel request: pty-req
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
handleRequest
INFO: Received SSH_MSG_CHANNEL_REQUEST on channel 0
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
handleRequest
INFO: Received channel request: env
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
handleRequest
INFO: Received SSH_MSG_CHANNEL_REQUEST on channel 0
Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
handleRequest
INFO: Received channel request: shell
RECEIVED bar
WRITE
RECEIVED exit
WRITE
Sep 12, 2009 6:39:08 PM org.apache.sshd.common.channel.AbstractChannel sendEof
INFO: Send SSH_MSG_CHANNEL_EOF on channel 0
Sep 12, 2009 6:39:08 PM
org.apache.sshd.server.channel.AbstractServerChannel sendExitStatus
INFO: Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 0
Sep 12, 2009 6:39:08 PM org.apache.sshd.common.channel.AbstractChannel close
INFO: Send SSH_MSG_CHANNEL_CLOSE on channel 0
Sep 12, 2009 6:39:08 PM org.apache.sshd.common.channel.AbstractChannel
handleClose
INFO: Received SSH_MSG_CHANNEL_CLOSE on channel 0
Sep 12, 2009 6:39:08 PM org.apache.sshd.common.session.AbstractSession close
INFO: Closing session
==============
Client logs
$ ssh -v -p 8000 foo@localhost
OpenSSH_5.1p1 Debian-5ubuntu1, OpenSSL 0.9.8g 19 Oct 2007
debug1: Reading configuration data /etc/ssh/ssh_config
debug1: Applying options for *
debug1: Connecting to localhost [::1] port 8000.
debug1: Connection established.
debug1: identity file /home/mriou/.ssh/identity type -1
debug1: identity file /home/mriou/.ssh/id_rsa type 1
debug1: Checking blacklist file /usr/share/ssh/blacklist.RSA-2048
debug1: Checking blacklist file /etc/ssh/blacklist.RSA-2048
debug1: identity file /home/mriou/.ssh/id_dsa type -1
debug1: Remote protocol version 2.0, remote software version SSHD-CORE-0.2.0
debug1: no match: SSHD-CORE-0.2.0
debug1: Enabling compatibility mode for protocol 2.0
debug1: Local version string SSH-2.0-OpenSSH_5.1p1 Debian-5ubuntu1
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: server->client aes128-cbc hmac-md5 none
debug1: kex: client->server aes128-cbc hmac-md5 none
debug1: sending SSH2_MSG_KEXDH_INIT
debug1: expecting SSH2_MSG_KEXDH_REPLY
debug1: Host '[localhost]:8000' is known and matches the RSA host key.
debug1: Found key in /home/mriou/.ssh/known_hosts:9
debug1: ssh_rsa_verify: signature correct
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: SSH2_MSG_SERVICE_REQUEST sent
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: password,publickey
debug1: Next authentication method: publickey
debug1: Trying private key: /home/mriou/.ssh/identity
debug1: Offering public key: /home/mriou/.ssh/id_rsa
debug1: Authentications that can continue: password,publickey
debug1: Trying private key: /home/mriou/.ssh/id_dsa
debug1: Next authentication method: password
foo@localhost's password:
debug1: Authentication succeeded (password).
debug1: channel 0: new [client-session]
debug1: Entering interactive session.
debug1: Sending environment.
debug1: Sending env LANG = en_US.UTF-8
bar
debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
exit
debug1: channel 0: free: client-session, nchannels 1
Connection to localhost closed.
Transferred: sent 2392, received 1688 bytes, in 79.8 seconds
Bytes per second: sent 30.0, received 21.1
debug1: Exit status 0
Re: [sshd] Can't execute a session with the OpenSSH client
Posted by Matthieu Riou <ma...@gmail.com>.
Thanks for the reply! Here's my terminal line settings:
$ stty -a
speed 38400 baud; rows 65; columns 235; line = 0;
intr = ^C; quit = ^\; erase = ^?; kill = ^U; eof = ^D; eol = M-^?;
eol2 = M-^?; swtch = M-^?; start = ^Q; stop = ^S; susp = ^Z; rprnt =
^R; werase = ^W; lnext = ^V; flush = ^O; min = 1; time = 0;
-parenb -parodd cs8 hupcl -cstopb cread -clocal -crtscts
-ignbrk brkint -ignpar -parmrk -inpck -istrip -inlcr -igncr icrnl ixon
-ixoff -iuclc ixany imaxbel iutf8
opost -olcuc -ocrnl onlcr -onocr -onlret -ofill -ofdel nl0 cr0 tab0 bs0 vt0 ff0
isig icanon iexten echo echoe echok -echonl -noflsh -xcase -tostop
-echoprt echoctl echoke
The output of the server running in debug is shown below.
Thanks!
Matthieu
$ sudo java -cp
sshd-0.2.0/sshd-core/target/sshd-core-0.2.0.jar:mina-2.0.0-M6/dist/mina-core-2.0.0-M6.jar:mina-2.0.0-M6/lib/slf4j-api-1.5.2.jar:slf4j-1.5.2/slf4j-log4j12-1.5.2.jar:log4j-1.2.15.jar:bcprov-jdk16-143.jar:.
org.apache.sshd.SshServer
INFO - SecurityUtils$BouncyCastleRegistration.run(94) | Trying to
register BouncyCastle as a JCE provider
INFO - SecurityUtils$BouncyCastleRegistration.run(98) | Registration succeeded
INFO - ServerSession.<init>(78) | Session created...
INFO - FileKeyPairProvider.loadKeys(93) | Unable to read key
/etc/ssh_host_dsa_key: java.io.FileNotFoundException:
/etc/ssh_host_dsa_key (No such file or directory)
INFO - ServerSession.readIdentification(269) | Client version string:
SSH-2.0-OpenSSH_5.1p1 Debian-5ubuntu1
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_KEXINIT
INFO - ServerSession.handleMessage(137) | Received SSH_MSG_KEXINIT
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_KEXDH_INIT
INFO - AbstractDHGServer.next(83) | Received SSH_MSG_KEXDH_INIT
INFO - FileKeyPairProvider.loadKeys(93) | Unable to read key
/etc/ssh_host_dsa_key: java.io.FileNotFoundException:
/etc/ssh_host_dsa_key (No such file or directory)
DEBUG - AbstractDHGServer.next(118) | K_S: 00 00 00 07 73 73 68 2d 72
73 61 00 00 00 01 23 00 00 01 01 00 a8 8e d5 12 7e 4a b2 cd 99 30 8c
dc c1 70 75 b2 e0 7f fe ab d9 c3 ac 8a e6 52 50 d6 34 ef 24 fe 01 b8
0a c7 b5 0d d5 e8 42 76 6b 46 fc ab b7 c7 e6 98 cf 0f 02 ec ed 7d a1
59 f6 69 a0 c7 af 83 a7 80 11 b1 bb da db 23 c3 08 71 ef 6c 7d 1d 08
c1 84 5f 64 e9 da 2b c3 69 54 bb bd a8 a6 5a 94 f5 90 7d 0f 3b 0e 13
94 8d 59 14 cb cd dd b0 ab 71 9a 91 57 47 a0 d6 f4 27 78 3f 9f 68 04
96 57 57 75 21 68 7c eb 1c 31 61 51 d6 c1 ce 61 5f ab ea 47 ca 60 d2
b9 e6 9b 49 d1 4d 4f b9 db 86 1b 1d 52 76 29 6b 5b 1b 68 16 d8 8c 38
20 f7 d1 0a 5f b0 84 eb 99 fe 99 d7 ac c6 1d 7b 88 12 37 8c ef 58 2a
46 d2 c2 c8 db da 18 f5 80 da f8 6d 63 b3 99 1a 2f c8 d4 69 93 71 fe
e3 a1 b5 88 74 0b 6d 5b 02 fd c4 8f 09 21 b0 e3 dc f4 19 a0 9b db 6f
00 6b e9 6b 8d 98 fe 81 16 b6 9a 99 a1 42 c7
DEBUG - AbstractDHGServer.next(119) | f: 00 c9 bd 63 7d a3 7b 9f 9e
5c 1f 84 78 29 59 77 00 a2 a0 ee b2 09 64 cd 3a 0c a8 e3 16 19 36 04
b8 bc 4b cc 07 a7 46 84 26 5a 65 3a 7b dc 9d a7 e7 1a 2a d2 00 dc 58
67 be 0e 65 33 d0 7e 85 3a fe 24 52 e6 a8 5c 1e 61 22 a5 29 94 e3 5a
5e ff 9d 6c d8 0e c0 d6 3c 0a d5 2c 7c ba 03 16 6f 3a 80 dd 1b 02 f1
3f a3 6d 88 9b 42 b6 94 29 08 43 90 dd 15 63 0d 55 d8 90 72 3d d3 ae
81 39 f2 fa 95 27 95 7e ba 6b 68 26 a8 51 83 35 96 e1 4d fb ad 7a b0
67 71 d1 51 75 d8 d7 85 c7 9e 8e ae 3b 95 27 96 74 03 1d 18 cb 07 3f
53 7e 5c e0 27 4b a4 1e 3e 1e 94 c6 e3 ed ef f1 f1 c8 50 47 e4 23 26
0e 6b 54 70 cb 32 0b 76 d7 0b 30 4f 37 24 f3 14 a7 3d fe de f8 28 ab
01 75 91 d3 26 a2 3f bf 79 0d 43 dc 52 aa 8f c1 ad 1b 4f ca 9b 10 31
fe 78 00 90 19 f4 17 99 1c 9e ac 5b b1 92 42 6a ce 63
DEBUG - AbstractDHGServer.next(120) | sigH: 00 00 00 07 73 73 68 2d 72
73 61 00 00 01 00 84 01 6d 81 62 ef 49 a0 a8 35 7e b2 22 15 5b 1b 6a
22 ab 22 a1 98 b6 8b 01 44 f4 4c 7d 19 42 50 01 35 28 4a 1d 60 e5 0c
ae d5 f8 3f 69 9e 51 1d 89 ac 7b 1b 6b 1a 2a 89 19 37 45 10 06 07 10
89 5d f4 90 02 a0 be 56 73 c6 b1 a0 66 bc 58 f7 08 67 43 48 99 f1 67
55 79 b4 17 83 ad 39 17 de 92 7e 68 cf 14 8a 1c 49 72 5d 41 e1 a0 74
e0 4c 1b 94 e6 03 03 15 99 50 0a 11 f7 f1 23 a6 05 09 3a 5d dc f6 c1
00 02 25 c4 ea 27 b2 e6 20 14 34 50 37 59 90 a1 af be df 6c b3 96 e7
4b da 96 27 1d 6f 55 b0 c3 e0 9a 8a a7 30 27 a8 31 b7 20 a1 a3 de 65
76 4a a8 5f a0 c3 40 55 50 49 55 69 7a 75 6b 04 1b 1f d4 a6 3c ed 02
fc c7 1f 21 57 31 eb 2a 3b 93 a0 0f 06 a3 be b3 6b f5 16 6f c2 1a 99
ee f9 b4 ea a6 74 30 3c 18 45 da 77 00 0e 2d db bf 3b 28 aa dd 68 02
3f d1 e4 d4 d6 08 38 82 53
INFO - AbstractDHGServer.next(124) | Send SSH_MSG_KEXDH_REPLY
INFO - AbstractSession.sendNewKeys(654) | Send SSH_MSG_NEWKEYS
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_NEWKEYS
INFO - ServerSession.handleMessage(156) | Received SSH_MSG_NEWKEYS
DEBUG - ServerSession.handleMessage(107) | Received packet
SSH_MSG_SERVICE_REQUEST
INFO - ServerSession.handleMessage(167) | Received
SSH_MSG_SERVICE_REQUEST 'ssh-userauth'
INFO - ServerSession.userAuth(291) | Accepting user authentication request
INFO - ServerSession.userAuth(296) | Authorized authentication
methods: password,publickey
DEBUG - ServerSession.handleMessage(107) | Received packet
SSH_MSG_USERAUTH_REQUEST
INFO - ServerSession.handleMessage(180) | Received SSH_MSG_USERAUTH_REQUEST
INFO - ServerSession.userAuth(306) | Authenticating user 'foo' with
method 'none'
INFO - ServerSession.userAuth(325) | Unsupported authentication method 'none'
DEBUG - ServerSession.handleMessage(107) | Received packet
SSH_MSG_USERAUTH_REQUEST
INFO - ServerSession.handleMessage(180) | Received SSH_MSG_USERAUTH_REQUEST
INFO - ServerSession.userAuth(306) | Authenticating user 'foo' with
method 'publickey'
INFO - ServerSession.userAuth(322) | Authentication failed: No
PublickeyAuthenticator configured
DEBUG - ServerSession.handleMessage(107) | Received packet
SSH_MSG_USERAUTH_REQUEST
INFO - ServerSession.handleMessage(180) | Received SSH_MSG_USERAUTH_REQUEST
INFO - ServerSession.userAuth(306) | Authenticating user 'foo' with
method 'password'
INFO - ServerSession.userAuth(318) | Authentication succeeded
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_OPEN
INFO - ServerSession.channelOpen(354) | Received SSH_MSG_CHANNEL_OPEN session
DEBUG - ServerSession.handleMessage(107) | Received packet
SSH_MSG_CHANNEL_REQUEST
INFO - ChannelSession.handleRequest(249) | Received
SSH_MSG_CHANNEL_REQUEST on channel 0
INFO - ChannelSession.handleRequest(251) | Received channel request: pty-req
DEBUG - ChannelSession.handlePtyReq(368) | pty for channel 0:
term=xterm, size=(235 - 65), pixels=(0, 0), modes=[TTY_OP_OSPEED(129)
=38400, TTY_OP_ISPEED(128) =38400, VINTR(1) =3, VQUIT(2) =28,
VERASE(3) =127, VKILL(4) =21, VEOF(5) =4, VEOL(6) =255, VEOL2(7) =255,
VSTART(8) =17, VSTOP(9) =19, VSUSP(10) =26, VREPRINT(12) =18,
VWERASE(13) =23, VLNEXT(14) =22, VDISCARD(18) =15, IGNPAR(30) =0,
PARMRK(31) =0, INPCK(32) =0, ISTRIP(33) =0, INCLR(34) =0, IGNCR(35)
=0, ICRNL(36) =1, IUCLC(37) =0, IXON(38) =1, IXANY(39) =1, IXOFF(40)
=0, IMAXBEL(41) =1, ISIG(50) =1, ICANON(51) =1, XCASE(52) =0, ECHO(53)
=1, ECHOE(54) =1, ECHOK(55) =1, ECHONL(56) =0, NOFLSH(57) =0,
TOSTOP(58) =0, IEXTEN(59) =1, ECHOCTL(60) =1, ECHOKE(61) =1,
PENDIN(62) =0, OPOST(70) =1, OLCUC(71) =0, ONLCR(72) =1, OCRNL(73) =0,
ONOCR(74) =0, ONLRET(75) =0, CS7(90) =1, CS8(91) =1, PARENB(92) =0,
PARODD(93) =0]
DEBUG - ServerSession.handleMessage(107) | Received packet
SSH_MSG_CHANNEL_REQUEST
INFO - ChannelSession.handleRequest(249) | Received
SSH_MSG_CHANNEL_REQUEST on channel 0
INFO - ChannelSession.handleRequest(251) | Received channel request: env
DEBUG - ChannelSession.handleEnv(332) | env for channel 0: LANG = en_US.UTF-8
DEBUG - ServerSession.handleMessage(107) | Received packet
SSH_MSG_CHANNEL_REQUEST
INFO - ChannelSession.handleRequest(249) | Received
SSH_MSG_CHANNEL_REQUEST on channel 0
INFO - ChannelSession.handleRequest(251) | Received channel request: shell
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
RECEIVED foo
WRITE
DEBUG - ChannelOutputStream.flush(90) | Send SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_DATA
DEBUG - AbstractChannel.handleData(112) | Received
SSH_MSG_CHANNEL_DATA on channel 0
RECEIVED exit
WRITE
DEBUG - ChannelOutputStream.flush(90) | Send SSH_MSG_CHANNEL_DATA on channel 0
INFO - AbstractChannel.sendEof(164) | Send SSH_MSG_CHANNEL_EOF on channel 0
INFO - AbstractServerChannel.sendExitStatus(49) | Send
SSH_MSG_CHANNEL_REQUEST exit-status on channel 0
INFO - AbstractChannel.close(81) | Send SSH_MSG_CHANNEL_CLOSE on channel 0
DEBUG - ServerSession.handleMessage(107) | Received packet SSH_MSG_CHANNEL_CLOSE
INFO - AbstractChannel.handleClose(96) | Received
SSH_MSG_CHANNEL_CLOSE on channel 0
INFO - AbstractSession.close(276) | Closing session
DEBUG - AbstractSession.close(292) | Closing IoSession
DEBUG - AbstractSession$1IoSessionCloser.operationComplete(266) |
IoSession closed
On Mon, Sep 14, 2009 at 7:24 AM, Guillaume Nodet <gn...@gmail.com> wrote:
> I'm not sure what happens, but I think there's one thing that might
> cause the problem.
> This is the echo flag on the pty-req request. One thing that would
> help is turning on DEBUG logging (you might want to use another slf4j
> implementation such as the log4j one and configure log4j for debug
> logging).
> Can you also give us the output of the following command:
> stty -a
> I wonder if the lflag contains "echo" or "-echo".
>
Re: [sshd] Can't execute a session with the OpenSSH client
Posted by Guillaume Nodet <gn...@gmail.com>.
I'm not sure what happens, but I think there's one thing that might
cause the problem.
This is the echo flag on the pty-req request. One thing that would
help is turning on DEBUG logging (you might want to use another slf4j
implementation such as the log4j one and configure log4j for debug
logging).
Can you also give us the output of the following command:
stty -a
I wonder if the lflag contains "echo" or "-echo".
On Sun, Sep 13, 2009 at 03:48, Matthieu Riou <ma...@offthelip.org> wrote:
> Hi,
>
> I'm trying to connect to the Mina SSHD server using the Linux OpenSSH
> client (exact version 5.1p1-5ubuntu1). The handshake seems to be
> working and I get to the shell but then the communication is one-way
> only. What I type is sent to the server but the reply is lost
> somewhere in between. I've tried both the process shell and the echo
> shell, the symptoms are identical. Things seem to be working better
> when I use the client shipped with SSHD but I'd really like to use the
> "standard" client.
>
> I've pasted below the logs both on the server side and the client side
> (with -v) using the echo shell (with a couple additional println),
> hope that helps. Otherwise let me know if I should try something else.
>
> Thanks,
> Matthieu
>
> ===============
> Server Logs
>
> $ sudo java -cp
> sshd-0.2.0/sshd-core/target/sshd-core-0.2.0.jar:mina-2.0.0-M6/dist/mina-core-2.0.0-M6.jar:mina-2.0.0-M6/lib/slf4j-api-1.5.2.jar:slf4j-1.5.2/slf4j-jdk14-1.5.2.jar:bcprov-jdk16-143.jar
> org.apache.sshd.SshServer
> Sep 12, 2009 6:35:08 PM
> org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration run
> INFO: Trying to register BouncyCastle as a JCE provider
> Sep 12, 2009 6:35:08 PM
> org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration run
> INFO: Registration succeeded
> Sep 12, 2009 6:37:45 PM org.apache.sshd.server.session.ServerSession <init>
> INFO: Session created...
> Sep 12, 2009 6:37:45 PM
> org.apache.sshd.common.keyprovider.FileKeyPairProvider loadKeys
> INFO: Unable to read key /etc/ssh_host_dsa_key:
> java.io.FileNotFoundException: /etc/ssh_host_dsa_key (No such file or
> directory)
> Sep 12, 2009 6:37:45 PM org.apache.sshd.server.session.ServerSession
> readIdentification
> INFO: Client version string: SSH-2.0-OpenSSH_5.1p1 Debian-5ubuntu1
> Sep 12, 2009 6:37:45 PM org.apache.sshd.server.session.ServerSession
> handleMessage
> INFO: Received SSH_MSG_KEXINIT
> Sep 12, 2009 6:37:45 PM org.apache.sshd.server.kex.AbstractDHGServer next
> INFO: Received SSH_MSG_KEXDH_INIT
> Sep 12, 2009 6:37:46 PM
> org.apache.sshd.common.keyprovider.FileKeyPairProvider loadKeys
> INFO: Unable to read key /etc/ssh_host_dsa_key:
> java.io.FileNotFoundException: /etc/ssh_host_dsa_key (No such file or
> directory)
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.kex.AbstractDHGServer next
> INFO: Send SSH_MSG_KEXDH_REPLY
> Sep 12, 2009 6:37:46 PM org.apache.sshd.common.session.AbstractSession
> sendNewKeys
> INFO: Send SSH_MSG_NEWKEYS
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
> handleMessage
> INFO: Received SSH_MSG_NEWKEYS
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
> handleMessage
> INFO: Received SSH_MSG_SERVICE_REQUEST 'ssh-userauth'
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Accepting user authentication request
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Authorized authentication methods: password,publickey
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
> handleMessage
> INFO: Received SSH_MSG_USERAUTH_REQUEST
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Authenticating user 'foo' with method 'none'
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Unsupported authentication method 'none'
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession
> handleMessage
> INFO: Received SSH_MSG_USERAUTH_REQUEST
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Authenticating user 'foo' with method 'publickey'
> Sep 12, 2009 6:37:46 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Authentication failed: No PublickeyAuthenticator configured
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession
> handleMessage
> INFO: Received SSH_MSG_USERAUTH_REQUEST
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Authenticating user 'foo' with method 'password'
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession userAuth
> INFO: Authentication succeeded
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.session.ServerSession channelOpen
> INFO: Received SSH_MSG_CHANNEL_OPEN session
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
> handleRequest
> INFO: Received SSH_MSG_CHANNEL_REQUEST on channel 0
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
> handleRequest
> INFO: Received channel request: pty-req
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
> handleRequest
> INFO: Received SSH_MSG_CHANNEL_REQUEST on channel 0
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
> handleRequest
> INFO: Received channel request: env
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
> handleRequest
> INFO: Received SSH_MSG_CHANNEL_REQUEST on channel 0
> Sep 12, 2009 6:37:48 PM org.apache.sshd.server.channel.ChannelSession
> handleRequest
> INFO: Received channel request: shell
> RECEIVED bar
> WRITE
> RECEIVED exit
> WRITE
> Sep 12, 2009 6:39:08 PM org.apache.sshd.common.channel.AbstractChannel sendEof
> INFO: Send SSH_MSG_CHANNEL_EOF on channel 0
> Sep 12, 2009 6:39:08 PM
> org.apache.sshd.server.channel.AbstractServerChannel sendExitStatus
> INFO: Send SSH_MSG_CHANNEL_REQUEST exit-status on channel 0
> Sep 12, 2009 6:39:08 PM org.apache.sshd.common.channel.AbstractChannel close
> INFO: Send SSH_MSG_CHANNEL_CLOSE on channel 0
> Sep 12, 2009 6:39:08 PM org.apache.sshd.common.channel.AbstractChannel
> handleClose
> INFO: Received SSH_MSG_CHANNEL_CLOSE on channel 0
> Sep 12, 2009 6:39:08 PM org.apache.sshd.common.session.AbstractSession close
> INFO: Closing session
>
> ==============
> Client logs
>
> $ ssh -v -p 8000 foo@localhost
> OpenSSH_5.1p1 Debian-5ubuntu1, OpenSSL 0.9.8g 19 Oct 2007
> debug1: Reading configuration data /etc/ssh/ssh_config
> debug1: Applying options for *
> debug1: Connecting to localhost [::1] port 8000.
> debug1: Connection established.
> debug1: identity file /home/mriou/.ssh/identity type -1
> debug1: identity file /home/mriou/.ssh/id_rsa type 1
> debug1: Checking blacklist file /usr/share/ssh/blacklist.RSA-2048
> debug1: Checking blacklist file /etc/ssh/blacklist.RSA-2048
> debug1: identity file /home/mriou/.ssh/id_dsa type -1
> debug1: Remote protocol version 2.0, remote software version SSHD-CORE-0.2.0
> debug1: no match: SSHD-CORE-0.2.0
> debug1: Enabling compatibility mode for protocol 2.0
> debug1: Local version string SSH-2.0-OpenSSH_5.1p1 Debian-5ubuntu1
> debug1: SSH2_MSG_KEXINIT sent
> debug1: SSH2_MSG_KEXINIT received
> debug1: kex: server->client aes128-cbc hmac-md5 none
> debug1: kex: client->server aes128-cbc hmac-md5 none
> debug1: sending SSH2_MSG_KEXDH_INIT
> debug1: expecting SSH2_MSG_KEXDH_REPLY
> debug1: Host '[localhost]:8000' is known and matches the RSA host key.
> debug1: Found key in /home/mriou/.ssh/known_hosts:9
> debug1: ssh_rsa_verify: signature correct
> debug1: SSH2_MSG_NEWKEYS sent
> debug1: expecting SSH2_MSG_NEWKEYS
> debug1: SSH2_MSG_NEWKEYS received
> debug1: SSH2_MSG_SERVICE_REQUEST sent
> debug1: SSH2_MSG_SERVICE_ACCEPT received
> debug1: Authentications that can continue: password,publickey
> debug1: Next authentication method: publickey
> debug1: Trying private key: /home/mriou/.ssh/identity
> debug1: Offering public key: /home/mriou/.ssh/id_rsa
> debug1: Authentications that can continue: password,publickey
> debug1: Trying private key: /home/mriou/.ssh/id_dsa
> debug1: Next authentication method: password
> foo@localhost's password:
> debug1: Authentication succeeded (password).
> debug1: channel 0: new [client-session]
> debug1: Entering interactive session.
> debug1: Sending environment.
> debug1: Sending env LANG = en_US.UTF-8
> bar
> debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
> exit
> debug1: channel 0: free: client-session, nchannels 1
> Connection to localhost closed.
> Transferred: sent 2392, received 1688 bytes, in 79.8 seconds
> Bytes per second: sent 30.0, received 21.1
> debug1: Exit status 0
>
--
Cheers,
Guillaume Nodet
------------------------
Blog: http://gnodet.blogspot.com/
------------------------
Open Source SOA
http://fusesource.com