You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mina.apache.org by "binwang (JIRA)" <ji...@apache.org> on 2014/12/02 13:08:12 UTC
[jira] [Updated] (DIRMINA-997) Apache Mina2.0.9 + SSL + Android not
working
[ https://issues.apache.org/jira/browse/DIRMINA-997?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
binwang updated DIRMINA-997:
----------------------------
Description:
I'm developing an Android app using Apache Mina for network IO. Non-SSL connections (reading, writing) work fine, but as soon as I add an SSL filter things stop working. I also tried pure SSL sockets and they work fine.
This is my Mina connection code (in a separate networking thread):
IoConnector connector = new NioSocketConnector();
connector.getSessionConfig().setReadBufferSize(2048);
SocketSessionConfig cfg = (SocketSessionConfig)connector.getSessionConfig();
cfg.setTcpNoDelay(true);
SslContextFactory f = new SslContextFactory();
f.setTrustManagerFactory(new BogusTrustManagerFactory());
f.setProtocol("SSL");
try {
filter = new SslFilter(f.newInstance(), true);
} catch (Exception e) {
Log.d(TAG, "Exception: ", e);
return;
}
filter.setUseClientMode(true);
connector.getFilterChain().addLast("sslFilter", filter);
connector.getFilterChain().addLast("logger", new LoggingFilter());
connector.getFilterChain().addLast("codec", new ProtocolCodecFilter(new TextLineCodecFactory(Charset.forName("ASCII"))));
connector.setHandler(new MinaClientHandler());
ConnectFuture future = connector.connect(new InetSocketAddress("10.0.1.9", 7072));
future.awaitUninterruptibly();
if (!future.isConnected())
{
Log.d(TAG, "not connected, return");
return;
}
IoSession session = future.getSession();
session.getConfig().setUseReadOperation(true);
session.getCloseFuture().awaitUninterruptibly();
//System.out.println(session.read().getMessage());
Log.d(TAG, "after writting");
connector.dispose();
Not my actual code, but it reproduces the problem.
On the server side I see that the connection is accepted an the handshake succeeds. But on the client side nothing is sent over the socket. I have tried the same code in a desktop Java application and it also works.
Also if I move the write call just after
future.awaitUninterruptibly(); and program is not continue.
server log:
DEBUG 2014-12-02 20:05:34,413 [org.apache.mina.filter.ssl.SslFilter] - Adding the SSL Filter sslFilter to the chain
DEBUG 2014-12-02 20:05:34,417 [org.apache.mina.filter.ssl.SslHandler] - Session Server[1](no sslEngine) Initializing the SSL Handler
DEBUG 2014-12-02 20:05:34,440 [org.apache.mina.filter.ssl.SslHandler] - Session Server[1](no sslEngine) SSL Handler Initialization done.
DEBUG 2014-12-02 20:05:34,441 [org.apache.mina.filter.ssl.SslFilter] - Session Server[1](ssl...) : Starting the first handshake
DEBUG 2014-12-02 20:05:34,441 [org.apache.mina.filter.ssl.SslHandler] - Session Server[1](ssl...) processing the NEED_UNWRAP state
INFO 2014-12-02 20:05:34,443 [org.apache.mina.filter.logging.LoggingFilter] - CREATED
DEBUG 2014-12-02 20:05:34,443 [zycj.ktc.server.ThunderMinaIOHandler] - SessionCreated, client = 192.168.1.112/null
INFO 2014-12-02 20:05:34,443 [org.apache.mina.filter.logging.LoggingFilter] - OPENED
DEBUG 2014-12-02 20:05:34,445 [org.apache.mina.core.filterchain.IoFilterEvent] - Firing a SESSION_OPENED event for session 1
DEBUG 2014-12-02 20:05:34,446 [zycj.ktc.server.ThunderMinaIOHandler] - SessionOpened, client = 192.168.1.112/null
DEBUG 2014-12-02 20:05:34,446 [org.apache.mina.core.filterchain.IoFilterEvent] - Event SESSION_OPENED has been fired for session 1
android log:
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): Unexpected exception.
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): org.apache.mina.core.filterchain.IoFilterLifeCycleException: onPreAdd(): SSL:SslFilter in (0x00000001: nio socket, client, 192.168.1.112/192.168.1.112:41921 => 192.168.1.100/192.168.1.100:8900)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.register(DefaultIoFilterChain.java:383)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.addLast(DefaultIoFilterChain.java:189)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChainBuilder.buildFilterChain(DefaultIoFilterChainBuilder.java:436)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.addNow(AbstractPollingIoProcessor.java:532)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.handleNewSessions(AbstractPollingIoProcessor.java:505)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:67)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1113)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1088)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:581)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.lang.Thread.run(Thread.java:1019)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): Caused by: java.lang.IllegalArgumentException: Log tag "org.apache.mina.filter.ssl.SslHandler" exceeds limit of 23 characters
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at android.util.Log.isLoggable(Native Method)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.slf4j.impl.AndroidLogger.isDebugEnabled(AndroidLogger.java:109)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.filter.ssl.SslHandler.init(SslHandler.java:193)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.filter.ssl.SslFilter.onPreAdd(SslFilter.java:426)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.register(DefaultIoFilterChain.java:381)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): ... 10 more
was:
I'm developing an Android app using Apache Mina for network IO. Non-SSL connections (reading, writing) work fine, but as soon as I add an SSL filter things stop working. I also tried pure SSL sockets and they work fine.
This is my Mina connection code (in a separate networking thread):
IoConnector connector = new NioSocketConnector();
connector.getSessionConfig().setReadBufferSize(2048);
SocketSessionConfig cfg = (SocketSessionConfig)connector.getSessionConfig();
cfg.setTcpNoDelay(true);
SslContextFactory f = new SslContextFactory();
f.setTrustManagerFactory(new BogusTrustManagerFactory());
f.setProtocol("SSL");
try {
filter = new SslFilter(f.newInstance(), true);
} catch (Exception e) {
Log.d(TAG, "Exception: ", e);
return;
}
filter.setUseClientMode(true);
connector.getFilterChain().addLast("sslFilter", filter);
connector.getFilterChain().addLast("logger", new LoggingFilter());
connector.getFilterChain().addLast("codec", new ProtocolCodecFilter(new TextLineCodecFactory(Charset.forName("ASCII"))));
connector.setHandler(new MinaClientHandler());
ConnectFuture future = connector.connect(new InetSocketAddress("10.0.1.9", 7072));
future.awaitUninterruptibly();
if (!future.isConnected())
{
Log.d(TAG, "not connected, return");
return;
}
IoSession session = future.getSession();
session.getConfig().setUseReadOperation(true);
session.getCloseFuture().awaitUninterruptibly();
//System.out.println(session.read().getMessage());
Log.d(TAG, "after writting");
connector.dispose();
Not my actual code, but it reproduces the problem.
On the server side I see that the connection is accepted an the handshake succeeds. But on the client side nothing is sent over the socket. I have tried the same code in a desktop Java application and it also works.
Also if I move the write call just after
future.awaitUninterruptibly(); and program is not continue.
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): Unexpected exception.
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): org.apache.mina.core.filterchain.IoFilterLifeCycleException: onPreAdd(): SSL:SslFilter in (0x00000001: nio socket, client, 192.168.1.112/192.168.1.112:41921 => 192.168.1.100/192.168.1.100:8900)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.register(DefaultIoFilterChain.java:383)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.addLast(DefaultIoFilterChain.java:189)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChainBuilder.buildFilterChain(DefaultIoFilterChainBuilder.java:436)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.addNow(AbstractPollingIoProcessor.java:532)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.handleNewSessions(AbstractPollingIoProcessor.java:505)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:67)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1113)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1088)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:581)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.lang.Thread.run(Thread.java:1019)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): Caused by: java.lang.IllegalArgumentException: Log tag "org.apache.mina.filter.ssl.SslHandler" exceeds limit of 23 characters
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at android.util.Log.isLoggable(Native Method)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.slf4j.impl.AndroidLogger.isDebugEnabled(AndroidLogger.java:109)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.filter.ssl.SslHandler.init(SslHandler.java:193)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.filter.ssl.SslFilter.onPreAdd(SslFilter.java:426)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.register(DefaultIoFilterChain.java:381)
12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): ... 10 more
> Apache Mina2.0.9 + SSL + Android not working
> --------------------------------------------
>
> Key: DIRMINA-997
> URL: https://issues.apache.org/jira/browse/DIRMINA-997
> Project: MINA
> Issue Type: Bug
> Components: SSL
> Affects Versions: 2.0.9
> Environment: Android+mina2.0.9 +ssl
> Reporter: binwang
> Fix For: 2.0.9
>
>
> I'm developing an Android app using Apache Mina for network IO. Non-SSL connections (reading, writing) work fine, but as soon as I add an SSL filter things stop working. I also tried pure SSL sockets and they work fine.
> This is my Mina connection code (in a separate networking thread):
> IoConnector connector = new NioSocketConnector();
> connector.getSessionConfig().setReadBufferSize(2048);
> SocketSessionConfig cfg = (SocketSessionConfig)connector.getSessionConfig();
> cfg.setTcpNoDelay(true);
> SslContextFactory f = new SslContextFactory();
> f.setTrustManagerFactory(new BogusTrustManagerFactory());
> f.setProtocol("SSL");
> try {
> filter = new SslFilter(f.newInstance(), true);
> } catch (Exception e) {
> Log.d(TAG, "Exception: ", e);
> return;
> }
> filter.setUseClientMode(true);
> connector.getFilterChain().addLast("sslFilter", filter);
> connector.getFilterChain().addLast("logger", new LoggingFilter());
> connector.getFilterChain().addLast("codec", new ProtocolCodecFilter(new TextLineCodecFactory(Charset.forName("ASCII"))));
> connector.setHandler(new MinaClientHandler());
> ConnectFuture future = connector.connect(new InetSocketAddress("10.0.1.9", 7072));
> future.awaitUninterruptibly();
> if (!future.isConnected())
> {
> Log.d(TAG, "not connected, return");
> return;
> }
> IoSession session = future.getSession();
> session.getConfig().setUseReadOperation(true);
> session.getCloseFuture().awaitUninterruptibly();
> //System.out.println(session.read().getMessage());
> Log.d(TAG, "after writting");
> connector.dispose();
> Not my actual code, but it reproduces the problem.
> On the server side I see that the connection is accepted an the handshake succeeds. But on the client side nothing is sent over the socket. I have tried the same code in a desktop Java application and it also works.
> Also if I move the write call just after
> future.awaitUninterruptibly(); and program is not continue.
> server log:
> DEBUG 2014-12-02 20:05:34,413 [org.apache.mina.filter.ssl.SslFilter] - Adding the SSL Filter sslFilter to the chain
> DEBUG 2014-12-02 20:05:34,417 [org.apache.mina.filter.ssl.SslHandler] - Session Server[1](no sslEngine) Initializing the SSL Handler
> DEBUG 2014-12-02 20:05:34,440 [org.apache.mina.filter.ssl.SslHandler] - Session Server[1](no sslEngine) SSL Handler Initialization done.
> DEBUG 2014-12-02 20:05:34,441 [org.apache.mina.filter.ssl.SslFilter] - Session Server[1](ssl...) : Starting the first handshake
> DEBUG 2014-12-02 20:05:34,441 [org.apache.mina.filter.ssl.SslHandler] - Session Server[1](ssl...) processing the NEED_UNWRAP state
> INFO 2014-12-02 20:05:34,443 [org.apache.mina.filter.logging.LoggingFilter] - CREATED
> DEBUG 2014-12-02 20:05:34,443 [zycj.ktc.server.ThunderMinaIOHandler] - SessionCreated, client = 192.168.1.112/null
> INFO 2014-12-02 20:05:34,443 [org.apache.mina.filter.logging.LoggingFilter] - OPENED
> DEBUG 2014-12-02 20:05:34,445 [org.apache.mina.core.filterchain.IoFilterEvent] - Firing a SESSION_OPENED event for session 1
> DEBUG 2014-12-02 20:05:34,446 [zycj.ktc.server.ThunderMinaIOHandler] - SessionOpened, client = 192.168.1.112/null
> DEBUG 2014-12-02 20:05:34,446 [org.apache.mina.core.filterchain.IoFilterEvent] - Event SESSION_OPENED has been fired for session 1
> android log:
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): Unexpected exception.
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): org.apache.mina.core.filterchain.IoFilterLifeCycleException: onPreAdd(): SSL:SslFilter in (0x00000001: nio socket, client, 192.168.1.112/192.168.1.112:41921 => 192.168.1.100/192.168.1.100:8900)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.register(DefaultIoFilterChain.java:383)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.addLast(DefaultIoFilterChain.java:189)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChainBuilder.buildFilterChain(DefaultIoFilterChainBuilder.java:436)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.addNow(AbstractPollingIoProcessor.java:532)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.handleNewSessions(AbstractPollingIoProcessor.java:505)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor.access$400(AbstractPollingIoProcessor.java:67)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1113)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1088)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:581)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at java.lang.Thread.run(Thread.java:1019)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): Caused by: java.lang.IllegalArgumentException: Log tag "org.apache.mina.filter.ssl.SslHandler" exceeds limit of 23 characters
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at android.util.Log.isLoggable(Native Method)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.slf4j.impl.AndroidLogger.isDebugEnabled(AndroidLogger.java:109)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.filter.ssl.SslHandler.init(SslHandler.java:193)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.filter.ssl.SslFilter.onPreAdd(SslFilter.java:426)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): at org.apache.mina.core.filterchain.DefaultIoFilterChain.register(DefaultIoFilterChain.java:381)
> 12-02 19:55:40.156: W/org.apache.mina.util.DefaultExceptionMonitor(16032): ... 10 more
>
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)