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!

Forum
OFTP2

Comments

Permalink

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!

Permalink

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

Permalink

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

Profile picture for user service
Permalink

taurus,

[quote=taurus]
javax.net.ssl.SSLHandshakeException: SSL handshake failed.
..
Received fatal alert: certificate_unknown
[/quote]

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