Return-Path: X-Original-To: apmail-geode-issues-archive@minotaur.apache.org Delivered-To: apmail-geode-issues-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 69DF217DC5 for ; Tue, 27 Oct 2015 21:19:35 +0000 (UTC) Received: (qmail 23109 invoked by uid 500); 27 Oct 2015 21:19:35 -0000 Delivered-To: apmail-geode-issues-archive@geode.apache.org Received: (qmail 23074 invoked by uid 500); 27 Oct 2015 21:19:35 -0000 Mailing-List: contact issues-help@geode.incubator.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@geode.incubator.apache.org Delivered-To: mailing list issues@geode.incubator.apache.org Received: (qmail 23065 invoked by uid 99); 27 Oct 2015 21:19:35 -0000 Received: from Unknown (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 27 Oct 2015 21:19:35 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id D7FD4C3A6E for ; Tue, 27 Oct 2015 21:19:34 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 1 X-Spam-Level: * X-Spam-Status: No, score=1 tagged_above=-999 required=6.31 tests=[KAM_LAZY_DOMAIN_SECURITY=1, RP_MATCHES_RCVD=-0.001, URIBL_BLOCKED=0.001] autolearn=disabled Received: from mx1-us-east.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id WPEVP34g7aog for ; Tue, 27 Oct 2015 21:19:29 +0000 (UTC) Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx1-us-east.apache.org (ASF Mail Server at mx1-us-east.apache.org) with SMTP id 636AA43A11 for ; Tue, 27 Oct 2015 21:19:28 +0000 (UTC) Received: (qmail 22805 invoked by uid 99); 27 Oct 2015 21:19:27 -0000 Received: from arcas.apache.org (HELO arcas) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 27 Oct 2015 21:19:27 +0000 Received: from arcas.apache.org (localhost [127.0.0.1]) by arcas (Postfix) with ESMTP id B99302C1F58 for ; Tue, 27 Oct 2015 21:19:27 +0000 (UTC) Date: Tue, 27 Oct 2015 21:19:27 +0000 (UTC) From: "ASF subversion and git services (JIRA)" To: issues@geode.incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (GEODE-502) CI Failure: TestSuite$1.warning MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/GEODE-502?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14977187#comment-14977187 ] ASF subversion and git services commented on GEODE-502: ------------------------------------------------------- Commit d177a3f2a4883b85870d059e57484eddfa48f9e3 in incubator-geode's branch refs/heads/feature/GEODE-409 from [~upthewaterspout] [ https://git-wip-us.apache.org/repos/asf?p=incubator-geode.git;h=d177a3f ] GEODE-502: Keeping a reference to RMI objects in the dunit framework My suspicion with this failure is that the GC happened that cleaned up one of these objects, if the RMI framework is not keeping a strong reference to the class. > CI Failure: TestSuite$1.warning > ------------------------------- > > Key: GEODE-502 > URL: https://issues.apache.org/jira/browse/GEODE-502 > Project: Geode > Issue Type: Bug > Reporter: Dan Smith > Assignee: Dan Smith > Labels: CI > > I saw this in a internal CI run. The first test fails with the below error about VMs not launching in 30 seconds. Then later tests fail with a cause of "Log writer has already been created" > This is actually a failure to launch the dunit framework, which is why it's misreporting the test name as "TestSuite$1.warning"; the failure happens during the constructor of the test case. > {noformat} > junit.framework.AssertionFailedError: Exception in constructor: testPutGetTimeout (java.lang.RuntimeException: Unable to launch dunit VMS > at dunit.standalone.DUnitLauncher.launchIfNeeded(DUnitLauncher.java:130) > at dunit.DistributedTestCase.(DistributedTestCase.java:416) > at com.gemstone.gemfire.cache30.CacheTestCase.(CacheTestCase.java:89) > at com.gemstone.gemfire.cache30.RegionTestCase.(RegionTestCase.java:110) > at com.gemstone.gemfire.cache30.MultiVMRegionTestCase.(MultiVMRegionTestCase.java:160) > at com.gemstone.gemfire.cache30.GlobalRegionDUnitTest.(GlobalRegionDUnitTest.java:39) > at com.gemstone.gemfire.cache30.GlobalRegionCompressionDUnitTest.(GlobalRegionCompressionDUnitTest.java:34) > at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) > at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) > at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) > at java.lang.reflect.Constructor.newInstance(Constructor.java:422) > at junit.framework.TestSuite.createTest(TestSuite.java:65) > at junit.framework.TestSuite.addTestMethod(TestSuite.java:307) > at junit.framework.TestSuite.addTestsFromTestCase(TestSuite.java:150) > at junit.framework.TestSuite.(TestSuite.java:129) > at org.junit.internal.runners.JUnit38ClassRunner.(JUnit38ClassRunner.java:74) > at org.junit.internal.builders.JUnit3Builder.runnerForClass(JUnit3Builder.java:11) > at org.junit.runners.model.RunnerBuilder.safeRunnerForClass(RunnerBuilder.java:59) > at org.junit.internal.builders.AllDefaultPossibilitiesBuilder.runnerForClass(AllDefaultPossibilitiesBuilder.java:26) > at org.junit.runners.model.RunnerBuilder.safeRunnerForClass(RunnerBuilder.java:59) > at org.junit.internal.requests.ClassRequest.getRunner(ClassRequest.java:33) > at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:88) > at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:56) > at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:64) > at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:50) > 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:497) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) > at org.gradle.messaging.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32) > at org.gradle.messaging.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93) > at com.sun.proxy.$Proxy2.processTestClass(Unknown Source) > at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:106) > 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:497) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) > at org.gradle.messaging.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:360) > at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54) > at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at java.lang.Thread.run(Thread.java:745) > Caused by: java.lang.RuntimeException: VMs did not start up with 30 seconds > at dunit.standalone.DUnitLauncher.launch(DUnitLauncher.java:181) > at dunit.standalone.DUnitLauncher.launchIfNeeded(DUnitLauncher.java:128) > ... 46 more > ) > at junit.framework.Assert.fail(Assert.java:57) > at junit.framework.TestCase.fail(TestCase.java:227) > at junit.framework.TestSuite$1.runTest(TestSuite.java:97) > at junit.framework.TestCase.runBare(TestCase.java:141) > at junit.framework.TestResult$1.protect(TestResult.java:122) > at junit.framework.TestResult.runProtected(TestResult.java:142) > at junit.framework.TestResult.run(TestResult.java:125) > at junit.framework.TestCase.run(TestCase.java:129) > at junit.framework.TestSuite.runTest(TestSuite.java:252) > at junit.framework.TestSuite.run(TestSuite.java:247) > at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:86) > at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:105) > at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:56) > at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:64) > at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:50) > 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:497) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) > at org.gradle.messaging.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32) > at org.gradle.messaging.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93) > at com.sun.proxy.$Proxy2.processTestClass(Unknown Source) > at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:106) > 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:497) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35) > at org.gradle.messaging.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) > at org.gradle.messaging.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:360) > at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54) > at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at java.lang.Thread.run(Thread.java:745) > {noformat} > I was able to track down the stdout for this first test that failed. This looks like something that has been reported as a JDK bug when related to GC of some classes held in a WeakCache in the JDK - https://bugs.openjdk.java.net/browse/JDK-8087168 > {noformat} > [locator]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [locator]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_0]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_0]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_1]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_1]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_2]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_2]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_3]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_3]Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=256M; support was removed in 8.0 > [vm_3]java.rmi.ServerException: RemoteException occurred in server thread; nested exception is: > [vm_3] java.rmi.UnmarshalException: error unmarshalling arguments; nested exception is: > [vm_3] java.io.InvalidClassException: Not a proxy > [vm_3] at sun.rmi.server.UnicastServerRef.oldDispatch(UnicastServerRef.java:420) > [vm_3] at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:268) > [vm_3] at sun.rmi.transport.Transport$1.run(Transport.java:200) > [vm_3] at sun.rmi.transport.Transport$1.run(Transport.java:197) > [vm_3] at java.security.AccessController.doPrivileged(Native Method) > [vm_3] at sun.rmi.transport.Transport.serviceCall(Transport.java:196) > [vm_3] at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:568) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:826) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$241(TCPTransport.java:683) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler$$Lambda$8/125090748.run(Unknown Source) > [vm_3] at java.security.AccessController.doPrivileged(Native Method) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:682) > [vm_3] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > [vm_3] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > [vm_3] at java.lang.Thread.run(Thread.java:745) > [vm_3] at sun.rmi.transport.StreamRemoteCall.exceptionReceivedFromServer(StreamRemoteCall.java:276) > [vm_3] at sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:253) > [vm_3] at sun.rmi.server.UnicastRef.invoke(UnicastRef.java:379) > [vm_3] at sun.rmi.registry.RegistryImpl_Stub.rebind(Unknown Source) > [vm_3] at java.rmi.Naming.rebind(Naming.java:177) > [vm_3] at dunit.standalone.ChildVM.main(ChildVM.java:52) > [vm_3]Caused by: java.rmi.UnmarshalException: error unmarshalling arguments; nested exception is: > [vm_3] java.io.InvalidClassException: Not a proxy > [vm_3] at sun.rmi.registry.RegistryImpl_Skel.dispatch(Unknown Source) > [vm_3] at sun.rmi.server.UnicastServerRef.oldDispatch(UnicastServerRef.java:410) > [vm_3] at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:268) > [vm_3] at sun.rmi.transport.Transport$1.run(Transport.java:200) > [vm_3] at sun.rmi.transport.Transport$1.run(Transport.java:197) > [vm_3] at java.security.AccessController.doPrivileged(Native Method) > [vm_3] at sun.rmi.transport.Transport.serviceCall(Transport.java:196) > [vm_3] at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:568) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:826) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$241(TCPTransport.java:683) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler$$Lambda$8/125090748.run(Unknown Source) > [vm_3] at java.security.AccessController.doPrivileged(Native Method) > [vm_3] at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:682) > [vm_3] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > [vm_3] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > [vm_3] at java.lang.Thread.run(Thread.java:745) > [vm_3]Caused by: java.io.InvalidClassException: Not a proxy > [vm_3] at java.io.ObjectInputStream.readProxyDesc(ObjectInputStream.java:1562) > [vm_3] at java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1515) > [vm_3] at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1774) > [vm_3] at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1351) > [vm_3] at java.io.ObjectInputStream.readObject(ObjectInputStream.java:371) > [vm_3] ... 16 more > {noformat} -- This message was sent by Atlassian JIRA (v6.3.4#6332)