You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@geode.apache.org by "Kirk Lund (JIRA)" <ji...@apache.org> on 2016/01/07 23:44:39 UTC
[jira] [Commented] (GEODE-755) CI failure: ResourceManagerDUnitTest.testRemoveDuringGet

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

Kirk Lund commented on GEODE-755:
---------------------------------

Standard Output
{noformat}
Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRQueryPerfDUnitTest, PRQueryCacheCloseDUnitTest, PRBasicIndexCreationDeadlockDUnitTest, ConcurrentIndexInitOnOverflowRegionDUnitTest, ConcurrentIndexUpdateWithoutWLDUnitTest, CopyOnReadIndexDUnitTest, ConcurrentIndexUpdateWithInplaceObjectModFalseDUnitTest, ConcurrentIndexOperationsOnOverflowRegionDUnitTest, InitializeIndexEntryDestroyQueryDUnitTest, IndexTrackingQueryObserverDUnitTest, MultiIndexCreationDUnitTest, PartitionRegionHelperDUnitTest, ResourceManagerDUnitTest]
[vm_0][info 2016/01/05 23:08:49.449 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/05 23:08:49.449 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.449 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/05 23:08:49.449 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:49.450 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/05 23:08:49.450 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.450 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/05 23:08:49.450 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST ResourceManagerDUnitTest.testRemoveDuringGetEntry


[vm_0][info 2016/01/05 23:08:49.458 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@59c866c7

[vm_0][info 2016/01/05 23:08:49.463 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _monitoringRegion_10.118.33.94<v158>1025

[vm_0][info 2016/01/05 23:08:49.464 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _monitoringRegion_10.118.33.94<v158>1025 completed

[vm_0][info 2016/01/05 23:08:49.467 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/05 23:08:49.468 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region PdxTypes

[vm_0][info 2016/01/05 23:08:49.469 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region PdxTypes completed

[vm_0][info 2016/01/05 23:08:49.469 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region __PR

[vm_0][info 2016/01/05 23:08:49.470 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region __PR completed

[vm_0][info 2016/01/05 23:08:49.472 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringGetEntry_PR_0 is born with prId=45 ident:#ResourceManagerDUnitTest_testRemoveDuringGetEntry_PR_0

[vm_0][info 2016/01/05 23:08:49.477 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@59c866c7 (took 19 ms)

[vm_1][info 2016/01/05 23:08:49.483 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@723bf96b

[vm_1][info 2016/01/05 23:08:49.490 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region _monitoringRegion_10.118.33.94<v159>1026

[vm_1][info 2016/01/05 23:08:49.491 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region _monitoringRegion_10.118.33.94<v159>1026 completed

[vm_1][info 2016/01/05 23:08:49.494 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/05 23:08:49.495 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region PdxTypes

[vm_1][info 2016/01/05 23:08:49.497 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region PdxTypes requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.497 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] PdxTypes is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.497 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region PdxTypes completed

[vm_1][info 2016/01/05 23:08:49.498 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region __PR

[vm_1][info 2016/01/05 23:08:49.503 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region __PR requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.504 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] __PR is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.504 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region __PR completed

[vm_1][info 2016/01/05 23:08:49.506 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringGetEntry_PR_0 is created with prId=45

[vm_1][info 2016/01/05 23:08:49.517 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@723bf96b (took 34 ms)

[vm_0][info 2016/01/05 23:08:49.524 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@5cfe107a

[vm_0][info 2016/01/05 23:08:49.526 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _B__ResourceManagerDUnitTest__testRemoveDuringGetEntry__PR__0_0

[vm_0][info 2016/01/05 23:08:49.526 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _B__ResourceManagerDUnitTest__testRemoveDuringGetEntry__PR__0_0 completed

[vm_1][info 2016/01/05 23:08:49.528 PST <Pooled Waiting Message Processor 0> tid=0x922] Initializing region _B__ResourceManagerDUnitTest__testRemoveDuringGetEntry__PR__0_0

[vm_1][info 2016/01/05 23:08:49.529 PST <Pooled Waiting Message Processor 0> tid=0x922] Region _B__ResourceManagerDUnitTest__testRemoveDuringGetEntry__PR__0_0 requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.530 PST <Pooled Waiting Message Processor 0> tid=0x922] _B__ResourceManagerDUnitTest__testRemoveDuringGetEntry__PR__0_0 is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.530 PST <Pooled Waiting Message Processor 0> tid=0x922] Initialization of region _B__ResourceManagerDUnitTest__testRemoveDuringGetEntry__PR__0_0 completed

[vm_0][info 2016/01/05 23:08:49.537 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@5cfe107a (took 13 ms)

[vm_0][info 2016/01/05 23:08:49.540 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@404934a9

[vm_0][info 2016/01/05 23:08:49.547 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: cc4-rh6(28182)<ec><v158>:1025
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@404934a9 (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.550 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@167cb0ce

[vm_0][info 2016/01/05 23:08:49.550 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 59
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@167cb0ce (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.554 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@66495a65

[vm_0][info 2016/01/05 23:08:49.554 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 1
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@66495a65 (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.556 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@3484ada2

[vm_0][info 2016/01/05 23:08:49.556 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 1
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@3484ada2 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.558 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@7b5861d5

[vm_1][info 2016/01/05 23:08:49.559 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: cc4-rh6(28191)<ec><v159>:1026
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@7b5861d5 (took 1 ms)

[vm_1][info 2016/01/05 23:08:49.561 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@7251fb0c

[vm_1][info 2016/01/05 23:08:49.562 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 59
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@7251fb0c (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.565 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@2f05e1dc

[vm_1][info 2016/01/05 23:08:49.565 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@2f05e1dc (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.567 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@5d60a4f8

[vm_1][info 2016/01/05 23:08:49.567 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 0
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@5d60a4f8 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.570 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18@3ed785f3

[vm_1][info 2016/01/05 23:08:49.570 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18@3ed785f3 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.574 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19@3e6f8318

[vm_0][warn 2016/01/05 23:08:49.575 PST <Pooled Waiting Message Processor 1> tid=0x848] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveDuringGetEntry_PR_0

[vm_1][warn 2016/01/05 23:08:49.576 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveDuringGetEntry_PR_0

[vm_1][info 2016/01/05 23:08:49.579 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19@3e6f8318 (took 5 ms)

[vm_1][info 2016/01/05 23:08:49.582 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20@59f21a35

[vm_1][info 2016/01/05 23:08:49.583 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20@59f21a35 (took 1 ms)

[vm_0][info 2016/01/05 23:08:49.584 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/05 23:08:49.585 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] GemFireCache[id = 927317449; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:49 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][info 2016/01/05 23:08:49.592 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 8 ms)

[vm_1][info 2016/01/05 23:08:49.592 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/05 23:08:49.593 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] GemFireCache[id = 1600675297; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:49 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_1][info 2016/01/05 23:08:49.598 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 6 ms)

[vm_2][info 2016/01/05 23:08:49.599 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/05 23:08:49.599 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.599 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/05 23:08:49.600 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 1 ms)

[vm_0][info 2016/01/05 23:08:49.600 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/05 23:08:49.600 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.601 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/05 23:08:49.601 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:49.601 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/05 23:08:49.602 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 1 ms)

[vm_3][info 2016/01/05 23:08:49.602 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/05 23:08:49.602 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/05 23:08:49.603 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@4b371aa4

[locator][info 2016/01/05 23:08:49.603 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@4b371aa4 (took 0 ms)

Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRQueryPerfDUnitTest, PRQueryCacheCloseDUnitTest, PRBasicIndexCreationDeadlockDUnitTest, ConcurrentIndexInitOnOverflowRegionDUnitTest, ConcurrentIndexUpdateWithoutWLDUnitTest, CopyOnReadIndexDUnitTest, ConcurrentIndexUpdateWithInplaceObjectModFalseDUnitTest, ConcurrentIndexOperationsOnOverflowRegionDUnitTest, InitializeIndexEntryDestroyQueryDUnitTest, IndexTrackingQueryObserverDUnitTest, MultiIndexCreationDUnitTest, PartitionRegionHelperDUnitTest, ResourceManagerDUnitTest]
[vm_0][info 2016/01/05 23:08:49.604 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/05 23:08:49.604 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.604 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/05 23:08:49.605 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 1 ms)

[vm_2][info 2016/01/05 23:08:49.605 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/05 23:08:49.605 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.605 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/05 23:08:49.605 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST ResourceManagerDUnitTest.testResourceManagerBasics


[info 2016/01/05 23:08:49.618 PST <Test worker> tid=0xa] Initializing region _monitoringRegion_10.118.33.94<v147>1029

[info 2016/01/05 23:08:49.625 PST <Test worker> tid=0xa] Initialization of region _monitoringRegion_10.118.33.94<v147>1029 completed

[info 2016/01/05 23:08:49.643 PST <Test worker> tid=0xa] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[info 2016/01/05 23:08:49.644 PST <Test worker> tid=0xa] Initializing region PdxTypes

[info 2016/01/05 23:08:49.645 PST <Test worker> tid=0xa] Initialization of region PdxTypes completed

[info 2016/01/05 23:08:49.648 PST <Test worker> tid=0xa] GemFireCache[id = 1977911389; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:49 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][info 2016/01/05 23:08:49.651 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/05 23:08:49.652 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 1 ms)

[vm_1][info 2016/01/05 23:08:49.652 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/05 23:08:49.652 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:49.653 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/05 23:08:49.653 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.654 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/05 23:08:49.654 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.654 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/05 23:08:49.654 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.655 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/05 23:08:49.655 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:49.655 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/05 23:08:49.655 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.656 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/05 23:08:49.656 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/05 23:08:49.657 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@6966f573

[locator][info 2016/01/05 23:08:49.657 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@6966f573 (took 0 ms)

Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRQueryPerfDUnitTest, PRQueryCacheCloseDUnitTest, PRBasicIndexCreationDeadlockDUnitTest, ConcurrentIndexInitOnOverflowRegionDUnitTest, ConcurrentIndexUpdateWithoutWLDUnitTest, CopyOnReadIndexDUnitTest, ConcurrentIndexUpdateWithInplaceObjectModFalseDUnitTest, ConcurrentIndexOperationsOnOverflowRegionDUnitTest, InitializeIndexEntryDestroyQueryDUnitTest, IndexTrackingQueryObserverDUnitTest, MultiIndexCreationDUnitTest, PartitionRegionHelperDUnitTest, ResourceManagerDUnitTest]
[vm_0][info 2016/01/05 23:08:49.659 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/05 23:08:49.660 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 1 ms)

[vm_1][info 2016/01/05 23:08:49.660 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/05 23:08:49.660 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:49.660 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/05 23:08:49.661 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.661 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/05 23:08:49.661 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST ResourceManagerDUnitTest.testRemoveDuringEntrySet


[vm_0][info 2016/01/05 23:08:49.662 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@3611446

[vm_0][info 2016/01/05 23:08:49.668 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _monitoringRegion_10.118.33.94<v158>1025

[vm_0][info 2016/01/05 23:08:49.669 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _monitoringRegion_10.118.33.94<v158>1025 completed

[vm_0][info 2016/01/05 23:08:49.673 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/05 23:08:49.673 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region PdxTypes

[vm_0][info 2016/01/05 23:08:49.675 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region PdxTypes completed

[vm_0][info 2016/01/05 23:08:49.675 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region __PR

[vm_0][info 2016/01/05 23:08:49.676 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region __PR completed

[vm_0][info 2016/01/05 23:08:49.679 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringEntrySet_PR_0 is born with prId=46 ident:#ResourceManagerDUnitTest_testRemoveDuringEntrySet_PR_0

[vm_0][info 2016/01/05 23:08:49.688 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@3611446 (took 26 ms)

[vm_1][info 2016/01/05 23:08:49.690 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@7dd61d90

[vm_1][info 2016/01/05 23:08:49.699 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region _monitoringRegion_10.118.33.94<v159>1026

[vm_1][info 2016/01/05 23:08:49.700 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region _monitoringRegion_10.118.33.94<v159>1026 completed

[vm_1][info 2016/01/05 23:08:49.703 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/05 23:08:49.704 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region PdxTypes

[vm_1][info 2016/01/05 23:08:49.707 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region PdxTypes requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.707 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] PdxTypes is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.708 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region PdxTypes completed

[vm_1][info 2016/01/05 23:08:49.708 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region __PR

[vm_1][info 2016/01/05 23:08:49.710 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region __PR requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.711 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] __PR is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.711 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region __PR completed

[vm_1][info 2016/01/05 23:08:49.713 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringEntrySet_PR_0 is created with prId=46

[vm_1][info 2016/01/05 23:08:49.718 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@7dd61d90 (took 28 ms)

[vm_0][info 2016/01/05 23:08:49.719 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@288548d7

[vm_0][info 2016/01/05 23:08:49.720 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _B__ResourceManagerDUnitTest__testRemoveDuringEntrySet__PR__0_0

[vm_0][info 2016/01/05 23:08:49.721 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _B__ResourceManagerDUnitTest__testRemoveDuringEntrySet__PR__0_0 completed

[vm_1][info 2016/01/05 23:08:49.727 PST <Pooled Waiting Message Processor 0> tid=0x922] Initializing region _B__ResourceManagerDUnitTest__testRemoveDuringEntrySet__PR__0_0

[vm_1][info 2016/01/05 23:08:49.729 PST <Pooled Waiting Message Processor 0> tid=0x922] Region _B__ResourceManagerDUnitTest__testRemoveDuringEntrySet__PR__0_0 requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.729 PST <Pooled Waiting Message Processor 0> tid=0x922] _B__ResourceManagerDUnitTest__testRemoveDuringEntrySet__PR__0_0 is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.729 PST <Pooled Waiting Message Processor 0> tid=0x922] Initialization of region _B__ResourceManagerDUnitTest__testRemoveDuringEntrySet__PR__0_0 completed

[vm_0][info 2016/01/05 23:08:49.736 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@288548d7 (took 17 ms)

[vm_0][info 2016/01/05 23:08:49.736 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@37084904

[vm_0][info 2016/01/05 23:08:49.737 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: cc4-rh6(28182)<ec><v158>:1025
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@37084904 (took 1 ms)

[vm_0][info 2016/01/05 23:08:49.737 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@f2c0de5

[vm_0][info 2016/01/05 23:08:49.737 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 59
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@f2c0de5 (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.737 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@4a664372

[vm_0][info 2016/01/05 23:08:49.737 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 1
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@4a664372 (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.738 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@4f59fca2

[vm_0][info 2016/01/05 23:08:49.738 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 1
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@4f59fca2 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.738 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@4462c80f

[vm_1][info 2016/01/05 23:08:49.738 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: cc4-rh6(28191)<ec><v159>:1026
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@4462c80f (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.739 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@737c4d40

[vm_1][info 2016/01/05 23:08:49.739 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 59
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@737c4d40 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.739 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@2e246098

[vm_1][info 2016/01/05 23:08:49.740 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@2e246098 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.740 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@3a2f0aa8

[vm_1][info 2016/01/05 23:08:49.740 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 0
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@3a2f0aa8 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.741 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18@1c86c346

[vm_1][info 2016/01/05 23:08:49.741 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18@1c86c346 (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.744 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19@7f1a3166

[vm_0][warn 2016/01/05 23:08:49.745 PST <Pooled Waiting Message Processor 1> tid=0x848] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveDuringEntrySet_PR_0

[vm_1][warn 2016/01/05 23:08:49.746 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveDuringEntrySet_PR_0

[vm_1][info 2016/01/05 23:08:49.750 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19@7f1a3166 (took 6 ms)

[vm_1][info 2016/01/05 23:08:49.751 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20@6ab51a57

[vm_1][info 2016/01/05 23:08:49.751 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20@6ab51a57 (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.752 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/05 23:08:49.753 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] GemFireCache[id = 481510379; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:49 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][info 2016/01/05 23:08:49.765 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 13 ms)

[vm_1][info 2016/01/05 23:08:49.766 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/05 23:08:49.766 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] GemFireCache[id = 1430120353; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:49 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_1][info 2016/01/05 23:08:49.776 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 10 ms)

[vm_2][info 2016/01/05 23:08:49.776 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/05 23:08:49.777 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 1 ms)

[vm_3][info 2016/01/05 23:08:49.777 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/05 23:08:49.777 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/05 23:08:49.778 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/05 23:08:49.778 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:49.779 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/05 23:08:49.779 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:49.779 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/05 23:08:49.779 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.779 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/05 23:08:49.779 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/05 23:08:49.780 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@f6f4aac

[locator][info 2016/01/05 23:08:49.780 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@f6f4aac (took 0 ms)

Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRQueryPerfDUnitTest, PRQueryCacheCloseDUnitTest, PRBasicIndexCreationDeadlockDUnitTest, ConcurrentIndexInitOnOverflowRegionDUnitTest, ConcurrentIndexUpdateWithoutWLDUnitTest, CopyOnReadIndexDUnitTest, ConcurrentIndexUpdateWithInplaceObjectModFalseDUnitTest, ConcurrentIndexOperationsOnOverflowRegionDUnitTest, InitializeIndexEntryDestroyQueryDUnitTest, IndexTrackingQueryObserverDUnitTest, MultiIndexCreationDUnitTest, PartitionRegionHelperDUnitTest, ResourceManagerDUnitTest]
[vm_0][info 2016/01/05 23:08:49.781 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/05 23:08:49.782 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 1 ms)

[vm_1][info 2016/01/05 23:08:49.782 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/05 23:08:49.782 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:49.783 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/05 23:08:49.783 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:49.783 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/05 23:08:49.783 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST ResourceManagerDUnitTest.testRemoveDuringContainsValueForKey


[vm_0][info 2016/01/05 23:08:49.784 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@60f3da77

[vm_0][info 2016/01/05 23:08:49.790 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _monitoringRegion_10.118.33.94<v158>1025

[vm_0][info 2016/01/05 23:08:49.793 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _monitoringRegion_10.118.33.94<v158>1025 completed

[vm_0][info 2016/01/05 23:08:49.796 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/05 23:08:49.799 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region PdxTypes

[vm_0][info 2016/01/05 23:08:49.799 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region PdxTypes completed

[vm_0][info 2016/01/05 23:08:49.800 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region __PR

[vm_0][info 2016/01/05 23:08:49.800 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region __PR completed

[vm_0][info 2016/01/05 23:08:49.802 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringContainsValueForKey_PR_0 is born with prId=47 ident:#ResourceManagerDUnitTest_testRemoveDuringContainsValueForKey_PR_0

[vm_0][info 2016/01/05 23:08:49.805 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@60f3da77 (took 21 ms)

[vm_1][info 2016/01/05 23:08:49.808 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@54b9085c

[vm_1][info 2016/01/05 23:08:49.815 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region _monitoringRegion_10.118.33.94<v159>1026

[vm_1][info 2016/01/05 23:08:49.816 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region _monitoringRegion_10.118.33.94<v159>1026 completed

[vm_1][info 2016/01/05 23:08:49.819 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/05 23:08:49.819 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region PdxTypes

[vm_1][info 2016/01/05 23:08:49.822 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region PdxTypes requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.822 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] PdxTypes is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.823 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region PdxTypes completed

[vm_1][info 2016/01/05 23:08:49.823 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region __PR

[vm_1][info 2016/01/05 23:08:49.825 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region __PR requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:49.827 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] __PR is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:49.827 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region __PR completed

[vm_1][info 2016/01/05 23:08:49.829 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringContainsValueForKey_PR_0 is created with prId=47

[vm_1][info 2016/01/05 23:08:49.836 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@54b9085c (took 28 ms)

[vm_0][info 2016/01/05 23:08:49.836 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.Re
...[truncated 311100 chars]...
 0 ms)

[vm_0][info 2016/01/05 23:08:52.404 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@150a0e8

[vm_0][info 2016/01/05 23:08:52.404 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 0
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@150a0e8 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.404 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@7550f659

[vm_1][info 2016/01/05 23:08:52.404 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: cc4-rh6(28191)<ec><v159>:1026
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@7550f659 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.405 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@59217f5f

[vm_1][info 2016/01/05 23:08:52.405 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 13
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@59217f5f (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.406 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@1f2b6a2

[vm_1][info 2016/01/05 23:08:52.406 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@1f2b6a2 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.406 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@3f6d55f5

[vm_1][info 2016/01/05 23:08:52.406 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@3f6d55f5 (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.410 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$32.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$32@747afab4

[vm_0][info 2016/01/05 23:08:52.410 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$32.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$32@747afab4 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.414 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$33.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$33@416b72a3

[vm_1][warn 2016/01/05 23:08:52.415 PST <Pooled Waiting Message Processor 2> tid=0x963] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveColocatedBuckets-PR-2

[vm_0][warn 2016/01/05 23:08:52.416 PST <PartitionedRegion Message Processor1> tid=0x846] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveColocatedBuckets-PR-2

[vm_1][warn 2016/01/05 23:08:52.418 PST <Pooled Waiting Message Processor 2> tid=0x963] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveColocatedBuckets-PR-1

[vm_0][warn 2016/01/05 23:08:52.418 PST <PartitionedRegion Message Processor1> tid=0x846] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveColocatedBuckets-PR-1

[vm_1][warn 2016/01/05 23:08:52.419 PST <Pooled Waiting Message Processor 2> tid=0x963] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveColocatedBuckets-PR-0

[vm_0][warn 2016/01/05 23:08:52.420 PST <PartitionedRegion Message Processor1> tid=0x846] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveColocatedBuckets-PR-0

[vm_1][info 2016/01/05 23:08:52.421 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: true
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$33.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$33@416b72a3 (took 7 ms)

[vm_0][info 2016/01/05 23:08:52.424 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$34.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$34@c4915b3

[vm_0][info 2016/01/05 23:08:52.424 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$34.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$34@c4915b3 (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.425 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/05 23:08:52.426 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] GemFireCache[id = 1924081940; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:52 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][info 2016/01/05 23:08:52.438 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 13 ms)

[vm_1][info 2016/01/05 23:08:52.439 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/05 23:08:52.440 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] GemFireCache[id = 1538010655; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:52 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_1][info 2016/01/05 23:08:52.446 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 7 ms)

[vm_2][info 2016/01/05 23:08:52.447 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/05 23:08:52.447 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:52.448 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/05 23:08:52.448 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.450 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/05 23:08:52.451 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 1 ms)

[vm_1][info 2016/01/05 23:08:52.451 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/05 23:08:52.451 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:52.452 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/05 23:08:52.452 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:52.452 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/05 23:08:52.452 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/05 23:08:52.453 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@48c76a66

[locator][info 2016/01/05 23:08:52.453 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@48c76a66 (took 0 ms)

Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRQueryPerfDUnitTest, PRQueryCacheCloseDUnitTest, PRBasicIndexCreationDeadlockDUnitTest, ConcurrentIndexInitOnOverflowRegionDUnitTest, ConcurrentIndexUpdateWithoutWLDUnitTest, CopyOnReadIndexDUnitTest, ConcurrentIndexUpdateWithInplaceObjectModFalseDUnitTest, ConcurrentIndexOperationsOnOverflowRegionDUnitTest, InitializeIndexEntryDestroyQueryDUnitTest, IndexTrackingQueryObserverDUnitTest, MultiIndexCreationDUnitTest, PartitionRegionHelperDUnitTest, ResourceManagerDUnitTest]
[vm_0][info 2016/01/05 23:08:52.454 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/05 23:08:52.454 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.457 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/05 23:08:52.458 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:52.458 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/05 23:08:52.458 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:52.459 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/05 23:08:52.459 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST ResourceManagerDUnitTest.testCreateRedundantBucket


[vm_0][info 2016/01/05 23:08:52.459 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@58bcf04f

[vm_0][info 2016/01/05 23:08:52.469 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _monitoringRegion_10.118.33.94<v158>1025

[vm_0][info 2016/01/05 23:08:52.470 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _monitoringRegion_10.118.33.94<v158>1025 completed

[vm_0][info 2016/01/05 23:08:52.473 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/05 23:08:52.473 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region PdxTypes

[vm_0][info 2016/01/05 23:08:52.474 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region PdxTypes completed

[vm_0][info 2016/01/05 23:08:52.475 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region __PR

[vm_0][info 2016/01/05 23:08:52.476 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region __PR completed

[vm_0][info 2016/01/05 23:08:52.483 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Partitioned Region /ResourceManagerDUnitTest_testCreateRedundantBucket-PR-0 is born with prId=69 ident:#ResourceManagerDUnitTest_testCreateRedundantBucket-PR-0

[vm_0][info 2016/01/05 23:08:52.486 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@58bcf04f (took 26 ms)

[vm_1][info 2016/01/05 23:08:52.486 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@7fb47abf

[vm_1][info 2016/01/05 23:08:52.493 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region _monitoringRegion_10.118.33.94<v159>1026

[vm_1][info 2016/01/05 23:08:52.494 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region _monitoringRegion_10.118.33.94<v159>1026 completed

[vm_1][info 2016/01/05 23:08:52.498 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/05 23:08:52.499 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region PdxTypes

[vm_1][info 2016/01/05 23:08:52.502 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region PdxTypes requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:52.502 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] PdxTypes is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:52.502 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region PdxTypes completed

[vm_1][info 2016/01/05 23:08:52.504 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region __PR

[vm_1][info 2016/01/05 23:08:52.506 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region __PR requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:52.508 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] __PR is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:52.508 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region __PR completed

[vm_1][info 2016/01/05 23:08:52.509 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Partitioned Region /ResourceManagerDUnitTest_testCreateRedundantBucket-PR-0 is created with prId=69

[vm_1][info 2016/01/05 23:08:52.515 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@7fb47abf (took 29 ms)

[vm_0][info 2016/01/05 23:08:52.516 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@70117c98

[vm_1][info 2016/01/05 23:08:52.517 PST <Pooled Waiting Message Processor 2> tid=0x963] Initializing region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0

[vm_1][info 2016/01/05 23:08:52.518 PST <Pooled Waiting Message Processor 2> tid=0x963] Initialization of region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0 completed

[vm_0][info 2016/01/05 23:08:52.519 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0

[vm_0][info 2016/01/05 23:08:52.520 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0 requesting initial image from cc4-rh6(28191)<ec><v159>:1026

[vm_0][info 2016/01/05 23:08:52.520 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0 is done getting image from cc4-rh6(28191)<ec><v159>:1026. isDeltaGII is false

[vm_0][info 2016/01/05 23:08:52.521 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0 completed

[vm_0][info 2016/01/05 23:08:52.523 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@70117c98 (took 7 ms)

[vm_0][info 2016/01/05 23:08:52.524 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@18f5b33

[vm_0][info 2016/01/05 23:08:52.524 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: cc4-rh6(28182)<ec><v158>:1025
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@18f5b33 (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.524 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@e84fd28

[vm_0][info 2016/01/05 23:08:52.525 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 13
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@e84fd28 (took 1 ms)

[vm_0][info 2016/01/05 23:08:52.525 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@66fcf59

[vm_0][info 2016/01/05 23:08:52.525 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 1
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@66fcf59 (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.526 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@7610024c

[vm_0][info 2016/01/05 23:08:52.526 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 0
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@7610024c (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.527 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@789da20d

[vm_1][info 2016/01/05 23:08:52.527 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: cc4-rh6(28191)<ec><v159>:1026
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@789da20d (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.528 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@72529e9f

[vm_1][info 2016/01/05 23:08:52.528 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 13
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@72529e9f (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.528 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@6e97c0fd

[vm_1][info 2016/01/05 23:08:52.528 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@6e97c0fd (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.529 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@48ad758b

[vm_1][info 2016/01/05 23:08:52.529 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@48ad758b (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.532 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$35@da05f7e

[vm_0][info 2016/01/05 23:08:52.532 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$35.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$35@da05f7e (took 0 ms)

[vm_2][info 2016/01/05 23:08:52.533 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@311284eb

[vm_2][info 2016/01/05 23:08:52.543 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initializing region _monitoringRegion_10.118.33.94<v160>1027

[vm_2][info 2016/01/05 23:08:52.544 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initialization of region _monitoringRegion_10.118.33.94<v160>1027 completed

[vm_2][info 2016/01/05 23:08:52.548 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_2][info 2016/01/05 23:08:52.548 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initializing region PdxTypes

[vm_2][info 2016/01/05 23:08:52.551 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Region PdxTypes requesting initial image from cc4-rh6(28191)<ec><v159>:1026

[vm_2][info 2016/01/05 23:08:52.551 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] PdxTypes is done getting image from cc4-rh6(28191)<ec><v159>:1026. isDeltaGII is false

[vm_2][info 2016/01/05 23:08:52.551 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initialization of region PdxTypes completed

[vm_2][info 2016/01/05 23:08:52.552 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initializing region __PR

[vm_2][info 2016/01/05 23:08:52.555 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Region __PR requesting initial image from cc4-rh6(28191)<ec><v159>:1026

[vm_2][info 2016/01/05 23:08:52.556 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] __PR is done getting image from cc4-rh6(28191)<ec><v159>:1026. isDeltaGII is false

[vm_2][info 2016/01/05 23:08:52.557 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initialization of region __PR completed

[vm_2][info 2016/01/05 23:08:52.558 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Partitioned Region /ResourceManagerDUnitTest_testCreateRedundantBucket-PR-0 is created with prId=69

[vm_2][info 2016/01/05 23:08:52.570 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@311284eb (took 37 ms)

[vm_2][info 2016/01/05 23:08:52.576 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$36.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$36@6f17f210

[vm_2][info 2016/01/05 23:08:52.577 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initializing region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0

[vm_2][info 2016/01/05 23:08:52.579 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0 requesting initial image from cc4-rh6(28191)<ec><v159>:1026

[vm_2][info 2016/01/05 23:08:52.580 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0 is done getting image from cc4-rh6(28191)<ec><v159>:1026. isDeltaGII is false

[vm_2][info 2016/01/05 23:08:52.580 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Initialization of region _B__ResourceManagerDUnitTest__testCreateRedundantBucket-PR-0_0 completed

[vm_2][info 2016/01/05 23:08:52.581 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$36.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$36@6f17f210 (took 4 ms)

[vm_0][info 2016/01/05 23:08:52.582 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/05 23:08:52.583 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] GemFireCache[id = 1957785212; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:52 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][info 2016/01/05 23:08:52.595 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 13 ms)

[vm_1][info 2016/01/05 23:08:52.597 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/05 23:08:52.598 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] GemFireCache[id = 787583788; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:52 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_2][warn 2016/01/05 23:08:52.602 PST <PartitionedRegion Message Processor2> tid=0x7a5] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testCreateRedundantBucket-PR-0

[vm_1][info 2016/01/05 23:08:52.609 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 12 ms)

[vm_2][info 2016/01/05 23:08:52.609 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/05 23:08:52.610 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] GemFireCache[id = 309221733; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:52 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_2][info 2016/01/05 23:08:52.621 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 12 ms)

[vm_3][info 2016/01/05 23:08:52.622 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/05 23:08:52.622 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.623 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/05 23:08:52.623 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.624 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/05 23:08:52.624 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:52.624 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/05 23:08:52.624 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:52.625 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/05 23:08:52.625 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/05 23:08:52.626 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@d407d41

[locator][info 2016/01/05 23:08:52.626 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@d407d41 (took 0 ms)

Previously run tests: [PRBasicQueryDUnitTest, PRBasicMultiIndexCreationDUnitTest, PRQueryPerfDUnitTest, PRQueryCacheCloseDUnitTest, PRBasicIndexCreationDeadlockDUnitTest, ConcurrentIndexInitOnOverflowRegionDUnitTest, ConcurrentIndexUpdateWithoutWLDUnitTest, CopyOnReadIndexDUnitTest, ConcurrentIndexUpdateWithInplaceObjectModFalseDUnitTest, ConcurrentIndexOperationsOnOverflowRegionDUnitTest, InitializeIndexEntryDestroyQueryDUnitTest, IndexTrackingQueryObserverDUnitTest, MultiIndexCreationDUnitTest, PartitionRegionHelperDUnitTest, ResourceManagerDUnitTest]
[vm_0][info 2016/01/05 23:08:52.627 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_0][info 2016/01/05 23:08:52.627 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.628 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_1][info 2016/01/05 23:08:52.628 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_2][info 2016/01/05 23:08:52.629 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_2][info 2016/01/05 23:08:52.629 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:52.629 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.perVMSetUp with 2 args

[vm_3][info 2016/01/05 23:08:52.629 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.perVMSetUp with 2 args (took 0 ms)



[setup] START TEST ResourceManagerDUnitTest.testRemoveDuringContainsKey


[vm_0][info 2016/01/05 23:08:52.630 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@3ce9c8de

[vm_0][info 2016/01/05 23:08:52.636 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _monitoringRegion_10.118.33.94<v158>1025

[vm_0][info 2016/01/05 23:08:52.637 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _monitoringRegion_10.118.33.94<v158>1025 completed

[vm_0][info 2016/01/05 23:08:52.640 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_0][info 2016/01/05 23:08:52.641 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region PdxTypes

[vm_0][info 2016/01/05 23:08:52.642 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region PdxTypes completed

[vm_0][info 2016/01/05 23:08:52.643 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region __PR

[vm_0][info 2016/01/05 23:08:52.644 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region __PR completed

[vm_0][info 2016/01/05 23:08:52.647 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringContainsKey_PR_0 is born with prId=70 ident:#ResourceManagerDUnitTest_testRemoveDuringContainsKey_PR_0

[vm_0][info 2016/01/05 23:08:52.649 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@3ce9c8de (took 19 ms)

[vm_1][info 2016/01/05 23:08:52.650 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@62d013e2

[vm_1][info 2016/01/05 23:08:52.662 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region _monitoringRegion_10.118.33.94<v159>1026

[vm_1][info 2016/01/05 23:08:52.663 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region _monitoringRegion_10.118.33.94<v159>1026 completed

[vm_1][info 2016/01/05 23:08:52.667 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] The cache has been created with "use-cluster-configuration=false". It will not receive any cluster configuration

[vm_1][info 2016/01/05 23:08:52.667 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region PdxTypes

[vm_1][info 2016/01/05 23:08:52.670 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region PdxTypes requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:52.671 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] PdxTypes is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:52.671 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region PdxTypes completed

[vm_1][info 2016/01/05 23:08:52.672 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initializing region __PR

[vm_1][info 2016/01/05 23:08:52.673 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Region __PR requesting initial image from cc4-rh6(28182)<ec><v158>:1025

[vm_1][info 2016/01/05 23:08:52.675 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] __PR is done getting image from cc4-rh6(28182)<ec><v158>:1025. isDeltaGII is false

[vm_1][info 2016/01/05 23:08:52.675 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Initialization of region __PR completed

[vm_1][info 2016/01/05 23:08:52.677 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Partitioned Region /ResourceManagerDUnitTest_testRemoveDuringContainsKey_PR_0 is created with prId=70

[vm_1][info 2016/01/05 23:08:52.681 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$8@62d013e2 (took 31 ms)

[vm_0][info 2016/01/05 23:08:52.683 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@1802438c

[vm_1][info 2016/01/05 23:08:52.685 PST <Pooled Waiting Message Processor 0> tid=0x922] Initializing region _B__ResourceManagerDUnitTest__testRemoveDuringContainsKey__PR__0_0

[vm_1][info 2016/01/05 23:08:52.685 PST <Pooled Waiting Message Processor 0> tid=0x922] Initialization of region _B__ResourceManagerDUnitTest__testRemoveDuringContainsKey__PR__0_0 completed

[vm_0][info 2016/01/05 23:08:52.687 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initializing region _B__ResourceManagerDUnitTest__testRemoveDuringContainsKey__PR__0_0

[vm_0][info 2016/01/05 23:08:52.688 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Region _B__ResourceManagerDUnitTest__testRemoveDuringContainsKey__PR__0_0 requesting initial image from cc4-rh6(28191)<ec><v159>:1026

[vm_0][info 2016/01/05 23:08:52.689 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] _B__ResourceManagerDUnitTest__testRemoveDuringContainsKey__PR__0_0 is done getting image from cc4-rh6(28191)<ec><v159>:1026. isDeltaGII is false

[vm_0][info 2016/01/05 23:08:52.689 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Initialization of region _B__ResourceManagerDUnitTest__testRemoveDuringContainsKey__PR__0_0 completed

[vm_0][info 2016/01/05 23:08:52.692 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$9@1802438c (took 9 ms)

[vm_0][info 2016/01/05 23:08:52.693 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@6667aa47

[vm_0][info 2016/01/05 23:08:52.693 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: cc4-rh6(28182)<ec><v158>:1025
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@6667aa47 (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.693 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@11457b7d

[vm_0][info 2016/01/05 23:08:52.694 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 59
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@11457b7d (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.694 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@76cd8b79

[vm_0][info 2016/01/05 23:08:52.694 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 1
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@76cd8b79 (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.695 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@23a54a86

[vm_0][info 2016/01/05 23:08:52.695 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: 0
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@23a54a86 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.696 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@12463f0

[vm_1][info 2016/01/05 23:08:52.696 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: cc4-rh6(28191)<ec><v159>:1026
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$10@12463f0 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.696 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@601bf342

[vm_1][info 2016/01/05 23:08:52.697 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 59
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$11@601bf342 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.697 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@7925d39

[vm_1][info 2016/01/05 23:08:52.697 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$12@7925d39 (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.698 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@489027b5

[vm_1][info 2016/01/05 23:08:52.698 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: 1
[vm_1] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13.call with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$13@489027b5 (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.698 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18@23d5465f

[vm_0][info 2016/01/05 23:08:52.698 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$18@23d5465f (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.702 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19@39d115e

[vm_1][warn 2016/01/05 23:08:52.704 PST <Pooled Waiting Message Processor 2> tid=0x963] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveDuringContainsKey_PR_0

[vm_0][warn 2016/01/05 23:08:52.705 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Redundancy has dropped below 1 configured copies to 0 actual copies for /ResourceManagerDUnitTest_testRemoveDuringContainsKey_PR_0

[vm_0][info 2016/01/05 23:08:52.706 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$19@39d115e (took 4 ms)

[vm_0][info 2016/01/05 23:08:52.707 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20@3dda0add

[vm_0][info 2016/01/05 23:08:52.708 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run with 0 args on object: com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20@3dda0add (took 1 ms)

[vm_0][info 2016/01/05 23:08:52.709 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_0][info 2016/01/05 23:08:52.710 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] GemFireCache[id = 1865348476; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:52 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_0][info 2016/01/05 23:08:52.717 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 8 ms)

[vm_1][info 2016/01/05 23:08:52.718 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_1][info 2016/01/05 23:08:52.718 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] GemFireCache[id = 763134905; isClosing = true; isShutDownAll = false; created = Tue Jan 05 23:08:52 PST 2016; server = false; copyOnRead = false; lockLease = 120; lockTimeout = 60]: Now closing.

[vm_1][info 2016/01/05 23:08:52.726 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 8 ms)

[vm_2][info 2016/01/05 23:08:52.726 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_2][info 2016/01/05 23:08:52.727 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 1 ms)

[vm_3][info 2016/01/05 23:08:52.727 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args

[vm_3][info 2016/01/05 23:08:52.727 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from com.gemstone.gemfire.cache30.CacheTestCase.remoteTearDown with 0 args (took 0 ms)

[vm_0][info 2016/01/05 23:08:52.728 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_0][info 2016/01/05 23:08:52.728 PST <RMI TCP Connection(12)-10.118.33.94> tid=0x5dd] Got result: null
[vm_0] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_1][info 2016/01/05 23:08:52.728 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_1][info 2016/01/05 23:08:52.729 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x8f2] Got result: null
[vm_1] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 1 ms)

[vm_2][info 2016/01/05 23:08:52.729 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_2][info 2016/01/05 23:08:52.729 PST <RMI TCP Connection(14)-10.118.33.94> tid=0x75c] Got result: null
[vm_2] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[vm_3][info 2016/01/05 23:08:52.729 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Received method: dunit.DistributedTestCase.cleanupThisVM with 0 args

[vm_3][info 2016/01/05 23:08:52.730 PST <RMI TCP Connection(15)-10.118.33.94> tid=0x672] Got result: null
[vm_3] from dunit.DistributedTestCase.cleanupThisVM with 0 args (took 0 ms)

[locator][info 2016/01/05 23:08:52.730 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Received method: dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@2e1a0169

[locator][info 2016/01/05 23:08:52.730 PST <RMI TCP Connection(4)-10.118.33.94> tid=0x1c9] Got result: null
[locator] from dunit.DistributedTestCase$5.run with 0 args on object: dunit.DistributedTestCase$5@2e1a0169 (took 0 ms)
{noformat}


> CI failure: ResourceManagerDUnitTest.testRemoveDuringGet
> --------------------------------------------------------
>
>                 Key: GEODE-755
>                 URL: https://issues.apache.org/jira/browse/GEODE-755
>             Project: Geode
>          Issue Type: Bug
>          Components: core
>            Reporter: Kirk Lund
>
> {noformat}
> dunit.RMIException: While invoking com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run in VM 1 running on Host cc4-rh6.gemstone.com with 4 VMs
> 	at dunit.VM.invoke(VM.java:369)
> 	at dunit.VM.invoke(VM.java:312)
> 	at dunit.VM.invoke(VM.java:266)
> 	at com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest.doOpDuringBucketRemove(ResourceManagerDUnitTest.java:940)
> 	at com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest.testRemoveDuringGet(ResourceManagerDUnitTest.java:973)
> 	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)
> Caused by: junit.framework.AssertionFailedError: Target member is still hosting removed bucket
> 	at junit.framework.Assert.fail(Assert.java:57)
> 	at junit.framework.Assert.assertTrue(Assert.java:22)
> 	at junit.framework.Assert.assertFalse(Assert.java:39)
> 	at junit.framework.TestCase.assertFalse(TestCase.java:210)
> 	at com.gemstone.gemfire.cache.management.ResourceManagerDUnitTest$20.run(ResourceManagerDUnitTest.java:949)
> 	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 hydra.MethExecutor.executeObject(MethExecutor.java:267)
> 	at dunit.standalone.RemoteDUnitVM.executeMethodOnObject(RemoteDUnitVM.java:77)
> 	at sun.reflect.GeneratedMethodAccessor174.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:497)
> 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:323)
> 	at sun.rmi.transport.Transport$1.run(Transport.java:200)
> 	at sun.rmi.transport.Transport$1.run(Transport.java:197)
> 	at java.security.AccessController.doPrivileged(Native Method)
> 	at sun.rmi.transport.Transport.serviceCall(Transport.java:196)
> 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:568)
> 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:826)
> 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.lambda$run$95(TCPTransport.java:683)
> 	at java.security.AccessController.doPrivileged(Native Method)
> 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:682)
> 	... 3 more
> {noformat}



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