You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ratis.apache.org by "runzhiwang (Jira)" <ji...@apache.org> on 2020/08/03 11:19:00 UTC

[jira] [Updated] (RATIS-1017) Fix failed UT: Failed to start NettyRpcService

     [ https://issues.apache.org/jira/browse/RATIS-1017?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

runzhiwang updated RATIS-1017:
------------------------------
    Description: 
2020-07-17T19:27:46.4194040Z java.io.IOException: s2: Failed to start NettyRpcService
2020-07-17T19:27:46.4194366Z 	at org.apache.ratis.netty.server.NettyRpcService.startImpl(NettyRpcService.java:140)
2020-07-17T19:27:46.4194771Z 	at org.apache.ratis.util.LifeCycle.startAndTransition(LifeCycle.java:214)
2020-07-17T19:27:46.4195087Z 	at org.apache.ratis.server.impl.RaftServerRpcWithProxy.start(RaftServerRpcWithProxy.java:69)
2020-07-17T19:27:46.4195380Z 	at org.apache.ratis.server.impl.RaftServerProxy.lambda$start$3(RaftServerProxy.java:305)
2020-07-17T19:27:46.4195735Z 	at org.apache.ratis.util.LifeCycle.startAndTransition(LifeCycle.java:214)
2020-07-17T19:27:46.4196124Z 	at org.apache.ratis.server.impl.RaftServerProxy.start(RaftServerProxy.java:303)
2020-07-17T19:27:46.4196380Z 	at org.apache.ratis.MiniRaftCluster.startServers(MiniRaftCluster.java:425)
2020-07-17T19:27:46.4196633Z 	at org.apache.ratis.MiniRaftCluster.start(MiniRaftCluster.java:294)
2020-07-17T19:27:46.4196888Z 	at org.apache.ratis.MiniRaftCluster$Factory$Get.runWithNewCluster(MiniRaftCluster.java:123)
2020-07-17T19:27:46.4197154Z 	at org.apache.ratis.MiniRaftCluster$Factory$Get.runWithNewCluster(MiniRaftCluster.java:113)
2020-07-17T19:27:46.4197408Z 	at org.apache.ratis.RetryCacheTests.testRetryOnNewLeader(RetryCacheTests.java:122)
2020-07-17T19:27:46.4197665Z 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-07-17T19:27:46.4202375Z 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-07-17T19:27:46.4202729Z 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-07-17T19:27:46.4203273Z 	at java.lang.reflect.Method.invoke(Method.java:498)
2020-07-17T19:27:46.4203487Z 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
2020-07-17T19:27:46.4203658Z 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
2020-07-17T19:27:46.4203825Z 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
2020-07-17T19:27:46.4204045Z 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
2020-07-17T19:27:46.4204249Z 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
2020-07-17T19:27:46.4204413Z 	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:298)
2020-07-17T19:27:46.4204582Z 	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:292)
2020-07-17T19:27:46.4204741Z 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
2020-07-17T19:27:46.4204896Z 	at java.lang.Thread.run(Thread.java:748)
2020-07-17T19:27:46.4205045Z Caused by: java.net.BindException: Address already in use
2020-07-17T19:27:46.4205190Z 	at sun.nio.ch.Net.bind0(Native Method)
2020-07-17T19:27:46.4205339Z 	at sun.nio.ch.Net.bind(Net.java:433)
2020-07-17T19:27:46.4205433Z 	at sun.nio.ch.Net.bind(Net.java:425)
2020-07-17T19:27:46.4205596Z 	at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:220)
2020-07-17T19:27:46.4205771Z 	at org.apache.ratis.thirdparty.io.netty.channel.socket.nio.NioServerSocketChannel.doBind(NioServerSocketChannel.java:134)
2020-07-17T19:27:46.4205955Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannel$AbstractUnsafe.bind(AbstractChannel.java:550)
2020-07-17T19:27:46.4206227Z 	at org.apache.ratis.thirdparty.io.netty.channel.DefaultChannelPipeline$HeadContext.bind(DefaultChannelPipeline.java:1334)
2020-07-17T19:27:46.4206417Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.invokeBind(AbstractChannelHandlerContext.java:506)
2020-07-17T19:27:46.4206604Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.bind(AbstractChannelHandlerContext.java:491)
2020-07-17T19:27:46.4206790Z 	at org.apache.ratis.thirdparty.io.netty.handler.logging.LoggingHandler.bind(LoggingHandler.java:221)
2020-07-17T19:27:46.4206966Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.invokeBind(AbstractChannelHandlerContext.java:506)
2020-07-17T19:27:46.4207161Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.bind(AbstractChannelHandlerContext.java:491)
2020-07-17T19:27:46.4207584Z 	at org.apache.ratis.thirdparty.io.netty.channel.DefaultChannelPipeline.bind(DefaultChannelPipeline.java:973)
2020-07-17T19:27:46.4207759Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannel.bind(AbstractChannel.java:248)
2020-07-17T19:27:46.4208120Z 	at org.apache.ratis.thirdparty.io.netty.bootstrap.AbstractBootstrap$2.run(AbstractBootstrap.java:356)
2020-07-17T19:27:46.4208334Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164)
2020-07-17T19:27:46.4216918Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:472)
2020-07-17T19:27:46.4217074Z 	at org.apache.ratis.thirdparty.io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:500)
2020-07-17T19:27:46.4217314Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
2020-07-17T19:27:46.4224782Z 	at org.apache.ratis.thirdparty.io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
2020-07-17T19:27:46.4225113Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2020-07-17T19:27:46.4225281Z 	... 1 more

> Fix failed UT: Failed to start NettyRpcService
> ----------------------------------------------
>
>                 Key: RATIS-1017
>                 URL: https://issues.apache.org/jira/browse/RATIS-1017
>             Project: Ratis
>          Issue Type: Sub-task
>            Reporter: runzhiwang
>            Assignee: runzhiwang
>            Priority: Major
>
> 2020-07-17T19:27:46.4194040Z java.io.IOException: s2: Failed to start NettyRpcService
> 2020-07-17T19:27:46.4194366Z 	at org.apache.ratis.netty.server.NettyRpcService.startImpl(NettyRpcService.java:140)
> 2020-07-17T19:27:46.4194771Z 	at org.apache.ratis.util.LifeCycle.startAndTransition(LifeCycle.java:214)
> 2020-07-17T19:27:46.4195087Z 	at org.apache.ratis.server.impl.RaftServerRpcWithProxy.start(RaftServerRpcWithProxy.java:69)
> 2020-07-17T19:27:46.4195380Z 	at org.apache.ratis.server.impl.RaftServerProxy.lambda$start$3(RaftServerProxy.java:305)
> 2020-07-17T19:27:46.4195735Z 	at org.apache.ratis.util.LifeCycle.startAndTransition(LifeCycle.java:214)
> 2020-07-17T19:27:46.4196124Z 	at org.apache.ratis.server.impl.RaftServerProxy.start(RaftServerProxy.java:303)
> 2020-07-17T19:27:46.4196380Z 	at org.apache.ratis.MiniRaftCluster.startServers(MiniRaftCluster.java:425)
> 2020-07-17T19:27:46.4196633Z 	at org.apache.ratis.MiniRaftCluster.start(MiniRaftCluster.java:294)
> 2020-07-17T19:27:46.4196888Z 	at org.apache.ratis.MiniRaftCluster$Factory$Get.runWithNewCluster(MiniRaftCluster.java:123)
> 2020-07-17T19:27:46.4197154Z 	at org.apache.ratis.MiniRaftCluster$Factory$Get.runWithNewCluster(MiniRaftCluster.java:113)
> 2020-07-17T19:27:46.4197408Z 	at org.apache.ratis.RetryCacheTests.testRetryOnNewLeader(RetryCacheTests.java:122)
> 2020-07-17T19:27:46.4197665Z 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 2020-07-17T19:27:46.4202375Z 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> 2020-07-17T19:27:46.4202729Z 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 2020-07-17T19:27:46.4203273Z 	at java.lang.reflect.Method.invoke(Method.java:498)
> 2020-07-17T19:27:46.4203487Z 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
> 2020-07-17T19:27:46.4203658Z 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
> 2020-07-17T19:27:46.4203825Z 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
> 2020-07-17T19:27:46.4204045Z 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
> 2020-07-17T19:27:46.4204249Z 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> 2020-07-17T19:27:46.4204413Z 	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:298)
> 2020-07-17T19:27:46.4204582Z 	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:292)
> 2020-07-17T19:27:46.4204741Z 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> 2020-07-17T19:27:46.4204896Z 	at java.lang.Thread.run(Thread.java:748)
> 2020-07-17T19:27:46.4205045Z Caused by: java.net.BindException: Address already in use
> 2020-07-17T19:27:46.4205190Z 	at sun.nio.ch.Net.bind0(Native Method)
> 2020-07-17T19:27:46.4205339Z 	at sun.nio.ch.Net.bind(Net.java:433)
> 2020-07-17T19:27:46.4205433Z 	at sun.nio.ch.Net.bind(Net.java:425)
> 2020-07-17T19:27:46.4205596Z 	at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:220)
> 2020-07-17T19:27:46.4205771Z 	at org.apache.ratis.thirdparty.io.netty.channel.socket.nio.NioServerSocketChannel.doBind(NioServerSocketChannel.java:134)
> 2020-07-17T19:27:46.4205955Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannel$AbstractUnsafe.bind(AbstractChannel.java:550)
> 2020-07-17T19:27:46.4206227Z 	at org.apache.ratis.thirdparty.io.netty.channel.DefaultChannelPipeline$HeadContext.bind(DefaultChannelPipeline.java:1334)
> 2020-07-17T19:27:46.4206417Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.invokeBind(AbstractChannelHandlerContext.java:506)
> 2020-07-17T19:27:46.4206604Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.bind(AbstractChannelHandlerContext.java:491)
> 2020-07-17T19:27:46.4206790Z 	at org.apache.ratis.thirdparty.io.netty.handler.logging.LoggingHandler.bind(LoggingHandler.java:221)
> 2020-07-17T19:27:46.4206966Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.invokeBind(AbstractChannelHandlerContext.java:506)
> 2020-07-17T19:27:46.4207161Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannelHandlerContext.bind(AbstractChannelHandlerContext.java:491)
> 2020-07-17T19:27:46.4207584Z 	at org.apache.ratis.thirdparty.io.netty.channel.DefaultChannelPipeline.bind(DefaultChannelPipeline.java:973)
> 2020-07-17T19:27:46.4207759Z 	at org.apache.ratis.thirdparty.io.netty.channel.AbstractChannel.bind(AbstractChannel.java:248)
> 2020-07-17T19:27:46.4208120Z 	at org.apache.ratis.thirdparty.io.netty.bootstrap.AbstractBootstrap$2.run(AbstractBootstrap.java:356)
> 2020-07-17T19:27:46.4208334Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164)
> 2020-07-17T19:27:46.4216918Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:472)
> 2020-07-17T19:27:46.4217074Z 	at org.apache.ratis.thirdparty.io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:500)
> 2020-07-17T19:27:46.4217314Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
> 2020-07-17T19:27:46.4224782Z 	at org.apache.ratis.thirdparty.io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
> 2020-07-17T19:27:46.4225113Z 	at org.apache.ratis.thirdparty.io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
> 2020-07-17T19:27:46.4225281Z 	... 1 more



--
This message was sent by Atlassian Jira
(v8.3.4#803005)