You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/01/02 08:40:32 UTC
[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.7.0_09) - Build #
2344 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/2344/
Java: 32bit/jdk1.7.0_09 -client -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.RecoveryZkTest.testDistribSearch
Error Message:
There are still nodes recoverying - waited for 230 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 230 seconds
at __randomizedtesting.SeedInfo.seed([DBE71B14E2A09240:5A01950C95FFF27C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:175)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:125)
at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:76)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:794)
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)
Build Log:
[...truncated 8986 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4] 2> 0 T2406 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /ux_xgd/j
[junit4:junit4] 2> 3 T2406 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-RecoveryZkTest-1357111387647
[junit4:junit4] 2> 5 T2406 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 6 T2407 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 9 T2407 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 9 T2407 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 9 T2407 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 10 T2407 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 12 T2407 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4] 2> 92 T2406 oasc.ZkTestServer.run start zk server on port:50358
[junit4:junit4] 2> 92 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1dcc26a
[junit4:junit4] 2> 93 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 94 T2412 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 94 T2412 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 94 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50361
[junit4:junit4] 2> 95 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50361
[junit4:junit4] 2> 95 T2410 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 100 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090000 with negotiated timeout 10000 for client /127.0.0.1:50361
[junit4:junit4] 2> 100 T2412 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090000, negotiated timeout = 10000
[junit4:junit4] 2> 100 T2413 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1dcc26a name:ZooKeeperConnection Watcher:127.0.0.1:50358 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 100 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 101 T2406 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 105 T2411 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13bfa2466090000
[junit4:junit4] 2> 106 T2413 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 106 T2406 oaz.ZooKeeper.close Session: 0x13bfa2466090000 closed
[junit4:junit4] 2> 106 T2408 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13bfa2466090000, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 107 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@17b3521
[junit4:junit4] 2> 109 T2408 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:50361 which had sessionid 0x13bfa2466090000
[junit4:junit4] 2> 110 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 111 T2414 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 111 T2414 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 112 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50364
[junit4:junit4] 2> 113 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50364
[junit4:junit4] 2> 115 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090001 with negotiated timeout 10000 for client /127.0.0.1:50364
[junit4:junit4] 2> 115 T2414 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090001, negotiated timeout = 10000
[junit4:junit4] 2> 115 T2415 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17b3521 name:ZooKeeperConnection Watcher:127.0.0.1:50358/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 115 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 115 T2406 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 120 T2406 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 123 T2406 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 126 T2406 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 131 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 131 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 143 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4] 2> 144 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 148 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4] 2> 149 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 152 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4] 2> 153 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 156 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4] 2> 156 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 160 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 160 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 165 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 165 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 168 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 169 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 172 T2406 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4] 2> 173 T2406 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 176 T2411 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13bfa2466090001
[junit4:junit4] 2> 177 T2408 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13bfa2466090001, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 177 T2406 oaz.ZooKeeper.close Session: 0x13bfa2466090001 closed
[junit4:junit4] 2> 177 T2415 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 178 T2408 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:50364 which had sessionid 0x13bfa2466090001
[junit4:junit4] 2> 468 T2406 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 473 T2406 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50365
[junit4:junit4] 2> 473 T2406 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 474 T2406 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821
[junit4:junit4] 2> 474 T2406 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821\'
[junit4:junit4] 2> 504 T2406 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 504 T2406 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 505 T2406 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821
[junit4:junit4] 2> 505 T2406 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821\solr.xml
[junit4:junit4] 2> 505 T2406 oasc.CoreContainer.<init> New CoreContainer 5368825
[junit4:junit4] 2> 505 T2406 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821\'
[junit4:junit4] 2> 506 T2406 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821\'
[junit4:junit4] 2> 525 T2406 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 30000
[junit4:junit4] 2> 525 T2406 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 526 T2406 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 526 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 526 T2406 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 526 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 527 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 527 T2406 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 527 T2406 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 528 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=30000&connTimeout=15000&retry=false
[junit4:junit4] 2> 540 T2406 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 563 T2406 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50358/solr
[junit4:junit4] 2> 563 T2406 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 563 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@d3feca
[junit4:junit4] 2> 565 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 566 T2425 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 567 T2425 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 567 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50370
[junit4:junit4] 2> 567 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50370
[junit4:junit4] 2> 579 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090002 with negotiated timeout 20000 for client /127.0.0.1:50370
[junit4:junit4] 2> 580 T2425 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090002, negotiated timeout = 20000
[junit4:junit4] 2> 580 T2426 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d3feca name:ZooKeeperConnection Watcher:127.0.0.1:50358 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 580 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 582 T2411 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13bfa2466090002
[junit4:junit4] 2> 583 T2408 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:50370 which had sessionid 0x13bfa2466090002
[junit4:junit4] 2> 583 T2426 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 583 T2406 oaz.ZooKeeper.close Session: 0x13bfa2466090002 closed
[junit4:junit4] 2> 584 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=15000&connTimeout=30000
[junit4:junit4] 2> 588 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@12a9d8b
[junit4:junit4] 2> 590 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 591 T2427 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 591 T2427 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 591 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50373
[junit4:junit4] 2> 591 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50373
[junit4:junit4] 2> 593 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090003 with negotiated timeout 20000 for client /127.0.0.1:50373
[junit4:junit4] 2> 593 T2427 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090003, negotiated timeout = 20000
[junit4:junit4] 2> 593 T2428 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12a9d8b name:ZooKeeperConnection Watcher:127.0.0.1:50358/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 594 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 596 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 598 T2406 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 600 T2406 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50365_ux_xgd%2Fj
[junit4:junit4] 2> 601 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:delete cxid:0x7 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50365_ux_xgd%2Fj Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50365_ux_xgd%2Fj
[junit4:junit4] 2> 602 T2406 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50365_ux_xgd%2Fj
[junit4:junit4] 2> 606 T2406 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 612 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:delete cxid:0x15 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4] 2> 614 T2406 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 616 T2406 oasc.Overseer.start Overseer (id=88939651901489155-127.0.0.1:50365_ux_xgd%2Fj-n_0000000000) starting
[junit4:junit4] 2> 617 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:create cxid:0x1a zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 619 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:create cxid:0x1b zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 621 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:create cxid:0x1c zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 622 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:create cxid:0x1d zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 623 T2430 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4] 2> 625 T2406 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 629 T2406 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 632 T2429 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 638 T2431 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821\collection1
[junit4:junit4] 2> 638 T2431 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 639 T2431 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 639 T2431 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 642 T2431 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821\collection1\'
[junit4:junit4] 2> 643 T2431 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 644 T2431 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821/collection1/lib/README' to classloader
[junit4:junit4] 2> 691 T2431 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4] 2> 763 T2431 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 764 T2431 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 773 T2431 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 1380 T2431 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 1390 T2431 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 1394 T2431 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 1408 T2431 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1413 T2431 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1418 T2431 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1421 T2431 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1423 T2431 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357111387821\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1357111387646/control/data\
[junit4:junit4] 2> 1423 T2431 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d0e91
[junit4:junit4] 2> 1424 T2431 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 1424 T2431 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\control\data forceNew:false
[junit4:junit4] 2> 1424 T2431 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\control\data
[junit4:junit4] 2> 1424 T2431 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1357111387646/control/data\index/
[junit4:junit4] 2> 1425 T2431 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\control\data\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 1425 T2431 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\control\data\index forceNew:false
[junit4:junit4] 2> 1429 T2431 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@19aae0d lockFactory=org.apache.lucene.store.NativeFSLockFactory@18beecf)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 1429 T2431 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 1430 T2431 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\control\data\index
[junit4:junit4] 2> 1431 T2431 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 1432 T2431 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 1432 T2431 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 1432 T2431 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 1433 T2431 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 1433 T2431 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 1433 T2431 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 1434 T2431 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 1434 T2431 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 1434 T2431 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 1434 T2431 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 1434 T2431 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 1434 T2431 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 1435 T2431 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 1435 T2431 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 1435 T2431 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 1435 T2431 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 1436 T2431 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1436 T2431 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1436 T2431 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1437 T2431 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1437 T2431 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1437 T2431 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1438 T2431 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1438 T2431 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1438 T2431 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 1439 T2431 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 1449 T2431 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 1453 T2431 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 1456 T2431 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 1459 T2431 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\control\data
[junit4:junit4] 2> 1459 T2431 oass.SolrIndexSearcher.<init> Opening Searcher@f6f053 main
[junit4:junit4] 2> 1461 T2431 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 1461 T2431 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 1461 T2431 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 1473 T2431 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 1517 T2432 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f6f053 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 1520 T2431 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 1521 T2431 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 1521 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 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> 1933 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 1933 T2429 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[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:50365_ux_xgd%2Fj",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50365/ux_xgd/j"}
[junit4:junit4] 2> 1933 T2429 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 1934 T2429 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 1934 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 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> 1943 T2428 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> 2389 T2431 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 2389 T2431 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50365/ux_xgd/j collection:control_collection shard:shard1
[junit4:junit4] 2> 2390 T2431 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 2403 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 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> 2407 T2431 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 2408 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 2413 T2431 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 2414 T2431 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 2415 T2431 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50365/ux_xgd/j/collection1/
[junit4:junit4] 2> 2415 T2431 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 2416 T2431 oasc.SyncStrategy.syncToMe http://127.0.0.1:50365/ux_xgd/j/collection1/ has no replicas
[junit4:junit4] 2> 2416 T2431 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50365/ux_xgd/j/collection1/
[junit4:junit4] 2> 2416 T2431 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 2422 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3245 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3270 T2428 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> 3304 T2431 oasc.ZkController.register We are http://127.0.0.1:50365/ux_xgd/j/collection1/ and leader is http://127.0.0.1:50365/ux_xgd/j/collection1/
[junit4:junit4] 2> 3304 T2431 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50365/ux_xgd/j
[junit4:junit4] 2> 3304 T2431 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 3305 T2431 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 3305 T2431 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 3308 T2431 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3309 T2406 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 3309 T2406 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 3309 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 3321 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 3325 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@ad68bc
[junit4:junit4] 2> 3326 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3327 T2433 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3327 T2433 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 3327 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50376
[junit4:junit4] 2> 3328 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50376
[junit4:junit4] 2> 3330 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090004 with negotiated timeout 10000 for client /127.0.0.1:50376
[junit4:junit4] 2> 3330 T2433 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090004, negotiated timeout = 10000
[junit4:junit4] 2> 3331 T2434 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ad68bc name:ZooKeeperConnection Watcher:127.0.0.1:50358/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3331 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3332 T2406 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3334 T2406 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 3709 T2406 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 3715 T2406 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50377
[junit4:junit4] 2> 3716 T2406 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 3716 T2406 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977
[junit4:junit4] 2> 3717 T2406 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977\'
[junit4:junit4] 2> 3737 T2406 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 3737 T2406 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 3737 T2406 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977
[junit4:junit4] 2> 3737 T2406 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977\solr.xml
[junit4:junit4] 2> 3738 T2406 oasc.CoreContainer.<init> New CoreContainer 23463483
[junit4:junit4] 2> 3739 T2406 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977\'
[junit4:junit4] 2> 3739 T2406 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977\'
[junit4:junit4] 2> 3771 T2406 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 30000
[junit4:junit4] 2> 3771 T2406 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 3772 T2406 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 3772 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 3772 T2406 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 3772 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 3772 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 3772 T2406 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 3773 T2406 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 3773 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=30000&connTimeout=15000&retry=false
[junit4:junit4] 2> 3793 T2406 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 3820 T2406 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50358/solr
[junit4:junit4] 2> 3820 T2406 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 3820 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@b16
[junit4:junit4] 2> 3822 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3823 T2444 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3824 T2444 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 3824 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50382
[junit4:junit4] 2> 3825 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50382
[junit4:junit4] 2> 3836 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090005 with negotiated timeout 20000 for client /127.0.0.1:50382
[junit4:junit4] 2> 3837 T2444 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090005, negotiated timeout = 20000
[junit4:junit4] 2> 3837 T2445 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b16 name:ZooKeeperConnection Watcher:127.0.0.1:50358 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3837 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3840 T2411 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13bfa2466090005
[junit4:junit4] 2> 3842 T2408 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:50382 which had sessionid 0x13bfa2466090005
[junit4:junit4] 2> 3844 T2445 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 3844 T2406 oaz.ZooKeeper.close Session: 0x13bfa2466090005 closed
[junit4:junit4] 2> 3845 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=15000&connTimeout=30000
[junit4:junit4] 2> 3853 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@77c1e5
[junit4:junit4] 2> 3855 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3856 T2446 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3857 T2446 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 3857 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50385
[junit4:junit4] 2> 3858 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50385
[junit4:junit4] 2> 3860 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090006 with negotiated timeout 20000 for client /127.0.0.1:50385
[junit4:junit4] 2> 3860 T2446 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090006, negotiated timeout = 20000
[junit4:junit4] 2> 3860 T2447 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77c1e5 name:ZooKeeperConnection Watcher:127.0.0.1:50358/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3861 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3863 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3864 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3868 T2406 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50377_ux_xgd%2Fj
[junit4:junit4] 2> 3869 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090006 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50377_ux_xgd%2Fj Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50377_ux_xgd%2Fj
[junit4:junit4] 2> 3871 T2406 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50377_ux_xgd%2Fj
[junit4:junit4] 2> 3876 T2434 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 3876 T2428 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 3876 T2428 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> 3882 T2406 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3894 T2448 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977\collection1
[junit4:junit4] 2> 3894 T2448 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 3895 T2448 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 3895 T2448 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 3897 T2448 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977\collection1\'
[junit4:junit4] 2> 3898 T2448 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 3898 T2448 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977/collection1/lib/README' to classloader
[junit4:junit4] 2> 3953 T2448 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4] 2> 4059 T2448 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 4059 T2448 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 4068 T2448 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 4579 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4580 T2429 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[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:50365_ux_xgd%2Fj",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50365/ux_xgd/j"}
[junit4:junit4] 2> 4591 T2447 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> 4591 T2434 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> 4591 T2428 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> 4957 T2448 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 4972 T2448 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 4978 T2448 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 4998 T2448 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5005 T2448 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5013 T2448 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5014 T2448 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5016 T2448 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357111390977\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1357111387646/jetty1\
[junit4:junit4] 2> 5017 T2448 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d0e91
[junit4:junit4] 2> 5017 T2448 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 5018 T2448 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty1 forceNew:false
[junit4:junit4] 2> 5019 T2448 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty1
[junit4:junit4] 2> 5019 T2448 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1357111387646/jetty1\index/
[junit4:junit4] 2> 5020 T2448 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 5021 T2448 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty1\index forceNew:false
[junit4:junit4] 2> 5025 T2448 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@15d6679 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1427bc3)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 5025 T2448 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 5028 T2448 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty1\index
[junit4:junit4] 2> 5028 T2448 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 5030 T2448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 5030 T2448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 5030 T2448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 5031 T2448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 5031 T2448 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 5032 T2448 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 5032 T2448 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 5033 T2448 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 5034 T2448 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 5034 T2448 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 5035 T2448 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 5035 T2448 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 5035 T2448 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 5035 T2448 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 5036 T2448 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 5036 T2448 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 5037 T2448 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5038 T2448 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5038 T2448 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5039 T2448 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5039 T2448 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5040 T2448 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5040 T2448 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5040 T2448 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5040 T2448 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 5040 T2448 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 5056 T2448 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 5063 T2448 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 5065 T2448 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 5068 T2448 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty1
[junit4:junit4] 2> 5068 T2448 oass.SolrIndexSearcher.<init> Opening Searcher@29ec8d main
[junit4:junit4] 2> 5072 T2448 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 5072 T2448 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 5073 T2448 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 5089 T2448 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 5162 T2449 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29ec8d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 5166 T2448 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 5167 T2448 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 5900 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 5902 T2429 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[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:50377_ux_xgd%2Fj",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50377/ux_xgd/j"}
[junit4:junit4] 2> 5903 T2429 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4] 2> 5903 T2429 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 5913 T2434 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> 5913 T2428 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> 5913 T2447 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> 6034 T2448 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 6034 T2448 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50377/ux_xgd/j collection:collection1 shard:shard1
[junit4:junit4] 2> 6035 T2448 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 6049 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090006 type:delete cxid:0x45 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4] 2> 6050 T2448 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 6051 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090006 type:create cxid:0x46 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 6056 T2448 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 6056 T2448 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 6056 T2448 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50377/ux_xgd/j/collection1/
[junit4:junit4] 2> 6057 T2448 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 6057 T2448 oasc.SyncStrategy.syncToMe http://127.0.0.1:50377/ux_xgd/j/collection1/ has no replicas
[junit4:junit4] 2> 6058 T2448 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50377/ux_xgd/j/collection1/
[junit4:junit4] 2> 6058 T2448 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 6065 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090006 type:create cxid:0x50 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 7325 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7344 T2447 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> 7344 T2428 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> 7344 T2434 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> 7368 T2448 oasc.ZkController.register We are http://127.0.0.1:50377/ux_xgd/j/collection1/ and leader is http://127.0.0.1:50377/ux_xgd/j/collection1/
[junit4:junit4] 2> 7368 T2448 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50377/ux_xgd/j
[junit4:junit4] 2> 7368 T2448 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 7369 T2448 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 7369 T2448 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 7373 T2448 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7375 T2406 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 7375 T2406 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 7376 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 7878 T2406 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 7883 T2406 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50386
[junit4:junit4] 2> 7885 T2406 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 7885 T2406 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033
[junit4:junit4] 2> 7886 T2406 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033\'
[junit4:junit4] 2> 7940 T2406 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 7941 T2406 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 7941 T2406 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033
[junit4:junit4] 2> 7941 T2406 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033\solr.xml
[junit4:junit4] 2> 7942 T2406 oasc.CoreContainer.<init> New CoreContainer 15187859
[junit4:junit4] 2> 7943 T2406 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033\'
[junit4:junit4] 2> 7944 T2406 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033\'
[junit4:junit4] 2> 7979 T2406 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 30000
[junit4:junit4] 2> 7979 T2406 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 7979 T2406 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 7980 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 7980 T2406 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 7981 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 7982 T2406 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 7983 T2406 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 7983 T2406 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 7984 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=30000&connTimeout=15000&retry=false
[junit4:junit4] 2> 8006 T2406 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 8039 T2406 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50358/solr
[junit4:junit4] 2> 8040 T2406 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 8040 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@a26f71
[junit4:junit4] 2> 8041 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 8043 T2459 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 8044 T2459 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 8044 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50391
[junit4:junit4] 2> 8045 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50391
[junit4:junit4] 2> 8058 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090007 with negotiated timeout 20000 for client /127.0.0.1:50391
[junit4:junit4] 2> 8058 T2459 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090007, negotiated timeout = 20000
[junit4:junit4] 2> 8059 T2460 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a26f71 name:ZooKeeperConnection Watcher:127.0.0.1:50358 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 8060 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 8062 T2411 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13bfa2466090007
[junit4:junit4] 2> 8067 T2408 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13bfa2466090007, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 8068 T2460 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 8067 T2406 oaz.ZooKeeper.close Session: 0x13bfa2466090007 closed
[junit4:junit4] 2> 8069 T2408 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:50391 which had sessionid 0x13bfa2466090007
[junit4:junit4] 2> 8069 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=15000&connTimeout=30000
[junit4:junit4] 2> 8079 T2406 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50358/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@13e2ed3
[junit4:junit4] 2> 8080 T2406 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 8081 T2461 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:50358. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 8082 T2461 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:50358, initiating session
[junit4:junit4] 2> 8082 T2408 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:50394
[junit4:junit4] 2> 8083 T2408 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:50394
[junit4:junit4] 2> 8085 T2410 oazs.ZooKeeperServer.finishSessionInit Established session 0x13bfa2466090008 with negotiated timeout 20000 for client /127.0.0.1:50394
[junit4:junit4] 2> 8085 T2461 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:50358, sessionid = 0x13bfa2466090008, negotiated timeout = 20000
[junit4:junit4] 2> 8086 T2462 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13e2ed3 name:ZooKeeperConnection Watcher:127.0.0.1:50358/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 8086 T2406 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 8088 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8091 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8093 T2406 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50386_ux_xgd%2Fj
[junit4:junit4] 2> 8094 T2411 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13bfa2466090008 type:delete cxid:0x4 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50386_ux_xgd%2Fj Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50386_ux_xgd%2Fj
[junit4:junit4] 2> 8096 T2406 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50386_ux_xgd%2Fj
[junit4:junit4] 2> 8101 T2447 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> 8102 T2434 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 8102 T2428 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 8103 T2428 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> 8102 T2434 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> 8107 T2447 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 8110 T2406 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 8122 T2463 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033\collection1
[junit4:junit4] 2> 8122 T2463 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 8123 T2463 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 8124 T2463 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 8127 T2463 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033\collection1\'
[junit4:junit4] 2> 8130 T2463 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 8132 T2463 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033/collection1/lib/README' to classloader
[junit4:junit4] 2> 8207 T2463 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4] 2> 8315 T2463 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 8317 T2463 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 8327 T2463 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 8783 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8784 T2429 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[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:50377_ux_xgd%2Fj",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50377/ux_xgd/j"}
[junit4:junit4] 2> 8799 T2447 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> 8800 T2462 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> 8799 T2434 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> 8801 T2428 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> 9292 T2463 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 9307 T2463 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 9313 T2463 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 9342 T2463 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 9350 T2463 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 9357 T2463 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 9359 T2463 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 9362 T2463 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357111395033\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1357111387646/jetty2\
[junit4:junit4] 2> 9362 T2463 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d0e91
[junit4:junit4] 2> 9363 T2463 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 9364 T2463 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty2 forceNew:false
[junit4:junit4] 2> 9364 T2463 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty2
[junit4:junit4] 2> 9364 T2463 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1357111387646/jetty2\index/
[junit4:junit4] 2> 9366 T2463 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 9367 T2463 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty2\index forceNew:false
[junit4:junit4] 2> 9372 T2463 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1cb979b lockFactory=org.apache.lucene.store.NativeFSLockFactory@19b55d6)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 9372 T2463 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 9375 T2463 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty2\index
[junit4:junit4] 2> 9376 T2463 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 9378 T2463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 9378 T2463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 9379 T2463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 9379 T2463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 9380 T2463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 9380 T2463 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 9381 T2463 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 9381 T2463 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 9382 T2463 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 9383 T2463 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 9383 T2463 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 9383 T2463 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 9384 T2463 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 9384 T2463 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 9384 T2463 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 9385 T2463 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 9386 T2463 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9386 T2463 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9387 T2463 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9388 T2463 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9388 T2463 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9388 T2463 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9390 T2463 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9391 T2463 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 9391 T2463 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 9392 T2463 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 9409 T2463 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 9417 T2463 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 9420 T2463 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 9423 T2463 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty2
[junit4:junit4] 2> 9423 T2463 oass.SolrIndexSearcher.<init> Opening Searcher@166f726 main
[junit4:junit4] 2> 9426 T2463 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 9427 T2463 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 9428 T2463 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 9442 T2463 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 9517 T2464 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@166f726 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 9522 T2463 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 9522 T2463 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 10241 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 10242 T2429 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[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:50386_ux_xgd%2Fj",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50386/ux_xgd/j"}
[junit4:junit4] 2> 10242 T2429 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 10242 T2429 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 10252 T2447 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> 10252 T2434 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> 10252 T2462 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> 10253 T2428 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> 10476 T2463 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 10476 T2463 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50386/ux_xgd/j collection:collection1 shard:shard1
[junit4:junit4] 2> 10485 T2463 oasc.ZkController.register We are http://127.0.0.1:50386/ux_xgd/j/collection1/ and leader is http://127.0.0.1:50377/ux_xgd/j/collection1/
[junit4:junit4] 2> 10485 T2463 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50386/ux_xgd/j
[junit4:junit4] 2> 10485 T2463 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 10485 T2463 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> 10487 T2463 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C174 name=collection1 org.apache.solr.core.SolrCore@1af6fdc url=http://127.0.0.1:50386/ux_xgd/j/collection1 node=127.0.0.1:50386_ux_xgd%2Fj C174_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, state=down, core=collection1, collection=collection1, node_name=127.0.0.1:50386_ux_xgd%2Fj, base_url=http://127.0.0.1:50386/ux_xgd/j}
[junit4:junit4] 2> 10487 T2465 C174 P50386 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 10489 T2465 C174 P50386 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 10489 T2465 C174 P50386 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 10489 T2465 C174 P50386 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 10490 T2406 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 10490 T2406 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 10490 T2406 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 10491 T2465 C174 P50386 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 10504 T2406 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C175 name=collection1 org.apache.solr.core.SolrCore@1a3b772 url=http://127.0.0.1:50365/ux_xgd/j/collection1 node=127.0.0.1:50365_ux_xgd%2Fj C175_STATE=coll:control_collection core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:50365_ux_xgd%2Fj, base_url=http://127.0.0.1:50365/ux_xgd/j, leader=true}
[junit4:junit4] 2> 10526 T2419 C175 P50365 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\control\data
[junit4:junit4] 2> 10532 T2419 C175 P50365 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@19aae0d lockFactory=org.apache.lucene.store.NativeFSLockFactory@18beecf)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 10533 T2419 C175 P50365 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 10537 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[1 (1423034441446457344)]} 0 13
[junit4:junit4] 2> 10537 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10001 (1423034441444360192)]} 0 14
[junit4:junit4] 2> ASYNC NEW_CORE C176 name=collection1 org.apache.solr.core.SolrCore@164360d url=http://127.0.0.1:50377/ux_xgd/j/collection1 node=127.0.0.1:50377_ux_xgd%2Fj C176_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50377_ux_xgd%2Fj, base_url=http://127.0.0.1:50377/ux_xgd/j, leader=true}
[junit4:junit4] 2> 10551 T2442 C176 P50377 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty1
[junit4:junit4] 2> 10557 T2442 C176 P50377 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@15d6679 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1427bc3)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 10558 T2442 C176 P50377 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 10562 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10001 (1423034441472671744)]} 0 11
[junit4:junit4] 2> 10562 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[1 (1423034441471623168)]} 0 12
[junit4:junit4] 2> 10567 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[2 (1423034441488400384)]} 0 1
[junit4:junit4] 2> 10567 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10002 (1423034441488400385)]} 0 1
[junit4:junit4] 2> 10569 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[2 (1423034441490497536)]} 0 1
[junit4:junit4] 2> 10571 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10002 (1423034441492594688)]} 0 1
[junit4:junit4] 2> 10574 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[3 (1423034441495740416)]} 0 1
[junit4:junit4] 2> 10575 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10003 (1423034441497837568)]} 0 1
[junit4:junit4] 2> 10577 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[3 (1423034441499934720)]} 0 1
[junit4:junit4] 2> 10580 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10003 (1423034441502031872)]} 0 1
[junit4:junit4] 2> 10581 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[4 (1423034441503080448)]} 0 0
[junit4:junit4] 2> 10583 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[4 (1423034441505177600)]} 0 1
[junit4:junit4] 2> 10583 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10004 (1423034441505177600)]} 0 1
[junit4:junit4] 2> 10585 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[2 (-1423034441508323328)]} 0 0
[junit4:junit4] 2> 10586 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10004 (1423034441508323328)]} 0 1
[junit4:junit4] 2> 10589 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[2 (-1423034441511469056)]} 0 1
[junit4:junit4] 2> 10589 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10002 (-1423034441511469056)]} 0 1
[junit4:junit4] 2> 10593 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10002 (-1423034441515663360)]} 0 1
[junit4:junit4] 2> 10593 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[5 (1423034441515663360)]} 0 1
[junit4:junit4] 2> 10595 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[5 (1423034441518809088)]} 0 0
[junit4:junit4] 2> 10599 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10005 (1423034441518809088)]} 0 4
[junit4:junit4] 2> 10599 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[6 (1423034441523003392)]} 0 0
[junit4:junit4] 2> 10604 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[6 (1423034441526149120)]} 0 2
[junit4:junit4] 2> 10607 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[7 (1423034441531392000)]} 0 0
[junit4:junit4] 2> 10609 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[7 (1423034441533489152)]} 0 0
[junit4:junit4] 2> 10612 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[8 (1423034441536634880)]} 0 0
[junit4:junit4] 2> 10616 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[8 (1423034441539780608)]} 0 1
[junit4:junit4] 2> 10618 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[3 (-1423034441542926336)]} 0 0
[junit4:junit4] 2> 10620 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[3 (-1423034441545023488)]} 0 0
[junit4:junit4] 2> 10624 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[9 (1423034441548169216)]} 0 1
[junit4:junit4] 2> 10627 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[9 (1423034441551314944)]} 0 1
[junit4:junit4] 2> 10630 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10 (1423034441554460672)]} 0 1
[junit4:junit4] 2> 10633 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10 (1423034441557606400)]} 0 1
[junit4:junit4] 2> 10634 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[4 (-1423034441559703552)]} 0 0
[junit4:junit4] 2> 10637 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[4 (-1423034441562849280)]} 0 0
[junit4:junit4] 2> 10641 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[11 (1423034441565995008)]} 0 1
[junit4:junit4] 2> 10644 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[11 (1423034441569140736)]} 0 1
[junit4:junit4] 2> 10645 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10005 (1423034441525100544)]} 0 44
[junit4:junit4] 2> 10646 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[12 (1423034441572286464)]} 0 0
[junit4:junit4] 2> 10649 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10006 (1423034441574383616)]} 0 0
[junit4:junit4] 2> 10650 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[12 (1423034441575432192)]} 0 1
[junit4:junit4] 2> 10652 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10006 (1423034441577529344)]} 0 1
[junit4:junit4] 2> 10652 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[13 (1423034441578577920)]} 0 0
[junit4:junit4] 2> 10656 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10007 (1423034441580675072)]} 0 2
[junit4:junit4] 2> 10656 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[13 (1423034441582772224)]} 0 0
[junit4:junit4] 2> 10659 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[6 (-1423034441585917952)]} 0 0
[junit4:junit4] 2> 10663 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[6 (-1423034441590112256)]} 0 1
[junit4:junit4] 2> 10664 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10007 (1423034441585917952)]} 0 5
[junit4:junit4] 2> 10668 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[14 (1423034441593257984)]} 0 2
[junit4:junit4] 2> 10668 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10008 (1423034441594306560)]} 0 1
[junit4:junit4] 2> 10671 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[14 (1423034441597452288)]} 0 1
[junit4:junit4] 2> 10672 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10008 (1423034441599549440)]} 0 0
[junit4:junit4] 2> 10673 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[15 (1423034441599549440)]} 0 1
[junit4:junit4] 2> 10674 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10003 (-1423034441601646592)]} 0 0
[junit4:junit4] 2> 10676 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[15 (1423034441602695168)]} 0 1
[junit4:junit4] 2> 10677 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10003 (-1423034441604792320)]} 0 0
[junit4:junit4] 2> 10679 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[9 (-1423034441606889472)]} 0 0
[junit4:junit4] 2> 10681 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10009 (1423034441607938048)]} 0 1
[junit4:junit4] 2> 10682 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[9 (-1423034441610035200)]} 0 0
[junit4:junit4] 2> 10684 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10009 (1423034441611083776)]} 0 1
[junit4:junit4] 2> 10685 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[16 (1423034441613180928)]} 0 0
[junit4:junit4] 2> 10686 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10010 (1423034441614229504)]} 0 0
[junit4:junit4] 2> 10688 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[16 (1423034441615278080)]} 0 1
[junit4:junit4] 2> 10690 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10010 (1423034441617375232)]} 0 0
[junit4:junit4] 2> 10690 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10 (-1423034441618423808)]} 0 0
[junit4:junit4] 2> 10692 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10004 (-1423034441620520960)]} 0 0
[junit4:junit4] 2> 10693 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10 (-1423034441621569536)]} 0 0
[junit4:junit4] 2> 10695 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10004 (-1423034441623666688)]} 0 0
[junit4:junit4] 2> 10697 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[17 (1423034441624715264)]} 0 1
[junit4:junit4] 2> 10699 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10011 (1423034441626812416)]} 0 1
[junit4:junit4] 2> 10699 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[17 (1423034441627860992)]} 0 0
[junit4:junit4] 2> 10701 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[12 (-1423034441629958144)]} 0 0
[junit4:junit4] 2> 10701 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10011 (1423034441629958144)]} 0 0
[junit4:junit4] 2> 10704 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[12 (-1423034441633103872)]} 0 0
[junit4:junit4] 2> 10706 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10012 (1423034441634152448)]} 0 1
[junit4:junit4] 2> 10709 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10012 (1423034441637298176)]} 0 1
[junit4:junit4] 2> 10711 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[18 (1423034441636249600)]} 0 4
[junit4:junit4] 2> 10713 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10013 (1423034441641492480)]} 0 1
[junit4:junit4] 2> 10716 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10013 (1423034441644638208)]} 0 1
[junit4:junit4] 2> 10718 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10006 (-1423034441647783936)]} 0 0
[junit4:junit4] 2> 10721 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10006 (-1423034441650929664)]} 0 0
[junit4:junit4] 2> 10725 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10014 (1423034441654075392)]} 0 1
[junit4:junit4] 2> 10727 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10014 (1423034441656172544)]} 0 1
[junit4:junit4] 2> 10730 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10015 (1423034441659318272)]} 0 1
[junit4:junit4] 2> 10733 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10015 (1423034441662464000)]} 0 1
[junit4:junit4] 2> 10735 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[10009 (-1423034441665609728)]} 0 0
[junit4:junit4] 2> 10738 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10009 (-1423034441668755456)]} 0 0
[junit4:junit4] 2> 10741 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10016 (1423034441670852608)]} 0 1
[junit4:junit4] 2> 10745 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10016 (1423034441675046912)]} 0 1
[junit4:junit4] 2> 10747 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10010 (-1423034441678192640)]} 0 0
[junit4:junit4] 2> 10750 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10010 (-1423034441681338368)]} 0 0
[junit4:junit4] 2> 10753 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10017 (1423034441683435520)]} 0 1
[junit4:junit4] 2> 10753 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[18 (1423034441642541056)]} 0 40
[junit4:junit4] 2> 10757 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[19 (1423034441687629824)]} 0 1
[junit4:junit4] 2> 10757 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10017 (1423034441687629824)]} 0 1
[junit4:junit4] 2> 10759 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10012 (-1423034441690775552)]} 0 0
[junit4:junit4] 2> 10760 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[19 (1423034441690775552)]} 0 1
[junit4:junit4] 2> 10762 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10012 (-1423034441693921280)]} 0 0
[junit4:junit4] 2> 10763 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[14 (-1423034441694969856)]} 0 0
[junit4:junit4] 2> 10765 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10018 (1423034441696018432)]} 0 1
[junit4:junit4] 2> 10765 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[14 (-1423034441697067008)]} 0 0
[junit4:junit4] 2> 10768 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10018 (1423034441699164160)]} 0 1
[junit4:junit4] 2> 10769 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[20 (1423034441700212736)]} 0 1
[junit4:junit4] 2> 10771 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10019 (1423034441702309888)]} 0 1
[junit4:junit4] 2> 10771 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[20 (1423034441703358464)]} 0 0
[junit4:junit4] 2> 10774 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10019 (1423034441706504192)]} 0 1
[junit4:junit4] 2> 10777 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10014 (-1423034441709649920)]} 0 0
[junit4:junit4] 2> 10779 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[21 (1423034441708601344)]} 0 4
[junit4:junit4] 2> 10781 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10014 (-1423034441713844224)]} 0 0
[junit4:junit4] 2> 10786 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10020 (1423034441716989952)]} 0 2
[junit4:junit4] 2> 10790 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10020 (1423034441722232832)]} 0 1
[junit4:junit4] 2> 10792 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10021 (1423034441725378560)]} 0 0
[junit4:junit4] 2> 10794 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[21 (1423034441714892800)]} 0 12
[junit4:junit4] 2> 10797 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10021 (1423034441729572864)]} 0 1
[junit4:junit4] 2> 10797 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[15 (-1423034441730621440)]} 0 0
[junit4:junit4] 2> 10799 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10015 (-1423034441732718592)]} 0 0
[junit4:junit4] 2> 10800 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[15 (-1423034441733767168)]} 0 0
[junit4:junit4] 2> 10802 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10015 (-1423034441735864320)]} 0 0
[junit4:junit4] 2> 10802 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[22 (1423034441735864320)]} 0 0
[junit4:junit4] 2> 10804 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[22 (1423034441737961472)]} 0 0
[junit4:junit4] 2> 10804 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10022 (1423034441737961472)]} 0 0
[junit4:junit4] 2> 10807 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[23 (1423034441740058624)]} 0 1
[junit4:junit4] 2> 10808 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10022 (1423034441742155776)]} 0 0
[junit4:junit4] 2> 10809 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[23 (1423034441743204352)]} 0 0
[junit4:junit4] 2> 10812 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10023 (1423034441745301504)]} 0 1
[junit4:junit4] 2> 10812 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[16 (-1423034441746350080)]} 0 0
[junit4:junit4] 2> 10814 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[16 (-1423034441748447233)]} 0 0
[junit4:junit4] 2> 10814 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10023 (1423034441748447232)]} 0 0
[junit4:junit4] 2> 10816 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10016 (-1423034441750544384)]} 0 0
[junit4:junit4] 2> 10817 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[24 (1423034441750544385)]} 0 1
[junit4:junit4] 2> 10818 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10016 (-1423034441752641536)]} 0 0
[junit4:junit4] 2> 10820 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[24 (1423034441753690112)]} 0 1
[junit4:junit4] 2> 10822 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10024 (1423034441755787264)]} 0 1
[junit4:junit4] 2> 10822 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[19 (-1423034441756835840)]} 0 0
[junit4:junit4] 2> 10825 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[19 (-1423034441759981568)]} 0 0
[junit4:junit4] 2> 10825 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10024 (1423034441759981569)]} 0 0
[junit4:junit4] 2> 10828 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10019 (-1423034441763127296)]} 0 0
[junit4:junit4] 2> 10829 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[25 (1423034441763127297)]} 0 1
[junit4:junit4] 2> 10829 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10019 (-1423034441764175872)]} 0 0
[junit4:junit4] 2> 10831 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[25 (1423034441765224448)]} 0 1
[junit4:junit4] 2> 10833 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[26 (1423034441768370176)]} 0 0
[junit4:junit4] 2> 10835 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10025 (1423034441767321600)]} 0 3
[junit4:junit4] 2> 10838 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10025 (1423034441773613056)]} 0 0
[junit4:junit4] 2> 10839 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[26 (1423034441771515904)]} 0 3
[junit4:junit4] 2> 10842 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10026 (1423034441776758784)]} 0 1
[junit4:junit4] 2> 10842 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[27 (1423034441777807360)]} 0 0
[junit4:junit4] 2> 10844 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10026 (1423034441778855936)]} 0 1
[junit4:junit4] 2> 10848 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10027 (1423034441783050240)]} 0 1
[junit4:junit4] 2> 10848 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[27 (1423034441783050240)]} 0 1
[junit4:junit4] 2> 10852 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[28 (1423034441787244544)]} 0 1
[junit4:junit4] 2> 10852 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10027 (1423034441787244544)]} 0 1
[junit4:junit4] 2> 10854 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10028 (1423034441790390272)]} 0 0
[junit4:junit4] 2> 10854 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[28 (1423034441790390272)]} 0 0
[junit4:junit4] 2> 10856 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[21 (-1423034441792487424)]} 0 0
[junit4:junit4] 2> 10856 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10028 (1423034441792487424)]} 0 0
[junit4:junit4] 2> 10859 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[21 (-1423034441795633152)]} 0 0
[junit4:junit4] 2> 10860 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[10021 (-1423034441796681728)]} 0 0
[junit4:junit4] 2> 10862 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[29 (1423034441798778880)]} 0 0
[junit4:junit4] 2> 10864 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10021 (-1423034441800876032)]} 0 0
[junit4:junit4] 2> 10866 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[29 (1423034441802973184)]} 0 0
[junit4:junit4] 2> 10868 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10029 (1423034441804021760)]} 0 1
[junit4:junit4] 2> 10868 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[30 (1423034441805070336)]} 0 0
[junit4:junit4] 2> 10871 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10029 (1423034441807167488)]} 0 1
[junit4:junit4] 2> 10871 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[30 (1423034441808216064)]} 0 0
[junit4:junit4] 2> 10875 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[31 (1423034441811361792)]} 0 1
[junit4:junit4] 2> 10876 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10030 (1423034441810313216)]} 0 3
[junit4:junit4] 2> 10879 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10030 (1423034441816604672)]} 0 0
[junit4:junit4] 2> 10881 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[31 (1423034441815556096)]} 0 3
[junit4:junit4] 2> 10882 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10031 (1423034441818701824)]} 0 1
[junit4:junit4] 2> 10882 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[32 (1423034441819750400)]} 0 0
[junit4:junit4] 2> 10885 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10031 (1423034441821847552)]} 0 1
[junit4:junit4] 2> 10886 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[32 (1423034441823944704)]} 0 0
[junit4:junit4] 2> 10888 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10032 (1423034441824993280)]} 0 1
[junit4:junit4] 2> 10890 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[33 (1423034441827090432)]} 0 1
[junit4:junit4] 2> 10892 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10032 (1423034441829187584)]} 0 1
[junit4:junit4] 2> 10893 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[33 (1423034441830236160)]} 0 1
[junit4:junit4] 2> 10894 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10033 (1423034441832333312)]} 0 0
[junit4:junit4] 2> 10894 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[23 (-1423034441832333313)]} 0 0
[junit4:junit4] 2> 10897 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10033 (1423034441834430464)]} 0 1
[junit4:junit4] 2> 10897 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[23 (-1423034441835479040)]} 0 0
[junit4:junit4] 2> 10899 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[10023 (-1423034441837576192)]} 0 0
[junit4:junit4] 2> 10901 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[34 (1423034441838624768)]} 0 1
[junit4:junit4] 2> 10902 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10023 (-1423034441840721920)]} 0 0
[junit4:junit4] 2> 10903 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[34 (1423034441841770496)]} 0 0
[junit4:junit4] 2> 10906 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10034 (1423034441843867648)]} 0 1
[junit4:junit4] 2> 10906 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[24 (-1423034441844916224)]} 0 0
[junit4:junit4] 2> 10908 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[24 (-1423034441847013376)]} 0 0
[junit4:junit4] 2> 10909 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10034 (1423034441847013377)]} 0 1
[junit4:junit4] 2> 10911 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10024 (-1423034441850159104)]} 0 0
[junit4:junit4] 2> 10911 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[35 (1423034441850159105)]} 0 0
[junit4:junit4] 2> 10913 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10024 (-1423034441852256256)]} 0 0
[junit4:junit4] 2> 10915 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[35 (1423034441854353408)]} 0 0
[junit4:junit4] 2> 10919 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[36 (1423034441857499136)]} 0 1
[junit4:junit4] 2> 10919 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10035 (1423034441855401984)]} 0 3
[junit4:junit4] 2> 10922 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10035 (1423034441860644865)]} 0 1
[junit4:junit4] 2> 10924 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[36 (1423034441860644864)]} 0 3
[junit4:junit4] 2> 10926 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10036 (1423034441864839168)]} 0 1
[junit4:junit4] 2> 10927 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[26 (-1423034441866936320)]} 0 0
[junit4:junit4] 2> 10928 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10036 (1423034441867984896)]} 0 0
[junit4:junit4] 2> 10930 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[26 (-1423034441870082048)]} 0 0
[junit4:junit4] 2> 10932 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[10026 (-1423034441872179200)]} 0 0
[junit4:junit4] 2> 10933 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[37 (1423034441873227776)]} 0 0
[junit4:junit4] 2> 10934 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10026 (-1423034441874276352)]} 0 0
[junit4:junit4] 2> 10936 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[37 (1423034441875324928)]} 0 1
[junit4:junit4] 2> 10938 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10037 (1423034441877422080)]} 0 1
[junit4:junit4] 2> 10938 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[27 (-1423034441878470656)]} 0 0
[junit4:junit4] 2> 10941 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10037 (1423034441880567808)]} 0 1
[junit4:junit4] 2> 10941 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[27 (-1423034441881616384)]} 0 0
[junit4:junit4] 2> 10943 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10027 (-1423034441883713536)]} 0 0
[junit4:junit4] 2> 10944 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[38 (1423034441884762112)]} 0 0
[junit4:junit4] 2> 10946 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10027 (-1423034441886859264)]} 0 0
[junit4:junit4] 2> 10947 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[38 (1423034441886859265)]} 0 1
[junit4:junit4] 2> 10949 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10038 (1423034441888956416)]} 0 1
[junit4:junit4] 2> 10949 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[39 (1423034441890004992)]} 0 0
[junit4:junit4] 2> 10952 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10038 (1423034441892102144)]} 0 1
[junit4:junit4] 2> 10953 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[39 (1423034441894199296)]} 0 0
[junit4:junit4] 2> 10955 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10039 (1423034441896296448)]} 0 0
[junit4:junit4] 2> 10956 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[40 (1423034441897345024)]} 0 0
[junit4:junit4] 2> 10959 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10039 (1423034441899442176)]} 0 1
[junit4:junit4] 2> 10959 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[40 (1423034441900490752)]} 0 0
[junit4:junit4] 2> 10961 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[28 (-1423034441902587904)]} 0 0
[junit4:junit4] 2> 10963 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10040 (1423034441901539328)]} 0 3
[junit4:junit4] 2> 10965 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[28 (-1423034441906782208)]} 0 0
[junit4:junit4] 2> 10970 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10040 (1423034441908879360)]} 0 3
[junit4:junit4] 2> 10973 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10028 (-1423034441915170816)]} 0 0
[junit4:junit4] 2> 10975 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10028 (-1423034441917267968)]} 0 0
[junit4:junit4] 2> 11011 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[41 (1423034441908879360)]} 0 44
[junit4:junit4] 2> 11012 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10041 (1423034441920413696)]} 0 34
[junit4:junit4] 2> 11016 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10041 (1423034441960259584)]} 0 1
[junit4:junit4] 2> 11017 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[41 (1423034441958162432)]} 0 3
[junit4:junit4] 2> 11020 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10042 (1423034441963405312)]} 0 1
[junit4:junit4] 2> 11021 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[42 (1423034441965502464)]} 0 0
[junit4:junit4] 2> 11023 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10042 (1423034441966551040)]} 0 1
[junit4:junit4] 2> 11024 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[42 (1423034441968648192)]} 0 0
[junit4:junit4] 2> 11026 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10043 (1423034441970745344)]} 0 0
[junit4:junit4] 2> 11029 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[43 (1423034441971793920)]} 0 2
[junit4:junit4] 2> 11030 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10043 (1423034441974939648)]} 0 0
[junit4:junit4] 2> 11033 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[43 (1423034441977036800)]} 0 1
[junit4:junit4] 2> 11035 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[10032 (-1423034441980182528)]} 0 0
[junit4:junit4] 2> 11035 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[32 (-1423034441980182529)]} 0 0
[junit4:junit4] 2> 11037 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[32 (-1423034441982279681)]} 0 0
[junit4:junit4] 2> 11037 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10032 (-1423034441982279680)]} 0 0
[junit4:junit4] 2> 11043 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10044 (1423034441985425408)]} 0 3
[junit4:junit4] 2> 11043 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[44 (1423034441985425409)]} 0 3
[junit4:junit4] 2> 11047 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10044 (1423034441991716864)]} 0 1
[junit4:junit4] 2> 11047 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[44 (1423034441991716865)]} 0 1
[junit4:junit4] 2> 11050 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10033 (-1423034441995911168)]} 0 0
[junit4:junit4] 2> 11050 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[33 (-1423034441995911169)]} 0 0
[junit4:junit4] 2> 11051 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10033 (-1423034441996959744)]} 0 0
[junit4:junit4] 2> 11053 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[33 (-1423034441999056896)]} 0 0
[junit4:junit4] 2> 11054 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10045 (1423034442000105472)]} 0 0
[junit4:junit4] 2> 11059 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10045 (1423034442005348352)]} 0 0
[junit4:junit4] 2> 11063 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10046 (1423034442008494080)]} 0 1
[junit4:junit4] 2> 11124 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10046 (1423034442011639808)]} 0 59
[junit4:junit4] 2> 11128 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10047 (1423034442076651520)]} 0 1
[junit4:junit4] 2> 11132 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10047 (1423034442079797248)]} 0 2
[junit4:junit4] 2> 11134 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10036 (-1423034442083991552)]} 0 0
[junit4:junit4] 2> 11135 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[45 (1423034442002202624)]} 0 79
[junit4:junit4] 2> 11137 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10036 (-1423034442087137280)]} 0 0
[junit4:junit4] 2> 11139 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[45 (1423034442089234432)]} 0 0
[junit4:junit4] 2> 11141 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10048 (1423034442091331584)]} 0 0
[junit4:junit4] 2> 11142 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[46 (1423034442092380160)]} 0 0
[junit4:junit4] 2> 11144 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10048 (1423034442094477312)]} 0 0
[junit4:junit4] 2> 11147 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[46 (1423034442096574464)]} 0 1
[junit4:junit4] 2> 11149 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10049 (1423034442098671616)]} 0 1
[junit4:junit4] 2> 11150 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[47 (1423034442100768768)]} 0 0
[junit4:junit4] 2> 11154 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10049 (1423034442103914496)]} 0 1
[junit4:junit4] 2> 11154 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[47 (1423034442103914497)]} 0 1
[junit4:junit4] 2> 11156 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10050 (1423034442107060224)]} 0 0
[junit4:junit4] 2> 11157 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[36 (-1423034442108108800)]} 0 0
[junit4:junit4] 2> 11160 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10050 (1423034442110205952)]} 0 1
[junit4:junit4] 2> 11160 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[36 (-1423034442111254528)]} 0 0
[junit4:junit4] 2> 11162 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10037 (-1423034442113351680)]} 0 0
[junit4:junit4] 2> 11164 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[48 (1423034442114400256)]} 0 1
[junit4:junit4] 2> 11165 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10037 (-1423034442116497408)]} 0 0
[junit4:junit4] 2> 11167 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[48 (1423034442117545984)]} 0 1
[junit4:junit4] 2> 11168 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10051 (1423034442119643136)]} 0 0
[junit4:junit4] 2> 11172 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10051 (1423034442122788864)]} 0 1
[junit4:junit4] 2> 11175 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10052 (1423034442125934592)]} 0 1
[junit4:junit4] 2> 11184 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10052 (1423034442130128896)]} 0 6
[junit4:junit4] 2> 11189 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10053 (1423034442140614656)]} 0 0
[junit4:junit4] 2> 11192 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10053 (1423034442143760384)]} 0 1
[junit4:junit4] 2> 11194 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10039 (-1423034442146906112)]} 0 0
[junit4:junit4] 2> 11196 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10039 (-1423034442149003264)]} 0 0
[junit4:junit4] 2> 11200 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10054 (1423034442152148992)]} 0 1
[junit4:junit4] 2> 11203 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10054 (1423034442155294720)]} 0 1
[junit4:junit4] 2> 11206 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10055 (1423034442158440448)]} 0 1
[junit4:junit4] 2> 11208 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10055 (1423034442160537600)]} 0 1
[junit4:junit4] 2> 11210 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10042 (-1423034442163683328)]} 0 0
[junit4:junit4] 2> 11213 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10042 (-1423034442166829056)]} 0 0
[junit4:junit4] 2> 11217 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10056 (1423034442169974784)]} 0 1
[junit4:junit4] 2> 11220 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10056 (1423034442173120512)]} 0 1
[junit4:junit4] 2> 11221 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10046 (-1423034442175217664)]} 0 0
[junit4:junit4] 2> 11224 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10046 (-1423034442178363392)]} 0 0
[junit4:junit4] 2> 11228 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10057 (1423034442181509120)]} 0 1
[junit4:junit4] 2> 11230 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10057 (1423034442184654848)]} 0 0
[junit4:junit4] 2> 11233 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[10049 (-1423034442187800576)]} 0 0
[junit4:junit4] 2> 11233 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[49 (1423034442119643137)]} 0 65
[junit4:junit4] 2> 11235 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10049 (-1423034442189897728)]} 0 0
[junit4:junit4] 2> 11236 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[49 (1423034442190946304)]} 0 0
[junit4:junit4] 2> 11238 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10058 (1423034442193043456)]} 0 0
[junit4:junit4] 2> 11240 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[50 (1423034442194092032)]} 0 1
[junit4:junit4] 2> 11241 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10058 (1423034442196189184)]} 0 0
[junit4:junit4] 2> 11243 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[50 (1423034442198286336)]} 0 0
[junit4:junit4] 2> 11245 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10050 (-1423034442200383488)]} 0 0
[junit4:junit4] 2> 11251 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[37 (-1423034442206674944)]} 0 1
[junit4:junit4] 2> 11257 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[37 (-1423034442211917824)]} 0 1
[junit4:junit4] 2> 11258 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10050 (-1423034442214014976)]} 0 0
[junit4:junit4] 2> 11259 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[51 (1423034442215063552)]} 0 0
[junit4:junit4] 2> 11261 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[51 (1423034442217160704)]} 0 0
[junit4:junit4] 2> 11265 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[52 (1423034442220306432)]} 0 1
[junit4:junit4] 2> 11270 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[52 (1423034442224500736)]} 0 2
[junit4:junit4] 2> 11274 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10059 (1423034442216112128)]} 0 14
[junit4:junit4] 2> 11274 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[53 (1423034442229743616)]} 0 1
[junit4:junit4] 2> 11277 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10059 (1423034442232889345)]} 0 1
[junit4:junit4] 2> 11277 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[53 (1423034442232889344)]} 0 1
[junit4:junit4] 2> 11280 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10051 (-1423034442237083649)]} 0 0
[junit4:junit4] 2> 11280 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[39 (-1423034442237083648)]} 0 0
[junit4:junit4] 2> 11283 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10051 (-1423034442240229376)]} 0 0
[junit4:junit4] 2> 11283 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[39 (-1423034442240229377)]} 0 0
[junit4:junit4] 2> 11286 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[54 (1423034442242326528)]} 0 1
[junit4:junit4] 2> 11286 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10060 (1423034442242326529)]} 0 1
[junit4:junit4] 2> 11290 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10060 (1423034442246520833)]} 0 1
[junit4:junit4] 2> 11290 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[54 (1423034442246520832)]} 0 1
[junit4:junit4] 2> 11292 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10054 (-1423034442249666560)]} 0 0
[junit4:junit4] 2> 11294 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[55 (1423034442251763712)]} 0 0
[junit4:junit4] 2> 11294 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10054 (-1423034442251763712)]} 0 0
[junit4:junit4] 2> 11298 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[55 (1423034442254909440)]} 0 1
[junit4:junit4] 2> 11298 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10061 (1423034442255958016)]} 0 0
[junit4:junit4] 2> 11299 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[42 (-1423034442257006592)]} 0 0
[junit4:junit4] 2> 11301 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10061 (1423034442259103744)]} 0 0
[junit4:junit4] 2> 11302 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[42 (-1423034442260152320)]} 0 0
[junit4:junit4] 2> 11305 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10062 (1423034442262249472)]} 0 1
[junit4:junit4] 2> 11305 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[56 (1423034442263298048)]} 0 0
[junit4:junit4] 2> 11308 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10062 (1423034442265395200)]} 0 1
[junit4:junit4] 2> 11308 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[56 (1423034442266443776)]} 0 0
[junit4:junit4] 2> 11311 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10063 (1423034442268540928)]} 0 1
[junit4:junit4] 2> 11311 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[46 (-1423034442269589504)]} 0 0
[junit4:junit4] 2> 11313 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10063 (1423034442270638080)]} 0 1
[junit4:junit4] 2> 11313 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[46 (-1423034442271686656)]} 0 0
[junit4:junit4] 2> 11315 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[10055 (-1423034442273783808)]} 0 0
[junit4:junit4] 2> 11319 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10055 (-1423034442276929536)]} 0 1
[junit4:junit4] 2> 11320 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[57 (1423034442274832384)]} 0 4
[junit4:junit4] 2> 11322 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10064 (1423034442280075264)]} 0 1
[junit4:junit4] 2> 11325 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10064 (1423034442283220992)]} 0 1
[junit4:junit4] 2> 11325 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[57 (1423034442281123840)]} 0 3
[junit4:junit4] 2> 11327 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10056 (-1423034442286366720)]} 0 0
[junit4:junit4] 2> 11328 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[49 (-1423034442287415296)]} 0 0
[junit4:junit4] 2> 11330 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10056 (-1423034442289512448)]} 0 0
[junit4:junit4] 2> 11331 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[49 (-1423034442290561024)]} 0 0
[junit4:junit4] 2> 11334 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10065 (1423034442292658176)]} 0 1
[junit4:junit4] 2> 11335 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[58 (1423034442294755328)]} 0 0
[junit4:junit4] 2> 11338 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[58 (1423034442296852480)]} 0 1
[junit4:junit4] 2> 11338 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10065 (1423034442296852481)]} 0 1
[junit4:junit4] 2> 11340 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[50 (-1423034442299998208)]} 0 0
[junit4:junit4] 2> 11342 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10066 (1423034442301046784)]} 0 1
[junit4:junit4] 2> 11343 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[50 (-1423034442303143936)]} 0 0
[junit4:junit4] 2> 11345 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10066 (1423034442304192512)]} 0 1
[junit4:junit4] 2> 11346 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[59 (1423034442306289664)]} 0 0
[junit4:junit4] 2> 11348 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10057 (-1423034442308386816)]} 0 0
[junit4:junit4] 2> 11350 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[59 (1423034442310483968)]} 0 0
[junit4:junit4] 2> 11350 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10057 (-1423034442310483969)]} 0 0
[junit4:junit4] 2> 11352 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[51 (-1423034442312581120)]} 0 0
[junit4:junit4] 2> 11353 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10067 (1423034442313629696)]} 0 0
[junit4:junit4] 2> 11355 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[51 (-1423034442315726848)]} 0 0
[junit4:junit4] 2> 11357 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10067 (1423034442316775424)]} 0 1
[junit4:junit4] 2> 11358 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[60 (1423034442318872576)]} 0 0
[junit4:junit4] 2> 11359 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10059 (-1423034442319921152)]} 0 0
[junit4:junit4] 2> 11361 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[60 (1423034442322018304)]} 0 0
[junit4:junit4] 2> 11363 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10059 (-1423034442324115456)]} 0 0
[junit4:junit4] 2> 11364 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[54 (-1423034442325164032)]} 0 0
[junit4:junit4] 2> 11366 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10068 (1423034442326212608)]} 0 1
[junit4:junit4] 2> 11367 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[54 (-1423034442328309760)]} 0 0
[junit4:junit4] 2> 11369 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10068 (1423034442329358336)]} 0 1
[junit4:junit4] 2> 11370 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[61 (1423034442331455488)]} 0 0
[junit4:junit4] 2> 11372 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10060 (-1423034442333552640)]} 0 0
[junit4:junit4] 2> 11374 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[61 (1423034442335649792)]} 0 0
[junit4:junit4] 2> 11376 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10060 (-1423034442337746944)]} 0 0
[junit4:junit4] 2> 11379 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10069 (1423034442339844096)]} 0 1
[junit4:junit4] 2> 11379 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[62 (1423034442337746944)]} 0 3
[junit4:junit4] 2> 11383 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[62 (1423034442344038401)]} 0 1
[junit4:junit4] 2> 11386 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10069 (1423034442344038400)]} 0 4
[junit4:junit4] 2> 11387 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[63 (1423034442349281280)]} 0 0
[junit4:junit4] 2> 11389 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10061 (-1423034442351378432)]} 0 0
[junit4:junit4] 2> 11391 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[63 (1423034442352427008)]} 0 1
[junit4:junit4] 2> 11391 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10061 (-1423034442353475584)]} 0 0
[junit4:junit4] 2> 11393 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[55 (-1423034442355572736)]} 0 0
[junit4:junit4] 2> 11395 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10070 (1423034442356621312)]} 0 1
[junit4:junit4] 2> 11396 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[55 (-1423034442358718464)]} 0 0
[junit4:junit4] 2> 11399 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10070 (1423034442361864192)]} 0 0
[junit4:junit4] 2> 11400 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[64 (1423034442361864192)]} 0 1
[junit4:junit4] 2> 11402 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[10062 (-1423034442365009920)]} 0 0
[junit4:junit4] 2> 11402 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[64 (1423034442365009920)]} 0 0
[junit4:junit4] 2> 11404 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10062 (-1423034442367107072)]} 0 0
[junit4:junit4] 2> 11406 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[56 (-1423034442369204224)]} 0 0
[junit4:junit4] 2> 11408 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10071 (1423034442370252800)]} 0 1
[junit4:junit4] 2> 11409 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[56 (-1423034442372349952)]} 0 0
[junit4:junit4] 2> 11410 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10071 (1423034442373398528)]} 0 0
[junit4:junit4] 2> 11413 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[65 (1423034442375495680)]} 0 1
[junit4:junit4] 2> 11414 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[10063 (-1423034442377592832)]} 0 0
[junit4:junit4] 2> 11415 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[65 (1423034442378641408)]} 0 0
[junit4:junit4] 2> 11416 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10063 (-1423034442379689984)]} 0 0
[junit4:junit4] 2> 11418 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[66 (1423034442381787136)]} 0 0
[junit4:junit4] 2> 11420 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10072 (1423034442383884288)]} 0 0
[junit4:junit4] 2> 11422 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[66 (1423034442385981440)]} 0 0
[junit4:junit4] 2> 11423 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10072 (1423034442387030016)]} 0 0
[junit4:junit4] 2> 11425 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[57 (-1423034442389127168)]} 0 0
[junit4:junit4] 2> 11427 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10067 (-1423034442391224320)]} 0 0
[junit4:junit4] 2> 11428 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[57 (-1423034442392272896)]} 0 0
[junit4:junit4] 2> 11428 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10067 (-1423034442392272897)]} 0 0
[junit4:junit4] 2> 11430 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[67 (1423034442394370048)]} 0 0
[junit4:junit4] 2> 11434 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[67 (1423034442398564352)]} 0 0
[junit4:junit4] 2> 11435 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10073 (1423034442396467200)]} 0 3
[junit4:junit4] 2> 11437 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[59 (-1423034442401710080)]} 0 0
[junit4:junit4] 2> 11440 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[59 (-1423034442404855808)]} 0 0
[junit4:junit4] 2> 11441 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10073 (1423034442402758656)]} 0 3
[junit4:junit4] 2> 11443 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[68 (1423034442406952960)]} 0 1
[junit4:junit4] 2> 11444 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10069 (-1423034442409050112)]} 0 0
[junit4:junit4] 2> 11447 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[68 (1423034442411147264)]} 0 1
[junit4:junit4] 2> 11448 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10069 (-1423034442413244416)]} 0 1
[junit4:junit4] 2> 11449 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[60 (-1423034442414292992)]} 0 0
[junit4:junit4] 2> 11450 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10074 (1423034442415341568)]} 0 0
[junit4:junit4] 2> 11452 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[60 (-1423034442417438720)]} 0 0
[junit4:junit4] 2> 11454 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10074 (1423034442419535872)]} 0 0
[junit4:junit4] 2> 11455 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[69 (1423034442419535872)]} 0 1
[junit4:junit4] 2> 11457 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10075 (1423034442421633024)]} 0 1
[junit4:junit4] 2> 11457 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[69 (1423034442422681600)]} 0 0
[junit4:junit4] 2> 11459 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10075 (1423034442424778752)]} 0 0
[junit4:junit4] 2> 11461 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[61 (-1423034442426875904)]} 0 0
[junit4:junit4] 2> 11463 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10070 (-1423034442428973056)]} 0 0
[junit4:junit4] 2> 11464 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[61 (-1423034442430021632)]} 0 0
[junit4:junit4] 2> 11466 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10070 (-1423034442432118784)]} 0 0
[junit4:junit4] 2> 11467 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[70 (1423034442433167360)]} 0 0
[junit4:junit4] 2> 11469 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10076 (1423034442434215936)]} 0 1
[junit4:junit4] 2> 11469 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[70 (1423034442435264512)]} 0 0
[junit4:junit4] 2> 11472 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10076 (1423034442437361664)]} 0 1
[junit4:junit4] 2> 11472 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[62 (-1423034442438410240)]} 0 0
[junit4:junit4] 2> 11474 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10071 (-1423034442440507392)]} 0 0
[junit4:junit4] 2> 11476 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[62 (-1423034442442604544)]} 0 0
[junit4:junit4] 2> 11477 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10071 (-1423034442443653120)]} 0 0
[junit4:junit4] 2> 11478 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[71 (1423034442444701696)]} 0 0
[junit4:junit4] 2> 11480 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10077 (1423034442446798848)]} 0 0
[junit4:junit4] 2> 11480 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[71 (1423034442446798848)]} 0 0
[junit4:junit4] 2> 11483 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10077 (1423034442448896000)]} 0 1
[junit4:junit4] 2> 11483 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[63 (-1423034442449944576)]} 0 0
[junit4:junit4] 2> 11485 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[10072 (-1423034442452041728)]} 0 0
[junit4:junit4] 2> 11486 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[63 (-1423034442453090304)]} 0 0
[junit4:junit4] 2> 11488 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10072 (-1423034442455187456)]} 0 0
[junit4:junit4] 2> 11490 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[72 (1423034442456236032)]} 0 1
[junit4:junit4] 2> 11492 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[72 (1423034442459381760)]} 0 0
[junit4:junit4] 2> 11494 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10078 (1423034442458333184)]} 0 3
[junit4:junit4] 2> 11494 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[67 (-1423034442461478912)]} 0 0
[junit4:junit4] 2> 11497 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[67 (-1423034442464624640)]} 0 0
[junit4:junit4] 2> 11503 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[73 (1423034442468818944)]} 0 2
[junit4:junit4] 2> 11503 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10078 (1423034442464624641)]} 0 6
[junit4:junit4] 2> 11506 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10076 (-1423034442474061824)]} 0 0
[junit4:junit4] 2> 11506 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[73 (1423034442474061824)]} 0 0
[junit4:junit4] 2> 11508 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10076 (-1423034442476158976)]} 0 0
[junit4:junit4] 2> 11509 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[69 (-1423034442477207552)]} 0 0
[junit4:junit4] 2> 11510 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10079 (1423034442478256128)]} 0 0
[junit4:junit4] 2> 11512 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[69 (-1423034442480353280)]} 0 0
[junit4:junit4] 2> 11514 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10079 (1423034442481401856)]} 0 1
[junit4:junit4] 2> 11515 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[74 (1423034442483499008)]} 0 0
[junit4:junit4] 2> 11518 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10080 (1423034442485596160)]} 0 1
[junit4:junit4] 2> 11519 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[74 (1423034442487693312)]} 0 0
[junit4:junit4] 2> 11520 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10080 (1423034442487693313)]} 0 1
[junit4:junit4] 2> 11522 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10077 (-1423034442490839040)]} 0 0
[junit4:junit4] 2> 11522 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[75 (1423034442490839041)]} 0 0
[junit4:junit4] 2> 11525 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10077 (-1423034442493984768)]} 0 0
[junit4:junit4] 2> 11526 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[75 (1423034442493984769)]} 0 1
[junit4:junit4] 2> 11528 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[70 (-1423034442497130496)]} 0 0
[junit4:junit4] 2> 11528 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10081 (1423034442497130497)]} 0 0
[junit4:junit4] 2> 11531 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[70 (-1423034442500276224)]} 0 0
[junit4:junit4] 2> 11532 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10081 (1423034442501324800)]} 0 0
[junit4:junit4] 2> 11533 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[76 (1423034442502373376)]} 0 0
[junit4:junit4] 2> 11534 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10082 (1423034442503421952)]} 0 0
[junit4:junit4] 2> 11536 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[76 (1423034442505519104)]} 0 0
[junit4:junit4] 2> 11538 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10082 (1423034442506567680)]} 0 1
[junit4:junit4] 2> 11539 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[71 (-1423034442508664832)]} 0 0
[junit4:junit4] 2> 11541 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10078 (-1423034442510761984)]} 0 0
[junit4:junit4] 2> 11543 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[71 (-1423034442512859136)]} 0 0
[junit4:junit4] 2> 11544 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10078 (-1423034442513907712)]} 0 0
[junit4:junit4] 2> 11546 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10083 (1423034442516004864)]} 0 0
[junit4:junit4] 2> 11551 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10083 (1423034442520199168)]} 0 1
[junit4:junit4] 2> 11556 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10084 (1423034442524393472)]} 0 1
[junit4:junit4] 2> 11562 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[77 (1423034442516004865)]} 0 16
[junit4:junit4] 2> 11602 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[77 (1423034442536976384)]} 0 36
[junit4:junit4] 2> 11606 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[72 (-1423034442577870848)]} 0 0
[junit4:junit4] 2> 11608 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[72 (-1423034442581016576)]} 0 0
[junit4:junit4] 2> 11611 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[78 (1423034442583113728)]} 0 0
[junit4:junit4] 2> 11614 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[78 (1423034442587308032)]} 0 0
[junit4:junit4] 2> 11617 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[76 (-1423034442590453760)]} 0 0
[junit4:junit4] 2> 11620 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[76 (-1423034442593599488)]} 0 0
[junit4:junit4] 2> 11623 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[79 (1423034442596745216)]} 0 0
[junit4:junit4] 2> 11626 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[79 (1423034442598842368)]} 0 1
[junit4:junit4] 2> 11629 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[80 (1423034442601988096)]} 0 1
[junit4:junit4] 2> 11631 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[80 (1423034442605133824)]} 0 0
[junit4:junit4] 2> 11635 T2421 C175 P50365 /update {wt=javabin&version=2} {delete=[77 (-1423034442609328128)]} 0 0
[junit4:junit4] 2> 11637 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[77 (-1423034442611425280)]} 0 0
[junit4:junit4] 2> 11640 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[81 (1423034442614571008)]} 0 0
[junit4:junit4] 2> 11644 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[81 (1423034442618765312)]} 0 0
[junit4:junit4] 2> 11647 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[82 (1423034442621911040)]} 0 0
[junit4:junit4] 2> 11649 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[82 (1423034442624008192)]} 0 0
[junit4:junit4] 2> 11652 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[78 (-1423034442627153920)]} 0 0
[junit4:junit4] 2> 11655 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[78 (-1423034442630299648)]} 0 0
[junit4:junit4] 2> 11658 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[83 (1423034442633445376)]} 0 0
[junit4:junit4] 2> 11662 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[83 (1423034442636591104)]} 0 1
[junit4:junit4] 2> 11665 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[84 (1423034442640785408)]} 0 0
[junit4:junit4] 2> 11669 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[84 (1423034442643931136)]} 0 1
[junit4:junit4] 2> 11672 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[85 (1423034442648125440)]} 0 0
[junit4:junit4] 2> 11675 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[85 (1423034442651271168)]} 0 0
[junit4:junit4] 2> 11680 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[86 (1423034442653368320)]} 0 3
[junit4:junit4] 2> 11687 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[86 (1423034442659659776)]} 0 4
[junit4:junit4] 2> 11690 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[87 (1423034442665951232)]} 0 1
[junit4:junit4] 2> 11694 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[87 (1423034442670145536)]} 0 1
[junit4:junit4] 2> 11694 T2429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 11696 T2429 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[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:50386_ux_xgd%2Fj",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50386/ux_xgd/j"}
[junit4:junit4] 2> 11699 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[88 (1423034442675388416)]} 0 1
[junit4:junit4] 2> 11702 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[88 (1423034442678534144)]} 0 1
[junit4:junit4] 2> 11705 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[89 (1423034442682728448)]} 0 0
[junit4:junit4] 2> 11709 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[89 (1423034442685874176)]} 0 1
[junit4:junit4] 2> 11712 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[79 (-1423034442690068480)]} 0 0
[junit4:junit4] 2> 11714 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[79 (-1423034442692165632)]} 0 0
[junit4:junit4] 2> 11717 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[90 (1423034442695311360)]} 0 0
[junit4:junit4] 2> 11718 T2447 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> 11719 T2462 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> 11721 T2434 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> 11721 T2428 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> 11722 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[90 (1423034442699505664)]} 0 1
[junit4:junit4] 2> 11726 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[80 (-1423034442704748544)]} 0 0
[junit4:junit4] 2> 11728 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10084 (1423034442528587776)]} 0 170
[junit4:junit4] 2> 11730 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10085 (1423034442707894272)]} 0 1
[junit4:junit4] 2> C174_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:50386_ux_xgd%2Fj, base_url=http://127.0.0.1:50386/ux_xgd/j}
[junit4:junit4] 2> 11743 T2458 C174 P50386 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357111387646\jetty2
[junit4:junit4] 2> 11749 T2458 C174 P50386 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1cb979b lockFactory=org.apache.lucene.store.NativeFSLockFactory@19b55d6)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 11750 T2458 C174 P50386 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 11752 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[80 (-1423034442707894272)]} 0 11
[junit4:junit4] 2> 11754 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[80 (-1423034442707894272)]} 0 25
[junit4:junit4] 2> 11754 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10085 (1423034442713137152)]} 0 9
[junit4:junit4] 2> 11754 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10085 (1423034442713137152)]} 0 20
[junit4:junit4] 2> 11757 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[91 (1423034442736205824)]} 0 1
[junit4:junit4] 2> 11758 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10086 (1423034442737254400)]} 0 1
[junit4:junit4] 2> 11763 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[91 (1423034442739351552)]} 0 1
[junit4:junit4] 2> 11765 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[91 (1423034442739351552)]} 0 6
[junit4:junit4] 2> 11765 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10086 (1423034442741448704)]} 0 1
[junit4:junit4] 2> 11766 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10086 (1423034442741448704)]} 0 5
[junit4:junit4] 2> 11769 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[92 (1423034442748788736)]} 0 1
[junit4:junit4] 2> 11769 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10087 (1423034442748788737)]} 0 1
[junit4:junit4] 2> 11775 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[92 (1423034442751934464)]} 0 1
[junit4:junit4] 2> 11776 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[92 (1423034442751934464)]} 0 5
[junit4:junit4] 2> 11777 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10087 (1423034442752983040)]} 0 1
[junit4:junit4] 2> 11778 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10087 (1423034442752983040)]} 0 6
[junit4:junit4] 2> 11780 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10088 (1423034442761371648)]} 0 0
[junit4:junit4] 2> 11781 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[93 (1423034442760323072)]} 0 2
[junit4:junit4] 2> 11788 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[93 (1423034442765565952)]} 0 1
[junit4:junit4] 2> 11789 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[93 (1423034442765565952)]} 0 5
[junit4:junit4] 2> 11792 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[94 (1423034442773954560)]} 0 0
[junit4:junit4] 2> 11798 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[94 (1423034442776051712)]} 0 1
[junit4:junit4] 2> 11799 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10088 (1423034442764517376)]} 0 1
[junit4:junit4] 2> 11799 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[94 (1423034442776051712)]} 0 5
[junit4:junit4] 2> 11799 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10088 (1423034442764517376)]} 0 16
[junit4:junit4] 2> 11803 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[95 (1423034442784440320)]} 0 1
[junit4:junit4] 2> 11803 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10089 (1423034442784440321)]} 0 1
[junit4:junit4] 2> 11808 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[95 (1423034442787586048)]} 0 0
[junit4:junit4] 2> 11809 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[95 (1423034442787586048)]} 0 4
[junit4:junit4] 2> 11813 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[96 (1423034442795974656)]} 0 0
[junit4:junit4] 2> 11814 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10089 (1423034442789683200)]} 0 4
[junit4:junit4] 2> 11817 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10089 (1423034442789683200)]} 0 10
[junit4:junit4] 2> 11819 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[10079 (-1423034442802266112)]} 0 0
[junit4:junit4] 2> 11820 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[96 (1423034442800168960)]} 0 1
[junit4:junit4] 2> 11821 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[96 (1423034442800168960)]} 0 4
[junit4:junit4] 2> 11823 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10079 (-1423034442804363264)]} 0 0
[junit4:junit4] 2> 11824 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[10079 (-1423034442804363264)]} 0 3
[junit4:junit4] 2> 11825 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[97 (1423034442807508992)]} 0 1
[junit4:junit4] 2> 11827 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10090 (1423034442809606144)]} 0 1
[junit4:junit4] 2> 11831 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[97 (1423034442810654720)]} 0 1
[junit4:junit4] 2> 11831 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[97 (1423034442810654720)]} 0 4
[junit4:junit4] 2> 11832 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10090 (1423034442812751872)]} 0 0
[junit4:junit4] 2> 11833 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10090 (1423034442812751872)]} 0 4
[junit4:junit4] 2> 11833 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[81 (-1423034442816946176)]} 0 0
[junit4:junit4] 2> 11835 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[10080 (-1423034442819043328)]} 0 0
[junit4:junit4] 2> 11840 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[81 (-1423034442821140480)]} 0 1
[junit4:junit4] 2> 11840 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[81 (-1423034442821140480)]} 0 3
[junit4:junit4] 2> 11842 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10080 (-1423034442823237632)]} 0 0
[junit4:junit4] 2> 11843 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10080 (-1423034442823237632)]} 0 4
[junit4:junit4] 2> 11844 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[98 (1423034442827431936)]} 0 1
[junit4:junit4] 2> 11846 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10091 (1423034442829529088)]} 0 1
[junit4:junit4] 2> 11849 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[98 (1423034442830577664)]} 0 0
[junit4:junit4] 2> 11851 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[98 (1423034442830577664)]} 0 5
[junit4:junit4] 2> 11852 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10091 (1423034442832674816)]} 0 1
[junit4:junit4] 2> 11853 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10091 (1423034442832674816)]} 0 5
[junit4:junit4] 2> 11854 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[82 (-1423034442838966272)]} 0 0
[junit4:junit4] 2> 11858 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10092 (1423034442840014848)]} 0 3
[junit4:junit4] 2> 11858 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[82 (-1423034442842112000)]} 0 0
[junit4:junit4] 2> 11859 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[82 (-1423034442842112000)]} 0 2
[junit4:junit4] 2> 11863 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[99 (1423034442847354880)]} 0 1
[junit4:junit4] 2> 11867 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10092 (1423034442846306304)]} 0 1
[junit4:junit4] 2> 11868 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10092 (1423034442846306304)]} 0 7
[junit4:junit4] 2> 11870 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[99 (1423034442851549184)]} 0 1
[junit4:junit4] 2> 11870 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10093 (1423034442855743488)]} 0 0
[junit4:junit4] 2> 11870 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[99 (1423034442851549184)]} 0 4
[junit4:junit4] 2> 11874 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[100 (1423034442858889216)]} 0 0
[junit4:junit4] 2> 11876 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10093 (1423034442858889216)]} 0 0
[junit4:junit4] 2> 11877 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10093 (1423034442858889216)]} 0 4
[junit4:junit4] 2> 11879 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[100 (1423034442862034944)]} 0 1
[junit4:junit4] 2> 11880 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[100 (1423034442862034944)]} 0 4
[junit4:junit4] 2> 11881 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10094 (1423034442866229248)]} 0 1
[junit4:junit4] 2> 11883 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[101 (1423034442869374976)]} 0 0
[junit4:junit4] 2> 11889 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[101 (1423034442871472128)]} 0 1
[junit4:junit4] 2> 11890 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[101 (1423034442871472128)]} 0 5
[junit4:junit4] 2> 11891 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10094 (1423034442870423552)]} 0 4
[junit4:junit4] 2> 11892 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10094 (1423034442870423552)]} 0 8
[junit4:junit4] 2> 11894 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[85 (-1423034442880909312)]} 0 0
[junit4:junit4] 2> 11897 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[10095 (1423034442883006464)]} 0 1
[junit4:junit4] 2> 11898 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[85 (-1423034442884055040)]} 0 0
[junit4:junit4] 2> 11899 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[85 (-1423034442884055040)]} 0 2
[junit4:junit4] 2> 11901 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10095 (1423034442885103616)]} 0 0
[junit4:junit4] 2> 11901 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[102 (1423034442888249344)]} 0 0
[junit4:junit4] 2> 11902 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10095 (1423034442885103616)]} 0 4
[junit4:junit4] 2> 11905 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10096 (1423034442892443648)]} 0 0
[junit4:junit4] 2> 11908 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[102 (1423034442892443648)]} 0 0
[junit4:junit4] 2> 11909 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[102 (1423034442892443648)]} 0 4
[junit4:junit4] 2> 11910 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10096 (1423034442895589376)]} 0 0
[junit4:junit4] 2> 11911 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[103 (1423034442898735104)]} 0 0
[junit4:junit4] 2> 11912 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10096 (1423034442895589376)]} 0 4
[junit4:junit4] 2> 11916 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[103 (1423034442901880832)]} 0 0
[junit4:junit4] 2> 11916 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[10097 (1423034442901880832)]} 0 2
[junit4:junit4] 2> 11918 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[103 (1423034442901880832)]} 0 4
[junit4:junit4] 2> 11920 T2422 C175 P50365 /update {wt=javabin&version=2} {delete=[88 (-1423034442908172288)]} 0 0
[junit4:junit4] 2> 11923 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[88 (-1423034442910269440)]} 0 0
[junit4:junit4] 2> 11924 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[88 (-1423034442910269440)]} 0 2
[junit4:junit4] 2> 11925 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10097 (1423034442908172288)]} 0 1
[junit4:junit4] 2> 11926 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[10097 (1423034442908172288)]} 0 6
[junit4:junit4] 2> 11928 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[10081 (-1423034442916560897)]} 0 0
[junit4:junit4] 2> 11929 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[104 (1423034442916560896)]} 0 1
[junit4:junit4] 2> 11933 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10081 (-1423034442919706624)]} 0 0
[junit4:junit4] 2> 11934 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[10081 (-1423034442919706624)]} 0 3
[junit4:junit4] 2> 11935 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[104 (1423034442920755200)]} 0 0
[junit4:junit4] 2> 11936 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[104 (1423034442920755200)]} 0 4
[junit4:junit4] 2> 11937 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10098 (1423034442925998080)]} 0 0
[junit4:junit4] 2> 11939 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[105 (1423034442928095232)]} 0 0
[junit4:junit4] 2> 11945 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10098 (1423034442930192384)]} 0 1
[junit4:junit4] 2> 11945 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10098 (1423034442930192384)]} 0 4
[junit4:junit4] 2> 11948 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[105 (1423034442932289536)]} 0 0
[junit4:junit4] 2> 11949 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[105 (1423034442932289536)]} 0 6
[junit4:junit4] 2> 11950 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[10082 (-1423034442939629568)]} 0 0
[junit4:junit4] 2> 11951 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[91 (-1423034442940678144)]} 0 0
[junit4:junit4] 2> 11956 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10082 (-1423034442942775296)]} 0 0
[junit4:junit4] 2> 11957 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10082 (-1423034442942775296)]} 0 4
[junit4:junit4] 2> 11958 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[91 (-1423034442943823872)]} 0 0
[junit4:junit4] 2> 11959 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[91 (-1423034442943823872)]} 0 5
[junit4:junit4] 2> 11961 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[10099 (1423034442950115328)]} 0 1
[junit4:junit4] 2> 11962 T2417 C175 P50365 /update {wt=javabin&version=2} {add=[106 (1423034442952212480)]} 0 0
[junit4:junit4] 2> 11967 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[106 (1423034442954309632)]} 0 0
[junit4:junit4] 2> 11969 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10099 (1423034442952212480)]} 0 3
[junit4:junit4] 2> 11970 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[106 (1423034442954309632)]} 0 6
[junit4:junit4] 2> 11970 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10099 (1423034442952212480)]} 0 8
[junit4:junit4] 2> 11974 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[107 (1423034442963746816)]} 0 1
[junit4:junit4] 2> 11974 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[10100 (1423034442963746817)]} 0 1
[junit4:junit4] 2> 11980 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10100 (1423034442966892545)]} 0 1
[junit4:junit4] 2> 11980 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[107 (1423034442966892544)]} 0 1
[junit4:junit4] 2> 11980 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[107 (1423034442966892544)]} 0 4
[junit4:junit4] 2> 11980 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10100 (1423034442966892545)]} 0 4
[junit4:junit4] 2> 11985 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[108 (1423034442975281152)]} 0 1
[junit4:junit4] 2> 11985 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10101 (1423034442975281153)]} 0 1
[junit4:junit4] 2> 11989 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[108 (1423034442978426880)]} 0 0
[junit4:junit4] 2> 11990 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[108 (1423034442978426880)]} 0 3
[junit4:junit4] 2> 11990 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10101 (1423034442979475456)]} 0 0
[junit4:junit4] 2> 11992 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10101 (1423034442979475456)]} 0 4
[junit4:junit4] 2> 11995 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10085 (-1423034442986815488)]} 0 0
[junit4:junit4] 2> 11996 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[109 (1423034442984718336)]} 0 3
[junit4:junit4] 2> 12001 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10085 (-1423034442991009792)]} 0 0
[junit4:junit4] 2> 12002 T2442 C176 P50377 /update {wt=javabin&version=2} {delete=[10085 (-1423034442991009792)]} 0 3
[junit4:junit4] 2> 12003 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[109 (1423034442991009793)]} 0 0
[junit4:junit4] 2> 12005 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[109 (1423034442991009793)]} 0 6
[junit4:junit4] 2> 12005 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10102 (1423034442997301248)]} 0 0
[junit4:junit4] 2> 12008 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[95 (-1423034443000446976)]} 0 0
[junit4:junit4] 2> 12012 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10102 (1423034443000446976)]} 0 0
[junit4:junit4] 2> 12014 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[10102 (1423034443000446976)]} 0 6
[junit4:junit4] 2> 12014 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[95 (-1423034443003592704)]} 0 0
[junit4:junit4] 2> 12014 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[95 (-1423034443003592704)]} 0 3
[junit4:junit4] 2> 12016 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10103 (1423034443007787008)]} 0 1
[junit4:junit4] 2> 12016 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[110 (1423034443008835584)]} 0 0
[junit4:junit4] 2> 12022 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10103 (1423034443010932736)]} 0 1
[junit4:junit4] 2> 12023 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[110 (1423034443011981312)]} 0 1
[junit4:junit4] 2> 12024 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10103 (1423034443010932736)]} 0 6
[junit4:junit4] 2> 12024 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[110 (1423034443011981312)]} 0 5
[junit4:junit4] 2> 12026 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10088 (-1423034443019321344)]} 0 0
[junit4:junit4] 2> 12027 T2419 C175 P50365 /update {wt=javabin&version=2} {add=[111 (1423034443020369920)]} 0 0
[junit4:junit4] 2> 12030 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10088 (-1423034443021418496)]} 0 0
[junit4:junit4] 2> 12040 T2440 C176 P50377 /update {wt=javabin&version=2} {delete=[10088 (-1423034443021418496)]} 0 12
[junit4:junit4] 2> 12043 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[111 (1423034443022467072)]} 0 3
[junit4:junit4] 2> 12043 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10104 (1423034443037147136)]} 0 0
[junit4:junit4] 2> 12044 T2439 C176 P50377 /update {wt=javabin&version=2} {add=[111 (1423034443022467072)]} 0 15
[junit4:junit4] 2> 12047 T2423 C175 P50365 /update {wt=javabin&version=2} {delete=[96 (-1423034443041341440)]} 0 0
[junit4:junit4] 2> 12051 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10104 (1423034443041341440)]} 0 1
[junit4:junit4] 2> 12052 T2438 C176 P50377 /update {wt=javabin&version=2} {add=[10104 (1423034443041341440)]} 0 5
[junit4:junit4] 2> 12052 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[96 (-1423034443044487168)]} 0 0
[junit4:junit4] 2> 12053 T2443 C176 P50377 /update {wt=javabin&version=2} {delete=[96 (-1423034443044487168)]} 0 3
[junit4:junit4] 2> 12054 T2422 C175 P50365 /update {wt=javabin&version=2} {add=[10105 (1423034443047632896)]} 0 1
[junit4:junit4] 2> 12055 T2424 C175 P50365 /update {wt=javabin&version=2} {add=[112 (1423034443049730048)]} 0 0
[junit4:junit4] 2> 12060 T2458 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10105 (1423034443051827200)]} 0 0
[junit4:junit4] 2> 12062 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[10105 (1423034443051827200)]} 0 5
[junit4:junit4] 2> 12062 T2455 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[112 (1423034443052875776)]} 0 0
[junit4:junit4] 2> 12064 T2440 C176 P50377 /update {wt=javabin&version=2} {add=[112 (1423034443052875776)]} 0 6
[junit4:junit4] 2> 12064 T2417 C175 P50365 /update {wt=javabin&version=2} {delete=[10091 (-1423034443059167232)]} 0 0
[junit4:junit4] 2> 12066 T2419 C175 P50365 /update {wt=javabin&version=2} {delete=[97 (-1423034443061264384)]} 0 0
[junit4:junit4] 2> 12069 T2456 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10091 (-1423034443062312960)]} 0 0
[junit4:junit4] 2> 12070 T2439 C176 P50377 /update {wt=javabin&version=2} {delete=[10091 (-1423034443062312960)]} 0 4
[junit4:junit4] 2> 12072 T2454 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[97 (-1423034443064410112)]} 0 0
[junit4:junit4] 2> 12074 T2438 C176 P50377 /update {wt=javabin&version=2} {delete=[97 (-1423034443064410112)]} 0 4
[junit4:junit4] 2> 12074 T2421 C175 P50365 /update {wt=javabin&version=2} {add=[10106 (1423034443069652992)]} 0 0
[junit4:junit4] 2> 12077 T2423 C175 P50365 /update {wt=javabin&version=2} {add=[113 (1423034443071750144)]} 0 1
[junit4:junit4] 2> 12081 T2453 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10106 (1423034443073847296)]} 0 1
[junit4:junit4] 2> 12081 T2443 C176 P50377 /update {wt=javabin&version=2} {add=[10106 (1423034443073847296)]} 0 3
[junit4:junit4] 2> 12084 T2457 C174 P50386 /update {distrib.from=http://127.0.0.1:50377/ux_xgd/j/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[113 (1423034443075944448)]} 0 1
[junit4:junit4] 2> 12085 T2442 C176 P50377 /update {wt=javabin&version=2} {add=[113 (1423034443075944448)]} 0 5
[junit4:junit4] 2> 12087 T2424 C175 P50365 /update {wt=javabin&version=2} {delete=[99 (-14230
[...truncated too long message...]
unit4:junit4] > at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:175)
[junit4:junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:125)
[junit4:junit4] > at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:76)
[junit4:junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:794)
[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> 298883 T2406 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene41: {range_facet_l=PostingsFormat(name=Direct), text=PostingsFormat(name=Memory doPackFST= true), _version_=PostingsFormat(name=Direct), rnd_b=PostingsFormat(name=NestedPulsing), intDefault=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=Direct), id=PostingsFormat(name=NestedPulsing), a_t=PostingsFormat(name=Direct), range_facet_sl=PostingsFormat(name=Memory doPackFST= true), range_facet_si=PostingsFormat(name=MockSep), other_tl1=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=MockSep), a_si=PostingsFormat(name=MockSep)}, sim=DefaultSimilarity, locale=is_IS, timezone=Africa/Monrovia
[junit4:junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_09 (32-bit)/cpus=2,threads=1,free=47907848,total=275054592
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestQueryTypes, TestStressReorder, SolrInfoMBeanTest, ResourceLoaderTest, MinimalSchemaTest, UUIDFieldTest, TestFoldingMultitermQuery, FileUtilsTest, TestSolrJ, PrimitiveFieldTypeTest, TestLMJelinekMercerSimilarityFactory, BasicFunctionalityTest, DistributedTermsComponentTest, PreAnalyzedFieldTest, LukeRequestHandlerTest, JSONWriterTest, TestSolrDeletionPolicy2, FastVectorHighlighterTest, TestRTGBase, HighlighterConfigTest, QueryEqualityTest, TestCoreContainer, TimeZoneUtilsTest, TestDocumentBuilder, TestReload, TestXIncludeConfig, FieldMutatingUpdateProcessorTest, TestCollationField, TestElisionMultitermQuery, CurrencyFieldTest, TestFaceting, DebugComponentTest, TestPhraseSuggestions, ShowFileRequestHandlerTest, CoreAdminHandlerTest, PathHierarchyTokenizerFactoryTest, TestQuerySenderListener, TestPHPSerializedResponseWriter, LeaderElectionIntegrationTest, TestPseudoReturnFields, TestFastWriter, TestSuggestSpellingConverter, DateFieldTest, TestRangeQuery, TestBinaryResponseWriter, TestCodecSupport, TestSolrXMLSerializer, TestMergePolicyConfig, DateMathParserTest, TestDistributedSearch, TestAnalyzedSuggestions, BasicZkTest, SolrCmdDistributorTest, TestFunctionQuery, ZkSolrClientTest, SpellCheckComponentTest, SuggesterWFSTTest, TestRemoteStreaming, DirectUpdateHandlerOptimizeTest, TestJoin, BasicDistributedZk2Test, TestQueryUtils, SearchHandlerTest, TestPerFieldSimilarity, DistributedSpellCheckComponentTest, TestDefaultSimilarityFactory, RAMDirectoryFactoryTest, FullSolrCloudDistribCmdsTest, TermVectorComponentDistributedTest, BasicDistributedZkTest, DirectSolrConnectionTest, WordBreakSolrSpellCheckerTest, TestJmxMonitoredMap, TestRandomFaceting, CacheHeaderTest, BadIndexSchemaTest, StatelessScriptUpdateProcessorFactoryTest, TestDocSet, SpellCheckCollatorTest, TestSolrQueryParser, TestFuzzyAnalyzedSuggestions, SpellPossibilityIteratorTest, SolrCoreCheckLockOnStartupTest, TestPluginEnable, TestLRUCache, SuggesterFSTTest, TestSearchPerf, SortByFunctionTest, JsonLoaderTest, TestPropInjectDefaults, TestSolrIndexConfig, NumericFieldsTest, SpellingQueryConverterTest, ClusterStateUpdateTest, OutputWriterTest, ReturnFieldsTest, TestAtomicUpdateErrorCases, ShardRoutingCustomTest, TermVectorComponentTest, IndexSchemaTest, PeerSyncTest, TestCharFilters, TestPropInject, TestStressVersions, UpdateRequestProcessorFactoryTest, SynonymTokenizerTest, TestOmitPositions, TestFiltering, SchemaVersionSpecificBehaviorTest, DOMUtilTest, TestLazyCores, RecoveryZkTest]
[junit4:junit4] Completed in 298.91s, 1 test, 1 failure <<< FAILURES!
[...truncated 419 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:353: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:178: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:438: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1176: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:841: There were test failures: 246 suites, 1015 tests, 1 failure, 14 ignored (9 assumptions)
Total time: 52 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_09 -client -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure