[
https://issues.apache.org/jira/browse/SSHD-290?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Tuan Do updated SSHD-290:
-------------------------
Comment: was deleted
(was: Here is the full trace:
SSHD Error Log
[DEBUG] 2014-02-11 13:54:06,990 .() - Creating Nio2Session on
/192.168.240.11:22 from /192.168.240.10:32866
[INFO ] 2014-02-11 13:54:07,031 .() - Session created from /192.168.240.10:32866
[DEBUG] 2014-02-11 13:54:07,031 .() - Writing 25 bytes
[INFO ] 2014-02-11 13:54:07,036 .() - Generating host key...
[TRACE] 2014-02-11 13:54:07,079 .() - Sending packet #0: 14 01 c2 3e 4b 5a 36
83 87 09 ad 6d ec e9 3a b6 80 00 00 00 1a 64 69 66 66 69 65 2d 68 65 6c 6c 6d
61 6e 2d 67 72 6f 75 70 31 2d 73 68 61 31 00 00 00 07 73 73 68 2d 64 73 73 00
00 00 2b 61 65 73 31 32 38 2d 63 74 72 2c 61 65 73 31 32 38 2d 63 62 63 2c 33
64 65 73 2d 63 62 63 2c 62 6c 6f 77 66 69 73 68 2d 63 62 63 00 00 00 2b 61 65
73 31 32 38 2d 63 74 72 2c 61 65 73 31 32 38 2d 63 62 63 2c 33 64 65 73 2d 63
62 63 2c 62 6c 6f 77 66 69 73 68 2d 63 62 63 00 00 00 2b 68 6d 61 63 2d 6d 64
35 2c 68 6d 61 63 2d 73 68 61 31 2c 68 6d 61 63 2d 6d 64 35 2d 39 36 2c 68 6d
61 63 2d 73 68 61 31 2d 39 36 00 00 00 2b 68 6d 61 63 2d 6d 64 35 2c 68 6d 61
63 2d 73 68 61 31 2c 68 6d 61 63 2d 6d 64 35 2d 39 36 2c 68 6d 61 63 2d 73 68
61 31 2d 39 36 00 00 00 04 6e 6f 6e 65 00 00 00 04 6e 6f 6e 65 00 00 00 00 00
00 00 00 00 00 00 00 00
[DEBUG] 2014-02-11 13:54:07,079 .() - Writing 288 bytes
[DEBUG] 2014-02-11 13:54:07,081 .() - Read 264 bytes
[DEBUG] 2014-02-11 13:54:07,082 .() - Client version string:
SSH-2.0-RomSShell_4.60
[TRACE] 2014-02-11 13:54:07,082 .() - Received packet #1: 14 3a d0 3e 38 aa 25
8d 53 7b f7 b3 cc 7b 05 d2 f9 00 00 00 36 64 69 66 66 69 65 2d 68 65 6c 6c 6d
61 6e 2d 67 72 6f 75 70 31 34 2d 73 68 61 31 2c 64 69 66 66 69 65 2d 68 65 6c
6c 6d 61 6e 2d 67 72 6f 75 70 31 2d 73 68 61 31 00 00 00 07 73 73 68 2d 64 73
73 00 00 00 29 61 65 73 32 35 36 2d 63 62 63 2c 61 65 73 31 39 32 2d 63 62 63
2c 61 65 73 31 32 38 2d 63 62 63 2c 33 64 65 73 2d 63 62 63 00 00 00 29 61 65
73 32 35 36 2d 63 62 63 2c 61 65 73 31 39 32 2d 63 62 63 2c 61 65 73 31 32 38
2d 63 62 63 2c 33 64 65 73 2d 63 62 63 00 00 00 09 68 6d 61 63 2d 73 68 61 31
00 00 00 09 68 6d 61 63 2d 73 68 61 31 00 00 00 04 6e 6f 6e 65 00 00 00 04 6e
6f 6e 65 00 00 00 00 00 00 00 00 00 00 00 00 00
[DEBUG] 2014-02-11 13:54:07,082 .() - Received packet SSH_MSG_KEXINIT
[DEBUG] 2014-02-11 13:54:07,083 .() - Received SSH_MSG_KEXINIT
[DEBUG] 2014-02-11 13:54:07,342 .() - Read 144 bytes
[TRACE] 2014-02-11 13:54:07,365 .() - Received packet #2: 1e 00 00 00 80 24 0c
31 f6 af a2 ab 9e b6 5f c3 77 bb 2a 08 a0 35 20 e9 bf 67 4a 93 13 af 86 0c 61
c4 9b e3 c9 ee 81 bc e6 31 07 43 13 fc 64 6d ef d4 de 12 bb a1 95 fe c9 9a e0
ed 5a ad 4e c0 f6 0a ec ae 1f c2 bc dc 61 45 1a 16 32 61 2f 80 07 fa e5 13 c4
b6 ef 2d b0 ea 88 15 1b 1b 46 7c 04 fb b1 d5 e8 02 46 f6 8b 1d 85 28 71 5c 6b
64 f6 6e 3a 12 41 d6 6e 2e c0 07 69 aa ac 58 7a 01 37 27 64 24 77
[DEBUG] 2014-02-11 13:54:07,366 .() - Received packet SSH_MSG_KEXDH_INIT
[DEBUG] 2014-02-11 13:54:07,366 .() - Received SSH_MSG_KEXDH_INIT
[DEBUG] 2014-02-11 13:54:07,388 .() - K_S: 00 00 00 07 73 73 68 2d 64 73 73 00
00 00 81 00 fd 7f 53 81 1d 75 12 29 52 df 4a 9c 2e ec e4 e7 f6 11 b7 52 3c ef
44 00 c3 1e 3f 80 b6 51 26 69 45 5d 40 22 51 fb 59 3d 8d 58 fa bf c5 f5 ba 30
f6 cb 9b 55 6c d7 81 3b 80 1d 34 6f f2 66 60 b7 6b 99 50 a5 a4 9f 9f e8 04 7b
10 22 c2 4f bb a9 d7 fe b7 c6 1b f8 3b 57 e7 c6 a8 a6 15 0f 04 fb 83 f6 d3 c5
1e c3 02 35 54 13 5a 16 91 32 f6 75 f3 ae 2b 61 d7 2a ef f2 22 03 19 9d d1 48
01 c7 00 00 00 15 00 97 60 50 8f 15 23 0b cc b2 92 b9 82 a2 eb 84 0b f0 58 1c
f5 00 00 00 81 00 f7 e1 a0 85 d6 9b 3d de cb bc ab 5c 36 b8 57 b9 79 94 af bb
fa 3a ea 82 f9 57 4c 0b 3d 07 82 67 51 59 57 8e ba d4 59 4f e6 71 07 10 81 80
b4 49 16 71 23 e8 4c 28 16 13 b7 cf 09 32 8c c8 a6 e1 3c 16 7a 8b 54 7c 8d 28
e0 a3 ae 1e 2b b3 a6 75 91 6e a3 7f 0b fa 21 35 62 f1 fb 62 7a 01 24 3b cc a4
f1 be a8 51 90 89 a8 83 df e1 5a e5 9f 06 92 8b 66 5e 80 7b 55 25 64 01 4c 3b
fe cf 49 2a 00 00 00 81 00 a9 e6 89 28 a3 9b 2c c9 dc 5f f5 72 86 25 77 92 7f
b8 67 5d 5c 64 f2 f4 ca 85 0d b9 b3 a5 2d b7 1d e8 70 cd fd a0 6f bb 3c 8f 76
1d 7d 91 1d 74 e4 b5 6f 65 c1 46 37 6d 47 17 1f d3 8a 3c 2b e9 00 52 4e 00 87
e5 85 85 23 ff 96 e5 64 f0 4d e8 76 ae 76 70 27 11 1c 6a 2d 16 15 e6 be 4f 97
25 80 e3 f6 66 ba a1 6b 79 1d c5 e0 fe e7 a1 be ed 8a c6 9a 1d 8e 46 0d 40 82
0a 6d f2 fd 12 03 fc
[DEBUG] 2014-02-11 13:54:07,388 .() - f: 59 c1 3f 5f a9 8a 27 cc 75 c9 f9 e9
7c 58 0d 57 85 09 10 7e 5c 9e 2b a7 0e f9 58 a5 ad 19 c2 45 c5 05 c2 f6 3a ce
83 35 a1 03 7a b7 9b e2 cf 7b f6 d9 f8 df fb 4b 3f 1a 6d 2a b1 22 6b c0 db b5
2e 87 8c 7a 70 7f ec cd 7b 76 bc ed 26 d7 d2 a7 27 60 6c 83 04 49 7a 0d 7f cd
7a 52 d1 ef 85 bb e2 37 a6 91 ae e3 16 7c 74 6b 1f 8c 1b 30 e8 d2 aa 97 16 b6
88 ee 28 fe 07 59 9c e8 91 6f 8d e7
[DEBUG] 2014-02-11 13:54:07,388 .() - sigH: 00 00 00 07 73 73 68 2d 64 73 73 00
00 00 28 5f 23 2a 83 49 4a 2f dc 19 58 6f 87 58 c7 94 33 44 70 e6 9f 72 b0 56
04 f8 2c f5 2a 3e a9 78 4e 71 65 4a 5c 7e a3 2a 5e
[DEBUG] 2014-02-11 13:54:07,388 .() - Send SSH_MSG_KEXDH_REPLY
[TRACE] 2014-02-11 13:54:07,389 .() - Sending packet #1: 1f 00 00 01 b3 00 00
00 07 73 73 68 2d 64 73 73 00 00 00 81 00 fd 7f 53 81 1d 75 12 29 52 df 4a 9c
2e ec e4 e7 f6 11 b7 52 3c ef 44 00 c3 1e 3f 80 b6 51 26 69 45 5d 40 22 51 fb
59 3d 8d 58 fa bf c5 f5 ba 30 f6 cb 9b 55 6c d7 81 3b 80 1d 34 6f f2 66 60 b7
6b 99 50 a5 a4 9f 9f e8 04 7b 10 22 c2 4f bb a9 d7 fe b7 c6 1b f8 3b 57 e7 c6
a8 a6 15 0f 04 fb 83 f6 d3 c5 1e c3 02 35 54 13 5a 16 91 32 f6 75 f3 ae 2b 61
d7 2a ef f2 22 03 19 9d d1 48 01 c7 00 00 00 15 00 97 60 50 8f 15 23 0b cc b2
92 b9 82 a2 eb 84 0b f0 58 1c f5 00 00 00 81 00 f7 e1 a0 85 d6 9b 3d de cb bc
ab 5c 36 b8 57 b9 79 94 af bb fa 3a ea 82 f9 57 4c 0b 3d 07 82 67 51 59 57 8e
ba d4 59 4f e6 71 07 10 81 80 b4 49 16 71 23 e8 4c 28 16 13 b7 cf 09 32 8c c8
a6 e1 3c 16 7a 8b 54 7c 8d 28 e0 a3 ae 1e 2b b3 a6 75 91 6e a3 7f 0b fa 21 35
62 f1 fb 62 7a 01 24 3b cc a4 f1 be a8 51 90 89 a8 83 df e1 5a e5 9f 06 92 8b
66 5e 80 7b 55 25 64 01 4c 3b fe cf 49 2a 00 00 00 81 00 a9 e6 89 28 a3 9b 2c
c9 dc 5f f5 72 86 25 77 92 7f b8 67 5d 5c 64 f2 f4 ca 85 0d b9 b3 a5 2d b7 1d
e8 70 cd fd a0 6f bb 3c 8f 76 1d 7d 91 1d 74 e4 b5 6f 65 c1 46 37 6d 47 17 1f
d3 8a 3c 2b e9 00 52 4e 00 87 e5 85 85 23 ff 96 e5 64 f0 4d e8 76 ae 76 70 27
11 1c 6a 2d 16 15 e6 be 4f 97 25 80 e3 f6 66 ba a1 6b 79 1d c5 e0 fe e7 a1 be
ed 8a c6 9a 1d 8e 46 0d 40 82 0a 6d f2 fd 12 03 fc 00 00 00 80 59 c1 3f 5f a9
8a 27 cc 75 c9 f9 e9 7c 58 0d 57 85 09 10 7e 5c 9e 2b a7 0e f9 58 a5 ad 19 c2
45 c5 05 c2 f6 3a ce 83 35 a1 03 7a b7 9b e2 cf 7b f6 d9 f8 df fb 4b 3f 1a 6d
2a b1 22 6b c0 db b5 2e 87 8c 7a 70 7f ec cd 7b 76 bc ed 26 d7 d2 a7 27 60 6c
83 04 49 7a 0d 7f cd 7a 52 d1 ef 85 bb e2 37 a6 91 ae e3 16 7c 74 6b 1f 8c 1b
30 e8 d2 aa 97 16 b6 88 ee 28 fe 07 59 9c e8 91 6f 8d e7 00 00 00 37 00 00 00
07 73 73 68 2d 64 73 73 00 00 00 28 5f 23 2a 83 49 4a 2f dc 19 58 6f 87 58 c7
94 33 44 70 e6 9f 72 b0 56 04 f8 2c f5 2a 3e a9 78 4e 71 65 4a 5c 7e a3 2a 5e
[DEBUG] 2014-02-11 13:54:07,389 .() - Writing 648 bytes
[DEBUG] 2014-02-11 13:54:07,389 .() - Send SSH_MSG_NEWKEYS
[TRACE] 2014-02-11 13:54:07,389 .() - Sending packet #2: 15
[DEBUG] 2014-02-11 13:54:07,389 .() - Writing 16 bytes
[DEBUG] 2014-02-11 13:54:07,789 .() - Read 16 bytes
[TRACE] 2014-02-11 13:54:07,791 .() - Received packet #3: 15
[DEBUG] 2014-02-11 13:54:07,791 .() - Received packet SSH_MSG_NEWKEYS
[DEBUG] 2014-02-11 13:54:07,792 .() - Received SSH_MSG_NEWKEYS
[DEBUG] 2014-02-11 13:54:07,810 .() - Read 152 bytes
[TRACE] 2014-02-11 13:54:07,813 .() - Received packet #4: 05 00 00 00 0c 73 73
68 2d 75 73 65 72 61 75 74 68
[DEBUG] 2014-02-11 13:54:07,813 .() - Received packet SSH_MSG_SERVICE_REQUEST
[DEBUG] 2014-02-11 13:54:07,813 .() - Received SSH_MSG_SERVICE_REQUEST
'ssh-userauth'
[DEBUG] 2014-02-11 13:54:07,813 .() - Accepting user authentication request
[TRACE] 2014-02-11 13:54:07,813 .() - Sending packet #3: 06 00 00 00 0c 73 73
68 2d 75 73 65 72 61 75 74 68
[DEBUG] 2014-02-11 13:54:07,813 .() - Writing 68 bytes
[DEBUG] 2014-02-11 13:54:07,814 .() - Authorized authentication methods:
password,keyboard-interactive
[TRACE] 2014-02-11 13:54:07,814 .() - Received packet #5: 32 00 00 00 08 6a 65
6e 6d 74 65 73 74 00 00 00 0e 73 73 68 2d 63 6f 6e 6e 65 63 74 69 6f 6e 00 00
00 08 70 61 73 73 77 6f 72 64 00 00 00 00 08 70 61 73 73 77 6f 72 64
[DEBUG] 2014-02-11 13:54:07,814 .() - Received packet SSH_MSG_USERAUTH_REQUEST
[DEBUG] 2014-02-11 13:54:07,814 .() - Received SSH_MSG_USERAUTH_REQUEST
[DEBUG] 2014-02-11 13:54:07,814 .() - Authenticating user 'username' with
service 'ssh-connection' and method 'password'
[DEBUG] 2014-02-11 13:54:07,832 .() - Authentication succeeded
[TRACE] 2014-02-11 13:54:07,832 .() - Sending packet #4: 34
[DEBUG] 2014-02-11 13:54:07,833 .() - Writing 52 bytes
[INFO ] 2014-02-11 13:54:07,833 .() - Session username@/192.168.240.10:32866
authenticated
[DEBUG] 2014-02-11 13:54:08,028 .() - Read 68 bytes
[TRACE] 2014-02-11 13:54:08,031 .() - Received packet #6: 5a 00 00 00 07 73 65
73 73 69 6f 6e 00 00 01 00 00 00 04 00 00 00 04 00
[DEBUG] 2014-02-11 13:54:08,032 .() - Received packet SSH_MSG_CHANNEL_OPEN
[DEBUG] 2014-02-11 13:54:08,039 .() - Received SSH_MSG_CHANNEL_OPEN session
[TRACE] 2014-02-11 13:54:08,054 .() - Sending packet #5: 5b 00 00 01 00 00 00
00 65 00 20 00 00 00 00 80 00
[DEBUG] 2014-02-11 13:54:08,055 .() - Writing 68 bytes
[DEBUG] 2014-02-11 13:54:08,061 .() - Read 68 bytes
[TRACE] 2014-02-11 13:54:08,061 .() - Received packet #7: 62 00 00 00 00 00 00
00 04 65 78 65 63 01 00 00 00 10 73 63 70 20 2d 66 20 4d 44 44 49 2e 70 73 70 69
[DEBUG] 2014-02-11 13:54:08,061 .() - Received packet SSH_MSG_CHANNEL_REQUEST
[DEBUG] 2014-02-11 13:54:08,061 .() - Caught exception, now calling handler
[WARN ] 2014-02-11 13:54:08,068 .() - Exception caught
org.apache.sshd.common.SshException: Received SSH_MSG_CHANNEL_REQUEST on
unknown channel 0
at
org.apache.sshd.common.session.AbstractSession.getChannel(AbstractSession.java:1110)
~[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.common.session.AbstractSession.channelRequest(AbstractSession.java:1082)
~[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.server.session.ServerSession.running(ServerSession.java:240)
~[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.server.session.ServerSession.handleMessage(ServerSession.java:216)
~[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.common.session.AbstractSession.decode(AbstractSession.java:587)
~[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.common.session.AbstractSession.messageReceived(AbstractSession.java:253)
~[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.common.AbstractSessionIoHandler.messageReceived(AbstractSessionIoHandler.java:54)
~[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.common.io.nio2.Nio2Session$2.completed(Nio2Session.java:231)
[sshd-core-0.9.0.jar:0.9.0]
at
org.apache.sshd.common.io.nio2.Nio2Session$2.completed(Nio2Session.java:217)
[sshd-core-0.9.0.jar:0.9.0]
at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126) [na:1.7.0_21]
at sun.nio.ch.Invoker$2.run(Invoker.java:206) [na:1.7.0_21]
at
sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
[na:1.7.0_21]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[na:1.7.0_21]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[na:1.7.0_21]
at java.lang.Thread.run(Thread.java:722) [na:1.7.0_21]
[DEBUG] 2014-02-11 13:54:08,068 .() - Closing session
[DEBUG] 2014-02-11 13:54:08,068 .() - Closing channel 101
[DEBUG] 2014-02-11 13:54:08,068 .() - Closing channel 101 immediately
[DEBUG] 2014-02-11 13:54:08,070 .() - Closing IoSession
[DEBUG] 2014-02-11 13:54:08,071 .() - Closing Nio2Session
[DEBUG] 2014-02-11 13:54:08,071 .() - Closing socket
[DEBUG] 2014-02-11 13:54:08,072 .() - IoSession closed
[INFO ] 2014-02-11 13:54:08,072 .() - Session username@/192.168.240.10:32866
closed)
> Received SSH_MSG_CHANNEL_REQUEST on unknown channel 0
> -----------------------------------------------------
>
> Key: SSHD-290
> URL: https://issues.apache.org/jira/browse/SSHD-290
> Project: MINA SSHD
> Issue Type: Bug
> Affects Versions: 0.9.0
> Reporter: Tuan Do
> Attachments: SSHD_Error.txt
>
>
> SSHD Server closing session after Received SSH_MSG_CHANNEL_REQUEST on unknown
> channel 0.
> org.apache.sshd.common.SshException: Received SSH_MSG_CHANNEL_REQUEST on
> unknown channel 0
> at
> org.apache.sshd.common.session.AbstractSession.getChannel(AbstractSession.java:1110)
> ~[sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.common.session.AbstractSession.channelRequest(AbstractSession.java:1082)
> ~[sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.server.session.ServerSession.running(ServerSession.java:240)
> ~[sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.server.session.ServerSession.handleMessage(ServerSession.java:216)
> ~[sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.common.session.AbstractSession.decode(AbstractSession.java:587)
> ~[sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.common.session.AbstractSession.messageReceived(AbstractSession.java:253)
> ~[sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.common.AbstractSessionIoHandler.messageReceived(AbstractSessionIoHandler.java:54)
> ~[sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.common.io.nio2.Nio2Session$2.completed(Nio2Session.java:231)
> [sshd-core-0.9.0.jar:0.9.0]
> at
> org.apache.sshd.common.io.nio2.Nio2Session$2.completed(Nio2Session.java:217)
> [sshd-core-0.9.0.jar:0.9.0]
> at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126) [na:1.7.0_21]
> at sun.nio.ch.Invoker$2.run(Invoker.java:206) [na:1.7.0_21]
> at
> sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
> [na:1.7.0_21]
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> [na:1.7.0_21]
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> [na:1.7.0_21]
> at java.lang.Thread.run(Thread.java:722) [na:1.7.0_21]
--
This message was sent by Atlassian JIRA
(v6.1.5#6160)
