[
https://issues.apache.org/jira/browse/GEODE-6419?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16982941#comment-16982941
]
Bill Burcham commented on GEODE-6419:
-------------------------------------
Saw this fail again:
https://concourse.apachegeode-ci.info/teams/main/pipelines/apache-develop-main/jobs/WindowsGfshDistributedTestOpenJDK11/builds/1084
{noformat}
> Task :geode-web:distributedTest
org.apache.geode.management.internal.cli.commands.ClusterConfigurationDUnitTest
> testStartServerAndExecuteCommands[0] FAILED
java.lang.AssertionError: Suspicious strings were written to the log during
this run.
Fix the strings or use IgnoredException.addIgnoredException to ignore.
-----------------------------------------------------------------------
Found suspect string in log4j at line 620
[error 2019/11/26 19:56:35.625 GMT <RMI TCP Connection(1)-10.0.0.159>
tid=82] Jmx manager could not be started because
java.rmi.server.ExportException: Port already in use: 22501; nested exception
is:
java.net.BindException: Failed to create server socket on
packer-5d794b32-99c3-fdab-e026-47bbb6c369ad/10.0.0.159[22501]
org.apache.geode.management.ManagementException:
java.rmi.server.ExportException: Port already in use: 22501; nested exception
is:
java.net.BindException: Failed to create server socket on
packer-5d794b32-99c3-fdab-e026-47bbb6c369ad/10.0.0.159[22501]
at
org.apache.geode.management.internal.ManagementAgent.startAgent(ManagementAgent.java:135)
at
org.apache.geode.management.internal.SystemManagementService.startManager(SystemManagementService.java:358)
at
org.apache.geode.management.internal.beans.ManagementAdapter.handleCacheCreation(ManagementAdapter.java:197)
at
org.apache.geode.management.internal.beans.ManagementListener.handleEvent(ManagementListener.java:127)
at
org.apache.geode.distributed.internal.InternalDistributedSystem.notifyResourceEventListeners(InternalDistributedSystem.java:2072)
at
org.apache.geode.distributed.internal.InternalDistributedSystem.handleResourceEvent(InternalDistributedSystem.java:620)
at
org.apache.geode.internal.cache.GemFireCacheImpl.initialize(GemFireCacheImpl.java:1215)
at
org.apache.geode.internal.cache.InternalCacheBuilder.create(InternalCacheBuilder.java:191)
at
org.apache.geode.distributed.internal.InternalLocator.startCache(InternalLocator.java:730)
at
org.apache.geode.distributed.internal.InternalLocator.startDistributedSystem(InternalLocator.java:717)
at
org.apache.geode.distributed.internal.InternalLocator.startLocator(InternalLocator.java:380)
at
org.apache.geode.distributed.internal.InternalLocator.startLocator(InternalLocator.java:330)
at org.apache.geode.distributed.Locator.startLocator(Locator.java:252)
at
org.apache.geode.distributed.Locator.startLocatorAndDS(Locator.java:139)
at
org.apache.geode.test.junit.rules.LocatorStarterRule.startLocator(LocatorStarterRule.java:88)
at
org.apache.geode.test.junit.rules.LocatorStarterRule.before(LocatorStarterRule.java:69)
at
org.apache.geode.test.dunit.rules.ClusterStartupRule.lambda$startLocatorVM$d6d0ef45$1(ClusterStartupRule.java:237)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
org.apache.geode.test.dunit.internal.MethodInvoker.executeObject(MethodInvoker.java:123)
at
org.apache.geode.test.dunit.internal.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:78)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
java.rmi/sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:359)
at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:200)
at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:197)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at java.rmi/sun.rmi.transport.Transport.serviceCall(Transport.java:196)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:562)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:796)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$0(TCPTransport.java:677)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:676)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.rmi.server.ExportException: Port already in use: 22501;
nested exception is:
java.net.BindException: Failed to create server socket on
packer-5d794b32-99c3-fdab-e026-47bbb6c369ad/10.0.0.159[22501]
at
java.rmi/sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:335)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:243)
at
java.rmi/sun.rmi.transport.tcp.TCPEndpoint.exportObject(TCPEndpoint.java:411)
at java.rmi/sun.rmi.transport.LiveRef.exportObject(LiveRef.java:147)
at
java.rmi/sun.rmi.server.UnicastServerRef.exportObject(UnicastServerRef.java:234)
at java.rmi/sun.rmi.registry.RegistryImpl.setup(RegistryImpl.java:220)
at java.rmi/sun.rmi.registry.RegistryImpl.<init>(RegistryImpl.java:180)
at java.rmi/sun.rmi.registry.RegistryImpl.<init>(RegistryImpl.java:151)
at
java.rmi/java.rmi.registry.LocateRegistry.createRegistry(LocateRegistry.java:239)
at
org.apache.geode.management.internal.ManagementAgent.configureAndStart(ManagementAgent.java:347)
at
org.apache.geode.management.internal.ManagementAgent.startAgent(ManagementAgent.java:133)
... 39 more
Caused by: java.net.BindException: Failed to create server socket on
packer-5d794b32-99c3-fdab-e026-47bbb6c369ad/10.0.0.159[22501]
at
org.apache.geode.internal.net.SocketCreator.createServerSocket(SocketCreator.java:722)
at
org.apache.geode.internal.net.SocketCreator.createServerSocket(SocketCreator.java:680)
at
org.apache.geode.management.internal.ManagementAgent$GemFireRMIServerSocketFactory.createServerSocket(ManagementAgent.java:512)
at
java.rmi/sun.rmi.transport.tcp.TCPEndpoint.newServerSocket(TCPEndpoint.java:666)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:324)
... 49 more
Caused by: java.net.BindException: Address already in use: NET_Bind
at java.base/java.net.PlainSocketImpl.bind0(Native Method)
at
java.base/java.net.PlainSocketImpl.socketBind(PlainSocketImpl.java:132)
at
java.base/java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:436)
at java.base/java.net.ServerSocket.bind(ServerSocket.java:381)
at
org.apache.geode.internal.net.SocketCreator.createServerSocket(SocketCreator.java:719)
... 53 more
-----------------------------------------------------------------------
Found suspect string in log4j at line 693
[error 2019/11/26 19:56:35.625 GMT <RMI TCP Connection(1)-10.0.0.159>
tid=82] org.apache.geode.management.ManagementException:
java.rmi.server.ExportException: Port already in use: 22501; nested exception
is:
java.net.BindException: Failed to create server socket on
packer-5d794b32-99c3-fdab-e026-47bbb6c369ad/10.0.0.159[22501]
org.apache.geode.management.internal.cli.commands.ConnectCommandWithSSLTest >
connectWithSSL FAILED
java.lang.AssertionError: Suspicious strings were written to the log during
this run.
Fix the strings or use IgnoredException.addIgnoredException to ignore.
-----------------------------------------------------------------------
Found suspect string in log4j at line 1289
[error 2019/11/26 19:59:07.047 GMT <RMI TCP Connection(2)-10.0.0.159>
tid=76] Jmx manager could not be started because HTTP service failed to start
org.apache.geode.management.ManagementException: HTTP service failed to
start
at
org.apache.geode.management.internal.ManagementAgent.loadWebApplications(ManagementAgent.java:244)
at
org.apache.geode.management.internal.ManagementAgent.startAgent(ManagementAgent.java:129)
at
org.apache.geode.management.internal.SystemManagementService.startManager(SystemManagementService.java:358)
at
org.apache.geode.management.internal.beans.ManagementAdapter.handleCacheCreation(ManagementAdapter.java:197)
at
org.apache.geode.management.internal.beans.ManagementListener.handleEvent(ManagementListener.java:127)
at
org.apache.geode.distributed.internal.InternalDistributedSystem.notifyResourceEventListeners(InternalDistributedSystem.java:2072)
at
org.apache.geode.distributed.internal.InternalDistributedSystem.handleResourceEvent(InternalDistributedSystem.java:620)
at
org.apache.geode.internal.cache.GemFireCacheImpl.initialize(GemFireCacheImpl.java:1215)
at
org.apache.geode.internal.cache.InternalCacheBuilder.create(InternalCacheBuilder.java:191)
at
org.apache.geode.distributed.internal.InternalLocator.startCache(InternalLocator.java:730)
at
org.apache.geode.distributed.internal.InternalLocator.startDistributedSystem(InternalLocator.java:717)
at
org.apache.geode.distributed.internal.InternalLocator.startLocator(InternalLocator.java:380)
at
org.apache.geode.distributed.internal.InternalLocator.startLocator(InternalLocator.java:330)
at org.apache.geode.distributed.Locator.startLocator(Locator.java:252)
at
org.apache.geode.distributed.Locator.startLocatorAndDS(Locator.java:139)
at
org.apache.geode.test.junit.rules.LocatorStarterRule.startLocator(LocatorStarterRule.java:88)
at
org.apache.geode.test.junit.rules.LocatorStarterRule.before(LocatorStarterRule.java:69)
at
org.apache.geode.test.dunit.rules.ClusterStartupRule.lambda$startLocatorVM$d6d0ef45$1(ClusterStartupRule.java:237)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
org.apache.geode.test.dunit.internal.MethodInvoker.executeObject(MethodInvoker.java:123)
at
org.apache.geode.test.dunit.internal.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:78)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
java.rmi/sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:359)
at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:200)
at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:197)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at java.rmi/sun.rmi.transport.Transport.serviceCall(Transport.java:196)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:562)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:796)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$0(TCPTransport.java:677)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at
java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:676)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.io.IOException: Failed to bind to localhost/127.0.0.1:22500
at
org.eclipse.jetty.server.ServerConnector.openAcceptChannel(ServerConnector.java:346)
at
org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:307)
at
org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
at
org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:231)
at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:72)
at org.eclipse.jetty.server.Server.doStart(Server.java:385)
at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:72)
at
org.apache.geode.internal.cache.InternalHttpService.addWebApplication(InternalHttpService.java:207)
at
org.apache.geode.management.internal.ManagementAgent.loadWebApplications(ManagementAgent.java:214)
... 40 more
Caused by: java.net.BindException: Address already in use: bind
at java.base/sun.nio.ch.Net.bind0(Native Method)
at java.base/sun.nio.ch.Net.bind(Net.java:461)
at java.base/sun.nio.ch.Net.bind(Net.java:453)
at
java.base/sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:227)
at
java.base/sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:80)
at
org.eclipse.jetty.server.ServerConnector.openAcceptChannel(ServerConnector.java:342)
... 48 more
-----------------------------------------------------------------------
Found suspect string in log4j at line 1352
[error 2019/11/26 19:59:07.062 GMT <RMI TCP Connection(2)-10.0.0.159>
tid=76] org.apache.geode.management.ManagementException: HTTP service failed to
start
122 tests completed, 2 failed
{noformat}
=-=-=-=-=-=-=-=-=-=-=-=-=-=-= Test Results URI
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
http://files.apachegeode-ci.info/builds/apache-develop-main/1.12.0-SNAPSHOT.0052/test-results/distributedTest/1574799673/
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Test report artifacts from this job are available at:
http://files.apachegeode-ci.info/builds/apache-develop-main/1.12.0-SNAPSHOT.0052/test-artifacts/1574799673/windows-gfshdistributedtest-OpenJDK11-1.12.0-SNAPSHOT.0052.tgz
> CI Failure: ClusterConfigurationDUnitTest.testStartServerAndExecuteCommands
> fails with BindException
> ----------------------------------------------------------------------------------------------------
>
> Key: GEODE-6419
> URL: https://issues.apache.org/jira/browse/GEODE-6419
> Project: Geode
> Issue Type: Bug
> Components: tests
> Reporter: Benjamin P Ross
> Priority: Major
>
> Stack Trace:
> {noformat}
> org.apache.geode.management.internal.cli.commands.ClusterConfigurationDUnitTest
> > testStartServerAndExecuteCommands[0] FAILED
> java.lang.AssertionError: Suspicious strings were written to the log
> during this run.
> Fix the strings or use IgnoredException.addIgnoredException to ignore.
> -----------------------------------------------------------------------
> Found suspect string in log4j at line 1121
> [error 2019/02/14 19:04:04.702 GMT <RMI TCP Connection(1)-10.0.0.34>
> tid=64] Jmx manager could not be started because HTTP service failed to start
> org.apache.geode.management.ManagementException: HTTP service failed to
> start
> at
> org.apache.geode.management.internal.ManagementAgent.loadWebApplications(ManagementAgent.java:240)
> at
> org.apache.geode.management.internal.ManagementAgent.startAgent(ManagementAgent.java:127)
> at
> org.apache.geode.management.internal.SystemManagementService.startManager(SystemManagementService.java:432)
> at
> org.apache.geode.management.internal.beans.ManagementAdapter.handleCacheCreation(ManagementAdapter.java:179)
> at
> org.apache.geode.management.internal.beans.ManagementListener.handleEvent(ManagementListener.java:127)
> at
> org.apache.geode.distributed.internal.InternalDistributedSystem.notifyResourceEventListeners(InternalDistributedSystem.java:2127)
> at
> org.apache.geode.distributed.internal.InternalDistributedSystem.handleResourceEvent(InternalDistributedSystem.java:679)
> at
> org.apache.geode.internal.cache.GemFireCacheImpl.initialize(GemFireCacheImpl.java:1241)
> at
> org.apache.geode.internal.cache.GemFireCacheImpl.basicCreate(GemFireCacheImpl.java:809)
> at
> org.apache.geode.internal.cache.GemFireCacheImpl.create(GemFireCacheImpl.java:795)
> at org.apache.geode.cache.CacheFactory.create(CacheFactory.java:176)
> at org.apache.geode.cache.CacheFactory.create(CacheFactory.java:218)
> at
> org.apache.geode.distributed.internal.InternalLocator.startCache(InternalLocator.java:674)
> at
> org.apache.geode.distributed.internal.InternalLocator.startDistributedSystem(InternalLocator.java:661)
> at
> org.apache.geode.distributed.internal.InternalLocator.startLocator(InternalLocator.java:345)
> at org.apache.geode.distributed.Locator.startLocator(Locator.java:252)
> at
> org.apache.geode.distributed.Locator.startLocatorAndDS(Locator.java:139)
> at
> org.apache.geode.test.junit.rules.LocatorStarterRule.startLocator(LocatorStarterRule.java:85)
> at
> org.apache.geode.test.junit.rules.LocatorStarterRule.before(LocatorStarterRule.java:66)
> at
> org.apache.geode.test.dunit.rules.ClusterStartupRule.lambda$startLocatorVM$22d9b8a8$1(ClusterStartupRule.java:208)
> at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.base/java.lang.reflect.Method.invoke(Method.java:566)
> at
> org.apache.geode.test.dunit.internal.MethodInvoker.executeObject(MethodInvoker.java:123)
> at
> org.apache.geode.test.dunit.internal.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:69)
> at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.base/java.lang.reflect.Method.invoke(Method.java:566)
> at
> java.rmi/sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:359)
> at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:200)
> at java.rmi/sun.rmi.transport.Transport$1.run(Transport.java:197)
> at java.base/java.security.AccessController.doPrivileged(Native Method)
> at java.rmi/sun.rmi.transport.Transport.serviceCall(Transport.java:196)
> at
> java.rmi/sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:562)
> at
> java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:796)
> at
> java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$0(TCPTransport.java:677)
> at java.base/java.security.AccessController.doPrivileged(Native Method)
> at
> java.rmi/sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:676)
> at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
> at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
> at java.base/java.lang.Thread.run(Thread.java:834)
> Caused by: java.net.BindException: Address already in use: bind
> at java.base/sun.nio.ch.Net.bind0(Native Method)
> at java.base/sun.nio.ch.Net.bind(Net.java:461)
> at java.base/sun.nio.ch.Net.bind(Net.java:453)
> at
> java.base/sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:227)
> at
> java.base/sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:80)
> at
> org.eclipse.jetty.server.ServerConnector.openAcceptChannel(ServerConnector.java:339)
> at
> org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:307)
> at
> org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
> at
> org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:235)
> at
> org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
> at org.eclipse.jetty.server.Server.doStart(Server.java:395)
> at
> org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
> at
> org.apache.geode.internal.cache.HttpService.addWebApplication(HttpService.java:198)
> at
> org.apache.geode.management.internal.ManagementAgent.loadWebApplications(ManagementAgent.java:214)
> ... 42 more
> -----------------------------------------------------------------------
> Found suspect string in log4j at line 1183
> [error 2019/02/14 19:04:04.717 GMT <RMI TCP Connection(1)-10.0.0.34>
> tid=64] org.apache.geode.management.ManagementException: HTTP service failed
> to start
> {noformat}
> Artifacts can be found here:
> http://files.apachegeode-ci.info/builds/apache-develop-main/1.9.0-SNAPSHOT.0439/test-artifacts/1550177497/windows-gfshdistributedtest-OpenJDK11-1.9.0-SNAPSHOT.0439.tgz
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
