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 C84EA18E38 for ; Tue, 27 Oct 2015 17:23:50 +0000 (UTC) Received: (qmail 2643 invoked by uid 500); 27 Oct 2015 17:23:34 -0000 Delivered-To: apmail-geode-issues-archive@geode.apache.org Received: (qmail 2608 invoked by uid 500); 27 Oct 2015 17:23:34 -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 2599 invoked by uid 99); 27 Oct 2015 17:23:34 -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 17:23:34 +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 72A42C3A4A for ; Tue, 27 Oct 2015 17:23: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 FrLozpOnQ4rr for ; Tue, 27 Oct 2015 17:23: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 0600B44480 for ; Tue, 27 Oct 2015 17:23:28 +0000 (UTC) Received: (qmail 2529 invoked by uid 99); 27 Oct 2015 17:23: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 17:23:27 +0000 Received: from arcas.apache.org (localhost [127.0.0.1]) by arcas (Postfix) with ESMTP id A91B32C033A for ; Tue, 27 Oct 2015 17:23:27 +0000 (UTC) Date: Tue, 27 Oct 2015 17:23:27 +0000 (UTC) From: "Dan Smith (JIRA)" To: issues@geode.incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Assigned] (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:all-tabpanel ] Dan Smith reassigned GEODE-502: ------------------------------- Assignee: Dan Smith > 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)