You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/03/02 22:24:29 UTC
[JENKINS] Lucene-Solr-Tests-trunk-java7 - Build # 3779 - Still
Failing
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-java7/3779/
4 tests failed.
REGRESSION: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch
Error Message:
No registered leader was found, collection:collection1 slice:shard1
Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found, collection:collection1 slice:shard1
at __randomizedtesting.SeedInfo.seed([180CDBCE0234C1AA:99EA55D6756BA196]:0)
at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:430)
at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:295)
at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:116)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
REGRESSION: org.apache.solr.cloud.SyncSliceTest.testDistribSearch
Error Message:
Test Setup Failure: shard1 should have just been set up to be inconsistent - but it's still consistent. Leader:http://127.0.0.1:53964/ijujs/bh/collection1skip list:[CloudJettyRunner [url=http://127.0.0.1:63810/ijujs/bh/collection1], CloudJettyRunner [url=http://127.0.0.1:63810/ijujs/bh/collection1]]
Stack Trace:
java.lang.AssertionError: Test Setup Failure: shard1 should have just been set up to be inconsistent - but it's still consistent. Leader:http://127.0.0.1:53964/ijujs/bh/collection1skip list:[CloudJettyRunner [url=http://127.0.0.1:63810/ijujs/bh/collection1], CloudJettyRunner [url=http://127.0.0.1:63810/ijujs/bh/collection1]]
at __randomizedtesting.SeedInfo.seed([180CDBCE0234C1AA:99EA55D6756BA196]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:212)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest
Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1698, name=coreLoadExecutor-1313-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1698, name=coreLoadExecutor-1313-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
at java.lang.Thread.getStackTrace(Thread.java:1567)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:986)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:244)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:458)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:733)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:619)
at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:868)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:951)
at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:574)
at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:569)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.RAMDirectory@644a6ef6 lockFactory=org.apache.lucene.store.NativeFSLockFactory@760b5a14)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
... 1 more
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest
Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1700, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1700, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
at java.lang.Thread.getStackTrace(Thread.java:1567)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:986)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:383)
at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:281)
at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:153)
at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:409)
at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:223)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.RAMDirectory@5f028911 lockFactory=org.apache.lucene.store.NativeFSLockFactory@35263820)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
Build Log:
[...truncated 9163 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4] 2> 0 T1639 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4] 2> 5 T1639 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./solrtest-RecoveryZkTest-1362258889891
[junit4:junit4] 2> 6 T1639 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 7 T1640 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 10 T1640 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 10 T1640 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 10 T1640 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 11 T1640 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 11 T1640 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4] 2> 108 T1639 oasc.ZkTestServer.run start zk server on port:53691
[junit4:junit4] 2> 108 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5f3266c5
[junit4:junit4] 2> 109 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 109 T1645 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 110 T1645 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 110 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:27187
[junit4:junit4] 2> 111 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:27187
[junit4:junit4] 2> 111 T1643 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 139 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90000 with negotiated timeout 10000 for client /140.211.11.196:27187
[junit4:junit4] 2> 139 T1645 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90000, negotiated timeout = 10000
[junit4:junit4] 2> 140 T1646 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f3266c5 name:ZooKeeperConnection Watcher:127.0.0.1:53691 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 140 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 140 T1639 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 144 T1644 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2cf50ca90000
[junit4:junit4] 2> 145 T1646 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 145 T1641 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:27187 which had sessionid 0x13d2cf50ca90000
[junit4:junit4] 2> 145 T1639 oaz.ZooKeeper.close Session: 0x13d2cf50ca90000 closed
[junit4:junit4] 2> 146 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@56e7ec3b
[junit4:junit4] 2> 147 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 147 T1647 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 147 T1647 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 148 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:30213
[junit4:junit4] 2> 148 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:30213
[junit4:junit4] 2> 154 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90001 with negotiated timeout 10000 for client /140.211.11.196:30213
[junit4:junit4] 2> 154 T1647 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90001, negotiated timeout = 10000
[junit4:junit4] 2> 155 T1648 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@56e7ec3b name:ZooKeeperConnection Watcher:127.0.0.1:53691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 155 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 155 T1639 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 159 T1639 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 169 T1639 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 171 T1639 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 175 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 175 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 191 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4] 2> 192 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 321 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4] 2> 322 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 325 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4] 2> 326 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 329 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4] 2> 330 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 337 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 338 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 341 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 342 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 345 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 345 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 349 T1639 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4] 2> 349 T1639 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 352 T1644 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2cf50ca90001
[junit4:junit4] 2> 353 T1648 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 353 T1641 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:30213 which had sessionid 0x13d2cf50ca90001
[junit4:junit4] 2> 353 T1639 oaz.ZooKeeper.close Session: 0x13d2cf50ca90001 closed
[junit4:junit4] 2> 546 T1639 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 552 T1639 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:55904
[junit4:junit4] 2> 552 T1639 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 553 T1639 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 553 T1639 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239
[junit4:junit4] 2> 554 T1639 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/solr.xml
[junit4:junit4] 2> 554 T1639 oasc.CoreContainer.<init> New CoreContainer 779999683
[junit4:junit4] 2> 555 T1639 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/'
[junit4:junit4] 2> 555 T1639 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/'
[junit4:junit4] 2> 628 T1639 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 629 T1639 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 629 T1639 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 630 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 630 T1639 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 631 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 631 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 632 T1639 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 632 T1639 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 633 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 653 T1639 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 673 T1639 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:53691/solr
[junit4:junit4] 2> 674 T1639 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 675 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@783c0c59
[junit4:junit4] 2> 676 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 676 T1658 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 677 T1658 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 677 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:17253
[junit4:junit4] 2> 678 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:17253
[junit4:junit4] 2> 679 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90002 with negotiated timeout 20000 for client /140.211.11.196:17253
[junit4:junit4] 2> 679 T1658 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90002, negotiated timeout = 20000
[junit4:junit4] 2> 680 T1659 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@783c0c59 name:ZooKeeperConnection Watcher:127.0.0.1:53691 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 680 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 682 T1644 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2cf50ca90002
[junit4:junit4] 2> 692 T1659 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 692 T1641 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:17253 which had sessionid 0x13d2cf50ca90002
[junit4:junit4] 2> 692 T1639 oaz.ZooKeeper.close Session: 0x13d2cf50ca90002 closed
[junit4:junit4] 2> 693 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 704 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@3a7eefa9
[junit4:junit4] 2> 705 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 705 T1660 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 706 T1660 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 706 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:21380
[junit4:junit4] 2> 706 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:21380
[junit4:junit4] 2> 708 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90003 with negotiated timeout 20000 for client /140.211.11.196:21380
[junit4:junit4] 2> 708 T1660 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90003, negotiated timeout = 20000
[junit4:junit4] 2> 708 T1661 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a7eefa9 name:ZooKeeperConnection Watcher:127.0.0.1:53691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 708 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 746 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 749 T1639 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 777 T1639 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55904_
[junit4:junit4] 2> 779 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55904_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55904_
[junit4:junit4] 2> 823 T1639 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55904_
[junit4:junit4] 2> 852 T1639 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 908 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4] 2> 922 T1639 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 925 T1639 oasc.Overseer.start Overseer (id=89276998608289795-127.0.0.1:55904_-n_0000000000) starting
[junit4:junit4] 2> 926 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 927 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 962 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 963 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 970 T1663 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4] 2> 971 T1639 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 974 T1639 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 977 T1662 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 980 T1664 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/collection1
[junit4:junit4] 2> 980 T1664 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 981 T1664 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 981 T1664 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 982 T1664 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/collection1/'
[junit4:junit4] 2> 983 T1664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 984 T1664 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/collection1/lib/README' to classloader
[junit4:junit4] 2> 1059 T1664 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 1122 T1664 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 1223 T1664 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 1230 T1664 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 1815 T1664 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 1822 T1664 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 1825 T1664 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 1836 T1664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1839 T1664 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1842 T1664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1843 T1664 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 1843 T1664 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 1844 T1664 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1844 T1664 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 1844 T1664 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 1845 T1664 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362258890239/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data/
[junit4:junit4] 2> 1845 T1664 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ce2b11f
[junit4:junit4] 2> 1845 T1664 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 1846 T1664 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data forceNew:false
[junit4:junit4] 2> 1846 T1664 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data
[junit4:junit4] 2> 1846 T1664 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data/index/
[junit4:junit4] 2> 1847 T1664 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 1847 T1664 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data/index forceNew:false
[junit4:junit4] 2> 1849 T1664 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6798c918 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4c153bdf)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 1849 T1664 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 1850 T1664 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data/index
[junit4:junit4] 2> 1851 T1664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 1852 T1664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 1852 T1664 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 1853 T1664 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 1853 T1664 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 1853 T1664 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 1853 T1664 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 1854 T1664 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 1854 T1664 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 1862 T1664 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 1866 T1664 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data
[junit4:junit4] 2> 1866 T1664 oass.SolrIndexSearcher.<init> Opening Searcher@10652a37 main
[junit4:junit4] 2> 1867 T1664 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data/tlog
[junit4:junit4] 2> 1867 T1664 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 1867 T1664 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 1869 T1664 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data
[junit4:junit4] 2> 1870 T1665 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10652a37 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 1871 T1664 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 1871 T1664 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 1871 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4] 2> 2480 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 2481 T1662 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "core_node_name":"1",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:55904_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:55904"}
[junit4:junit4] 2> 2481 T1662 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 2481 T1662 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 2482 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4] 2> 2497 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 2884 T1664 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 2884 T1664 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55904 collection:control_collection shard:shard1
[junit4:junit4] 2> 2885 T1664 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 2901 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:delete cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4] 2> 2902 T1664 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 2903 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 2912 T1664 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 2912 T1664 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 2913 T1664 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55904/collection1/
[junit4:junit4] 2> 2913 T1664 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 2913 T1664 oasc.SyncStrategy.syncToMe http://127.0.0.1:55904/collection1/ has no replicas
[junit4:junit4] 2> 2913 T1664 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55904/collection1/
[junit4:junit4] 2> 2914 T1664 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 2917 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4009 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4031 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 4041 T1664 oasc.ZkController.register We are http://127.0.0.1:55904/collection1/ and leader is http://127.0.0.1:55904/collection1/
[junit4:junit4] 2> 4041 T1664 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55904
[junit4:junit4] 2> 4041 T1664 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 4041 T1664 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 4042 T1664 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 4046 T1664 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4047 T1639 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4] 2> 4048 T1639 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 4048 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 4062 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 4063 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@30316c65
[junit4:junit4] 2> 4064 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4064 T1667 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4065 T1667 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 4065 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:30485
[junit4:junit4] 2> 4066 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:30485
[junit4:junit4] 2> 4067 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90004 with negotiated timeout 10000 for client /140.211.11.196:30485
[junit4:junit4] 2> 4067 T1667 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90004, negotiated timeout = 10000
[junit4:junit4] 2> 4067 T1668 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@30316c65 name:ZooKeeperConnection Watcher:127.0.0.1:53691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4067 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4069 T1639 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4071 T1639 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 4254 T1639 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 4257 T1639 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:49705
[junit4:junit4] 2> 4258 T1639 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 4259 T1639 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 4259 T1639 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956
[junit4:junit4] 2> 4260 T1639 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/solr.xml
[junit4:junit4] 2> 4260 T1639 oasc.CoreContainer.<init> New CoreContainer 1106065986
[junit4:junit4] 2> 4261 T1639 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/'
[junit4:junit4] 2> 4261 T1639 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/'
[junit4:junit4] 2> 4324 T1639 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 4325 T1639 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 4325 T1639 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 4326 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 4326 T1639 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 4327 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 4327 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 4328 T1639 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 4328 T1639 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 4329 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 4348 T1639 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 4368 T1639 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:53691/solr
[junit4:junit4] 2> 4368 T1639 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 4369 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@79cb2988
[junit4:junit4] 2> 4370 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4370 T1678 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4371 T1678 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 4372 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:29672
[junit4:junit4] 2> 4372 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:29672
[junit4:junit4] 2> 4374 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90005 with negotiated timeout 20000 for client /140.211.11.196:29672
[junit4:junit4] 2> 4374 T1678 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90005, negotiated timeout = 20000
[junit4:junit4] 2> 4375 T1679 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@79cb2988 name:ZooKeeperConnection Watcher:127.0.0.1:53691 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4375 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4376 T1644 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2cf50ca90005
[junit4:junit4] 2> 4390 T1679 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 4390 T1641 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:29672 which had sessionid 0x13d2cf50ca90005
[junit4:junit4] 2> 4390 T1639 oaz.ZooKeeper.close Session: 0x13d2cf50ca90005 closed
[junit4:junit4] 2> 4391 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 4401 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@ed94feb
[junit4:junit4] 2> 4402 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4402 T1680 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4403 T1680 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 4403 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:25521
[junit4:junit4] 2> 4404 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:25521
[junit4:junit4] 2> 4405 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90006 with negotiated timeout 20000 for client /140.211.11.196:25521
[junit4:junit4] 2> 4405 T1680 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90006, negotiated timeout = 20000
[junit4:junit4] 2> 4405 T1681 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ed94feb name:ZooKeeperConnection Watcher:127.0.0.1:53691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4405 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4407 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4408 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4417 T1639 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 5419 T1639 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49705_
[junit4:junit4] 2> 5420 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49705_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49705_
[junit4:junit4] 2> 5447 T1639 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49705_
[junit4:junit4] 2> 5452 T1668 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 5453 T1661 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 5453 T1681 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 5453 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5466 T1682 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/collection1
[junit4:junit4] 2> 5466 T1682 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 5467 T1682 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 5467 T1682 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 5468 T1682 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/collection1/'
[junit4:junit4] 2> 5470 T1682 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/collection1/lib/README' to classloader
[junit4:junit4] 2> 5470 T1682 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 5520 T1682 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 5542 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 5543 T1662 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "core_node_name":"1",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:55904_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:55904"}
[junit4:junit4] 2> 5556 T1668 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5556 T1681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5556 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5582 T1682 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 5683 T1682 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 5689 T1682 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 6311 T1682 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 6322 T1682 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 6325 T1682 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 6338 T1682 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6343 T1682 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6347 T1682 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6348 T1682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 6349 T1682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 6350 T1682 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6351 T1682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 6351 T1682 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 6351 T1682 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362258893956/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1/
[junit4:junit4] 2> 6351 T1682 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ce2b11f
[junit4:junit4] 2> 6352 T1682 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 6353 T1682 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1 forceNew:false
[junit4:junit4] 2> 6353 T1682 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1
[junit4:junit4] 2> 6353 T1682 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1/index/
[junit4:junit4] 2> 6354 T1682 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 6354 T1682 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1/index forceNew:false
[junit4:junit4] 2> 6357 T1682 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4a2875c4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@365e7207)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 6357 T1682 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 6358 T1682 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1/index
[junit4:junit4] 2> 6360 T1682 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 6360 T1682 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 6361 T1682 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 6361 T1682 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 6362 T1682 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 6362 T1682 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 6363 T1682 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 6363 T1682 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 6364 T1682 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 6375 T1682 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 6381 T1682 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1
[junit4:junit4] 2> 6381 T1682 oass.SolrIndexSearcher.<init> Opening Searcher@37b528c8 main
[junit4:junit4] 2> 6382 T1682 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1/tlog
[junit4:junit4] 2> 6382 T1682 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 6383 T1682 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 6386 T1683 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37b528c8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 6388 T1682 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 6389 T1682 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 7060 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7061 T1662 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "core_node_name":"2",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:49705_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49705"}
[junit4:junit4] 2> 7061 T1662 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4] 2> 7062 T1662 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 7105 T1668 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 7105 T1681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 7105 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 7391 T1682 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 7391 T1682 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49705 collection:collection1 shard:shard1
[junit4:junit4] 2> 7392 T1682 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 7407 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90006 type:delete cxid:0x46 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4] 2> 7407 T1682 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 7408 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 7410 T1682 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 7410 T1682 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 7410 T1682 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49705/collection1/
[junit4:junit4] 2> 7411 T1682 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 7411 T1682 oasc.SyncStrategy.syncToMe http://127.0.0.1:49705/collection1/ has no replicas
[junit4:junit4] 2> 7411 T1682 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49705/collection1/
[junit4:junit4] 2> 7411 T1682 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 7415 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8610 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8663 T1681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 8663 T1668 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 8663 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 8692 T1682 oasc.ZkController.register We are http://127.0.0.1:49705/collection1/ and leader is http://127.0.0.1:49705/collection1/
[junit4:junit4] 2> 8692 T1682 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49705
[junit4:junit4] 2> 8692 T1682 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 8692 T1682 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 8693 T1682 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 8694 T1682 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8696 T1639 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4] 2> 8696 T1639 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 8697 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 8907 T1639 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 8911 T1639 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:35798
[junit4:junit4] 2> 8911 T1639 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 8912 T1639 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 8912 T1639 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596
[junit4:junit4] 2> 8913 T1639 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/solr.xml
[junit4:junit4] 2> 8913 T1639 oasc.CoreContainer.<init> New CoreContainer 487216360
[junit4:junit4] 2> 8914 T1639 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/'
[junit4:junit4] 2> 8915 T1639 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/'
[junit4:junit4] 2> 8982 T1639 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4] 2> 8983 T1639 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 8983 T1639 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 8984 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 8984 T1639 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 8985 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 8985 T1639 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 8986 T1639 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 8986 T1639 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 8987 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4] 2> 9007 T1639 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 9028 T1639 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:53691/solr
[junit4:junit4] 2> 9028 T1639 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 9029 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1612ccf2
[junit4:junit4] 2> 9030 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 9031 T1694 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 9031 T1694 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 9032 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:19358
[junit4:junit4] 2> 9032 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:19358
[junit4:junit4] 2> 9034 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90007 with negotiated timeout 20000 for client /140.211.11.196:19358
[junit4:junit4] 2> 9034 T1694 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90007, negotiated timeout = 20000
[junit4:junit4] 2> 9035 T1695 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1612ccf2 name:ZooKeeperConnection Watcher:127.0.0.1:53691 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 9035 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 9036 T1644 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2cf50ca90007
[junit4:junit4] 2> 9049 T1695 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 9049 T1641 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:19358 which had sessionid 0x13d2cf50ca90007
[junit4:junit4] 2> 9049 T1639 oaz.ZooKeeper.close Session: 0x13d2cf50ca90007 closed
[junit4:junit4] 2> 9050 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4] 2> 9060 T1639 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53691/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@28ba1009
[junit4:junit4] 2> 9062 T1696 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:53691. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 9061 T1639 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 9062 T1696 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:53691, initiating session
[junit4:junit4] 2> 9062 T1641 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:32859
[junit4:junit4] 2> 9063 T1641 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:32859
[junit4:junit4] 2> 9064 T1643 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2cf50ca90008 with negotiated timeout 20000 for client /140.211.11.196:32859
[junit4:junit4] 2> 9064 T1696 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:53691, sessionid = 0x13d2cf50ca90008, negotiated timeout = 20000
[junit4:junit4] 2> 9064 T1697 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@28ba1009 name:ZooKeeperConnection Watcher:127.0.0.1:53691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 9065 T1639 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 9066 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9067 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9076 T1639 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 10078 T1639 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35798_
[junit4:junit4] 2> 10079 T1644 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2cf50ca90008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:35798_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:35798_
[junit4:junit4] 2> 10081 T1639 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35798_
[junit4:junit4] 2> 10084 T1668 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10084 T1697 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10084 T1661 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10085 T1668 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10084 T1681 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10085 T1681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10085 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10102 T1698 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/collection1
[junit4:junit4] 2> 10102 T1698 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 10102 T1698 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 10103 T1698 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 10104 T1698 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/collection1/'
[junit4:junit4] 2> 10105 T1698 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 10106 T1698 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/collection1/lib/README' to classloader
[junit4:junit4] 2> 10155 T1698 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 10178 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 10178 T1662 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "core_node_name":"2",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:49705_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49705"}
[junit4:junit4] 2> 10191 T1681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10191 T1668 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10191 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10191 T1697 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10217 T1698 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 10318 T1698 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 10324 T1698 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 10959 T1698 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 10970 T1698 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 10974 T1698 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 10988 T1698 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 10993 T1698 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 10997 T1698 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 10998 T1698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 10999 T1698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 10999 T1698 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 11000 T1698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4] 2> 11000 T1698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4] 2> 11001 T1698 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362258898596/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2/
[junit4:junit4] 2> 11001 T1698 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ce2b11f
[junit4:junit4] 2> 11001 T1698 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 11002 T1698 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2 forceNew:false
[junit4:junit4] 2> 11003 T1698 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2
[junit4:junit4] 2> 11003 T1698 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2/index/
[junit4:junit4] 2> 11004 T1698 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 11004 T1698 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2/index forceNew:false
[junit4:junit4] 2> 11007 T1698 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4f57516c lockFactory=org.apache.lucene.store.NativeFSLockFactory@545dd35e)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 11007 T1698 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 11008 T1698 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2/index
[junit4:junit4] 2> 11010 T1698 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 11010 T1698 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 11011 T1698 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 11012 T1698 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 11012 T1698 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 11012 T1698 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 11013 T1698 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 11013 T1698 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 11014 T1698 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 11027 T1698 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 11032 T1698 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2
[junit4:junit4] 2> 11033 T1698 oass.SolrIndexSearcher.<init> Opening Searcher@744d497e main
[junit4:junit4] 2> 11033 T1698 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2/tlog
[junit4:junit4] 2> 11034 T1698 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 11034 T1698 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 11039 T1699 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@744d497e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 11041 T1698 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 11041 T1698 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 11696 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 11697 T1662 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "core_node_name":"3",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:35798_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:35798"}
[junit4:junit4] 2> 11697 T1662 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 11698 T1662 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 11711 T1681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 11711 T1668 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 11711 T1697 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 11711 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12047 T1698 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 12047 T1698 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:35798 collection:collection1 shard:shard1
[junit4:junit4] 2> 12050 T1698 oasc.ZkController.register We are http://127.0.0.1:35798/collection1/ and leader is http://127.0.0.1:49705/collection1/
[junit4:junit4] 2> 12050 T1698 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35798
[junit4:junit4] 2> 12050 T1698 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 12050 T1698 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C126 name=collection1 org.apache.solr.core.SolrCore@23759af5 url=http://127.0.0.1:35798/collection1 node=127.0.0.1:35798_ C126_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, collection=collection1, node_name=127.0.0.1:35798_, base_url=http://127.0.0.1:35798}
[junit4:junit4] 2> 12051 T1700 C126 P35798 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 12051 T1700 C126 P35798 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 12051 T1698 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 12051 T1700 C126 P35798 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 12052 T1700 C126 P35798 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 12052 T1639 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4] 2> 12053 T1700 C126 P35798 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 12053 T1639 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 12054 T1639 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 12073 T1639 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C127 name=collection1 org.apache.solr.core.SolrCore@5671ba85 url=http://127.0.0.1:55904/collection1 node=127.0.0.1:55904_ C127_STATE=coll:control_collection core:collection1 props:{shard=shard1, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:55904_, base_url=http://127.0.0.1:55904, leader=true}
[junit4:junit4] 2> 12085 T1652 C127 P55904 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/control/data
[junit4:junit4] 2> 12087 T1652 C127 P55904 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6798c918 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4c153bdf)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 12088 T1652 C127 P55904 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 12090 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10001 (1428431990392094720)]} 0 6
[junit4:junit4] 2> 12090 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[1 (1428431990392094721)]} 0 6
[junit4:junit4] 2> ASYNC NEW_CORE C128 name=collection1 org.apache.solr.core.SolrCore@bb45efd url=http://127.0.0.1:49705/collection1 node=127.0.0.1:49705_ C128_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:49705_, base_url=http://127.0.0.1:49705, leader=true}
[junit4:junit4] 2> 12108 T1672 C128 P49705 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty1
[junit4:junit4] 2> 12110 T1672 C128 P49705 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4a2875c4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@365e7207)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 12110 T1672 C128 P49705 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 12113 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10001 (1428431990416211968)]} 0 6
[junit4:junit4] 2> 12113 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[1 (1428431990416211969)]} 0 5
[junit4:junit4] 2> 12117 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[2 (1428431990424600576)]} 0 1
[junit4:junit4] 2> 12118 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10002 (1428431990424600577)]} 0 2
[junit4:junit4] 2> 12121 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[2 (1428431990428794880)]} 0 1
[junit4:junit4] 2> 12122 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10002 (1428431990429843456)]} 0 1
[junit4:junit4] 2> 12125 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[3 (1428431990432989184)]} 0 1
[junit4:junit4] 2> 12126 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10003 (1428431990434037760)]} 0 1
[junit4:junit4] 2> 12129 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[3 (1428431990437183488)]} 0 1
[junit4:junit4] 2> 12130 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10003 (1428431990438232064)]} 0 1
[junit4:junit4] 2> 12134 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[4 (1428431990442426368)]} 0 1
[junit4:junit4] 2> 12135 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10004 (1428431990442426369)]} 0 2
[junit4:junit4] 2> 12138 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[4 (1428431990446620672)]} 0 1
[junit4:junit4] 2> 12139 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10004 (1428431990447669248)]} 0 2
[junit4:junit4] 2> 12142 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[3 (-1428431990450814976)]} 0 1
[junit4:junit4] 2> 12142 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10003 (-1428431990450814977)]} 0 1
[junit4:junit4] 2> 12144 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[3 (-1428431990453960704)]} 0 0
[junit4:junit4] 2> 12146 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10003 (-1428431990455009280)]} 0 1
[junit4:junit4] 2> 12148 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[5 (1428431990457106432)]} 0 1
[junit4:junit4] 2> 12149 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10005 (1428431990458155008)]} 0 1
[junit4:junit4] 2> 12152 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[5 (1428431990461300736)]} 0 1
[junit4:junit4] 2> 12153 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10005 (1428431990462349312)]} 0 1
[junit4:junit4] 2> 12156 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[6 (1428431990465495040)]} 0 1
[junit4:junit4] 2> 12157 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10006 (1428431990466543616)]} 0 1
[junit4:junit4] 2> 12161 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[6 (1428431990470737920)]} 0 1
[junit4:junit4] 2> 12162 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10006 (1428431990470737921)]} 0 2
[junit4:junit4] 2> 12165 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10007 (1428431990474932224)]} 0 1
[junit4:junit4] 2> 12166 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[7 (1428431990474932225)]} 0 2
[junit4:junit4] 2> 12169 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10007 (1428431990479126528)]} 0 1
[junit4:junit4] 2> 12170 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[7 (1428431990479126529)]} 0 2
[junit4:junit4] 2> 12173 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10006 (-1428431990483320833)]} 0 1
[junit4:junit4] 2> 12173 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[6 (-1428431990483320832)]} 0 1
[junit4:junit4] 2> 12176 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[6 (-1428431990486466560)]} 0 1
[junit4:junit4] 2> 12180 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10006 (-1428431990490660864)]} 0 1
[junit4:junit4] 2> 12180 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[8 (1428431990489612288)]} 0 2
[junit4:junit4] 2> 12183 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10008 (1428431990493806592)]} 0 1
[junit4:junit4] 2> 12184 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[8 (1428431990494855168)]} 0 1
[junit4:junit4] 2> 12187 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10008 (1428431990498000896)]} 0 1
[junit4:junit4] 2> 12188 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[9 (1428431990499049472)]} 0 1
[junit4:junit4] 2> 12191 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10009 (1428431990502195200)]} 0 1
[junit4:junit4] 2> 12192 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[9 (1428431990503243776)]} 0 1
[junit4:junit4] 2> 12195 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10009 (1428431990506389504)]} 0 1
[junit4:junit4] 2> 12197 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10 (1428431990508486656)]} 0 2
[junit4:junit4] 2> 12199 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10010 (1428431990510583808)]} 0 1
[junit4:junit4] 2> 12201 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10 (1428431990512680960)]} 0 1
[junit4:junit4] 2> 12203 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10010 (1428431990514778112)]} 0 1
[junit4:junit4] 2> 12205 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[11 (1428431990516875264)]} 0 1
[junit4:junit4] 2> 12207 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10011 (1428431990518972416)]} 0 1
[junit4:junit4] 2> 12209 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[11 (1428431990521069568)]} 0 1
[junit4:junit4] 2> 12211 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10011 (1428431990523166720)]} 0 1
[junit4:junit4] 2> 12213 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[11 (-1428431990525263872)]} 0 1
[junit4:junit4] 2> 12215 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10011 (-1428431990527361024)]} 0 1
[junit4:junit4] 2> 12216 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[11 (-1428431990528409600)]} 0 1
[junit4:junit4] 2> 12218 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10011 (-1428431990530506752)]} 0 1
[junit4:junit4] 2> 12219 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[12 (1428431990531555328)]} 0 1
[junit4:junit4] 2> 12221 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10012 (1428431990533652480)]} 0 1
[junit4:junit4] 2> 12223 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[12 (1428431990535749632)]} 0 1
[junit4:junit4] 2> 12225 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10012 (1428431990537846784)]} 0 1
[junit4:junit4] 2> 12227 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[13 (1428431990539943936)]} 0 1
[junit4:junit4] 2> 12229 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10013 (1428431990542041088)]} 0 1
[junit4:junit4] 2> 12231 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[13 (1428431990544138240)]} 0 1
[junit4:junit4] 2> 12233 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10013 (1428431990546235392)]} 0 1
[junit4:junit4] 2> 12235 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[13 (-1428431990549381120)]} 0 1
[junit4:junit4] 2> 12236 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10013 (-1428431990550429696)]} 0 0
[junit4:junit4] 2> 12238 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[13 (-1428431990552526848)]} 0 0
[junit4:junit4] 2> 12239 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10013 (-1428431990553575424)]} 0 0
[junit4:junit4] 2> 12242 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[14 (1428431990555672576)]} 0 1
[junit4:junit4] 2> 12243 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10014 (1428431990556721152)]} 0 1
[junit4:junit4] 2> 12246 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[14 (1428431990559866880)]} 0 1
[junit4:junit4] 2> 12247 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10014 (1428431990560915456)]} 0 1
[junit4:junit4] 2> 12250 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[15 (1428431990564061184)]} 0 1
[junit4:junit4] 2> 12251 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10015 (1428431990565109760)]} 0 1
[junit4:junit4] 2> 12254 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[15 (1428431990568255488)]} 0 1
[junit4:junit4] 2> 12255 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10015 (1428431990569304064)]} 0 1
[junit4:junit4] 2> 12258 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[16 (1428431990572449792)]} 0 1
[junit4:junit4] 2> 12259 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10016 (1428431990573498368)]} 0 1
[junit4:junit4] 2> 12262 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[16 (1428431990576644096)]} 0 1
[junit4:junit4] 2> 12263 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10016 (1428431990577692672)]} 0 1
[junit4:junit4] 2> 12266 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[17 (1428431990580838400)]} 0 1
[junit4:junit4] 2> 12267 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10017 (1428431990581886976)]} 0 1
[junit4:junit4] 2> 12270 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[17 (1428431990585032704)]} 0 1
[junit4:junit4] 2> 12271 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10017 (1428431990586081280)]} 0 1
[junit4:junit4] 2> 12274 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[18 (1428431990589227008)]} 0 1
[junit4:junit4] 2> 12275 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10018 (1428431990590275584)]} 0 1
[junit4:junit4] 2> 12278 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[18 (1428431990593421312)]} 0 1
[junit4:junit4] 2> 12279 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10018 (1428431990594469888)]} 0 1
[junit4:junit4] 2> 12282 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[18 (-1428431990597615616)]} 0 1
[junit4:junit4] 2> 12283 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10018 (-1428431990598664192)]} 0 1
[junit4:junit4] 2> 12285 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[18 (-1428431990600761344)]} 0 1
[junit4:junit4] 2> 12286 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10018 (-1428431990601809920)]} 0 1
[junit4:junit4] 2> 12289 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[19 (1428431990604955648)]} 0 1
[junit4:junit4] 2> 12290 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10019 (1428431990604955649)]} 0 2
[junit4:junit4] 2> 12293 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10019 (1428431990609149952)]} 0 1
[junit4:junit4] 2> 12294 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[19 (1428431990610198528)]} 0 1
[junit4:junit4] 2> 12297 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10020 (1428431990613344256)]} 0 1
[junit4:junit4] 2> 12298 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[20 (1428431990614392832)]} 0 1
[junit4:junit4] 2> 12301 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10020 (1428431990617538560)]} 0 1
[junit4:junit4] 2> 12302 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[20 (1428431990618587136)]} 0 1
[junit4:junit4] 2> 12305 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10021 (1428431990621732864)]} 0 1
[junit4:junit4] 2> 12306 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[21 (1428431990622781440)]} 0 1
[junit4:junit4] 2> 12309 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10021 (1428431990625927168)]} 0 1
[junit4:junit4] 2> 12310 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[21 (1428431990626975744)]} 0 1
[junit4:junit4] 2> 12313 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10022 (1428431990630121472)]} 0 1
[junit4:junit4] 2> 12314 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[22 (1428431990631170048)]} 0 1
[junit4:junit4] 2> 12317 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10022 (1428431990634315776)]} 0 1
[junit4:junit4] 2> 12318 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[22 (1428431990635364352)]} 0 1
[junit4:junit4] 2> 12321 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10023 (1428431990638510080)]} 0 1
[junit4:junit4] 2> 12322 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[23 (1428431990639558656)]} 0 1
[junit4:junit4] 2> 12326 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10023 (1428431990642704384)]} 0 2
[junit4:junit4] 2> 12326 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[23 (1428431990643752960)]} 0 1
[junit4:junit4] 2> 12329 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10019 (-1428431990647947264)]} 0 1
[junit4:junit4] 2> 12331 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[19 (-1428431990650044416)]} 0 1
[junit4:junit4] 2> 12332 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10019 (-1428431990651092992)]} 0 0
[junit4:junit4] 2> 12335 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[19 (-1428431990653190144)]} 0 1
[junit4:junit4] 2> 12336 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10024 (1428431990654238720)]} 0 1
[junit4:junit4] 2> 12338 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[24 (1428431990656335872)]} 0 1
[junit4:junit4] 2> 12340 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10024 (1428431990658433024)]} 0 1
[junit4:junit4] 2> 12342 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[24 (1428431990660530176)]} 0 1
[junit4:junit4] 2> 12343 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10022 (-1428431990662627328)]} 0 0
[junit4:junit4] 2> 12345 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[22 (-1428431990664724480)]} 0 0
[junit4:junit4] 2> 12346 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10022 (-1428431990665773056)]} 0 0
[junit4:junit4] 2> 12348 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[22 (-1428431990667870208)]} 0 0
[junit4:junit4] 2> 12350 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10025 (1428431990668918784)]} 0 1
[junit4:junit4] 2> 12354 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[25 (1428431990673113088)]} 0 1
[junit4:junit4] 2> 12354 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10025 (1428431990673113088)]} 0 1
[junit4:junit4] 2> 12358 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[25 (1428431990677307392)]} 0 1
[junit4:junit4] 2> 12358 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10026 (1428431990677307392)]} 0 1
[junit4:junit4] 2> 12362 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10026 (1428431990681501696)]} 0 1
[junit4:junit4] 2> 12362 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[26 (1428431990681501696)]} 0 1
[junit4:junit4] 2> 12365 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10026 (-1428431990685696000)]} 0 0
[junit4:junit4] 2> 12366 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[26 (1428431990685696000)]} 0 1
[junit4:junit4] 2> 12368 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10026 (-1428431990688841728)]} 0 0
[junit4:junit4] 2> 12369 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[26 (-1428431990689890304)]} 0 0
[junit4:junit4] 2> 12372 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10027 (1428431990691987456)]} 0 1
[junit4:junit4] 2> 12373 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[26 (-1428431990693036032)]} 0 1
[junit4:junit4] 2> 12376 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[27 (1428431990696181760)]} 0 1
[junit4:junit4] 2> 12376 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10027 (1428431990696181760)]} 0 1
[junit4:junit4] 2> 12380 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[27 (1428431990700376064)]} 0 1
[junit4:junit4] 2> 12380 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10028 (1428431990700376064)]} 0 1
[junit4:junit4] 2> 12384 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[28 (1428431990704570368)]} 0 1
[junit4:junit4] 2> 12384 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10028 (1428431990704570368)]} 0 1
[junit4:junit4] 2> 12388 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[28 (1428431990708764672)]} 0 1
[junit4:junit4] 2> 12388 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10029 (1428431990708764672)]} 0 1
[junit4:junit4] 2> 12392 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10029 (1428431990712958976)]} 0 1
[junit4:junit4] 2> 12392 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[29 (1428431990712958976)]} 0 1
[junit4:junit4] 2> 12396 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10027 (-1428431990717153280)]} 0 1
[junit4:junit4] 2> 12396 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[29 (1428431990717153280)]} 0 1
[junit4:junit4] 2> 12398 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10027 (-1428431990720299008)]} 0 0
[junit4:junit4] 2> 12399 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[27 (-1428431990721347584)]} 0 0
[junit4:junit4] 2> 12402 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10030 (1428431990723444736)]} 0 1
[junit4:junit4] 2> 12402 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[27 (-1428431990724493312)]} 0 0
[junit4:junit4] 2> 12406 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[30 (1428431990727639040)]} 0 1
[junit4:junit4] 2> 12406 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10030 (1428431990727639040)]} 0 1
[junit4:junit4] 2> 12410 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[30 (1428431990731833344)]} 0 1
[junit4:junit4] 2> 12410 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10031 (1428431990731833344)]} 0 1
[junit4:junit4] 2> 12414 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10031 (1428431990736027648)]} 0 1
[junit4:junit4] 2> 12414 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[31 (1428431990736027648)]} 0 1
[junit4:junit4] 2> 12418 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[31 (1428431990740221952)]} 0 1
[junit4:junit4] 2> 12418 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10032 (1428431990740221952)]} 0 1
[junit4:junit4] 2> 12422 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[32 (1428431990744416256)]} 0 1
[junit4:junit4] 2> 12422 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10032 (1428431990744416256)]} 0 1
[junit4:junit4] 2> 12426 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10033 (1428431990748610560)]} 0 1
[junit4:junit4] 2> 12426 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[32 (1428431990748610560)]} 0 1
[junit4:junit4] 2> 12430 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10033 (1428431990752804864)]} 0 1
[junit4:junit4] 2> 12430 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[33 (1428431990752804864)]} 0 1
[junit4:junit4] 2> 12434 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[33 (1428431990756999168)]} 0 1
[junit4:junit4] 2> 12434 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10034 (1428431990756999168)]} 0 1
[junit4:junit4] 2> 12438 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[34 (1428431990761193472)]} 0 1
[junit4:junit4] 2> 12438 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10034 (1428431990761193472)]} 0 1
[junit4:junit4] 2> 12442 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10035 (1428431990765387776)]} 0 1
[junit4:junit4] 2> 12442 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[34 (1428431990765387776)]} 0 1
[junit4:junit4] 2> 12446 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10035 (1428431990769582080)]} 0 1
[junit4:junit4] 2> 12446 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[35 (1428431990769582080)]} 0 1
[junit4:junit4] 2> 12450 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10032 (-1428431990773776384)]} 0 1
[junit4:junit4] 2> 12450 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[35 (1428431990773776384)]} 0 1
[junit4:junit4] 2> 12453 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10032 (-1428431990776922112)]} 0 1
[junit4:junit4] 2> 12453 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[32 (-1428431990777970688)]} 0 0
[junit4:junit4] 2> 12456 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10036 (1428431990780067840)]} 0 1
[junit4:junit4] 2> 12457 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[32 (-1428431990781116416)]} 0 1
[junit4:junit4] 2> 12460 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10036 (1428431990784262144)]} 0 1
[junit4:junit4] 2> 12460 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[36 (1428431990784262144)]} 0 1
[junit4:junit4] 2> 12464 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[36 (1428431990788456448)]} 0 1
[junit4:junit4] 2> 12464 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10037 (1428431990788456448)]} 0 1
[junit4:junit4] 2> 12468 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[37 (1428431990792650752)]} 0 1
[junit4:junit4] 2> 12468 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10037 (1428431990792650752)]} 0 1
[junit4:junit4] 2> 12472 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10038 (1428431990796845056)]} 0 1
[junit4:junit4] 2> 12472 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[37 (1428431990796845056)]} 0 1
[junit4:junit4] 2> 12476 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10038 (1428431990801039360)]} 0 1
[junit4:junit4] 2> 12476 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[38 (1428431990801039360)]} 0 1
[junit4:junit4] 2> 12480 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10034 (-1428431990805233664)]} 0 1
[junit4:junit4] 2> 12480 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[38 (1428431990805233664)]} 0 1
[junit4:junit4] 2> 12482 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10034 (-1428431990808379392)]} 0 0
[junit4:junit4] 2> 12483 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[34 (-1428431990809427968)]} 0 0
[junit4:junit4] 2> 12486 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10039 (1428431990811525120)]} 0 1
[junit4:junit4] 2> 12486 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[34 (-1428431990812573696)]} 0 0
[junit4:junit4] 2> 12490 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10039 (1428431990815719424)]} 0 1
[junit4:junit4] 2> 12490 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[39 (1428431990815719424)]} 0 1
[junit4:junit4] 2> 12494 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[39 (1428431990819913728)]} 0 1
[junit4:junit4] 2> 12494 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10040 (1428431990819913728)]} 0 1
[junit4:junit4] 2> 12498 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[40 (1428431990824108032)]} 0 1
[junit4:junit4] 2> 12498 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10040 (1428431990824108032)]} 0 1
[junit4:junit4] 2> 12502 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10035 (-1428431990828302336)]} 0 1
[junit4:junit4] 2> 12502 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[40 (1428431990828302336)]} 0 1
[junit4:junit4] 2> 12506 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10035 (-1428431990832496640)]} 0 0
[junit4:junit4] 2> 12506 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[35 (-1428431990832496640)]} 0 1
[junit4:junit4] 2> 12508 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[35 (-1428431990835642368)]} 0 0
[junit4:junit4] 2> 12509 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10041 (1428431990835642368)]} 0 1
[junit4:junit4] 2> 12513 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[41 (1428431990839836672)]} 0 1
[junit4:junit4] 2> 12513 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10041 (1428431990839836672)]} 0 1
[junit4:junit4] 2> 12517 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[41 (1428431990844030976)]} 0 1
[junit4:junit4] 2> 12517 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10042 (1428431990844030976)]} 0 1
[junit4:junit4] 2> 12521 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10042 (1428431990848225280)]} 0 1
[junit4:junit4] 2> 12521 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[42 (1428431990848225280)]} 0 1
[junit4:junit4] 2> 12525 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[42 (1428431990852419584)]} 0 1
[junit4:junit4] 2> 12525 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10043 (1428431990852419584)]} 0 1
[junit4:junit4] 2> 12529 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[43 (1428431990856613888)]} 0 1
[junit4:junit4] 2> 12529 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10043 (1428431990856613888)]} 0 1
[junit4:junit4] 2> 12533 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10044 (1428431990860808192)]} 0 1
[junit4:junit4] 2> 12533 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[43 (1428431990860808192)]} 0 1
[junit4:junit4] 2> 12537 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[44 (1428431990865002496)]} 0 1
[junit4:junit4] 2> 12537 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10044 (1428431990865002496)]} 0 1
[junit4:junit4] 2> 12540 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10041 (-1428431990869196800)]} 0 0
[junit4:junit4] 2> 12541 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[44 (1428431990869196800)]} 0 1
[junit4:junit4] 2> 12543 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10041 (-1428431990872342528)]} 0 0
[junit4:junit4] 2> 12544 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[41 (-1428431990873391104)]} 0 0
[junit4:junit4] 2> 12547 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10045 (1428431990875488256)]} 0 1
[junit4:junit4] 2> 12547 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[41 (-1428431990876536832)]} 0 0
[junit4:junit4] 2> 12551 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[45 (1428431990879682560)]} 0 1
[junit4:junit4] 2> 12551 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10045 (1428431990879682560)]} 0 1
[junit4:junit4] 2> 12555 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[45 (1428431990883876864)]} 0 1
[junit4:junit4] 2> 12555 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10046 (1428431990883876864)]} 0 1
[junit4:junit4] 2> 12559 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10046 (1428431990888071168)]} 0 1
[junit4:junit4] 2> 12559 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[46 (1428431990888071168)]} 0 1
[junit4:junit4] 2> 12610 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[46 (1428431990892265472)]} 0 47
[junit4:junit4] 2> 12610 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10042 (-1428431990892265472)]} 0 48
[junit4:junit4] 2> 12645 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10042 (-1428431990946791424)]} 0 32
[junit4:junit4] 2> 12645 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[42 (-1428431990945742848)]} 0 33
[junit4:junit4] 2> 12649 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[42 (-1428431990982443008)]} 0 1
[junit4:junit4] 2> 12650 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10047 (1428431990983491584)]} 0 1
[junit4:junit4] 2> 12652 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[47 (1428431990985588736)]} 0 1
[junit4:junit4] 2> 12653 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10047 (1428431990986637312)]} 0 1
[junit4:junit4] 2> 12656 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[47 (1428431990989783040)]} 0 1
[junit4:junit4] 2> 12657 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10048 (1428431990990831616)]} 0 1
[junit4:junit4] 2> 12661 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[48 (1428431990995025920)]} 0 2
[junit4:junit4] 2> 12662 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10048 (1428431990996074496)]} 0 2
[junit4:junit4] 2> 12664 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10043 (-1428431990999220224)]} 0 0
[junit4:junit4] 2> 12665 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[48 (1428431990999220224)]} 0 1
[junit4:junit4] 2> 12668 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[43 (-1428431991003414528)]} 0 0
[junit4:junit4] 2> 12668 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10043 (-1428431991003414528)]} 0 0
[junit4:junit4] 2> 12671 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[43 (-1428431991006560256)]} 0 0
[junit4:junit4] 2> 12672 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10049 (1428431991006560256)]} 0 1
[junit4:junit4] 2> 12675 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[49 (1428431991009705984)]} 0 1
[junit4:junit4] 2> 12676 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10049 (1428431991010754560)]} 0 1
[junit4:junit4] 2> 12680 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10050 (1428431991014948864)]} 0 1
[junit4:junit4] 2> 12680 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[49 (1428431991014948864)]} 0 1
[junit4:junit4] 2> 12684 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[50 (1428431991019143168)]} 0 1
[junit4:junit4] 2> 12684 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10050 (1428431991019143168)]} 0 1
[junit4:junit4] 2> 12687 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10046 (-1428431991023337472)]} 0 0
[junit4:junit4] 2> 12688 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[50 (1428431991023337472)]} 0 1
[junit4:junit4] 2> 12690 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10046 (-1428431991026483200)]} 0 0
[junit4:junit4] 2> 12691 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[46 (-1428431991027531776)]} 0 0
[junit4:junit4] 2> 12694 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10051 (1428431991029628928)]} 0 1
[junit4:junit4] 2> 12694 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[46 (-1428431991030677504)]} 0 0
[junit4:junit4] 2> 12698 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10051 (1428431991033823232)]} 0 1
[junit4:junit4] 2> 12698 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[51 (1428431991033823232)]} 0 1
[junit4:junit4] 2> 12701 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10047 (-1428431991038017536)]} 0 0
[junit4:junit4] 2> 12702 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[51 (1428431991038017536)]} 0 1
[junit4:junit4] 2> 12704 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10047 (-1428431991041163264)]} 0 0
[junit4:junit4] 2> 12705 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[47 (-1428431991042211840)]} 0 0
[junit4:junit4] 2> 12708 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10052 (1428431991044308992)]} 0 1
[junit4:junit4] 2> 12708 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[47 (-1428431991045357568)]} 0 0
[junit4:junit4] 2> 12712 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10052 (1428431991048503296)]} 0 1
[junit4:junit4] 2> 12712 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[52 (1428431991048503296)]} 0 1
[junit4:junit4] 2> 12715 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10049 (-1428431991052697600)]} 0 0
[junit4:junit4] 2> 12716 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[52 (1428431991052697600)]} 0 1
[junit4:junit4] 2> 12718 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10049 (-1428431991055843328)]} 0 0
[junit4:junit4] 2> 12719 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[49 (-1428431991056891904)]} 0 0
[junit4:junit4] 2> 12722 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10053 (1428431991058989056)]} 0 1
[junit4:junit4] 2> 12722 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[49 (-1428431991060037632)]} 0 0
[junit4:junit4] 2> 12726 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[53 (1428431991063183360)]} 0 1
[junit4:junit4] 2> 12726 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10053 (1428431991063183360)]} 0 1
[junit4:junit4] 2> 12730 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10054 (1428431991067377664)]} 0 1
[junit4:junit4] 2> 12730 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[53 (1428431991067377664)]} 0 1
[junit4:junit4] 2> 12734 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10054 (1428431991071571968)]} 0 1
[junit4:junit4] 2> 12734 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[54 (1428431991071571968)]} 0 1
[junit4:junit4] 2> 12738 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[54 (1428431991075766272)]} 0 1
[junit4:junit4] 2> 12738 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10055 (1428431991075766272)]} 0 1
[junit4:junit4] 2> 12742 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[55 (1428431991079960576)]} 0 1
[junit4:junit4] 2> 12742 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10055 (1428431991079960576)]} 0 1
[junit4:junit4] 2> 12746 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10056 (1428431991084154880)]} 0 1
[junit4:junit4] 2> 12746 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[55 (1428431991084154880)]} 0 1
[junit4:junit4] 2> 12750 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[56 (1428431991088349184)]} 0 1
[junit4:junit4] 2> 12750 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10056 (1428431991089397760)]} 0 1
[junit4:junit4] 2> 12753 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10050 (-1428431991092543488)]} 0 0
[junit4:junit4] 2> 12754 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[56 (1428431991092543488)]} 0 1
[junit4:junit4] 2> 12756 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10050 (-1428431991095689216)]} 0 0
[junit4:junit4] 2> 12757 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[50 (-1428431991096737792)]} 0 0
[junit4:junit4] 2> 12760 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10057 (1428431991098834944)]} 0 1
[junit4:junit4] 2> 12760 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[50 (-1428431991099883520)]} 0 0
[junit4:junit4] 2> 12764 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10057 (1428431991103029248)]} 0 1
[junit4:junit4] 2> 12765 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[57 (1428431991104077824)]} 0 1
[junit4:junit4] 2> 12767 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10051 (-1428431991107223552)]} 0 0
[junit4:junit4] 2> 12769 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[57 (1428431991108272128)]} 0 1
[junit4:junit4] 2> 12770 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10051 (-1428431991110369280)]} 0 0
[junit4:junit4] 2> 12772 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[51 (-1428431991112466432)]} 0 0
[junit4:junit4] 2> 12774 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10058 (1428431991113515008)]} 0 1
[junit4:junit4] 2> 12775 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[51 (-1428431991115612160)]} 0 0
[junit4:junit4] 2> 12778 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10058 (1428431991117709312)]} 0 1
[junit4:junit4] 2> 12779 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[58 (1428431991118757888)]} 0 1
[junit4:junit4] 2> 12782 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10059 (1428431991121903616)]} 0 1
[junit4:junit4] 2> 12783 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[58 (1428431991122952192)]} 0 1
[junit4:junit4] 2> 12786 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10059 (1428431991126097920)]} 0 1
[junit4:junit4] 2> 12787 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[59 (1428431991127146496)]} 0 1
[junit4:junit4] 2> 12790 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10052 (-1428431991131340800)]} 0 0
[junit4:junit4] 2> 12791 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[59 (1428431991131340800)]} 0 1
[junit4:junit4] 2> 12793 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10052 (-1428431991134486528)]} 0 0
[junit4:junit4] 2> 12794 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[52 (-1428431991135535104)]} 0 0
[junit4:junit4] 2> 12797 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10060 (1428431991137632256)]} 0 1
[junit4:junit4] 2> 12797 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[52 (-1428431991138680832)]} 0 0
[junit4:junit4] 2> 12802 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[60 (1428431991141826560)]} 0 2
[junit4:junit4] 2> 12802 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10060 (1428431991141826560)]} 0 2
[junit4:junit4] 2> 12804 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10054 (-1428431991146020864)]} 0 0
[junit4:junit4] 2> 12806 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[60 (1428431991147069440)]} 0 2
[junit4:junit4] 2> 12807 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10054 (-1428431991149166592)]} 0 0
[junit4:junit4] 2> 12808 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[54 (-1428431991150215168)]} 0 0
[junit4:junit4] 2> 12811 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10061 (1428431991152312320)]} 0 1
[junit4:junit4] 2> 12811 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[54 (-1428431991153360896)]} 0 0
[junit4:junit4] 2> 12815 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[61 (1428431991156506624)]} 0 1
[junit4:junit4] 2> 12815 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10061 (1428431991156506624)]} 0 1
[junit4:junit4] 2> 12819 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10055 (-1428431991160700928)]} 0 1
[junit4:junit4] 2> 12819 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[61 (1428431991160700928)]} 0 1
[junit4:junit4] 2> 12821 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10055 (-1428431991163846656)]} 0 0
[junit4:junit4] 2> 12822 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[55 (-1428431991164895232)]} 0 0
[junit4:junit4] 2> 12825 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10062 (1428431991166992384)]} 0 1
[junit4:junit4] 2> 12825 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[55 (-1428431991168040960)]} 0 0
[junit4:junit4] 2> 12830 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10062 (1428431991171186688)]} 0 2
[junit4:junit4] 2> 12830 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[62 (1428431991171186688)]} 0 2
[junit4:junit4] 2> 12833 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[62 (1428431991175380992)]} 0 1
[junit4:junit4] 2> 12833 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10063 (1428431991175380992)]} 0 1
[junit4:junit4] 2> 12837 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[63 (1428431991179575296)]} 0 1
[junit4:junit4] 2> 12838 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10063 (1428431991180623872)]} 0 2
[junit4:junit4] 2> 12841 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[63 (1428431991183769600)]} 0 1
[junit4:junit4] 2> 12842 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10064 (1428431991184818176)]} 0 1
[junit4:junit4] 2> 12845 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[64 (1428431991187963904)]} 0 1
[junit4:junit4] 2> 12846 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10064 (1428431991189012480)]} 0 1
[junit4:junit4] 2> 12849 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[64 (1428431991192158208)]} 0 1
[junit4:junit4] 2> 12849 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10058 (-1428431991193206784)]} 0 0
[junit4:junit4] 2> 12852 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10058 (-1428431991196352512)]} 0 0
[junit4:junit4] 2> 12852 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[58 (-1428431991196352512)]} 0 0
[junit4:junit4] 2> 12855 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[58 (-1428431991199498240)]} 0 0
[junit4:junit4] 2> 12857 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10065 (1428431991199498240)]} 0 2
[junit4:junit4] 2> 12859 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[65 (1428431991202643968)]} 0 1
[junit4:junit4] 2> 12860 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10065 (1428431991203692544)]} 0 1
[junit4:junit4] 2> 12863 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[65 (1428431991206838272)]} 0 1
[junit4:junit4] 2> 12864 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10066 (1428431991207886848)]} 0 1
[junit4:junit4] 2> 12868 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[66 (1428431991211032576)]} 0 2
[junit4:junit4] 2> 12868 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10066 (1428431991212081152)]} 0 1
[junit4:junit4] 2> 12871 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[66 (1428431991215226880)]} 0 1
[junit4:junit4] 2> 12872 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10067 (1428431991216275456)]} 0 1
[junit4:junit4] 2> 12876 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10067 (1428431991220469760)]} 0 1
[junit4:junit4] 2> 12880 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[67 (1428431991219421184)]} 0 6
[junit4:junit4] 2> 12881 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10068 (1428431991224664064)]} 0 2
[junit4:junit4] 2> 12888 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[67 (1428431991228858368)]} 0 5
[junit4:junit4] 2> 12889 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10068 (1428431991229906944)]} 0 5
[junit4:junit4] 2> 12891 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10061 (-1428431991237246976)]} 0 0
[junit4:junit4] 2> 12892 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[68 (1428431991237246977)]} 0 1
[junit4:junit4] 2> 12894 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10061 (-1428431991240392704)]} 0 0
[junit4:junit4] 2> 12896 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[68 (1428431991241441280)]} 0 1
[junit4:junit4] 2> 12898 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10069 (1428431991243538432)]} 0 1
[junit4:junit4] 2> 12899 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[61 (-1428431991245635584)]} 0 0
[junit4:junit4] 2> 12902 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10069 (1428431991247732736)]} 0 1
[junit4:junit4] 2> 12902 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[61 (-1428431991248781312)]} 0 0
[junit4:junit4] 2> 12905 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10062 (-1428431991251927040)]} 0 0
[junit4:junit4] 2> 12906 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[69 (1428431991251927041)]} 0 1
[junit4:junit4] 2> 12908 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10062 (-1428431991255072768)]} 0 0
[junit4:junit4] 2> 12910 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[69 (1428431991256121344)]} 0 1
[junit4:junit4] 2> 12912 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10070 (1428431991258218496)]} 0 1
[junit4:junit4] 2> 12913 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[62 (-1428431991260315648)]} 0 0
[junit4:junit4] 2> 12916 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10070 (1428431991262412800)]} 0 1
[junit4:junit4] 2> 12916 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[62 (-1428431991263461376)]} 0 0
[junit4:junit4] 2> 12919 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10064 (-1428431991266607104)]} 0 0
[junit4:junit4] 2> 12920 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[70 (1428431991266607105)]} 0 1
[junit4:junit4] 2> 12922 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10064 (-1428431991269752832)]} 0 0
[junit4:junit4] 2> 12924 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[70 (1428431991270801408)]} 0 1
[junit4:junit4] 2> 12926 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10071 (1428431991272898560)]} 0 1
[junit4:junit4] 2> 12927 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[64 (-1428431991274995712)]} 0 0
[junit4:junit4] 2> 12930 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10071 (1428431991277092864)]} 0 1
[junit4:junit4] 2> 12930 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[64 (-1428431991278141440)]} 0 0
[junit4:junit4] 2> 12934 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10072 (1428431991281287168)]} 0 1
[junit4:junit4] 2> 12935 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[71 (1428431991281287169)]} 0 2
[junit4:junit4] 2> 12938 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10072 (1428431991285481472)]} 0 1
[junit4:junit4] 2> 12939 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[71 (1428431991285481473)]} 0 2
[junit4:junit4] 2> 12941 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10065 (-1428431991289675777)]} 0 0
[junit4:junit4] 2> 12942 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[72 (1428431991289675776)]} 0 1
[junit4:junit4] 2> 12944 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10065 (-1428431991292821504)]} 0 0
[junit4:junit4] 2> 12946 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[72 (1428431991293870080)]} 0 1
[junit4:junit4] 2> 12948 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10073 (1428431991295967232)]} 0 1
[junit4:junit4] 2> 12950 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[65 (-1428431991298064384)]} 0 1
[junit4:junit4] 2> 12952 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10073 (1428431991300161536)]} 0 1
[junit4:junit4] 2> 12953 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[65 (-1428431991302258688)]} 0 0
[junit4:junit4] 2> 12956 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10074 (1428431991304355840)]} 0 1
[junit4:junit4] 2> 12957 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[73 (1428431991305404416)]} 0 1
[junit4:junit4] 2> 12960 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10074 (1428431991308550144)]} 0 1
[junit4:junit4] 2> 12961 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[73 (1428431991309598720)]} 0 1
[junit4:junit4] 2> 12964 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10075 (1428431991312744448)]} 0 1
[junit4:junit4] 2> 12965 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[74 (1428431991313793024)]} 0 1
[junit4:junit4] 2> 12968 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10075 (1428431991316938752)]} 0 1
[junit4:junit4] 2> 12969 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[74 (1428431991317987328)]} 0 1
[junit4:junit4] 2> 12971 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10069 (-1428431991321133056)]} 0 0
[junit4:junit4] 2> 12973 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[75 (1428431991322181632)]} 0 1
[junit4:junit4] 2> 12974 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10069 (-1428431991324278784)]} 0 0
[junit4:junit4] 2> 12977 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[75 (1428431991326375936)]} 0 1
[junit4:junit4] 2> 12978 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10076 (1428431991327424512)]} 0 1
[junit4:junit4] 2> 12980 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[69 (-1428431991330570240)]} 0 0
[junit4:junit4] 2> 12982 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10076 (1428431991331618816)]} 0 1
[junit4:junit4] 2> 12983 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[69 (-1428431991333715968)]} 0 0
[junit4:junit4] 2> 12986 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10077 (1428431991335813120)]} 0 1
[junit4:junit4] 2> 12987 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[76 (1428431991336861696)]} 0 1
[junit4:junit4] 2> 12990 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10077 (1428431991340007424)]} 0 1
[junit4:junit4] 2> 12991 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[76 (1428431991341056000)]} 0 1
[junit4:junit4] 2> 12994 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10078 (1428431991344201728)]} 0 1
[junit4:junit4] 2> 12995 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[77 (1428431991345250304)]} 0 1
[junit4:junit4] 2> 12998 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10078 (1428431991348396032)]} 0 1
[junit4:junit4] 2> 12999 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[77 (1428431991349444608)]} 0 1
[junit4:junit4] 2> 13001 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10073 (-1428431991352590336)]} 0 0
[junit4:junit4] 2> 13003 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[78 (1428431991353638912)]} 0 1
[junit4:junit4] 2> 13004 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10073 (-1428431991355736064)]} 0 0
[junit4:junit4] 2> 13007 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[78 (1428431991357833216)]} 0 1
[junit4:junit4] 2> 13008 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10079 (1428431991358881792)]} 0 1
[junit4:junit4] 2> 13010 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[73 (-1428431991362027520)]} 0 0
[junit4:junit4] 2> 13012 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10079 (1428431991363076096)]} 0 1
[junit4:junit4] 2> 13013 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[73 (-1428431991365173248)]} 0 0
[junit4:junit4] 2> 13015 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10074 (-1428431991367270400)]} 0 0
[junit4:junit4] 2> 13017 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[79 (1428431991368318976)]} 0 1
[junit4:junit4] 2> 13018 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10074 (-1428431991370416128)]} 0 0
[junit4:junit4] 2> 13021 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[79 (1428431991372513280)]} 0 1
[junit4:junit4] 2> 13022 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10080 (1428431991373561856)]} 0 1
[junit4:junit4] 2> 13024 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[74 (-1428431991376707584)]} 0 0
[junit4:junit4] 2> 13026 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10080 (1428431991377756160)]} 0 1
[junit4:junit4] 2> 13027 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[74 (-1428431991379853312)]} 0 0
[junit4:junit4] 2> 13029 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10075 (-1428431991381950464)]} 0 0
[junit4:junit4] 2> 13031 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[80 (1428431991382999040)]} 0 1
[junit4:junit4] 2> 13032 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10075 (-1428431991385096192)]} 0 0
[junit4:junit4] 2> 13035 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[80 (1428431991387193344)]} 0 1
[junit4:junit4] 2> 13036 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10081 (1428431991388241920)]} 0 1
[junit4:junit4] 2> 13038 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[75 (-1428431991391387648)]} 0 0
[junit4:junit4] 2> 13040 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10081 (1428431991392436224)]} 0 1
[junit4:junit4] 2> 13041 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[75 (-1428431991394533376)]} 0 0
[junit4:junit4] 2> 13043 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10077 (-1428431991396630528)]} 0 0
[junit4:junit4] 2> 13045 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[81 (1428431991397679104)]} 0 1
[junit4:junit4] 2> 13048 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10077 (-1428431991401873408)]} 0 0
[junit4:junit4] 2> 13049 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[81 (1428431991401873409)]} 0 1
[junit4:junit4] 2> 13052 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[77 (-1428431991406067712)]} 0 0
[junit4:junit4] 2> 13053 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10082 (1428431991405019136)]} 0 2
[junit4:junit4] 2> 13055 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[77 (-1428431991409213440)]} 0 0
[junit4:junit4] 2> 13057 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10082 (1428431991410262016)]} 0 1
[junit4:junit4] 2> 13060 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[82 (1428431991412359168)]} 0 2
[junit4:junit4] 2> 13061 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10083 (1428431991414456320)]} 0 1
[junit4:junit4] 2> 13064 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[82 (1428431991417602048)]} 0 1
[junit4:junit4] 2> 13065 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10083 (1428431991418650624)]} 0 1
[junit4:junit4] 2> 13068 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[83 (1428431991421796352)]} 0 1
[junit4:junit4] 2> 13070 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10084 (1428431991422844928)]} 0 2
[junit4:junit4] 2> 13072 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[83 (1428431991425990656)]} 0 1
[junit4:junit4] 2> 13074 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10084 (1428431991428087808)]} 0 1
[junit4:junit4] 2> 13077 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[84 (1428431991430184960)]} 0 1
[junit4:junit4] 2> 13078 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10085 (1428431991432282112)]} 0 1
[junit4:junit4] 2> 13080 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[84 (1428431991434379264)]} 0 1
[junit4:junit4] 2> 13082 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10085 (1428431991436476416)]} 0 1
[junit4:junit4] 2> 13084 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[85 (1428431991438573568)]} 0 1
[junit4:junit4] 2> 13087 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10086 (1428431991440670720)]} 0 2
[junit4:junit4] 2> 13088 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[85 (1428431991442767872)]} 0 1
[junit4:junit4] 2> 13091 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10086 (1428431991445913600)]} 0 1
[junit4:junit4] 2> 13092 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[86 (1428431991446962176)]} 0 1
[junit4:junit4] 2> 13094 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10078 (-1428431991450107904)]} 0 0
[junit4:junit4] 2> 13096 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[86 (1428431991451156480)]} 0 1
[junit4:junit4] 2> 13097 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10078 (-1428431991453253632)]} 0 0
[junit4:junit4] 2> 13099 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[78 (-1428431991455350784)]} 0 0
[junit4:junit4] 2> 13101 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10087 (1428431991456399360)]} 0 1
[junit4:junit4] 2> 13102 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[78 (-1428431991458496512)]} 0 0
[junit4:junit4] 2> 13105 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10087 (1428431991460593664)]} 0 1
[junit4:junit4] 2> 13106 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[87 (1428431991461642240)]} 0 1
[junit4:junit4] 2> 13109 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10088 (1428431991464787968)]} 0 1
[junit4:junit4] 2> 13110 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[87 (1428431991465836544)]} 0 1
[junit4:junit4] 2> 13113 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10088 (1428431991468982272)]} 0 1
[junit4:junit4] 2> 13114 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[88 (1428431991470030848)]} 0 1
[junit4:junit4] 2> 13117 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10089 (1428431991473176576)]} 0 1
[junit4:junit4] 2> 13118 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[88 (1428431991474225152)]} 0 1
[junit4:junit4] 2> 13121 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10089 (1428431991477370880)]} 0 1
[junit4:junit4] 2> 13122 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[89 (1428431991478419456)]} 0 1
[junit4:junit4] 2> 13125 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10090 (1428431991481565184)]} 0 1
[junit4:junit4] 2> 13126 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[89 (1428431991482613760)]} 0 1
[junit4:junit4] 2> 13129 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10090 (1428431991485759488)]} 0 1
[junit4:junit4] 2> 13130 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[90 (1428431991486808064)]} 0 1
[junit4:junit4] 2> 13133 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10091 (1428431991489953792)]} 0 1
[junit4:junit4] 2> 13134 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[90 (1428431991491002368)]} 0 1
[junit4:junit4] 2> 13137 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10091 (1428431991494148096)]} 0 1
[junit4:junit4] 2> 13138 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[91 (1428431991495196672)]} 0 1
[junit4:junit4] 2> 13141 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10092 (1428431991498342400)]} 0 1
[junit4:junit4] 2> 13142 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[91 (1428431991499390976)]} 0 1
[junit4:junit4] 2> 13145 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10092 (1428431991503585280)]} 0 1
[junit4:junit4] 2> 13146 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[92 (1428431991503585280)]} 0 1
[junit4:junit4] 2> 13148 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10079 (-1428431991506731008)]} 0 0
[junit4:junit4] 2> 13150 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[92 (1428431991507779584)]} 0 1
[junit4:junit4] 2> 13151 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10079 (-1428431991509876736)]} 0 0
[junit4:junit4] 2> 13153 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[79 (-1428431991511973888)]} 0 0
[junit4:junit4] 2> 13155 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10093 (1428431991513022464)]} 0 1
[junit4:junit4] 2> 13156 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[79 (-1428431991515119616)]} 0 0
[junit4:junit4] 2> 13159 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10093 (1428431991517216768)]} 0 1
[junit4:junit4] 2> 13160 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[93 (1428431991518265344)]} 0 1
[junit4:junit4] 2> 13163 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10094 (1428431991521411072)]} 0 1
[junit4:junit4] 2> 13164 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[93 (1428431991522459648)]} 0 1
[junit4:junit4] 2> 13167 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10094 (1428431991525605376)]} 0 1
[junit4:junit4] 2> 13168 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[94 (1428431991526653952)]} 0 1
[junit4:junit4] 2> 13170 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10080 (-1428431991529799680)]} 0 0
[junit4:junit4] 2> 13172 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[94 (1428431991530848256)]} 0 1
[junit4:junit4] 2> 13173 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10080 (-1428431991532945408)]} 0 0
[junit4:junit4] 2> 13175 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[80 (-1428431991535042560)]} 0 0
[junit4:junit4] 2> 13177 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10095 (1428431991536091136)]} 0 1
[junit4:junit4] 2> 13178 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[80 (-1428431991538188288)]} 0 0
[junit4:junit4] 2> 13181 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10095 (1428431991540285440)]} 0 1
[junit4:junit4] 2> 13182 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[95 (1428431991541334016)]} 0 1
[junit4:junit4] 2> 13185 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10096 (1428431991544479744)]} 0 1
[junit4:junit4] 2> 13186 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[95 (1428431991545528320)]} 0 1
[junit4:junit4] 2> 13189 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10096 (1428431991548674048)]} 0 1
[junit4:junit4] 2> 13190 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[96 (1428431991549722624)]} 0 1
[junit4:junit4] 2> 13194 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10097 (1428431991553916928)]} 0 1
[junit4:junit4] 2> 13194 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[96 (1428431991553916928)]} 0 1
[junit4:junit4] 2> 13198 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[97 (1428431991558111232)]} 0 1
[junit4:junit4] 2> 13198 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10097 (1428431991558111232)]} 0 1
[junit4:junit4] 2> 13202 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10098 (1428431991562305536)]} 0 1
[junit4:junit4] 2> 13202 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[97 (1428431991562305536)]} 0 1
[junit4:junit4] 2> 13206 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10098 (1428431991566499840)]} 0 1
[junit4:junit4] 2> 13206 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[98 (1428431991566499840)]} 0 1
[junit4:junit4] 2> 13209 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10081 (-1428431991570694144)]} 0 0
[junit4:junit4] 2> 13210 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[98 (1428431991570694144)]} 0 1
[junit4:junit4] 2> 13212 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10081 (-1428431991573839872)]} 0 0
[junit4:junit4] 2> 13213 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[81 (-1428431991574888448)]} 0 0
[junit4:junit4] 2> 13215 T1662 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 13216 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10099 (1428431991576985600)]} 0 1
[junit4:junit4] 2> 13216 T1662 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "core_node_name":"3",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:35798_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:35798"}
[junit4:junit4] 2> 13216 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[81 (-1428431991578034176)]} 0 0
[junit4:junit4] 2> 13220 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10099 (1428431991581179904)]} 0 1
[junit4:junit4] 2> 13220 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[99 (1428431991581179904)]} 0 1
[junit4:junit4] 2> 13223 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10086 (-1428431991585374208)]} 0 0
[junit4:junit4] 2> 13224 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[99 (1428431991585374208)]} 0 1
[junit4:junit4] 2> 13226 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10086 (-1428431991588519936)]} 0 0
[junit4:junit4] 2> 13227 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[86 (-1428431991589568512)]} 0 0
[junit4:junit4] 2> 13230 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10100 (1428431991591665664)]} 0 1
[junit4:junit4] 2> 13230 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[86 (-1428431991592714240)]} 0 0
[junit4:junit4] 2> 13232 T1681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13232 T1668 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13232 T1661 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13232 T1697 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13234 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[100 (1428431991595859968)]} 0 1
[junit4:junit4] 2> 13234 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10100 (1428431991595859968)]} 0 1
[junit4:junit4] 2> 13238 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10101 (1428431991600054272)]} 0 1
[junit4:junit4] 2> C126_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:35798_, base_url=http://127.0.0.1:35798}
[junit4:junit4] 2> 13249 T1687 C126 P35798 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362258889890/jetty2
[junit4:junit4] 2> 13251 T1687 C126 P35798 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4f57516c lockFactory=org.apache.lucene.store.NativeFSLockFactory@545dd35e)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 13251 T1687 C126 P35798 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 13254 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[100 (1428431991600054272)]} 0 6
[junit4:junit4] 2> 13254 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10101 (1428431991605297152)]} 0 3
[junit4:junit4] 2> 13255 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[100 (1428431991600054272)]} 0 18
[junit4:junit4] 2> 13255 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10101 (1428431991605297152)]} 0 13
[junit4:junit4] 2> 13258 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[101 (1428431991621025792)]} 0 1
[junit4:junit4] 2> 13258 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10087 (-1428431991622074368)]} 0 0
[junit4:junit4] 2> 13264 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10087 (-1428431991625220097)]} 0 0
[junit4:junit4] 2> 13265 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10087 (-1428431991625220097)]} 0 4
[junit4:junit4] 2> 13265 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[101 (1428431991625220096)]} 0 1
[junit4:junit4] 2> 13266 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[101 (1428431991625220096)]} 0 5
[junit4:junit4] 2> 13269 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10102 (1428431991632560128)]} 0 1
[junit4:junit4] 2> 13269 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[87 (-1428431991633608704)]} 0 0
[junit4:junit4] 2> 13276 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[87 (-1428431991636754433)]} 0 1
[junit4:junit4] 2> 13277 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10102 (1428431991636754432)]} 0 2
[junit4:junit4] 2> 13277 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[87 (-1428431991636754433)]} 0 5
[junit4:junit4] 2> 13277 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10102 (1428431991636754432)]} 0 5
[junit4:junit4] 2> 13281 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10103 (1428431991645143040)]} 0 1
[junit4:junit4] 2> 13282 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[102 (1428431991645143041)]} 0 2
[junit4:junit4] 2> 13288 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10103 (1428431991649337344)]} 0 1
[junit4:junit4] 2> 13289 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10103 (1428431991649337344)]} 0 5
[junit4:junit4] 2> 13289 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[102 (1428431991649337345)]} 0 1
[junit4:junit4] 2> 13290 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[102 (1428431991649337345)]} 0 6
[junit4:junit4] 2> 13292 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10088 (-1428431991657725952)]} 0 0
[junit4:junit4] 2> 13294 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[103 (1428431991658774528)]} 0 1
[junit4:junit4] 2> 13298 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10088 (-1428431991660871680)]} 0 0
[junit4:junit4] 2> 13299 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10088 (-1428431991660871680)]} 0 4
[junit4:junit4] 2> 13301 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[103 (1428431991662968832)]} 0 1
[junit4:junit4] 2> 13302 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[103 (1428431991662968832)]} 0 5
[junit4:junit4] 2> 13303 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10104 (1428431991668211712)]} 0 1
[junit4:junit4] 2> 13304 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[88 (-1428431991670308864)]} 0 0
[junit4:junit4] 2> 13310 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10104 (1428431991672406016)]} 0 1
[junit4:junit4] 2> 13310 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[88 (-1428431991673454592)]} 0 0
[junit4:junit4] 2> 13311 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10104 (1428431991672406016)]} 0 5
[junit4:junit4] 2> 13311 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[88 (-1428431991673454592)]} 0 4
[junit4:junit4] 2> 13313 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10090 (-1428431991679746048)]} 0 0
[junit4:junit4] 2> 13315 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[104 (1428431991680794624)]} 0 1
[junit4:junit4] 2> 13319 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10090 (-1428431991682891776)]} 0 0
[junit4:junit4] 2> 13320 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10090 (-1428431991682891776)]} 0 4
[junit4:junit4] 2> 13322 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[104 (1428431991684988928)]} 0 1
[junit4:junit4] 2> 13323 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[104 (1428431991684988928)]} 0 5
[junit4:junit4] 2> 13324 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10105 (1428431991690231808)]} 0 1
[junit4:junit4] 2> 13325 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[90 (-1428431991692328960)]} 0 0
[junit4:junit4] 2> 13331 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10105 (1428431991694426112)]} 0 1
[junit4:junit4] 2> 13331 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[90 (-1428431991695474688)]} 0 0
[junit4:junit4] 2> 13332 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10105 (1428431991694426112)]} 0 5
[junit4:junit4] 2> 13332 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[90 (-1428431991695474688)]} 0 4
[junit4:junit4] 2> 13335 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10092 (-1428431991702814720)]} 0 0
[junit4:junit4] 2> 13336 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[105 (1428431991702814721)]} 0 1
[junit4:junit4] 2> 13341 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10092 (-1428431991705960448)]} 0 0
[junit4:junit4] 2> 13342 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10092 (-1428431991705960448)]} 0 4
[junit4:junit4] 2> 13343 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[105 (1428431991707009024)]} 0 1
[junit4:junit4] 2> 13344 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[105 (1428431991707009024)]} 0 5
[junit4:junit4] 2> 13346 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10106 (1428431991713300480)]} 0 1
[junit4:junit4] 2> 13347 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[92 (-1428431991715397632)]} 0 0
[junit4:junit4] 2> 13353 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10106 (1428431991717494784)]} 0 1
[junit4:junit4] 2> 13353 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[92 (-1428431991718543360)]} 0 0
[junit4:junit4] 2> 13354 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10106 (1428431991717494784)]} 0 5
[junit4:junit4] 2> 13354 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[92 (-1428431991718543360)]} 0 4
[junit4:junit4] 2> 13357 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10093 (-1428431991725883392)]} 0 0
[junit4:junit4] 2> 13358 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[106 (1428431991725883393)]} 0 1
[junit4:junit4] 2> 13363 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10093 (-1428431991729029120)]} 0 0
[junit4:junit4] 2> 13364 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10093 (-1428431991729029120)]} 0 4
[junit4:junit4] 2> 13365 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[106 (1428431991730077696)]} 0 1
[junit4:junit4] 2> 13366 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[106 (1428431991730077696)]} 0 5
[junit4:junit4] 2> 13368 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10107 (1428431991736369152)]} 0 1
[junit4:junit4] 2> 13368 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[93 (-1428431991737417728)]} 0 0
[junit4:junit4] 2> 13375 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[93 (-1428431991740563456)]} 0 1
[junit4:junit4] 2> 13375 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10107 (1428431991740563457)]} 0 1
[junit4:junit4] 2> 13375 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[93 (-1428431991740563456)]} 0 4
[junit4:junit4] 2> 13376 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10107 (1428431991740563457)]} 0 5
[junit4:junit4] 2> 13379 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[107 (1428431991747903488)]} 0 1
[junit4:junit4] 2> 13380 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10108 (1428431991748952064)]} 0 1
[junit4:junit4] 2> 13386 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[107 (1428431991752097792)]} 0 1
[junit4:junit4] 2> 13387 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[107 (1428431991752097792)]} 0 5
[junit4:junit4] 2> 13387 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10108 (1428431991753146368)]} 0 1
[junit4:junit4] 2> 13388 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10108 (1428431991753146368)]} 0 5
[junit4:junit4] 2> 13389 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[108 (1428431991758389248)]} 0 1
[junit4:junit4] 2> 13392 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10109 (1428431991761534976)]} 0 1
[junit4:junit4] 2> 13396 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[108 (1428431991762583552)]} 0 1
[junit4:junit4] 2> 13397 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[108 (1428431991762583552)]} 0 5
[junit4:junit4] 2> 13399 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10109 (1428431991765729280)]} 0 1
[junit4:junit4] 2> 13400 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10109 (1428431991765729280)]} 0 5
[junit4:junit4] 2> 13400 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[109 (1428431991769923584)]} 0 1
[junit4:junit4] 2> 13403 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10110 (1428431991773069312)]} 0 1
[junit4:junit4] 2> 13407 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[109 (1428431991774117888)]} 0 1
[junit4:junit4] 2> 13408 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[109 (1428431991774117888)]} 0 5
[junit4:junit4] 2> 13410 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10110 (1428431991777263616)]} 0 1
[junit4:junit4] 2> 13411 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10110 (1428431991777263616)]} 0 5
[junit4:junit4] 2> 13411 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[110 (1428431991781457920)]} 0 1
[junit4:junit4] 2> 13413 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10097 (-1428431991784603648)]} 0 0
[junit4:junit4] 2> 13418 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[110 (1428431991785652224)]} 0 1
[junit4:junit4] 2> 13418 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10097 (-1428431991787749376)]} 0 0
[junit4:junit4] 2> 13419 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[110 (1428431991785652224)]} 0 5
[junit4:junit4] 2> 13419 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[10097 (-1428431991787749376)]} 0 3
[junit4:junit4] 2> 13421 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[97 (-1428431991792992256)]} 0 0
[junit4:junit4] 2> 13423 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10111 (1428431991794040832)]} 0 1
[junit4:junit4] 2> 13426 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[97 (-1428431991796137984)]} 0 0
[junit4:junit4] 2> 13427 T1672 C128 P49705 /update {wt=javabin&version=2} {delete=[97 (-1428431991796137984)]} 0 3
[junit4:junit4] 2> 13430 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10111 (1428431991798235136)]} 0 1
[junit4:junit4] 2> 13431 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[10111 (1428431991798235136)]} 0 5
[junit4:junit4] 2> 13431 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[111 (1428431991802429440)]} 0 1
[junit4:junit4] 2> 13435 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10112 (1428431991806623744)]} 0 1
[junit4:junit4] 2> 13439 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[111 (1428431991807672320)]} 0 1
[junit4:junit4] 2> 13440 T1673 C128 P49705 /update {wt=javabin&version=2} {add=[111 (1428431991807672320)]} 0 6
[junit4:junit4] 2> 13442 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10112 (1428431991810818048)]} 0 1
[junit4:junit4] 2> 13443 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10112 (1428431991810818048)]} 0 5
[junit4:junit4] 2> 13444 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[112 (1428431991816060928)]} 0 1
[junit4:junit4] 2> 13446 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10098 (-1428431991819206656)]} 0 0
[junit4:junit4] 2> 13451 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[112 (1428431991820255232)]} 0 1
[junit4:junit4] 2> 13452 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[112 (1428431991820255232)]} 0 5
[junit4:junit4] 2> 13452 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10098 (-1428431991822352384)]} 0 0
[junit4:junit4] 2> 13453 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10098 (-1428431991822352384)]} 0 4
[junit4:junit4] 2> 13455 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[98 (-1428431991828643840)]} 0 0
[junit4:junit4] 2> 13457 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10113 (1428431991829692416)]} 0 1
[junit4:junit4] 2> 13461 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[98 (-1428431991831789568)]} 0 0
[junit4:junit4] 2> 13462 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[98 (-1428431991831789568)]} 0 4
[junit4:junit4] 2> 13464 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10113 (1428431991833886720)]} 0 1
[junit4:junit4] 2> 13465 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10113 (1428431991833886720)]} 0 5
[junit4:junit4] 2> 13466 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[113 (1428431991839129600)]} 0 1
[junit4:junit4] 2> 13467 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10101 (-1428431991841226752)]} 0 0
[junit4:junit4] 2> 13473 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[113 (1428431991843323904)]} 0 1
[junit4:junit4] 2> 13473 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10101 (-1428431991844372480)]} 0 0
[junit4:junit4] 2> 13474 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[113 (1428431991843323904)]} 0 5
[junit4:junit4] 2> 13474 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10101 (-1428431991844372480)]} 0 4
[junit4:junit4] 2> 13476 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[101 (-1428431991850663936)]} 0 0
[junit4:junit4] 2> 13479 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[10114 (1428431991851712512)]} 0 1
[junit4:junit4] 2> 13482 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[101 (-1428431991853809664)]} 0 0
[junit4:junit4] 2> 13483 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[101 (-1428431991853809664)]} 0 4
[junit4:junit4] 2> 13485 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10114 (1428431991855906816)]} 0 1
[junit4:junit4] 2> 13486 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10114 (1428431991855906816)]} 0 5
[junit4:junit4] 2> 13487 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[114 (1428431991861149696)]} 0 1
[junit4:junit4] 2> 13488 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10107 (-1428431991863246848)]} 0 0
[junit4:junit4] 2> 13493 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10107 (-1428431991866392576)]} 0 0
[junit4:junit4] 2> 13494 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10107 (-1428431991866392576)]} 0 3
[junit4:junit4] 2> 13494 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[114 (1428431991865344000)]} 0 1
[junit4:junit4] 2> 13495 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[114 (1428431991865344000)]} 0 5
[junit4:junit4] 2> 13498 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10115 (1428431991872684032)]} 0 1
[junit4:junit4] 2> 13498 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[107 (-1428431991873732608)]} 0 0
[junit4:junit4] 2> 13504 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[107 (-1428431991876878336)]} 0 0
[junit4:junit4] 2> 13505 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[107 (-1428431991876878336)]} 0 4
[junit4:junit4] 2> 13506 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10115 (1428431991876878337)]} 0 1
[junit4:junit4] 2> 13507 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10115 (1428431991876878337)]} 0 6
[junit4:junit4] 2> 13509 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[115 (1428431991884218368)]} 0 1
[junit4:junit4] 2> 13510 T1652 C127 P55904 /update {wt=javabin&version=2} {delete=[10108 (-1428431991886315520)]} 0 0
[junit4:junit4] 2> 13515 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10108 (-1428431991889461248)]} 0 0
[junit4:junit4] 2> 13516 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10108 (-1428431991889461248)]} 0 3
[junit4:junit4] 2> 13516 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[115 (1428431991888412672)]} 0 1
[junit4:junit4] 2> 13517 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[115 (1428431991888412672)]} 0 5
[junit4:junit4] 2> 13520 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10116 (1428431991895752704)]} 0 1
[junit4:junit4] 2> 13520 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[108 (-1428431991896801280)]} 0 0
[junit4:junit4] 2> 13526 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[108 (-1428431991899947008)]} 0 0
[junit4:junit4] 2> 13527 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10116 (1428431991899947009)]} 0 1
[junit4:junit4] 2> 13528 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10116 (1428431991899947009)]} 0 5
[junit4:junit4] 2> 13528 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[108 (-1428431991899947008)]} 0 5
[junit4:junit4] 2> 13530 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[10110 (-1428431991907287040)]} 0 0
[junit4:junit4] 2> 13532 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[116 (1428431991908335616)]} 0 1
[junit4:junit4] 2> 13536 T1687 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10110 (-1428431991910432768)]} 0 0
[junit4:junit4] 2> 13537 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[10110 (-1428431991910432768)]} 0 4
[junit4:junit4] 2> 13539 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[116 (1428431991912529920)]} 0 1
[junit4:junit4] 2> 13540 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[116 (1428431991912529920)]} 0 5
[junit4:junit4] 2> 13541 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10117 (1428431991917772800)]} 0 1
[junit4:junit4] 2> 13542 T1651 C127 P55904 /update {wt=javabin&version=2} {delete=[110 (-1428431991919869952)]} 0 0
[junit4:junit4] 2> 13547 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[110 (-1428431991923015680)]} 0 0
[junit4:junit4] 2> 13548 T1673 C128 P49705 /update {wt=javabin&version=2} {delete=[110 (-1428431991923015680)]} 0 3
[junit4:junit4] 2> 13551 T1688 C126 P35798 /update {distrib.from=http://127.0.0.1:49705/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10117 (1428431991921967104)]} 0 1
[junit4:junit4] 2> 13552 T1672 C128 P49705 /update {wt=javabin&version=2} {add=[10117 (1428431991921967104)]} 0 8
[junit4:junit4] 2> 13552 T1651 C127 P55904 /update {wt=javabin&version=2} {add=[117 (1428431991929307136)]} 0 1
[junit4:junit4] 2> 13555 T1652 C127 P55904 /update {wt=javabin&version=2} {add=[10118 (1428431991932452864)]} 0 1
[junit4:junit4] 2>
[...truncated too long message...]
a:212)
[junit4:junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
[junit4:junit4] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4] > at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4] > at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4] > at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4] > at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4] > at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4] > at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4] > at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4] > at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4] > at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4] > at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4] > at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4] > at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2> 138204 T2712 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> 138209 T2711 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4] 2> 138316 T2715 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4] 2> 139123 T2804 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 139123 T2804 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 139123 T2804 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 139123 T2804 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=ms_MY, timezone=Europe/Uzhgorod
[junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_09 (64-bit)/cpus=16,threads=5,free=92262120,total=536870912
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestConfig, SynonymTokenizerTest, TestQuerySenderListener, TestZkChroot, TestCodecSupport, SpellPossibilityIteratorTest, SimpleFacetsTest, SuggesterTest, XmlUpdateRequestHandlerTest, SpatialFilterTest, PluginInfoTest, ClusterStateTest, TestMergePolicyConfig, TestDFRSimilarityFactory, TestSolrJ, SolrRequestParserTest, TestSearchPerf, TestSolrDiscoveryProperties, TestElisionMultitermQuery, TestLFUCache, ShardRoutingTest, SolrIndexConfigTest, TestPHPSerializedResponseWriter, TestComponentsName, SolrCoreCheckLockOnStartupTest, CollectionsAPIDistributedZkTest, TestUpdate, SolrPluginUtilsTest, TestLazyCores, FileUtilsTest, TestSolrCoreProperties, TestPropInject, TestRangeQuery, OutputWriterTest, SpellingQueryConverterTest, PrimUtilsTest, SchemaVersionSpecificBehaviorTest, TestFaceting, MBeansHandlerTest, TestBinaryResponseWriter, TestGroupingSearch, SampleTest, TestCoreContainer, IndexBasedSpellCheckerTest, SpellCheckCollatorTest, TestSolr4Spatial, TestFastOutputStream, DateMathParserTest, TestPseudoReturnFields, LeaderElectionIntegrationTest, SearchHandlerTest, TestUtils, NumericFieldsTest, TestExtendedDismaxParser, DebugComponentTest, TestDefaultSimilarityFactory, TestFoldingMultitermQuery, QueryElevationComponentTest, SuggesterWFSTTest, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, TestArbitraryIndexDir, TestBadConfig, TestLMDirichletSimilarityFactory, BasicDistributedZkTest, RecoveryZkTest, SpellCheckComponentTest, LeaderElectionTest, TestRealTimeGet, TestRandomFaceting, WordBreakSolrSpellCheckerTest, TestHashPartitioner, DistributedTermsComponentTest, StatsComponentTest, TestJoin, SortByFunctionTest, BasicFunctionalityTest, TestTrie, CacheHeaderTest, TestCSVLoader, PolyFieldTest, DistributedQueryElevationComponentTest, QueryParsingTest, XsltUpdateRequestHandlerTest, TestQueryTypes, PingRequestHandlerTest, TestSurroundQueryParser, TestReversedWildcardFilterFactory, JSONWriterTest, TestOmitPositions, CopyFieldTest, TestQueryUtils, SuggesterTSTTest, UniqFieldsUpdateProcessorFactoryTest, UpdateParamsTest, CSVRequestHandlerTest, NotRequiredUniqueKeyTest, BadIndexSchemaTest, TestLuceneMatchVersion, UpdateRequestProcessorFactoryTest, PrimitiveFieldTypeTest, SOLR749Test, DefaultValueUpdateProcessorTest, TestPerFieldSimilarity, LegacyHTMLStripCharFilterTest, TestSolrXMLSerializer, DateFieldTest, TestLRUCache, RAMDirectoryFactoryTest, PreAnalyzedFieldTest, TestDocumentBuilder, TestRandomDVFaceting, OverseerCollectionProcessorTest, SliceStateTest, SliceStateUpdateTest, SyncSliceTest]
[junit4:junit4] Completed on J0 in 139.24s, 1 test, 1 failure <<< FAILURES!
[...truncated 12 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:381: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:361: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build.xml:183: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/common-build.xml:449: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:1207: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:870: There were test failures: 260 suites, 1095 tests, 2 suite-level errors, 1 error, 1 failure, 23 ignored (4 assumptions)
Total time: 45 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure