Mailing-List: contact dev-help@geode.apache.org; run by ezmlm
Precedence: bulk
Reply-To: dev@geode.apache.org
From: Jared Stewart <jstewart@pivotal.io>
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable
Mime-Version: 1.0 (Mac OS X Mail 10.3 \(3273\))
Subject: Re: ExportConfigCommandDUnitTest
Date: Wed, 4 Oct 2017 17:25:22 -0700
References: <CAJrNg03bkqbbz9F4HKTs-JEDUVt+3DaUR2go1RmsyytcHJZUXw@mail.gmail.com>
 <FEC71D2C-ACA2-4499-BF2C-088F26AF40CB@pivotal.io>
 <CAJrNg02Da7ti9F=z-oAQK6DUpUvTb9qK15n5_Xmx_JOdt0W12Q@mail.gmail.com>
To: dev@geode.apache.org
In-Reply-To: <CAJrNg02Da7ti9F=z-oAQK6DUpUvTb9qK15n5_Xmx_JOdt0W12Q@mail.gmail.com>
Message-Id: <0084C4ED-91E7-47EF-891B-C9C0624048E1@pivotal.io>
archived-at: Thu, 05 Oct 2017 00:25:32 -0000

That test uses AvailablePortFinder to choose a random available port.  =
It=E2=80=99s possible that you hit a race condition in between the time =
port was chosen and the time it the server attempted to bind to it. =20

> On Oct 4, 2017, at 5:20 PM, Kirk Lund <klund@apache.org> wrote:
>=20
> It passes when I run it in IntelliJ. The cause was:
>=20
> Caused by: java.net.BindException: Address already in use
>=20
> So, I'm assuming the test tried to use a port that was in use. Is the =
test
> using a random port or a default port?
>=20
> On Wed, Oct 4, 2017 at 5:14 PM, Jared Stewart <jstewart@pivotal.io> =
wrote:
>=20
>> I haven=E2=80=99t seen that test fail before.  If you rerun the =
precheckin does it
>> still fail?
>>=20
>>=20
>>> On Oct 4, 2017, at 4:55 PM, Kirk Lund <klund@apache.org> wrote:
>>>=20
>>> ExportConfigCommandDUnitTest failed in my latest precheckin but my
>> changes
>>> don't involve the code this test is testing. Did someone commit =
break
>> this
>>> test or is it failing intermittently?
>>>=20
>>> Thanks,
>>> Kirk
>>>=20
>>> :geode-web:distributedTest
>>>=20
>>> org.apache.geode.management.internal.cli.commands.
>> ExportConfigCommandDUnitTest
>>>> testExportConfig(true) [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 336
>>>=20
>>>   [error 2017/10/04 23:34:54.964 UTC <RMI TCP =
Connection(1)-172.17.0.5>
>>> tid=3D0x1b] 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.startHttpService(
>> ManagementAgent.java:314)
>>>   at
>>> org.apache.geode.management.internal.ManagementAgent.
>> startAgent(ManagementAgent.java:143)
>>>   at
>>> org.apache.geode.management.internal.SystemManagementService.
>> startManager(SystemManagementService.java:427)
>>>   at
>>> org.apache.geode.management.internal.beans.ManagementAdapter.
>> handleCacheCreation(ManagementAdapter.java:173)
>>>   at
>>> org.apache.geode.management.internal.beans.ManagementListener.
>> handleEvent(ManagementListener.java:106)
>>>   at
>>> org.apache.geode.distributed.internal.InternalDistributedSystem.
>> notifyResourceEventListeners(InternalDistributedSystem.java:2198)
>>>   at
>>> org.apache.geode.distributed.internal.InternalDistributedSystem.
>> handleResourceEvent(InternalDistributedSystem.java:585)
>>>   at
>>> org.apache.geode.internal.cache.GemFireCacheImpl.
>> initialize(GemFireCacheImpl.java:1207)
>>>   at
>>> org.apache.geode.internal.cache.GemFireCacheImpl.
>> basicCreate(GemFireCacheImpl.java:768)
>>>   at
>>> org.apache.geode.internal.cache.GemFireCacheImpl.create(
>> GemFireCacheImpl.java:754)
>>>   at =
org.apache.geode.cache.CacheFactory.create(CacheFactory.java:175)
>>>   at =
org.apache.geode.cache.CacheFactory.create(CacheFactory.java:222)
>>>   at
>>> org.apache.geode.test.junit.rules.ServerStarterRule.
>> startServer(ServerStarterRule.java:147)
>>>   at
>>> org.apache.geode.test.junit.rules.ServerStarterRule.
>> before(ServerStarterRule.java:76)
>>>   at
>>> org.apache.geode.test.dunit.rules.LocatorServerStartupRule.lambda$
>> =
startServerAsEmbededLocator$207b7110$1(LocatorServerStartupRule.java:230)
>>>   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>>   at
>>> sun.reflect.NativeMethodAccessorImpl.invoke(
>> NativeMethodAccessorImpl.java:62)
>>>   at
>>> sun.reflect.DelegatingMethodAccessorImpl.invoke(
>> DelegatingMethodAccessorImpl.java:43)
>>>   at java.lang.reflect.Method.invoke(Method.java:498)
>>>   at hydra.MethExecutor.executeObject(MethExecutor.java:245)
>>>   at
>>> org.apache.geode.test.dunit.standalone.RemoteDUnitVM.
>> executeMethodOnObject(RemoteDUnitVM.java:70)
>>>   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>>   at
>>> sun.reflect.NativeMethodAccessorImpl.invoke(
>> NativeMethodAccessorImpl.java:62)
>>>   at
>>> sun.reflect.DelegatingMethodAccessorImpl.invoke(
>> DelegatingMethodAccessorImpl.java:43)
>>>   at java.lang.reflect.Method.invoke(Method.java:498)
>>>   at sun.rmi.server.UnicastServerRef.dispatch(
>> UnicastServerRef.java:357)
>>>   at sun.rmi.transport.Transport$1.run(Transport.java:200)
>>>   at sun.rmi.transport.Transport$1.run(Transport.java:197)
>>>   at java.security.AccessController.doPrivileged(Native Method)
>>>   at sun.rmi.transport.Transport.serviceCall(Transport.java:196)
>>>   at
>>> =
sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:568)
>>>   at
>>> sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(
>> TCPTransport.java:826)
>>>   at
>>> sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$
>> 0(TCPTransport.java:683)
>>>   at java.security.AccessController.doPrivileged(Native Method)
>>>   at
>>> sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(
>> TCPTransport.java:682)
>>>   at
>>> java.util.concurrent.ThreadPoolExecutor.runWorker(
>> ThreadPoolExecutor.java:1149)
>>>   at
>>> java.util.concurrent.ThreadPoolExecutor$Worker.run(
>> ThreadPoolExecutor.java:624)
>>>   at java.lang.Thread.run(Thread.java:748)
>>>   Caused by: java.net.BindException: Address already in use
>>>   at sun.nio.ch.Net.bind0(Native Method)
>>>   at sun.nio.ch.Net.bind(Net.java:433)
>>>   at sun.nio.ch.Net.bind(Net.java:425)
>>>   at
>>> =
sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:
>> 223)
>>>   at =
sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
>>>   at
>>> =
org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:317)
>>>   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:390)
>>>   at
>>> org.eclipse.jetty.util.component.AbstractLifeCycle.
>> start(AbstractLifeCycle.java:68)
>>>   at
>>> org.apache.geode.management.internal.JettyHelper.
>> startJetty(JettyHelper.java:149)
>>>   at
>>> =
org.apache.geode.management.internal.ManagementAgent.startHttpService(
>> ManagementAgent.java:286)
>>>   ... 37 more
>>>=20
>>>   ------------------------------------------------------------
>> -----------
>>>   Found suspect string in log4j at line 392
>>>=20
>>>   [error 2017/10/04 23:34:54.971 UTC <RMI TCP =
Connection(1)-172.17.0.5>
>>> tid=3D0x1b] org.apache.geode.management.ManagementException: HTTP =
service
>>> failed to start
>>>=20
>>> org.apache.geode.management.internal.cli.commands.
>> ExportConfigCommandDUnitTest
>>>> testExportConfig(false) [1] FAILED
>>>   org.junit.ComparisonFailure: [Could not read command response. =
Error
>>> occurred while executing "export config
>>> --dir=3D/tmp/junit2331171051443593975/all-members" on manager.
>>>=20
>>>   ] expected:<[OK]> but was:<[ERROR]>
>>>       at =
sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native
>>> Method)
>>>       at
>>> sun.reflect.NativeConstructorAccessorImpl.newInstance(
>> NativeConstructorAccessorImpl.java:62)
>>>       at
>>> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(
>> DelegatingConstructorAccessorImpl.java:45)
>>>       at
>>> org.apache.geode.test.junit.rules.GfshShellConnectionRule.
>> executeAndVerifyCommand(GfshShellConnectionRule.java:255)
>>>       at
>>> org.apache.geode.management.internal.cli.commands.
>> ExportConfigCommandDUnitTest.testExportConfig(
>> ExportConfigCommandDUnitTest.java:76)
>>>=20
>>> 79 tests completed, 2 failed
>>> :geode-web:distributedTest FAILED
>>=20
>>=20