geode-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Kirk Lund (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (GEODE-757) CI failure: PartitionedRegionDelayedRecoveryDUnitTest.testStartupDelay
Date Thu, 07 Jan 2016 22:51:39 GMT

    [ https://issues.apache.org/jira/browse/GEODE-757?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15088320#comment-15088320 ] 

Kirk Lund commented on GEODE-757:
---------------------------------

Standard Output
{noformat}
Previously run tests: [CacheXml90DUnitTest, JarDeployerDUnitTest, PdxRenameDUnitTest, ClassNotFoundExceptionDUnitTest, PdxDeleteFieldDUnitTest, IdleTimeOutDUnitTest, TxnManagerMultiThreadDUnitTest, MaxPoolSizeDUnitTest, TransactionTimeOutDUnitTest, TxnTimeOutDUnitTest, ExceptionsDUnitTest, LoginTimeOutDUnitTest, LocalProcessLauncherDUnitTest, ConcurrentDestroySubRegionDUnitTest, PartitionedRegionDelayedRecoveryDUnitTest]
[vm_0][info 2016/01/07 00:00:25.252 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/07 00:00:25.252 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_1][info 2016/01/07 00:00:25.253 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/07 00:00:25.253 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/07 00:00:25.253 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/07 00:00:25.253 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST PartitionedRegionDelayedRecoveryDUnitTest.testDelay


[vm_3][info 2016/01/07 00:00:25.220 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/07 00:00:25.221 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 1 ms)

[vm_3][info 2016/01/07 00:00:25.223 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/07 00:00:25.223 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/07 00:00:25.253 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/07 00:00:25.254 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_0][info 2016/01/07 00:00:25.258 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$6.run with 0 args on object: "createRegions"

[vm_0][info 2016/01/07 00:00:25.268 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _monitoringRegion_10.118.32.90<v57>1025

[vm_0][info 2016/01/07 00:00:25.269 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _monitoringRegion_10.118.32.90<v57>1025 completed

[vm_0][info 2016/01/07 00:00:25.273 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/07 00:00:25.274 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region PdxTypes

[vm_0][info 2016/01/07 00:00:25.275 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region PdxTypes completed

[vm_0][info 2016/01/07 00:00:25.276 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region __PR

[vm_0][info 2016/01/07 00:00:25.278 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region __PR completed

[vm_0][info 2016/01/07 00:00:25.281 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Partitioned Region /region1 is born with prId=77 ident:#region1

[vm_0][info 2016/01/07 00:00:25.285 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$6.run with 0 args on object: "createRegions" (took 27 ms)

[vm_1][info 2016/01/07 00:00:25.289 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$6.run with 0 args on object: "createRegions"

[vm_1][info 2016/01/07 00:00:25.295 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v58>1026

[vm_1][info 2016/01/07 00:00:25.296 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v58>1026 completed

[vm_1][info 2016/01/07 00:00:25.299 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/07 00:00:25.300 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region PdxTypes

[vm_1][info 2016/01/07 00:00:25.301 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region PdxTypes requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:25.302 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] PdxTypes is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:25.302 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region PdxTypes completed

[vm_1][info 2016/01/07 00:00:25.303 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region __PR

[vm_1][info 2016/01/07 00:00:25.304 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region __PR requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:25.306 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] __PR is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:25.306 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region __PR completed

[vm_1][info 2016/01/07 00:00:25.309 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 is created with prId=77

[vm_1][info 2016/01/07 00:00:25.316 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$6.run with 0 args on object: "createRegions" (took 27 ms)

[vm_0][info 2016/01/07 00:00:25.319 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$7.run with 0 args on object: "putData"

[vm_0][info 2016/01/07 00:00:25.322 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _B__region1_65

[vm_0][info 2016/01/07 00:00:25.323 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _B__region1_65 completed

[vm_1][info 2016/01/07 00:00:25.324 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initializing region _B__region1_65

[vm_1][info 2016/01/07 00:00:25.326 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Region _B__region1_65 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:25.326 PST <Pooled Waiting Message Processor 0> tid=0x1f1] _B__region1_65 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:25.326 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initialization of region _B__region1_65 completed

[vm_0][info 2016/01/07 00:00:25.331 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$7.run with 0 args on object: "putData" (took 12 ms)

[vm_2][info 2016/01/07 00:00:25.336 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$6.run with 0 args on object: "createRegions"

[vm_2][info 2016/01/07 00:00:25.340 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] 
[vm_2]---------------------------------------------------------------------------
[vm_2]  
[vm_2]  Licensed to the Apache Software Foundation (ASF) under one or more
[vm_2]  contributor license agreements.  See the NOTICE file distributed with this
[vm_2]  work for additional information regarding copyright ownership.
[vm_2]   
[vm_2]  The ASF licenses this file to You under the Apache License, Version 2.0
[vm_2]  (the "License"); you may not use this file except in compliance with the
[vm_2]  License.  You may obtain a copy of the License at
[vm_2]  
[vm_2]  http://www.apache.org/licenses/LICENSE-2.0
[vm_2]  
[vm_2]  Unless required by applicable law or agreed to in writing, software
[vm_2]  distributed under the License is distributed on an "AS IS" BASIS, WITHOUT
[vm_2]  WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.  See the
[vm_2]  License for the specific language governing permissions and limitations
[vm_2]  under the License.
[vm_2]  
[vm_2]---------------------------------------------------------------------------
[vm_2]Java version:   1.0.0-incubating-SNAPSHOT build 010616 2016-01-06 23:11:17 -0800 javac 1.8.0_66
[vm_2]Native version: native code unavailable
[vm_2]Source revision: 3e5ade76b5cc7ad01074d2b623468ebc41df1d39
[vm_2]Source repository: develop
[vm_2]Running on: venezuela/10.118.32.90, 4 cpu(s), amd64 Linux 2.6.18-262.el5
[vm_2]Process ID: 24112
[vm_2]User: build
[vm_2]Current dir: /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/distributedTest/dunit/vm2
[vm_2]Home dir: /home/build
[vm_2]Command Line Parameters:
[vm_2]  -Dgemfire.DUnitLauncher.RMI_PORT=29807
[vm_2]  -Dgemfire.DUnitLauncher.VM_NUM=2
[vm_2]  -DWORKSPACE_DIR=/venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/distributedTest/.
[vm_2]  -DlogLevel=info
[vm_2]  -Djava.library.path=/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
[vm_2]  -Xrunjdwp:transport=dt_socket,server=y,suspend=n
[vm_2]  -XX:+HeapDumpOnOutOfMemoryError
[vm_2]  -Xmx512m
[vm_2]  -Dgemfire.DEFAULT_MAX_OPLOG_SIZE=10
[vm_2]  -Dgemfire.disallowMcastDefaults=true
[vm_2]  -ea
[vm_2]  -DdummyArg=true
[vm_2]Class Path:
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/classes/test
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/resources/test
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/classes/main
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/resources/main
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/generated-resources/main
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-aop/3.2.12.RELEASE/216cb56450e9132c2aa5e31b40417bb5ea778f3d/spring-aop-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-beans/3.2.12.RELEASE/a1d13f4c8909986e1d40b81cd3bc8ef19a856616/spring-beans-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-context/3.2.12.RELEASE/2feaea75b4a188129babce4b149197fe10dce6b6/spring-context-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-context-support/3.2.12.RELEASE/9ec51a7dd751f4049d28c91e4e65326060922f6c/spring-context-support-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-core/3.2.12.RELEASE/6bca909d614f349f18aec2a924ffc8d28aa9efab/spring-core-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-expression/3.2.12.RELEASE/6fcbf13701c2dfa6eddca2f54165aca2a1ff4903/spring-expression-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-web/3.2.12.RELEASE/1cb1fe35b23c7d6946ca8d1897698845d84b18e/spring-web-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-webmvc/3.2.12.RELEASE/4073676738d460a4dc465969371c7eebadb47d6e/spring-webmvc-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/com.github.stephenc.findbugs/findbugs-annotations/1.3.9-1/a6b11447635d80757d64b355bed3c00786d86801/findbugs-annotations-1.3.9-1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.jgroups/jgroups/3.6.6.Final/688ee993083df9eb123d051d2ff0810859c8325e/jgroups-3.6.6.Final.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/antlr/antlr/2.7.7/antlr-2.7.7.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/com/fasterxml/jackson/core/jackson-annotations/2.2.0/jackson-annotations-2.2.0.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/com/fasterxml/jackson/core/jackson-core/2.2.0/jackson-core-2.2.0.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/com/fasterxml/jackson/core/jackson-databind/2.2.0/jackson-databind-2.2.0.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/commons-io/commons-io/2.3/commons-io-2.3.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/commons-lang/commons-lang/2.5/commons-lang-2.5.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/commons-logging/commons-logging/1.1.1/commons-logging-1.1.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/commons-modeler/commons-modeler/2.0/177f22811ed71980e5cda66de05bd070e4da3ee3/commons-modeler-2.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/io.netty/netty-all/4.0.4.Final/925914146e37041255ea18ae2d5982f9589c784d/netty-all-4.0.4.Final.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/it.unimi.dsi/fastutil/7.0.2/20876232e2cd12563507e4c9e85bd7948bc0656f/fastutil-7.0.2.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/javax.activation/activation/1.1.1/485de3a253e23f645037828c07f1d7f1af40763a/activation-1.1.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/javax.mail/javax.mail-api/1.4.5/42af3cb44d5ec658adc8a4c7354ac0d19547c4ca/javax.mail-api-1.4.5.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/javax.resource/javax.resource-api/1.7/ae40e0864eb1e92c48bf82a2a3399cbbf523fb79/javax.resource-api-1.7.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/javax/servlet/javax.servlet-api/3.1.0/javax.servlet-api-3.1.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/javax.transaction/javax.transaction-api/1.2/d81aff979d603edd90dcd8db2abc1f4ce6479e3e/javax.transaction-api-1.2.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/mx4j/mx4j/3.0.1/991089e21fce990b84d9e06e9ed9cc00d518ae56/mx4j-3.0.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/mx4j/mx4j-remote/3.0.1/f491951e754c24e7487efdd9f36d0677bfeb4184/mx4j-remote-3.0.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/mx4j/mx4j-tools/3.0.1/df853af9fe34d4eb6f849a1b5936fddfcbe67751/mx4j-tools-3.0.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/net.java.dev.jna/jna/4.0.0/9b3a11c613ec3fd3440af4103b12c3de82d38b6e/jna-4.0.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/jline/jline/2.12/ce9062c6a125e0f9ad766032573c041ae8ecc986/jline-2.12.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.hbase/hbase/0.94.27/d0e1f44e59e9d64f31b40621ae7bd53a6d0a13b1/hbase-0.94.27.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/org/apache/logging/log4j/log4j-api/2.1/log4j-api-2.1.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/org/apache/logging/log4j/log4j-core/2.1/log4j-core-2.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-http/9.3.6.v20151106/c2bba60bc1f9fe5779ac20ab30232bf9a89d3e52/jetty-http-9.3.6.v20151106.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-io/9.3.6.v20151106/9e59bde867e55d8c93cdd682d12317733ef5339/jetty-io-9.3.6.v20151106.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-security/9.3.6.v20151106/e44ffc80834a7f78a5b0ed15c54b875956772242/jetty-security-9.3.6.v20151106.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-server/9.3.6.v20151106/d9c43a1b20ede7e3c456237d71b4cce1dff5457a/jetty-server-9.3.6.v20151106.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-servlet/9.3.6.v20151106/62c03d6c7203735d4e28e4e78e22df38152f01ef/jetty-servlet-9.3.6.v20151106.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-util/9.3.6.v20151106/8721c8e670c11ea19005c567733453956b6243fc/jetty-util-9.3.6.v20151106.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-webapp/9.3.6.v20151106/9cf00a3b7b2c1b6e024bb687e3719e1b0ff9e899/jetty-webapp-9.3.6.v20151106.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.eclipse.jetty/jetty-xml/9.3.6.v20151106/b22e22977ea6c08751f8c945bb0785c35f9db28a/jetty-xml-9.3.6.v20151106.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/org/fusesource/jansi/jansi/1.8/jansi-1.8.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/org/slf4j/slf4j-api/1.7.7/slf4j-api-1.7.7.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework.data/spring-data-commons/1.9.1.RELEASE/d970ea4d8aa2fcf03aeb34cbe0a834390ca1597a/spring-data-commons-1.9.1.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework/spring-tx/3.2.12.RELEASE/e0422baa59eda8a9251916e249633e86c892175c/spring-tx-3.2.12.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework.shell/spring-shell/1.1.0.RELEASE/32fcf5aacf7bd5d997dbf2f8f95ed9369d81d9c0/spring-shell-1.1.0.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.xerial.snappy/snappy-java/1.1.1.6/e3f12835a135c0d253bee4dbbe74d37ce35d7269/snappy-java-1.1.1.6.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-common/build/libs/gemfire-common-1.0.0-incubating-SNAPSHOT.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-joptsimple/build/libs/gemfire-joptsimple-1.0.0-incubating-SNAPSHOT.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-json/build/libs/gemfire-json-1.0.0-incubating-SNAPSHOT.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/org/apache/logging/log4j/log4j-slf4j-impl/2.1/log4j-slf4j-impl-2.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.logging.log4j/log4j-jcl/2.1/58f1362403f0100da0415f69beaa78fa88fd4ed7/log4j-jcl-2.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.logging.log4j/log4j-jul/2.1/de6f610a8a29537ea6476332938e48981b2bd4c9/log4j-jul-2.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/com.github.stefanbirkner/system-rules/1.15.0/355adc877e54a2eb6b42a3d2801cdf8329f215d8/system-rules-1.15.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/com.jayway.awaitility/awaitility/1.7.0/e4fa7955cab67d880e4868a0795cce3498ea7bf2/awaitility-1.7.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/edu.umd.cs.mtc/multithreadedtc/1.01/43fbc1110d7e43cf79e415ef32036975ad85944c/multithreadedtc-1.01.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/eu.codearte.catch-exception/catch-exception/1.4.4/c54da7072e0ccaf1fb92118e5e6e24335039006e/catch-exception-1.4.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/eu.codearte.catch-exception/catch-throwable/1.4.4/e152e6679c9e57934cbf4126214e324c55603f82/catch-throwable-1.4.4.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/junit/junit/4.12/junit-4.12.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.assertj/assertj-core/3.2.0/eded8ad93bf57af8eff233c1751550bd40349ebb/assertj-core-3.2.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.mockito/mockito-core/1.10.19/e8546f5bef4e061d8dd73895b4e8f40e3fe6effe/mockito-core-1.10.19.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/org/hamcrest/hamcrest-all/1.3/hamcrest-all-1.3.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.jmock/jmock/2.8.1/aba0ed5962f5238421a2fee706d98133714590d0/jmock-2.8.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.jmock/jmock-junit4/2.8.1/f32f596560c90e68aa2ef6d3fae8022475dbb9d0/jmock-junit4-2.8.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.jmock/jmock-legacy/2.8.1/ec286ef0c3f662a2260a864c89ebfbb5da3bd4de/jmock-legacy-2.8.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/pl.pragmatists/JUnitParams/1.0.4/e311bbf8fe3f0d837d6d38fbd0a3cf00dc909704/JUnitParams-1.0.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.bcel/bcel/5.2/96b2cefeb067c08c31225d48e2a689f814baae25/bcel-5.2.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/net.spy/spymemcached/2.9.0/5af20b7abf49d0b49b7eaf92baa036620f3e4591/spymemcached-2.9.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/redis.clients/jedis/2.7.2/f2f47f1025ea5090263820e8598e56eb47f5c88a/jedis-2.7.2.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.powermock/powermock-core/1.6.4/85fb32e9ccba748d569fc36aef92e0b9e7f40b87/powermock-core-1.6.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.powermock/powermock-module-junit4/1.6.4/8692eb1d9bb8eb1310ffe8a20c2da7ee6d1b5994/powermock-module-junit4-1.6.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.powermock/powermock-module-junit4-common/1.6.4/b0b578da443794ceb8224bd5f5f852aaf40f1b81/powermock-module-junit4-common-1.6.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.powermock/powermock-api-support/1.6.4/314daafb761541293595630e10a3699ebc07881d/powermock-api-support-1.6.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.powermock/powermock-api-mockito/1.6.4/fe12509b7e9e49d25131f4155145748a31e42e40/powermock-api-mockito-1.6.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/cglib/cglib/3.1/1f1cb6c7a7479e0c7fd7987109e503914bebe84a/cglib-3.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.objenesis/objenesis/2.1/87c0ea803b69252868d09308b4618f766f135a96/objenesis-2.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.ow2.asm/asm/5.0.3/dcc2193db20e19e1feca8b1240dbbc4e190824fa/asm-5.0.3.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.derby/derby/10.2.2.0/f787c9b484cd7526f866c21d8925c4dace467f8a/derby-10.2.2.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.hadoop/hadoop-auth/2.4.1/96af98a34a335c5a8f2c9e40c6df57308060b1b1/hadoop-auth-2.4.1.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/commons-collections/commons-collections/3.2.1/commons-collections-3.2.1.jar
[vm_2]  /export/gf_archive/rls_archives/users/build/maven/repository/commons-configuration/commons-configuration/1.6/commons-configuration-1.6.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.powermock/powermock-reflect/1.6.4/5532f4e7c42db4bca4778bc9f1afcd4b0ee0b893/powermock-reflect-1.6.4.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.javassist/javassist/3.20.0-GA/a9cbcdfb7e9f86fbc74d3afae65f2248bfbf82a0/javassist-3.20.0-GA.jar
[vm_2]  /export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/lib/tools.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/com.google.guava/guava/15.0/ed727a8d9f247e2050281cb083f1c77b09dcb5cd/guava-15.0.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.hadoop/hadoop-common/2.4.1/5e9d624fb9eeab4b1a940f5e9b1c9ff644ec1d65/hadoop-common-2.4.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.hadoop/hadoop-annotations/2.4.1/80434aa40184be711d1c41568852fee625925ab0/hadoop-annotations-2.4.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.hadoop/hadoop-hdfs/2.4.1/e1a4a1d3554a1779cf69332f7aa04963237d74fb/hadoop-hdfs-2.4.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.apache.hadoop/hadoop-mapreduce-client-core/2.4.1/4b563f928dc108bfc9974e2c643e275bbb407625/hadoop-mapreduce-client-core-2.4.1.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/caches/modules-2/files-2.1/org.springframework.data/spring-data-gemfire/1.5.1.RELEASE/e71efeed660c4a3779a704476402f8ff299fa9b0/spring-data-gemfire-1.5.1.RELEASE.jar
[vm_2]  /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-junit/build/libs/gemfire-junit-1.0.0-incubating-SNAPSHOT-test.jar
[vm_2]Library Path:
[vm_2]  /usr/java/packages/lib/amd64
[vm_2]  /usr/lib64
[vm_2]  /lib64
[vm_2]  /lib
[vm_2]  /usr/lib
[vm_2]System Properties:
[vm_2]    WORKSPACE_DIR = /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/distributedTest/.
[vm_2]    awt.toolkit = sun.awt.X11.XToolkit
[vm_2]    dummyArg = true
[vm_2]    file.encoding = ANSI_X3.4-1968
[vm_2]    file.encoding.pkg = sun.io
[vm_2]    file.separator = /
[vm_2]    gemfire.DEFAULT_MAX_OPLOG_SIZE = 10
[vm_2]    gemfire.DISABLE_DISCONNECT_DS_ON_CACHE_CLOSE = true
[vm_2]    gemfire.DUnitLauncher.LAUNCHED = true
[vm_2]    gemfire.DUnitLauncher.RMI_PORT = 29807
[vm_2]    gemfire.DUnitLauncher.VM_NUM = 2
[vm_2]    gemfire.disallowMcastDefaults = true
[vm_2]    gemfire.free-off-heap-memory = true
[vm_2]    hoplog.ALLOW_LOCAL_HDFS = true
[vm_2]    java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment
[vm_2]    java.awt.printerjob = sun.print.PSPrinterJob
[vm_2]    java.class.version = 52.0
[vm_2]    java.endorsed.dirs = /export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/endorsed
[vm_2]    java.ext.dirs = /export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/ext:/usr/java/packages/lib/ext
[vm_2]    java.home = /export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre
[vm_2]    java.io.tmpdir = /tmp
[vm_2]    java.runtime.name = Java(TM) SE Runtime Environment
[vm_2]    java.runtime.version = 1.8.0_66-b17
[vm_2]    java.specification.name = Java Platform API Specification
[vm_2]    java.specification.vendor = Oracle Corporation
[vm_2]    java.specification.version = 1.8
[vm_2]    java.vendor = Oracle Corporation
[vm_2]    java.vendor.url = http://java.oracle.com/
[vm_2]    java.vendor.url.bug = http://bugreport.sun.com/bugreport/
[vm_2]    java.version = 1.8.0_66
[vm_2]    java.vm.info = mixed mode
[vm_2]    java.vm.name = Java HotSpot(TM) 64-Bit Server VM
[vm_2]    java.vm.specification.name = Java Virtual Machine Specification
[vm_2]    java.vm.specification.vendor = Oracle Corporation
[vm_2]    java.vm.specification.version = 1.8
[vm_2]    java.vm.vendor = Oracle Corporation
[vm_2]    java.vm.version = 25.66-b17
[vm_2]    line.separator = 

[vm_2]    logLevel = info
[vm_2]    os.version = 2.6.18-262.el5
[vm_2]    p2p.useSSL = false
[vm_2]    path.separator = :
[vm_2]    sun.arch.data.model = 64
[vm_2]    sun.boot.class.path = /export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/resources.jar:/export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/rt.jar:/export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/sunrsasign.jar:/export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/jsse.jar:/export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/jce.jar:/export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/charsets.jar:/export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/jfr.jar:/export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/classes
[vm_2]    sun.boot.library.path = /export/java/users/java_share/jdk/1.8.0_66/x86_64.linux/jre/lib/amd64
[vm_2]    sun.cpu.endian = little
[vm_2]    sun.cpu.isalist = 
[vm_2]    sun.io.unicode.encoding = UnicodeLittle
[vm_2]    sun.java.command = dunit.standalone.ChildVM
[vm_2]    sun.java.launcher = SUN_STANDARD
[vm_2]    sun.jnu.encoding = ANSI_X3.4-1968
[vm_2]    sun.management.compiler = HotSpot 64-Bit Tiered Compilers
[vm_2]    sun.nio.ch.bugLevel = 
[vm_2]    sun.os.patch.level = unknown
[vm_2]    user.country = US
[vm_2]    user.language = en
[vm_2]    user.timezone = US/Pacific
[vm_2]Log4J 2 Configuration:
[vm_2]    /venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/resources/main/log4j2.xml
[vm_2]---------------------------------------------------------------------------


[vm_2][info 2016/01/07 00:00:25.342 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Startup Configuration:
[vm_2] ### GemFire Properties defined with api ###
[vm_2]disable-auto-reconnect=true
[vm_2]enable-cluster-configuration=false
[vm_2]locators=localhost[25622]
[vm_2]log-level=info
[vm_2]mcast-port=0
[vm_2]use-cluster-configuration=false
[vm_2]### GemFire Properties using default values ###
[vm_2]ack-severe-alert-threshold=0
[vm_2]ack-wait-threshold=15
[vm_2]archive-disk-space-limit=0
[vm_2]archive-file-size-limit=0
[vm_2]async-distribution-timeout=0
[vm_2]async-max-queue-size=8
[vm_2]async-queue-timeout=60000
[vm_2]bind-address=
[vm_2]cache-xml-file=cache.xml
[vm_2]cluster-configuration-dir=/venezuela1/users/build/jenkins/blds/workspace/Geode_develop_DistributedTests/gemfire-core/build/distributedTest/dunit/vm2
[vm_2]cluster-ssl-ciphers=any
[vm_2]cluster-ssl-enabled=false
[vm_2]cluster-ssl-keystore=
[vm_2]cluster-ssl-keystore-password=
[vm_2]cluster-ssl-keystore-type=
[vm_2]cluster-ssl-protocols=any
[vm_2]cluster-ssl-require-authentication=true
[vm_2]cluster-ssl-truststore=
[vm_2]cluster-ssl-truststore-password=
[vm_2]conflate-events=server
[vm_2]conserve-sockets=true
[vm_2]delta-propagation=true
[vm_2]deploy-working-dir=.
[vm_2]disable-tcp=false
[vm_2]distributed-system-id=-1
[vm_2]distributed-transactions=false
[vm_2]durable-client-id=
[vm_2]durable-client-timeout=300
[vm_2]enable-network-partition-detection=false
[vm_2]enable-time-statistics=false
[vm_2]enforce-unique-host=false
[vm_2]gateway-ssl-ciphers=any
[vm_2]gateway-ssl-enabled=false
[vm_2]gateway-ssl-keystore=
[vm_2]gateway-ssl-keystore-password=
[vm_2]gateway-ssl-keystore-type=
[vm_2]gateway-ssl-protocols=any
[vm_2]gateway-ssl-require-authentication=true
[vm_2]gateway-ssl-truststore=
[vm_2]gateway-ssl-truststore-password=
[vm_2]groups=
[vm_2]http-service-bind-address=
[vm_2]http-service-port=7070
[vm_2]http-service-ssl-ciphers=any
[vm_2]http-service-ssl-enabled=false
[vm_2]http-service-ssl-keystore=
[vm_2]http-service-ssl-keystore-password=
[vm_2]http-service-ssl-keystore-type=
[vm_2]http-service-ssl-protocols=any
[vm_2]http-service-ssl-require-authentication=false
[vm_2]http-service-ssl-truststore=
[vm_2]http-service-ssl-truststore-password=
[vm_2]jmx-manager=false
[vm_2]jmx-manager-access-file=
[vm_2]jmx-manager-bind-address=
[vm_2]jmx-manager-hostname-for-clients=
[vm_2]jmx-manager-http-port=7070
[vm_2]jmx-manager-password-file=
[vm_2]jmx-manager-port=1099
[vm_2]jmx-manager-ssl=false
[vm_2]jmx-manager-ssl-ciphers=any
[vm_2]jmx-manager-ssl-enabled=false
[vm_2]jmx-manager-ssl-keystore=
[vm_2]jmx-manager-ssl-keystore-password=
[vm_2]jmx-manager-ssl-keystore-type=
[vm_2]jmx-manager-ssl-protocols=any
[vm_2]jmx-manager-ssl-require-authentication=true
[vm_2]jmx-manager-ssl-truststore=
[vm_2]jmx-manager-ssl-truststore-password=
[vm_2]jmx-manager-start=false
[vm_2]jmx-manager-update-rate=2000
[vm_2]load-cluster-configuration-from-dir=false
[vm_2]locator-wait-time=0
[vm_2]lock-memory=false
[vm_2]log-disk-space-limit=0
[vm_2]log-file=
[vm_2]log-file-size-limit=0
[vm_2]max-num-reconnect-tries=3
[vm_2]max-wait-time-reconnect=60000
[vm_2]mcast-address=239.192.81.1
[vm_2]mcast-flow-control=1048576, 0.25, 5000
[vm_2]mcast-recv-buffer-size=1048576
[vm_2]mcast-send-buffer-size=65535
[vm_2]mcast-ttl=32
[vm_2]member-timeout=5000
[vm_2]membership-port-range=1024-65535
[vm_2]memcached-bind-address=
[vm_2]memcached-port=0
[vm_2]memcached-protocol=ASCII
[vm_2]name=
[vm_2]off-heap-memory-size=
[vm_2]redis-bind-address=
[vm_2]redis-password=
[vm_2]redis-port=0
[vm_2]redundancy-zone=
[vm_2]remote-locators=
[vm_2]remove-unresponsive-client=false
[vm_2]roles=
[vm_2]security-=
[vm_2]security-client-accessor=
[vm_2]security-client-accessor-pp=
[vm_2]security-client-auth-init=
[vm_2]security-client-authenticator=
[vm_2]security-client-dhalgo=
[vm_2]security-log-file=
[vm_2]security-log-level=config
[vm_2]security-peer-auth-init=
[vm_2]security-peer-authenticator=
[vm_2]security-peer-verifymember-timeout=1000
[vm_2]server-bind-address=
[vm_2]server-ssl-ciphers=any
[vm_2]server-ssl-enabled=false
[vm_2]server-ssl-keystore=
[vm_2]server-ssl-keystore-password=
[vm_2]server-ssl-keystore-type=
[vm_2]server-ssl-protocols=any
[vm_2]server-ssl-require-authentication=true
[vm_2]server-ssl-truststore=
[vm_2]server-ssl-truststore-password=
[vm_2]socket-buffer-size=32768
[vm_2]socket-lease-time=60000
[vm_2]ssl-ciphers=any
[vm_2]ssl-enabled=false
[vm_2]ssl-protocols=any
[vm_2]ssl-require-authentication=true
[vm_2]start-dev-rest-api=false
[vm_2]start-locator=
[vm_2]statistic-archive-file=
[vm_2]statistic-sample-rate=1000
[vm_2]statistic-sampling-enabled=true
[vm_2]tcp-port=0
[vm_2]udp-fragment-size=60000
[vm_2]udp-recv-buffer-size=1048576
[vm_2]udp-send-buffer-size=65535
[vm_2]user-command-packages=


[vm_2][info 2016/01/07 00:00:25.479 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Starting membership services

[vm_2][info 2016/01/07 00:00:25.578 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] JGroups channel created (took 99ms)

[vm_2][info 2016/01/07 00:00:25.588 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] GemFire P2P Listener started on  null

[vm_2][info 2016/01/07 00:00:25.589 PST <Geode Failure Detection Server thread 0> tid=0x53] Started failure detection server thread on /10.118.32.90:15160.

[vm_2][info 2016/01/07 00:00:25.596 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Attempting to join the distributed system through coordinator venezuela(24090:locator)<ec><v0>:1024 using address venezuela(24112)<ec>:1027

[locator][info 2016/01/07 00:00:25.603 PST <unicast receiver,venezuela-64003> tid=0x21] received join request from venezuela(24112)<ec>:1027

[locator][info 2016/01/07 00:00:26.112 PST <Geode Membership View Creator> tid=0x27] View Creator is processing 1 requests for the next membership view

[locator][info 2016/01/07 00:00:26.113 PST <Geode Membership View Creator> tid=0x27] preparing new view View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]
[locator]failure detection ports: 31580 51847 65391 15160

[vm_2][info 2016/01/07 00:00:26.116 PST <unicast receiver,venezuela-54258> tid=0x4f] received new view: View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]
[locator][info 2016/01/07 00:00:26.116 PST <Geode Membership View Creator> tid=0x27] finished waiting for responses to view preparation

[locator][info 2016/01/07 00:00:26.116 PST <Geode Membership View Creator> tid=0x27] received new view: View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]
[locator]old view is: View[venezuela(24090:locator)<ec><v0>:1024|58] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026]

[vm_2]old view is: null
[locator][info 2016/01/07 00:00:26.116 PST <Geode Membership View Creator> tid=0x27] Peer locator received new membership view: View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]


[locator][info 2016/01/07 00:00:26.117 PST <View Message Processor> tid=0x33] Membership: Processing addition < venezuela(24112)<ec><v59>:1027 >

[vm_0][info 2016/01/07 00:00:26.118 PST <unicast receiver,venezuela-14240> tid=0x54e] received new view: View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]
[locator][info 2016/01/07 00:00:26.117 PST <View Message Processor> tid=0x33] Admitting member <venezuela(24112)<ec><v59>:1027>. Now there are 4 non-admin member(s).

[locator][info 2016/01/07 00:00:26.117 PST <Geode Membership View Creator> tid=0x27] sending new view View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]
[locator]failure detection ports: 31580 51847 65391 15160

[vm_0]old view is: View[venezuela(24090:locator)<ec><v0>:1024|58] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026]

[vm_0][info 2016/01/07 00:00:26.118 PST <View Message Processor> tid=0x565] Membership: Processing addition < venezuela(24112)<ec><v59>:1027 >

[vm_0][info 2016/01/07 00:00:26.118 PST <View Message Processor> tid=0x565] Admitting member <venezuela(24112)<ec><v59>:1027>. Now there are 4 non-admin member(s).

[vm_2][info 2016/01/07 00:00:26.120 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Finished joining (took 531ms).

[vm_1][info 2016/01/07 00:00:26.119 PST <unicast receiver,venezuela-39840> tid=0x1ea] received new view: View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]
[vm_1]old view is: View[venezuela(24090:locator)<ec><v0>:1024|58] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026]

[vm_2][info 2016/01/07 00:00:26.121 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Starting DistributionManager venezuela(24112)<ec><v59>:1027.  (took 764 ms)

[vm_2][info 2016/01/07 00:00:26.121 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initial (distribution manager) view =  View[venezuela(24090:locator)<ec><v0>:1024|59] members: [venezuela(24090:locator)<ec><v0>:1024, venezuela(24099)<ec><v57>:1025{lead}, venezuela(24105)<ec><v58>:1026, venezuela(24112)<ec><v59>:1027]

[vm_1][info 2016/01/07 00:00:26.121 PST <View Message Processor> tid=0x212] Membership: Processing addition < venezuela(24112)<ec><v59>:1027 >

[vm_1][info 2016/01/07 00:00:26.121 PST <View Message Processor> tid=0x212] Admitting member <venezuela(24112)<ec><v59>:1027>. Now there are 4 non-admin member(s).

[vm_2][info 2016/01/07 00:00:26.122 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Admitting member <venezuela(24090:locator)<ec><v0>:1024>. Now there are 1 non-admin member(s).

[vm_2][info 2016/01/07 00:00:26.122 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Admitting member <venezuela(24099)<ec><v57>:1025>. Now there are 2 non-admin member(s).

[vm_2][info 2016/01/07 00:00:26.122 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Admitting member <venezuela(24105)<ec><v58>:1026>. Now there are 3 non-admin member(s).

[vm_2][info 2016/01/07 00:00:26.122 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Admitting member <venezuela(24112)<ec><v59>:1027>. Now there are 4 non-admin member(s).

[vm_0][info 2016/01/07 00:00:26.154 PST <Pooled High Priority Message Processor 1> tid=0x563] Member venezuela(24112)<ec><v59>:1027 is equivalent or in the same redundancy zone.

[vm_1][info 2016/01/07 00:00:26.156 PST <Pooled High Priority Message Processor 2> tid=0x202] Member venezuela(24112)<ec><v59>:1027 is equivalent or in the same redundancy zone.

[locator][info 2016/01/07 00:00:26.154 PST <Pooled High Priority Message Processor 1> tid=0x37] Member venezuela(24112)<ec><v59>:1027 is equivalent or in the same redundancy zone.

[vm_2][info 2016/01/07 00:00:26.162 PST <P2P message reader for venezuela(24105)<ec><v58>:1026 shared unordered uid=23 port=46618> tid=0x5e] Member venezuela(24105)<ec><v58>:1026 is equivalent or in the same redundancy zone.

[vm_2][info 2016/01/07 00:00:26.162 PST <P2P message reader for venezuela(24090:locator)<ec><v0>:1024 shared unordered uid=31 port=46617> tid=0x5f] Member venezuela(24090:locator)<ec><v0>:1024 is equivalent or in the same redundancy zone.

[vm_2][info 2016/01/07 00:00:26.163 PST <P2P message reader for venezuela(24099)<ec><v57>:1025 shared unordered uid=36 port=46616> tid=0x5c] Member venezuela(24099)<ec><v57>:1025 is equivalent or in the same redundancy zone.

[vm_2][info 2016/01/07 00:00:26.211 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v59>1027

[vm_2][info 2016/01/07 00:00:26.224 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v59>1027 completed

[vm_2][info 2016/01/07 00:00:26.551 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_2][info 2016/01/07 00:00:26.563 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region PdxTypes

[vm_2][info 2016/01/07 00:00:26.577 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region PdxTypes requesting initial image from venezuela(24105)<ec><v58>:1026

[vm_2][info 2016/01/07 00:00:26.578 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] PdxTypes is done getting image from venezuela(24105)<ec><v58>:1026. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:26.579 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region PdxTypes completed

[vm_2][info 2016/01/07 00:00:26.595 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region __PR

[vm_2][info 2016/01/07 00:00:26.603 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region __PR requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:26.639 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] __PR is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:26.640 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region __PR completed

[vm_2][info 2016/01/07 00:00:26.665 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 is created with prId=77

[vm_2][info 2016/01/07 00:00:26.724 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$6.run with 0 args on object: "createRegions" (took 1388 ms)

[vm_0][warn 2016/01/07 00:00:26.733 PST <PartitionedRegion Message Processor1> tid=0x57b] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_2][warn 2016/01/07 00:00:26.736 PST <PartitionedRegion Message Processor1> tid=0x71] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_1][info 2016/01/07 00:00:26.727 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$8.run with 0 args on object: "close cache"

[vm_1][info 2016/01/07 00:00:26.728 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] GemFireCache[id = 886043176; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:25 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_1][info 2016/01/07 00:00:26.754 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$8.run with 0 args on object: "close cache" (took 27 ms)

[vm_2][info 2016/01/07 00:00:26.758 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$13.call with 0 args on object: "putData"

[vm_2][info 2016/01/07 00:00:31.785 PST <Pooled Waiting Message Processor 0> tid=0x56] Initializing region _B__region1_65

[vm_2][info 2016/01/07 00:00:31.790 PST <Pooled Waiting Message Processor 0> tid=0x56] Region _B__region1_65 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:31.792 PST <Pooled Waiting Message Processor 0> tid=0x56] _B__region1_65 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:31.792 PST <Pooled Waiting Message Processor 0> tid=0x56] Initialization of region _B__region1_65 completed

[vm_2][info 2016/01/07 00:00:31.860 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: 5106
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$13.call with 0 args on object: "putData" (took 5102 ms)

[vm_0][info 2016/01/07 00:00:31.863 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/07 00:00:31.864 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] GemFireCache[id = 1518506746; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:25 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_2][warn 2016/01/07 00:00:31.868 PST <PartitionedRegion Message Processor1> tid=0x71] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_0][info 2016/01/07 00:00:31.880 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 17 ms)

[vm_1][info 2016/01/07 00:00:31.881 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/07 00:00:31.881 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_2][info 2016/01/07 00:00:31.881 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/07 00:00:31.886 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] GemFireCache[id = 1929972342; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:26 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_2][info 2016/01/07 00:00:31.896 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 15 ms)

[vm_3][info 2016/01/07 00:00:31.898 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/07 00:00:31.898 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/07 00:00:31.900 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@3f6542e6

[vm_0][info 2016/01/07 00:00:31.900 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@3f6542e6 (took 0 ms)

[vm_1][info 2016/01/07 00:00:31.901 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@1bab6dd9

[vm_1][info 2016/01/07 00:00:31.901 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@1bab6dd9 (took 0 ms)

[vm_2][info 2016/01/07 00:00:31.902 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@7b034ce0

[vm_2][info 2016/01/07 00:00:31.903 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@7b034ce0 (took 1 ms)

[vm_3][info 2016/01/07 00:00:31.907 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@77c023dd

[vm_3][info 2016/01/07 00:00:31.907 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@77c023dd (took 0 ms)

[vm_0][info 2016/01/07 00:00:31.907 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/07 00:00:31.907 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/07 00:00:31.908 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/07 00:00:31.908 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/07 00:00:31.908 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/07 00:00:31.908 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/07 00:00:31.908 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args
[locator][info 2016/01/07 00:00:31.909 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@1a6503a6

[locator][info 2016/01/07 00:00:31.909 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@1a6503a6 (took 0 ms)


[vm_3][info 2016/01/07 00:00:31.908 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

Previously run tests: [CacheXml90DUnitTest, JarDeployerDUnitTest, PdxRenameDUnitTest, ClassNotFoundExceptionDUnitTest, PdxDeleteFieldDUnitTest, IdleTimeOutDUnitTest, TxnManagerMultiThreadDUnitTest, MaxPoolSizeDUnitTest, TransactionTimeOutDUnitTest, TxnTimeOutDUnitTest, ExceptionsDUnitTest, LoginTimeOutDUnitTest, LocalProcessLauncherDUnitTest, ConcurrentDestroySubRegionDUnitTest, PartitionedRegionDelayedRecoveryDUnitTest]
[vm_0][info 2016/01/07 00:00:31.911 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/07 00:00:31.911 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_1][info 2016/01/07 00:00:31.911 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/07 00:00:31.911 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/07 00:00:31.912 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/07 00:00:31.912 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST PartitionedRegionDelayedRecoveryDUnitTest.testStartupDelay


[vm_3][info 2016/01/07 00:00:31.912 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/07 00:00:31.912 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_0][info 2016/01/07 00:00:31.915 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$9.run with 0 args on object: "createRegions"

[vm_0][info 2016/01/07 00:00:31.922 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _monitoringRegion_10.118.32.90<v57>1025

[vm_0][info 2016/01/07 00:00:31.924 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _monitoringRegion_10.118.32.90<v57>1025 completed

[vm_0][info 2016/01/07 00:00:31.927 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/07 00:00:31.928 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region PdxTypes

[vm_0][info 2016/01/07 00:00:31.929 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region PdxTypes completed

[vm_0][info 2016/01/07 00:00:31.930 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region __PR

[vm_0][info 2016/01/07 00:00:31.931 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region __PR completed

[vm_0][info 2016/01/07 00:00:31.934 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Partitioned Region /region1 is born with prId=78 ident:#region1

[vm_0][info 2016/01/07 00:00:31.938 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$9.run with 0 args on object: "createRegions" (took 23 ms)

[vm_1][info 2016/01/07 00:00:31.939 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$9.run with 0 args on object: "createRegions"

[vm_1][info 2016/01/07 00:00:31.944 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v58>1026

[vm_1][info 2016/01/07 00:00:31.946 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v58>1026 completed

[vm_1][info 2016/01/07 00:00:31.949 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/07 00:00:31.950 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region PdxTypes

[vm_1][info 2016/01/07 00:00:31.952 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region PdxTypes requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:31.952 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] PdxTypes is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:31.952 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region PdxTypes completed

[vm_1][info 2016/01/07 00:00:31.954 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region __PR

[vm_1][info 2016/01/07 00:00:31.956 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region __PR requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:31.958 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] __PR is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:31.958 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region __PR completed

[vm_1][info 2016/01/07 00:00:31.959 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 is created with prId=78

[vm_1][info 2016/01/07 00:00:31.964 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$9.run with 0 args on object: "createRegions" (took 25 ms)

[vm_0][info 2016/01/07 00:00:31.967 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$10.run with 0 args on object: "putData"

[vm_0][info 2016/01/07 00:00:31.968 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _B__region1_1

[vm_0][info 2016/01/07 00:00:31.969 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _B__region1_1 completed

[vm_1][info 2016/01/07 00:00:31.971 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initializing region _B__region1_1

[vm_1][info 2016/01/07 00:00:31.972 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Region _B__region1_1 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:31.973 PST <Pooled Waiting Message Processor 0> tid=0x1f1] _B__region1_1 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:31.973 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initialization of region _B__region1_1 completed

[vm_1][info 2016/01/07 00:00:31.977 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initializing region _B__region1_2

[vm_1][info 2016/01/07 00:00:31.977 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initialization of region _B__region1_2 completed

[vm_0][info 2016/01/07 00:00:31.978 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _B__region1_2

[vm_0][info 2016/01/07 00:00:31.980 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Region _B__region1_2 requesting initial image from venezuela(24105)<ec><v58>:1026

[vm_0][info 2016/01/07 00:00:31.981 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] _B__region1_2 is done getting image from venezuela(24105)<ec><v58>:1026. isDeltaGII is false

[vm_0][info 2016/01/07 00:00:31.981 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _B__region1_2 completed

[vm_1][info 2016/01/07 00:00:31.989 PST <Pooled Waiting Message Processor 1> tid=0x224] Initializing region _B__region1_3

[vm_1][info 2016/01/07 00:00:31.989 PST <Pooled Waiting Message Processor 1> tid=0x224] Initialization of region _B__region1_3 completed

[vm_0][info 2016/01/07 00:00:31.993 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _B__region1_3

[vm_0][info 2016/01/07 00:00:31.996 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Region _B__region1_3 requesting initial image from venezuela(24105)<ec><v58>:1026

[vm_0][info 2016/01/07 00:00:31.997 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] _B__region1_3 is done getting image from venezuela(24105)<ec><v58>:1026. isDeltaGII is false

[vm_0][info 2016/01/07 00:00:31.997 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _B__region1_3 completed

[vm_0][info 2016/01/07 00:00:32.001 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _B__region1_4

[vm_0][info 2016/01/07 00:00:32.001 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _B__region1_4 completed

[vm_1][info 2016/01/07 00:00:32.002 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initializing region _B__region1_4

[vm_1][info 2016/01/07 00:00:32.003 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Region _B__region1_4 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:32.004 PST <Pooled Waiting Message Processor 0> tid=0x1f1] _B__region1_4 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:32.004 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initialization of region _B__region1_4 completed

[vm_0][info 2016/01/07 00:00:32.007 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$10.run with 0 args on object: "putData" (took 40 ms)

[vm_1][info 2016/01/07 00:00:32.010 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$11.run with 0 args on object: "close cache"

[vm_1][info 2016/01/07 00:00:32.010 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] GemFireCache[id = 588648041; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:31 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][warn 2016/01/07 00:00:36.969 PST <PartitionedRegion Message Processor1> tid=0x57b] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_1][info 2016/01/07 00:00:36.974 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$11.run with 0 args on object: "close cache" (took 4964 ms)

[vm_2][info 2016/01/07 00:00:36.975 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$9.run with 0 args on object: "createRegions"

[vm_2][info 2016/01/07 00:00:36.981 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v59>1027

[vm_2][info 2016/01/07 00:00:36.982 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v59>1027 completed

[vm_2][info 2016/01/07 00:00:36.986 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_2][info 2016/01/07 00:00:36.987 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region PdxTypes

[vm_2][info 2016/01/07 00:00:36.989 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region PdxTypes requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:36.990 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] PdxTypes is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:36.990 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region PdxTypes completed

[vm_2][info 2016/01/07 00:00:36.991 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region __PR

[vm_2][info 2016/01/07 00:00:36.994 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region __PR requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:36.996 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] __PR is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:36.996 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region __PR completed

[vm_2][info 2016/01/07 00:00:36.998 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 is created with prId=78

[vm_2][info 2016/01/07 00:00:37.064 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$9.run with 0 args on object: "createRegions" (took 89 ms)

[vm_2][info 2016/01/07 00:00:37.065 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$13.call with 0 args on object: "putData"

[vm_2][info 2016/01/07 00:00:42.026 PST <Pooled Waiting Message Processor 0> tid=0x56] Initializing region _B__region1_1

[vm_2][info 2016/01/07 00:00:42.027 PST <Pooled Waiting Message Processor 1> tid=0x76] Initializing region _B__region1_2

[vm_2][info 2016/01/07 00:00:42.029 PST <Pooled Waiting Message Processor 3> tid=0x85] Initializing region _B__region1_4

[vm_2][info 2016/01/07 00:00:42.030 PST <Pooled Waiting Message Processor 2> tid=0x84] Initializing region _B__region1_3

[vm_2][info 2016/01/07 00:00:42.030 PST <Pooled Waiting Message Processor 0> tid=0x56] Region _B__region1_1 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:42.031 PST <Pooled Waiting Message Processor 1> tid=0x76] Region _B__region1_2 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:42.032 PST <Pooled Waiting Message Processor 0> tid=0x56] _B__region1_1 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:42.032 PST <Pooled Waiting Message Processor 0> tid=0x56] Initialization of region _B__region1_1 completed

[vm_2][info 2016/01/07 00:00:42.033 PST <Pooled Waiting Message Processor 1> tid=0x76] _B__region1_2 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:42.033 PST <Pooled Waiting Message Processor 2> tid=0x84] Region _B__region1_3 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:42.033 PST <Pooled Waiting Message Processor 1> tid=0x76] Initialization of region _B__region1_2 completed

[vm_2][info 2016/01/07 00:00:42.034 PST <Pooled Waiting Message Processor 3> tid=0x85] Region _B__region1_4 requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_2][info 2016/01/07 00:00:42.036 PST <Pooled Waiting Message Processor 2> tid=0x84] _B__region1_3 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:42.036 PST <Pooled Waiting Message Processor 2> tid=0x84] Initialization of region _B__region1_3 completed

[vm_2][info 2016/01/07 00:00:42.036 PST <Pooled Waiting Message Processor 3> tid=0x85] _B__region1_4 is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:42.036 PST <Pooled Waiting Message Processor 3> tid=0x85] Initialization of region _B__region1_4 completed

[vm_2][info 2016/01/07 00:00:42.062 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: 4998
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$13.call with 0 args on object: "putData" (took 4997 ms)

[vm_0][info 2016/01/07 00:00:42.064 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/07 00:00:42.065 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] GemFireCache[id = 647231218; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:31 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_2][warn 2016/01/07 00:00:42.068 PST <PartitionedRegion Message Processor1> tid=0x71] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_0][info 2016/01/07 00:00:42.080 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 16 ms)

[vm_1][info 2016/01/07 00:00:42.080 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/07 00:00:42.081 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 1 ms)

[vm_2][info 2016/01/07 00:00:42.081 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/07 00:00:42.082 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] GemFireCache[id = 338888101; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:36 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_2][info 2016/01/07 00:00:42.104 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 23 ms)

[vm_3][info 2016/01/07 00:00:42.104 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/07 00:00:42.104 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/07 00:00:42.105 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@5ef36b86

[vm_0][info 2016/01/07 00:00:42.105 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@5ef36b86 (took 0 ms)

[vm_1][info 2016/01/07 00:00:42.105 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@5dac8aff

[vm_1][info 2016/01/07 00:00:42.105 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@5dac8aff (took 0 ms)

[vm_2][info 2016/01/07 00:00:42.106 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@5f0f2113

[vm_2][info 2016/01/07 00:00:42.106 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@5f0f2113 (took 0 ms)

[vm_3][info 2016/01/07 00:00:42.106 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@2f48fb7c

[vm_3][info 2016/01/07 00:00:42.106 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@2f48fb7c (took 0 ms)

[vm_0][info 2016/01/07 00:00:42.106 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/07 00:00:42.106 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/07 00:00:42.107 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/07 00:00:42.107 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/07 00:00:42.107 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/07 00:00:42.107 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/07 00:00:42.107 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/07 00:00:42.107 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/07 00:00:42.108 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@746e335

[locator][info 2016/01/07 00:00:42.108 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@746e335 (took 0 ms)

Previously run tests: [CacheXml90DUnitTest, JarDeployerDUnitTest, PdxRenameDUnitTest, ClassNotFoundExceptionDUnitTest, PdxDeleteFieldDUnitTest, IdleTimeOutDUnitTest, TxnManagerMultiThreadDUnitTest, MaxPoolSizeDUnitTest, TransactionTimeOutDUnitTest, TxnTimeOutDUnitTest, ExceptionsDUnitTest, LoginTimeOutDUnitTest, LocalProcessLauncherDUnitTest, ConcurrentDestroySubRegionDUnitTest, PartitionedRegionDelayedRecoveryDUnitTest]
[vm_0][info 2016/01/07 00:00:42.110 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/07 00:00:42.110 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_1][info 2016/01/07 00:00:42.112 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/07 00:00:42.112 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/07 00:00:42.113 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/07 00:00:42.113 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST PartitionedRegionDelayedRecoveryDUnitTest.testNoRecovery


[vm_0][info 2016/01/07 00:00:42.116 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions"

[vm_3][info 2016/01/07 00:00:42.113 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/07 00:00:42.113 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_0][info 2016/01/07 00:00:42.126 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _monitoringRegion_10.118.32.90<v57>1025

[vm_0][info 2016/01/07 00:00:42.127 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _monitoringRegion_10.118.32.90<v57>1025 completed

[vm_0][info 2016/01/07 00:00:42.132 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/07 00:00:42.133 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region PdxTypes

[vm_0][info 2016/01/07 00:00:42.135 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region PdxTypes completed

[vm_0][info 2016/01/07 00:00:42.135 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region __PR

[vm_0][info 2016/01/07 00:00:42.136 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region __PR completed

[vm_1][info 2016/01/07 00:00:42.153 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions"

[vm_1][info 2016/01/07 00:00:42.162 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v58>1026

[vm_1][info 2016/01/07 00:00:42.164 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v58>1026 completed

[vm_1][info 2016/01/07 00:00:42.167 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/07 00:00:42.168 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region PdxTypes

[vm_1][info 2016/01/07 00:00:42.171 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region PdxTypes requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:42.171 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] PdxTypes is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:42.172 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region PdxTypes completed

[vm_1][info 2016/01/07 00:00:42.172 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region __PR

[vm_1][info 2016/01/07 00:00:42.180 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region __PR requesting initial image from venezuela(24099)<ec><v57>:1025

[vm_1][info 2016/01/07 00:00:42.183 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] __PR is done getting image from venezuela(24099)<ec><v57>:1025. isDeltaGII is false

[vm_1][info 2016/01/07 00:00:42.183 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region __PR completed

[vm_1][info 2016/01/07 00:00:42.185 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 is created with prId=79

[vm_1][info 2016/01/07 00:00:42.191 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions" (took 38 ms)

[vm_1][info 2016/01/07 00:00:42.196 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initializing region _B__region1_65

[vm_1][info 2016/01/07 00:00:42.198 PST <Pooled Waiting Message Processor 0> tid=0x1f1] Initialization of region _B__region1_65 completed

[vm_0][info 2016/01/07 00:00:42.149 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Partitioned Region /region1 is born with prId=79 ident:#region1

[vm_0][info 2016/01/07 00:00:42.151 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions" (took 35 ms)

[vm_0][info 2016/01/07 00:00:42.194 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$3.run with 0 args on object: "putData"

[vm_0][info 2016/01/07 00:00:42.200 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initializing region _B__region1_65

[vm_0][info 2016/01/07 00:00:42.201 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Region _B__region1_65 requesting initial image from venezuela(24105)<ec><v58>:1026

[vm_0][info 2016/01/07 00:00:42.201 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] _B__region1_65 is done getting image from venezuela(24105)<ec><v58>:1026. isDeltaGII is false

[vm_0][info 2016/01/07 00:00:42.201 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Initialization of region _B__region1_65 completed

[vm_0][info 2016/01/07 00:00:42.206 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$3.run with 0 args on object: "putData" (took 12 ms)

[vm_2][info 2016/01/07 00:00:42.209 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions"

[vm_2][info 2016/01/07 00:00:42.254 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region _monitoringRegion_10.118.32.90<v59>1027

[vm_2][info 2016/01/07 00:00:42.255 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region _monitoringRegion_10.118.32.90<v59>1027 completed

[vm_2][info 2016/01/07 00:00:42.263 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_2][info 2016/01/07 00:00:42.264 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region PdxTypes

[vm_2][info 2016/01/07 00:00:42.273 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region PdxTypes requesting initial image from venezuela(24105)<ec><v58>:1026

[vm_2][info 2016/01/07 00:00:42.274 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] PdxTypes is done getting image from venezuela(24105)<ec><v58>:1026. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:42.274 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region PdxTypes completed

[vm_2][info 2016/01/07 00:00:42.276 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initializing region __PR

[vm_2][info 2016/01/07 00:00:42.282 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Region __PR requesting initial image from venezuela(24105)<ec><v58>:1026

[vm_2][info 2016/01/07 00:00:42.287 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] __PR is done getting image from venezuela(24105)<ec><v58>:1026. isDeltaGII is false

[vm_2][info 2016/01/07 00:00:42.287 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Initialization of region __PR completed

[vm_2][info 2016/01/07 00:00:42.290 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 is created with prId=79

[vm_2][info 2016/01/07 00:00:42.302 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions" (took 93 ms)

[vm_1][info 2016/01/07 00:00:42.308 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$4.run with 0 args on object: "Destroy region"

[vm_1][info 2016/01/07 00:00:42.309 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 with prId=79 closing.

[vm_2][warn 2016/01/07 00:00:42.313 PST <PartitionedRegion Message Processor1> tid=0x71] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_0][warn 2016/01/07 00:00:42.313 PST <PartitionedRegion Message Processor1> tid=0x57b] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_1][warn 2016/01/07 00:00:42.318 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Redundancy has dropped below 1 configured copies to 0 actual copies for /region1

[vm_1][info 2016/01/07 00:00:42.325 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 with prId=79 closed.

[vm_1][info 2016/01/07 00:00:42.326 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$4.run with 0 args on object: "Destroy region" (took 18 ms)

[vm_2][info 2016/01/07 00:00:43.331 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$5.run with 0 args on object: "Check for bucket"

[vm_2][info 2016/01/07 00:00:43.331 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$5.run with 0 args on object: "Check for bucket" (took 0 ms)

[vm_1][info 2016/01/07 00:00:43.332 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions"

[vm_1][info 2016/01/07 00:00:43.333 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Partitioned Region /region1 is created with prId=79

[vm_1][info 2016/01/07 00:00:43.338 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$2.run with 0 args on object: "createRegions" (took 6 ms)

[vm_1][info 2016/01/07 00:00:44.342 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$5.run with 0 args on object: "Check for bucket"

[vm_1][info 2016/01/07 00:00:44.342 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$5.run with 0 args on object: "Check for bucket" (took 0 ms)

[vm_2][info 2016/01/07 00:00:44.344 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$5.run with 0 args on object: "Check for bucket"

[vm_2][info 2016/01/07 00:00:44.344 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$5.run with 0 args on object: "Check for bucket" (took 0 ms)

[vm_0][info 2016/01/07 00:00:44.345 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/07 00:00:44.347 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] GemFireCache[id = 252211282; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:42 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_1][info 2016/01/07 00:00:44.360 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/07 00:00:44.361 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] GemFireCache[id = 1437515433; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:42 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][info 2016/01/07 00:00:44.359 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 14 ms)

[vm_1][info 2016/01/07 00:00:44.369 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 9 ms)

[vm_2][info 2016/01/07 00:00:44.369 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/07 00:00:44.373 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] GemFireCache[id = 1811311454; isClosing = true; isShutDownAll = false; created = Thu Jan 07 00:00:42 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_2][info 2016/01/07 00:00:44.377 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 8 ms)

[vm_3][info 2016/01/07 00:00:44.378 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/07 00:00:44.378 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/07 00:00:44.379 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@6ba80ad4
[vm_1][info 2016/01/07 00:00:44.379 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@7421b347

[vm_1][info 2016/01/07 00:00:44.379 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@7421b347 (took 0 ms)


[vm_0][info 2016/01/07 00:00:44.379 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@6ba80ad4 (took 0 ms)

[vm_2][info 2016/01/07 00:00:44.380 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@21348e73

[vm_2][info 2016/01/07 00:00:44.380 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@21348e73 (took 0 ms)

[vm_3][info 2016/01/07 00:00:44.381 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@24ac22c5

[vm_3][info 2016/01/07 00:00:44.381 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1.run with 0 args on object: com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest$1@24ac22c5 (took 0 ms)

[vm_0][info 2016/01/07 00:00:44.381 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/07 00:00:44.381 PST <RMI TCP Connection(6)-10.118.32.90> tid=0x476] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/07 00:00:44.381 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/07 00:00:44.381 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/07 00:00:44.382 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/07 00:00:44.382 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/07 00:00:44.382 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/07 00:00:44.382 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/07 00:00:44.382 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@123212e9

[locator][info 2016/01/07 00:00:44.382 PST <RMI TCP Connection(1)-10.118.32.90> tid=0x12] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@123212e9 (took 0 ms)
{noformat}

> CI failure: PartitionedRegionDelayedRecoveryDUnitTest.testStartupDelay
> ----------------------------------------------------------------------
>
>                 Key: GEODE-757
>                 URL: https://issues.apache.org/jira/browse/GEODE-757
>             Project: Geode
>          Issue Type: Bug
>            Reporter: Kirk Lund
>
> {noformat}
> junit.framework.AssertionFailedError: Did not wait at least 5 seconds to create the bucket. Elapsed=4998
> 	at junit.framework.Assert.fail(Assert.java:57)
> 	at junit.framework.Assert.assertTrue(Assert.java:22)
> 	at junit.framework.TestCase.assertTrue(TestCase.java:192)
> 	at com.gemstone.gemfire.internal.cache.PartitionedRegionDelayedRecoveryDUnitTest.testStartupDelay(PartitionedRegionDelayedRecoveryDUnitTest.java:253)
> 	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 junit.framework.TestCase.runTest(TestCase.java:176)
> 	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}



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Mime
View raw message