Found protocol is "NONE", expected protocol ist "TLS"

You are here

Found protocol is "NONE", expected protocol ist "TLS"

5 posts / 0 new
Last post
taurus
taurus's picture
Found protocol is "NONE", expected protocol ist "TLS"

Hello,
I'm using Mendelson OFTP2 1.0 b31

I managed to setup the station for communication using SSL and client athentication with a partner; Used protocol has been identified as "TLSv1", cipher suite is "TLS_RSA_WITH_AES_128_CBC_SHA"

Now I'm setting up a second partner but I'm unable to establish a connection (via TEST button): I get the following error:
[Jun 24, 2015 10:33:18 AM] [Connection test to xxx/xxx:6619] Connection to xxx/xxx:6619 established successfully
[Jun 24, 2015 10:33:18 AM] [Connection test to xxx/xxx:6619] Found protocol is "NONE", expected protocol ist "TLS"
[Jun 24, 2015 10:33:18 AM] [Connection test to xxx/xxx:6619] Either there is a plain connection expected, your partner uses the wrong protocol or client authentication is required

note: I have no trace of this event in systemactivity.log

Thank You for your help!

taurus
taurus's picture

addenda: also my parter cannot connect to my station: here is the log:

[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Incoming connection [/xxx:35646]
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Setting session var "Caller" to false
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Session state: IDLE
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Statemachine "Session Connection State Machine" received signal "N_CON_IND", state is "IDLE"
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Processing unit received signal "N_CON_RS", state is "IDLE"
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Send command: SSRM
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Send command:
o-------------------------------------------------------
| SSRM Start Session Ready Message
o-------------------------------------------------------
| 0 | X(1) | SSRMCMD | SSRM Command | 'I' | [49]
| 1 | X(17) | SSRMMSG | Ready Message | 'ODETTE FTP READY ' | [4f 44 45 54 54 45 20 46 54 50 20 52 45 41 44 59 20]
| 18 | X(1) | SSRMCR | Carriage Return | '
' | [0d]
o-------------------------------------------------------
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Session state: IDLE --> A_NC_ONLY
[Jun 24, 2015 10:47:47 AM] A processing problem occured. This could have several reasons, for further information please have a look at "C:\mendelson\opensource\oftp2\log\20150624\systemactivity.log".
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] SSL handshake failed.
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Connection closed [TAURUS - --, /xxx:35646]. Established for 0.0s, 2595 bytes transfered

[10:47:47 AM]
javax.net.ssl.SSLHandshakeException: SSL handshake failed.
at org.apache.mina.filter.ssl.SslFilter.messageReceived(SslFilter.java:507)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
at org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:109)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:535)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:714)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:668)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:657)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.access$600(AbstractPollingIoProcessor.java:67)
at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1121)
at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: javax.net.ssl.SSLException: Received fatal alert: certificate_unknown
at sun.security.ssl.Alerts.getSSLException(Unknown Source)
at sun.security.ssl.SSLEngineImpl.fatal(Unknown Source)
at sun.security.ssl.SSLEngineImpl.fatal(Unknown Source)
at sun.security.ssl.SSLEngineImpl.recvAlert(Unknown Source)
at sun.security.ssl.SSLEngineImpl.readRecord(Unknown Source)
at sun.security.ssl.SSLEngineImpl.readNetRecord(Unknown Source)
at sun.security.ssl.SSLEngineImpl.unwrap(Unknown Source)
at javax.net.ssl.SSLEngine.unwrap(Unknown Source)
at org.apache.mina.filter.ssl.SslHandler.unwrap(SslHandler.java:748)
at org.apache.mina.filter.ssl.SslHandler.unwrapHandshake(SslHandler.java:683)
at org.apache.mina.filter.ssl.SslHandler.handshake(SslHandler.java:569)
at org.apache.mina.filter.ssl.SslHandler.messageReceived(SslHandler.java:355)
at org.apache.mina.filter.ssl.SslFilter.messageReceived(SslFilter.java:488)
... 15 more

taurus
taurus's picture

addenda: also my parter cannot connect to my station: here is the log:

[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Incoming connection [/xxx:35646]
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Setting session var "Caller" to false
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Session state: IDLE
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Statemachine "Session Connection State Machine" received signal "N_CON_IND", state is "IDLE"
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Processing unit received signal "N_CON_RS", state is "IDLE"
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Send command: SSRM
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Send command:
o-------------------------------------------------------
| SSRM Start Session Ready Message
o-------------------------------------------------------
| 0 | X(1) | SSRMCMD | SSRM Command | 'I' | [49]
| 1 | X(17) | SSRMMSG | Ready Message | 'ODETTE FTP READY ' | [4f 44 45 54 54 45 20 46 54 50 20 52 45 41 44 59 20]
| 18 | X(1) | SSRMCR | Carriage Return | '
' | [0d]
o-------------------------------------------------------
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Session state: IDLE --> A_NC_ONLY
[Jun 24, 2015 10:47:47 AM] A processing problem occured. This could have several reasons, for further information please have a look at "C:\mendelson\opensource\oftp2\log\20150624\systemactivity.log".
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] SSL handshake failed.
[Jun 24, 2015 10:47:47 AM] [Session 143513566745310] Connection closed [TAURUS - --, /xxx:35646]. Established for 0.0s, 2595 bytes transfered

[10:47:47 AM]
javax.net.ssl.SSLHandshakeException: SSL handshake failed.
at org.apache.mina.filter.ssl.SslFilter.messageReceived(SslFilter.java:507)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1300(DefaultIoFilterChain.java:48)
at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:943)
at org.apache.mina.core.filterchain.IoFilterAdapter.messageReceived(IoFilterAdapter.java:109)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:542)
at org.apache.mina.core.filterchain.DefaultIoFilterChain.fireMessageReceived(DefaultIoFilterChain.java:535)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:714)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:668)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:657)
at org.apache.mina.core.polling.AbstractPollingIoProcessor.access$600(AbstractPollingIoProcessor.java:67)
at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1121)
at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: javax.net.ssl.SSLException: Received fatal alert: certificate_unknown
at sun.security.ssl.Alerts.getSSLException(Unknown Source)
at sun.security.ssl.SSLEngineImpl.fatal(Unknown Source)
at sun.security.ssl.SSLEngineImpl.fatal(Unknown Source)
at sun.security.ssl.SSLEngineImpl.recvAlert(Unknown Source)
at sun.security.ssl.SSLEngineImpl.readRecord(Unknown Source)
at sun.security.ssl.SSLEngineImpl.readNetRecord(Unknown Source)
at sun.security.ssl.SSLEngineImpl.unwrap(Unknown Source)
at javax.net.ssl.SSLEngine.unwrap(Unknown Source)
at org.apache.mina.filter.ssl.SslHandler.unwrap(SslHandler.java:748)
at org.apache.mina.filter.ssl.SslHandler.unwrapHandshake(SslHandler.java:683)
at org.apache.mina.filter.ssl.SslHandler.handshake(SslHandler.java:569)
at org.apache.mina.filter.ssl.SslHandler.messageReceived(SslHandler.java:355)
at org.apache.mina.filter.ssl.SslFilter.messageReceived(SslFilter.java:488)
... 15 more

service
service's picture

taurus,

taurus wrote:

javax.net.ssl.SSLHandshakeException: SSL handshake failed.
..
Received fatal alert: certificate_unknown

Your system received a signal during the handshake from your partner that he encountered an unknown certificate.
Please contact your partner and ensure that all the certificates are exchanged and imported in the keystores/truststores.

Regards

taurus
taurus's picture

Hello,
resolved. SSL handshake is now successful (I did no action, so probably was an issue due to missing certificate in partner's keystore).

Now I have an "invalid password" issue, but I'll open a new thread.

Thank You for your support!