lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-trunk-java7 - Build # 3778 - Still Failing
Date Sat, 02 Mar 2013 11:14:40 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-java7/3778/

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest

Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1191, name=coreLoadExecutor-730-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.

Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1191, name=coreLoadExecutor-730-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	at java.lang.Thread.getStackTrace(Thread.java:1567)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:986)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
	at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:244)
	at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:458)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:733)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:619)
	at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:868)
	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:951)
	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:574)
	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:569)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
	at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/index2241322431tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c069a07)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	... 1 more


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest

Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1193, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.

Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1193, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	at java.lang.Thread.getStackTrace(Thread.java:1567)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:986)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:383)
	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:281)
	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:153)
	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:409)
	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:223)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/index2241322432tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@5da8d28)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9069 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 0 T1132 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4]   2> 6 T1132 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./solrtest-RecoveryZkTest-1362221872341
[junit4:junit4]   2> 7 T1132 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 8 T1133 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 11 T1133 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 11 T1133 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 12 T1133 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 12 T1133 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 13 T1133 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 109 T1132 oasc.ZkTestServer.run start zk server on port:62292
[junit4:junit4]   2> 109 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@34d39449
[junit4:junit4]   2> 110 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 111 T1138 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 112 T1138 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 112 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:62293
[junit4:junit4]   2> 112 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:62293
[junit4:junit4]   2> 113 T1136 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 115 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0000 with negotiated timeout 10000 for client /140.211.11.196:62293
[junit4:junit4]   2> 115 T1138 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0000, negotiated timeout = 10000
[junit4:junit4]   2> 116 T1139 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@34d39449 name:ZooKeeperConnection Watcher:127.0.0.1:62292 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 116 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 117 T1132 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 133 T1137 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2ac034dc0000
[junit4:junit4]   2> 134 T1139 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 134 T1132 oaz.ZooKeeper.close Session: 0x13d2ac034dc0000 closed
[junit4:junit4]   2> 135 T1134 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:62293 which had sessionid 0x13d2ac034dc0000
[junit4:junit4]   2> 136 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ae9f669
[junit4:junit4]   2> 137 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 137 T1140 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 138 T1140 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 138 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:62294
[junit4:junit4]   2> 139 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:62294
[junit4:junit4]   2> 140 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0001 with negotiated timeout 10000 for client /140.211.11.196:62294
[junit4:junit4]   2> 140 T1140 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0001, negotiated timeout = 10000
[junit4:junit4]   2> 141 T1141 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ae9f669 name:ZooKeeperConnection Watcher:127.0.0.1:62292/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 141 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 141 T1132 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 185 T1132 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 194 T1132 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 196 T1132 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 242 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 243 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 270 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 271 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 381 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 382 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 385 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 386 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 397 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 398 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 401 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 402 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 405 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 406 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 409 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 409 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 412 T1132 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 413 T1132 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 416 T1137 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2ac034dc0001
[junit4:junit4]   2> 423 T1141 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 423 T1132 oaz.ZooKeeper.close Session: 0x13d2ac034dc0001 closed
[junit4:junit4]   2> 423 T1134 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:62294 which had sessionid 0x13d2ac034dc0001
[junit4:junit4]   2> 605 T1132 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 610 T1132 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:62295
[junit4:junit4]   2> 610 T1132 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 611 T1132 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 611 T1132 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758
[junit4:junit4]   2> 612 T1132 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/solr.xml
[junit4:junit4]   2> 612 T1132 oasc.CoreContainer.<init> New CoreContainer 1979465641
[junit4:junit4]   2> 613 T1132 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/'
[junit4:junit4]   2> 613 T1132 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/'
[junit4:junit4]   2> 676 T1132 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 676 T1132 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 677 T1132 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 677 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 678 T1132 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 678 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 679 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 679 T1132 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 680 T1132 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 680 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 702 T1132 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 721 T1132 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:62292/solr
[junit4:junit4]   2> 722 T1132 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 723 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@391d23ed
[junit4:junit4]   2> 724 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 724 T1151 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 725 T1151 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 725 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:29975
[junit4:junit4]   2> 725 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:29975
[junit4:junit4]   2> 728 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0002 with negotiated timeout 20000 for client /140.211.11.196:29975
[junit4:junit4]   2> 728 T1151 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0002, negotiated timeout = 20000
[junit4:junit4]   2> 728 T1152 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@391d23ed name:ZooKeeperConnection Watcher:127.0.0.1:62292 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 729 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 730 T1137 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2ac034dc0002
[junit4:junit4]   2> 739 T1152 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 739 T1134 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:29975 which had sessionid 0x13d2ac034dc0002
[junit4:junit4]   2> 739 T1132 oaz.ZooKeeper.close Session: 0x13d2ac034dc0002 closed
[junit4:junit4]   2> 739 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 751 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@4e390e40
[junit4:junit4]   2> 752 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 752 T1153 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 753 T1153 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 753 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:33659
[junit4:junit4]   2> 753 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:33659
[junit4:junit4]   2> 754 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0003 with negotiated timeout 20000 for client /140.211.11.196:33659
[junit4:junit4]   2> 755 T1153 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0003, negotiated timeout = 20000
[junit4:junit4]   2> 755 T1154 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e390e40 name:ZooKeeperConnection Watcher:127.0.0.1:62292/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 755 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 758 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 760 T1132 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 769 T1132 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62295_
[junit4:junit4]   2> 770 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:62295_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:62295_
[junit4:junit4]   2> 771 T1132 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:62295_
[junit4:junit4]   2> 775 T1132 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 786 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 793 T1132 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 795 T1132 oasc.Overseer.start Overseer (id=89274572626198531-127.0.0.1:62295_-n_0000000000) starting
[junit4:junit4]   2> 796 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 798 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 805 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 814 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 823 T1156 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 823 T1132 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 829 T1132 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 835 T1157 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/collection1
[junit4:junit4]   2> 835 T1155 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 835 T1157 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 836 T1157 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 836 T1157 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 838 T1157 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/collection1/'
[junit4:junit4]   2> 839 T1157 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 840 T1157 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/collection1/lib/README' to classloader
[junit4:junit4]   2> 889 T1157 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 949 T1157 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1050 T1157 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1056 T1157 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1672 T1157 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1680 T1157 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1683 T1157 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1695 T1157 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1699 T1157 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1703 T1157 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1704 T1157 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 1704 T1157 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 1704 T1157 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1705 T1157 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 1705 T1157 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 1705 T1157 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1362221872758/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data/
[junit4:junit4]   2> 1706 T1157 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c2e91ac
[junit4:junit4]   2> 1706 T1157 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 1707 T1157 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data forceNew:false
[junit4:junit4]   2> 1708 T1157 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data
[junit4:junit4]   2> 1708 T1157 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data/index/
[junit4:junit4]   2> 1708 T1157 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1709 T1157 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data/index forceNew:false
[junit4:junit4]   2> 1711 T1157 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@417f24d6 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a8baccb),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1711 T1157 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1712 T1157 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data/index
[junit4:junit4]   2> 1713 T1157 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1713 T1157 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1714 T1157 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1714 T1157 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1714 T1157 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1715 T1157 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1715 T1157 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1715 T1157 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1715 T1157 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1724 T1157 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1728 T1157 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data
[junit4:junit4]   2> 1728 T1157 oass.SolrIndexSearcher.<init> Opening Searcher@78baa1f main
[junit4:junit4]   2> 1728 T1157 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data/tlog
[junit4:junit4]   2> 1729 T1157 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1729 T1157 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1732 T1158 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78baa1f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1733 T1157 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 1733 T1157 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1734 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 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> 2339 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2340 T1155 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[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:62295_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:62295"}
[junit4:junit4]   2> 2340 T1155 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 2341 T1155 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 2341 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 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> 2392 T1154 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> 2772 T1157 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2772 T1157 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:62295 collection:control_collection shard:shard1
[junit4:junit4]   2> 2773 T1157 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 2789 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 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> 2790 T1157 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2790 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2800 T1157 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2800 T1157 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2801 T1157 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:62295/collection1/
[junit4:junit4]   2> 2801 T1157 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2801 T1157 oasc.SyncStrategy.syncToMe http://127.0.0.1:62295/collection1/ has no replicas
[junit4:junit4]   2> 2801 T1157 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:62295/collection1/
[junit4:junit4]   2> 2802 T1157 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 2806 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3903 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3922 T1154 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> 3938 T1157 oasc.ZkController.register We are http://127.0.0.1:62295/collection1/ and leader is http://127.0.0.1:62295/collection1/
[junit4:junit4]   2> 3938 T1157 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:62295
[junit4:junit4]   2> 3938 T1157 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3938 T1157 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3939 T1157 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3940 T1157 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3941 T1132 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3942 T1132 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3943 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3958 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3960 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1bdf5c67
[junit4:junit4]   2> 3961 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3961 T1160 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3962 T1160 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 3962 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:34375
[junit4:junit4]   2> 3962 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:34375
[junit4:junit4]   2> 3963 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0004 with negotiated timeout 10000 for client /140.211.11.196:34375
[junit4:junit4]   2> 3963 T1160 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0004, negotiated timeout = 10000
[junit4:junit4]   2> 3964 T1161 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bdf5c67 name:ZooKeeperConnection Watcher:127.0.0.1:62292/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3964 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3966 T1132 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3967 T1132 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4148 T1132 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 4151 T1132 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:49642
[junit4:junit4]   2> 4152 T1132 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4153 T1132 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4153 T1132 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302
[junit4:junit4]   2> 4154 T1132 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/solr.xml
[junit4:junit4]   2> 4154 T1132 oasc.CoreContainer.<init> New CoreContainer 1428311114
[junit4:junit4]   2> 4155 T1132 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/'
[junit4:junit4]   2> 4155 T1132 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/'
[junit4:junit4]   2> 4218 T1132 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 4218 T1132 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4219 T1132 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 4219 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4220 T1132 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4220 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4221 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4221 T1132 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4222 T1132 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4222 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 4242 T1132 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4261 T1132 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:62292/solr
[junit4:junit4]   2> 4262 T1132 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 4263 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@253de144
[junit4:junit4]   2> 4264 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4264 T1171 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4265 T1171 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 4265 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:55422
[junit4:junit4]   2> 4265 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:55422
[junit4:junit4]   2> 4268 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0005 with negotiated timeout 20000 for client /140.211.11.196:55422
[junit4:junit4]   2> 4268 T1171 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0005, negotiated timeout = 20000
[junit4:junit4]   2> 4268 T1172 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@253de144 name:ZooKeeperConnection Watcher:127.0.0.1:62292 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4268 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4270 T1137 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2ac034dc0005
[junit4:junit4]   2> 4279 T1172 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 4279 T1134 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:55422 which had sessionid 0x13d2ac034dc0005
[junit4:junit4]   2> 4279 T1132 oaz.ZooKeeper.close Session: 0x13d2ac034dc0005 closed
[junit4:junit4]   2> 4280 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 4290 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@6a4fa541
[junit4:junit4]   2> 4291 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4292 T1173 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4292 T1173 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 4292 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:33796
[junit4:junit4]   2> 4293 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:33796
[junit4:junit4]   2> 4294 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0006 with negotiated timeout 20000 for client /140.211.11.196:33796
[junit4:junit4]   2> 4294 T1173 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0006, negotiated timeout = 20000
[junit4:junit4]   2> 4294 T1174 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a4fa541 name:ZooKeeperConnection Watcher:127.0.0.1:62292/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4295 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4296 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4302 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4303 T1132 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5306 T1132 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49642_
[junit4:junit4]   2> 5307 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49642_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49642_
[junit4:junit4]   2> 5314 T1132 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49642_
[junit4:junit4]   2> 5321 T1174 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 5321 T1161 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 5322 T1154 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 5322 T1154 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> 5333 T1175 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/collection1
[junit4:junit4]   2> 5334 T1175 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5334 T1175 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5334 T1175 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5336 T1175 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/collection1/'
[junit4:junit4]   2> 5337 T1175 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5338 T1175 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/collection1/lib/README' to classloader
[junit4:junit4]   2> 5387 T1175 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5428 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5429 T1155 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[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:62295_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:62295"}
[junit4:junit4]   2> 5464 T1175 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5467 T1154 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> 5467 T1161 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> 5467 T1174 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> 5467 T1175 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5474 T1175 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6110 T1175 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6121 T1175 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6125 T1175 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6140 T1175 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6144 T1175 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6149 T1175 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6150 T1175 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 6150 T1175 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 6151 T1175 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6152 T1175 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 6152 T1175 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 6152 T1175 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1362221876302/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1/
[junit4:junit4]   2> 6152 T1175 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c2e91ac
[junit4:junit4]   2> 6153 T1175 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 6155 T1175 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1 forceNew:false
[junit4:junit4]   2> 6155 T1175 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1
[junit4:junit4]   2> 6156 T1175 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1/index/
[junit4:junit4]   2> 6156 T1175 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6157 T1175 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1/index forceNew:false
[junit4:junit4]   2> 6159 T1175 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4f201bbe lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ebf9b73),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6159 T1175 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6160 T1175 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1/index
[junit4:junit4]   2> 6162 T1175 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6162 T1175 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6163 T1175 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6164 T1175 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6164 T1175 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6164 T1175 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6165 T1175 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6165 T1175 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6166 T1175 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6178 T1175 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6183 T1175 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1
[junit4:junit4]   2> 6184 T1175 oass.SolrIndexSearcher.<init> Opening Searcher@7ba2b51b main
[junit4:junit4]   2> 6184 T1175 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1/tlog
[junit4:junit4]   2> 6185 T1175 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6185 T1175 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6189 T1176 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7ba2b51b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6191 T1175 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 6191 T1175 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6977 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6978 T1155 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[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:49642_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:49642"}
[junit4:junit4]   2> 6978 T1155 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 6979 T1155 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 7001 T1154 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> 7001 T1174 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> 7001 T1161 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> 7223 T1175 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 7223 T1175 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49642 collection:collection1 shard:shard1
[junit4:junit4]   2> 7224 T1175 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 7240 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0006 type:delete cxid:0x46 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 7241 T1175 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7241 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7252 T1175 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7252 T1175 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7252 T1175 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49642/collection1/
[junit4:junit4]   2> 7253 T1175 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7253 T1175 oasc.SyncStrategy.syncToMe http://127.0.0.1:49642/collection1/ has no replicas
[junit4:junit4]   2> 7253 T1175 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49642/collection1/
[junit4:junit4]   2> 7253 T1175 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 7257 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8507 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8530 T1154 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> 8530 T1174 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> 8530 T1161 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> 8534 T1175 oasc.ZkController.register We are http://127.0.0.1:49642/collection1/ and leader is http://127.0.0.1:49642/collection1/
[junit4:junit4]   2> 8534 T1175 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49642
[junit4:junit4]   2> 8534 T1175 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8534 T1175 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 8535 T1175 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8538 T1175 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8540 T1132 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 8540 T1132 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8541 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8740 T1132 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 8744 T1132 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:20044
[junit4:junit4]   2> 8745 T1132 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8745 T1132 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8746 T1132 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890
[junit4:junit4]   2> 8746 T1132 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/solr.xml
[junit4:junit4]   2> 8747 T1132 oasc.CoreContainer.<init> New CoreContainer 520863743
[junit4:junit4]   2> 8748 T1132 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/'
[junit4:junit4]   2> 8748 T1132 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/'
[junit4:junit4]   2> 8810 T1132 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 8811 T1132 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8812 T1132 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 8812 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8813 T1132 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8813 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8813 T1132 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8814 T1132 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8814 T1132 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8815 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 8834 T1132 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8854 T1132 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:62292/solr
[junit4:junit4]   2> 8855 T1132 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 8855 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@22451566
[junit4:junit4]   2> 8856 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8857 T1187 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8857 T1187 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 8857 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:32909
[junit4:junit4]   2> 8858 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:32909
[junit4:junit4]   2> 8860 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0007 with negotiated timeout 20000 for client /140.211.11.196:32909
[junit4:junit4]   2> 8860 T1187 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0007, negotiated timeout = 20000
[junit4:junit4]   2> 8860 T1188 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22451566 name:ZooKeeperConnection Watcher:127.0.0.1:62292 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8861 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8862 T1137 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d2ac034dc0007
[junit4:junit4]   2> 8873 T1188 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 8873 T1134 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:32909 which had sessionid 0x13d2ac034dc0007
[junit4:junit4]   2> 8873 T1132 oaz.ZooKeeper.close Session: 0x13d2ac034dc0007 closed
[junit4:junit4]   2> 8874 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 8884 T1132 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:62292/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@a85744f
[junit4:junit4]   2> 8885 T1132 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8885 T1189 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:62292. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8886 T1189 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:62292, initiating session
[junit4:junit4]   2> 8886 T1134 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:14659
[junit4:junit4]   2> 8886 T1134 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:14659
[junit4:junit4]   2> 8888 T1136 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d2ac034dc0008 with negotiated timeout 20000 for client /140.211.11.196:14659
[junit4:junit4]   2> 8888 T1189 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:62292, sessionid = 0x13d2ac034dc0008, negotiated timeout = 20000
[junit4:junit4]   2> 8888 T1190 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a85744f name:ZooKeeperConnection Watcher:127.0.0.1:62292/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8888 T1132 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8889 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8891 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8898 T1132 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9901 T1132 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:20044_
[junit4:junit4]   2> 9902 T1137 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d2ac034dc0008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:20044_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:20044_
[junit4:junit4]   2> 9904 T1132 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:20044_
[junit4:junit4]   2> 9907 T1174 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> 9908 T1190 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9908 T1161 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9908 T1154 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9908 T1161 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> 9908 T1154 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> 9909 T1174 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9928 T1191 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/collection1
[junit4:junit4]   2> 9929 T1191 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9929 T1191 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9929 T1191 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9931 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/collection1/'
[junit4:junit4]   2> 9932 T1191 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9933 T1191 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/collection1/lib/README' to classloader
[junit4:junit4]   2> 9985 T1191 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 10042 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10043 T1155 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[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:49642_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:49642"}
[junit4:junit4]   2> 10046 T1191 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 10085 T1174 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> 10085 T1154 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> 10085 T1161 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> 10183 T1190 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> 10183 T1191 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 10190 T1191 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10844 T1191 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 10856 T1191 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10860 T1191 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10878 T1191 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10884 T1191 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10891 T1191 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10893 T1191 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 10893 T1191 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 10893 T1191 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10895 T1191 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 10895 T1191 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 10895 T1191 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1362221880890/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2/
[junit4:junit4]   2> 10895 T1191 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c2e91ac
[junit4:junit4]   2> 10896 T1191 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 10898 T1191 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2 forceNew:false
[junit4:junit4]   2> 10899 T1191 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2
[junit4:junit4]   2> 10899 T1191 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2/index/
[junit4:junit4]   2> 10900 T1191 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10900 T1191 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2/index forceNew:false
[junit4:junit4]   2> 10903 T1191 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@599e5599 lockFactory=org.apache.lucene.store.NativeFSLockFactory@672307b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10903 T1191 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10904 T1191 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2/index
[junit4:junit4]   2> 10906 T1191 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 10907 T1191 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 10907 T1191 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 10908 T1191 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10909 T1191 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10909 T1191 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10909 T1191 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10910 T1191 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10910 T1191 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10926 T1191 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10933 T1191 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2
[junit4:junit4]   2> 10933 T1191 oass.SolrIndexSearcher.<init> Opening Searcher@16edd10f main
[junit4:junit4]   2> 10934 T1191 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2/tlog
[junit4:junit4]   2> 10935 T1191 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10935 T1191 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10939 T1192 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16edd10f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10942 T1191 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 10942 T1191 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11598 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11599 T1155 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[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:20044_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:20044"}
[junit4:junit4]   2> 11599 T1155 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 11599 T1155 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 11611 T1154 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> 11611 T1161 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> 11611 T1174 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> 11611 T1190 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> 11971 T1191 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11971 T1191 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:20044 collection:collection1 shard:shard1
[junit4:junit4]   2> 11975 T1191 oasc.ZkController.register We are http://127.0.0.1:20044/collection1/ and leader is http://127.0.0.1:49642/collection1/
[junit4:junit4]   2> 11975 T1191 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:20044
[junit4:junit4]   2> 11975 T1191 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 11975 T1191 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C102 name=collection1 org.apache.solr.core.SolrCore@1997078 url=http://127.0.0.1:20044/collection1 node=127.0.0.1:20044_ C102_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, collection=collection1, node_name=127.0.0.1:20044_, base_url=http://127.0.0.1:20044}
[junit4:junit4]   2> 11975 T1193 C102 P20044 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 11976 T1191 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11977 T1193 C102 P20044 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 11977 T1193 C102 P20044 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 11978 T1193 C102 P20044 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11978 T1132 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 11979 T1132 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 11980 T1132 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11983 T1193 C102 P20044 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12002 T1132 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C103 name=collection1 org.apache.solr.core.SolrCore@71deb4d6 url=http://127.0.0.1:62295/collection1 node=127.0.0.1:62295_ C103_STATE=coll:control_collection core:collection1 props:{shard=shard1, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:62295_, base_url=http://127.0.0.1:62295, leader=true}
[junit4:junit4]   2> 12012 T1144 C103 P62295 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/control/data
[junit4:junit4]   2> 12014 T1144 C103 P62295 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@417f24d6 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a8baccb),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12014 T1144 C103 P62295 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12017 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[1 (1428393174598942720)]} 0 6
[junit4:junit4]   2> 12017 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10001 (1428393174602088448)]} 0 4
[junit4:junit4]   2> ASYNC  NEW_CORE C104 name=collection1 org.apache.solr.core.SolrCore@63d99c82 url=http://127.0.0.1:49642/collection1 node=127.0.0.1:49642_ C104_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:49642_, base_url=http://127.0.0.1:49642, leader=true}
[junit4:junit4]   2> 12037 T1166 C104 P49642 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty1
[junit4:junit4]   2> 12040 T1166 C104 P49642 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4f201bbe lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ebf9b73),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12040 T1166 C104 P49642 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12043 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10001 (1428393174626205696)]} 0 7
[junit4:junit4]   2> 12043 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[1 (1428393174626205697)]} 0 7
[junit4:junit4]   2> 12047 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[2 (1428393174635642880)]} 0 1
[junit4:junit4]   2> 12047 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10002 (1428393174635642881)]} 0 1
[junit4:junit4]   2> 12050 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[2 (1428393174638788608)]} 0 1
[junit4:junit4]   2> 12050 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10002 (1428393174639837184)]} 0 0
[junit4:junit4]   2> 12054 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10002 (-1428393174642982912)]} 0 1
[junit4:junit4]   2> 12054 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[2 (-1428393174642982913)]} 0 1
[junit4:junit4]   2> 12056 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10002 (-1428393174646128640)]} 0 0
[junit4:junit4]   2> 12057 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[2 (-1428393174647177216)]} 0 0
[junit4:junit4]   2> 12060 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10003 (1428393174649274368)]} 0 1
[junit4:junit4]   2> 12061 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[3 (1428393174650322944)]} 0 1
[junit4:junit4]   2> 12064 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10003 (1428393174653468672)]} 0 1
[junit4:junit4]   2> 12064 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[3 (1428393174654517248)]} 0 0
[junit4:junit4]   2> 12067 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10004 (1428393174656614400)]} 0 1
[junit4:junit4]   2> 12068 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[4 (1428393174657662976)]} 0 1
[junit4:junit4]   2> 12070 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[4 (1428393174660808704)]} 0 0
[junit4:junit4]   2> 12071 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10004 (1428393174660808705)]} 0 1
[junit4:junit4]   2> 12073 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[4 (-1428393174663954432)]} 0 0
[junit4:junit4]   2> 12073 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10004 (-1428393174663954433)]} 0 0
[junit4:junit4]   2> 12076 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10004 (-1428393174667100160)]} 0 0
[junit4:junit4]   2> 12076 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[4 (-1428393174667100161)]} 0 0
[junit4:junit4]   2> 12080 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[5 (1428393174670245888)]} 0 1
[junit4:junit4]   2> 12080 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10005 (1428393174670245889)]} 0 1
[junit4:junit4]   2> 12083 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[5 (1428393174673391616)]} 0 1
[junit4:junit4]   2> 12083 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10005 (1428393174674440192)]} 0 0
[junit4:junit4]   2> 12086 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[6 (1428393174676537344)]} 0 1
[junit4:junit4]   2> 12086 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10006 (1428393174677585920)]} 0 0
[junit4:junit4]   2> 12089 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[6 (1428393174680731648)]} 0 0
[junit4:junit4]   2> 12090 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10006 (1428393174680731649)]} 0 1
[junit4:junit4]   2> 12092 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[5 (-1428393174683877376)]} 0 0
[junit4:junit4]   2> 12093 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10005 (-1428393174684925952)]} 0 0
[junit4:junit4]   2> 12096 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[5 (-1428393174688071680)]} 0 0
[junit4:junit4]   2> 12097 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10005 (-1428393174689120256)]} 0 1
[junit4:junit4]   2> 12100 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[7 (1428393174691217408)]} 0 1
[junit4:junit4]   2> 12101 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10007 (1428393174692265984)]} 0 1
[junit4:junit4]   2> 12104 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[7 (1428393174695411712)]} 0 1
[junit4:junit4]   2> 12105 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10007 (1428393174696460288)]} 0 1
[junit4:junit4]   2> 12109 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10008 (1428393174700654592)]} 0 1
[junit4:junit4]   2> 12109 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[8 (1428393174700654593)]} 0 1
[junit4:junit4]   2> 12112 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[8 (1428393174703800320)]} 0 1
[junit4:junit4]   2> 12112 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10008 (1428393174703800321)]} 0 1
[junit4:junit4]   2> 12115 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[8 (-1428393174707994625)]} 0 0
[junit4:junit4]   2> 12115 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10008 (-1428393174707994624)]} 0 0
[junit4:junit4]   2> 12118 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10008 (-1428393174711140352)]} 0 0
[junit4:junit4]   2> 12118 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[8 (-1428393174711140353)]} 0 0
[junit4:junit4]   2> 12122 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[9 (1428393174714286080)]} 0 1
[junit4:junit4]   2> 12122 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10009 (1428393174714286081)]} 0 1
[junit4:junit4]   2> 12125 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10009 (1428393174717431808)]} 0 1
[junit4:junit4]   2> 12125 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[9 (1428393174717431809)]} 0 1
[junit4:junit4]   2> 12128 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10010 (1428393174720577536)]} 0 1
[junit4:junit4]   2> 12129 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10 (1428393174721626112)]} 0 1
[junit4:junit4]   2> 12132 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10 (1428393174724771840)]} 0 1
[junit4:junit4]   2> 12132 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10010 (1428393174724771841)]} 0 1
[junit4:junit4]   2> 12135 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[11 (1428393174727917568)]} 0 1
[junit4:junit4]   2> 12136 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10011 (1428393174728966144)]} 0 1
[junit4:junit4]   2> 12138 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[11 (1428393174731063296)]} 0 1
[junit4:junit4]   2> 12140 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10011 (1428393174733160448)]} 0 1
[junit4:junit4]   2> 12141 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[12 (1428393174734209024)]} 0 1
[junit4:junit4]   2> 12142 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10012 (1428393174736306176)]} 0 0
[junit4:junit4]   2> 12143 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[12 (1428393174737354752)]} 0 0
[junit4:junit4]   2> 12146 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10012 (1428393174739451904)]} 0 1
[junit4:junit4]   2> 12146 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[13 (1428393174740500480)]} 0 0
[junit4:junit4]   2> 12149 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10013 (1428393174742597632)]} 0 1
[junit4:junit4]   2> 12150 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[13 (1428393174743646208)]} 0 1
[junit4:junit4]   2> 12152 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10013 (1428393174745743360)]} 0 1
[junit4:junit4]   2> 12152 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[13 (-1428393174746791936)]} 0 0
[junit4:junit4]   2> 12154 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10013 (-1428393174748889088)]} 0 0
[junit4:junit4]   2> 12155 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[13 (-1428393174749937664)]} 0 0
[junit4:junit4]   2> 12157 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10013 (-1428393174752034816)]} 0 0
[junit4:junit4]   2> 12159 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[14 (1428393174753083392)]} 0 1
[junit4:junit4]   2> 12161 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10014 (1428393174755180544)]} 0 1
[junit4:junit4]   2> 12162 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[14 (1428393174756229120)]} 0 1
[junit4:junit4]   2> 12164 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10014 (1428393174758326272)]} 0 1
[junit4:junit4]   2> 12165 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[15 (1428393174759374848)]} 0 1
[junit4:junit4]   2> 12166 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10015 (1428393174761472000)]} 0 0
[junit4:junit4]   2> 12167 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[15 (1428393174762520576)]} 0 0
[junit4:junit4]   2> 12170 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10015 (1428393174764617728)]} 0 1
[junit4:junit4]   2> 12170 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[14 (-1428393174765666304)]} 0 0
[junit4:junit4]   2> 12172 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10014 (-1428393174767763456)]} 0 0
[junit4:junit4]   2> 12173 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[14 (-1428393174768812032)]} 0 0
[junit4:junit4]   2> 12175 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10014 (-1428393174770909184)]} 0 0
[junit4:junit4]   2> 12177 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[16 (1428393174771957760)]} 0 1
[junit4:junit4]   2> 12179 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10016 (1428393174774054912)]} 0 1
[junit4:junit4]   2> 12181 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[16 (1428393174776152064)]} 0 1
[junit4:junit4]   2> 12181 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10016 (1428393174777200640)]} 0 0
[junit4:junit4]   2> 12184 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[17 (1428393174779297792)]} 0 1
[junit4:junit4]   2> 12185 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10017 (1428393174780346368)]} 0 1
[junit4:junit4]   2> 12186 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[17 (1428393174782443520)]} 0 0
[junit4:junit4]   2> 12187 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10017 (1428393174783492096)]} 0 0
[junit4:junit4]   2> 12189 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[15 (-1428393174785589248)]} 0 0
[junit4:junit4]   2> 12190 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10015 (-1428393174786637824)]} 0 0
[junit4:junit4]   2> 12192 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[15 (-1428393174788734976)]} 0 0
[junit4:junit4]   2> 12193 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10015 (-1428393174789783552)]} 0 0
[junit4:junit4]   2> 12196 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[18 (1428393174791880704)]} 0 1
[junit4:junit4]   2> 12197 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10018 (1428393174792929280)]} 0 1
[junit4:junit4]   2> 12198 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[18 (1428393174795026432)]} 0 0
[junit4:junit4]   2> 12200 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10018 (1428393174797123584)]} 0 0
[junit4:junit4]   2> 12202 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[19 (1428393174798172160)]} 0 1
[junit4:junit4]   2> 12204 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10019 (1428393174800269312)]} 0 1
[junit4:junit4]   2> 12204 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[19 (1428393174801317888)]} 0 0
[junit4:junit4]   2> 12206 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10019 (1428393174803415040)]} 0 0
[junit4:junit4]   2> 12207 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[20 (1428393174804463616)]} 0 0
[junit4:junit4]   2> 12210 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10020 (1428393174806560768)]} 0 1
[junit4:junit4]   2> 12210 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[20 (1428393174807609344)]} 0 0
[junit4:junit4]   2> 12212 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10020 (1428393174809706496)]} 0 0
[junit4:junit4]   2> 12214 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[21 (1428393174810755072)]} 0 1
[junit4:junit4]   2> 12215 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10021 (1428393174812852224)]} 0 0
[junit4:junit4]   2> 12216 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[21 (1428393174813900800)]} 0 0
[junit4:junit4]   2> 12218 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10021 (1428393174815997952)]} 0 0
[junit4:junit4]   2> 12219 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[22 (1428393174817046528)]} 0 0
[junit4:junit4]   2> 12222 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10022 (1428393174819143680)]} 0 1
[junit4:junit4]   2> 12222 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[22 (1428393174820192256)]} 0 0
[junit4:junit4]   2> 12224 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10022 (1428393174822289408)]} 0 0
[junit4:junit4]   2> 12226 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[23 (1428393174823337984)]} 0 1
[junit4:junit4]   2> 12227 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10023 (1428393174825435136)]} 0 0
[junit4:junit4]   2> 12228 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[23 (1428393174826483712)]} 0 0
[junit4:junit4]   2> 12230 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10023 (1428393174828580864)]} 0 0
[junit4:junit4]   2> 12232 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[24 (1428393174829629440)]} 0 1
[junit4:junit4]   2> 12234 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10024 (1428393174831726592)]} 0 1
[junit4:junit4]   2> 12235 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[24 (1428393174833823744)]} 0 0
[junit4:junit4]   2> 12237 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10024 (1428393174835920896)]} 0 0
[junit4:junit4]   2> 12238 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[22 (-1428393174836969472)]} 0 0
[junit4:junit4]   2> 12240 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10022 (-1428393174839066624)]} 0 0
[junit4:junit4]   2> 12241 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[22 (-1428393174840115200)]} 0 0
[junit4:junit4]   2> 12243 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10022 (-1428393174842212352)]} 0 0
[junit4:junit4]   2> 12245 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[25 (1428393174843260928)]} 0 1
[junit4:junit4]   2> 12247 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10025 (1428393174845358080)]} 0 1
[junit4:junit4]   2> 12249 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[25 (1428393174848503808)]} 0 0
[junit4:junit4]   2> 12251 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10025 (1428393174849552384)]} 0 1
[junit4:junit4]   2> 12254 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10026 (1428393174852698112)]} 0 1
[junit4:junit4]   2> 12255 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[26 (1428393174853746688)]} 0 1
[junit4:junit4]   2> 12258 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10026 (1428393174856892416)]} 0 1
[junit4:junit4]   2> 12258 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[26 (1428393174856892417)]} 0 1
[junit4:junit4]   2> 12261 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10027 (1428393174860038144)]} 0 1
[junit4:junit4]   2> 12262 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[27 (1428393174861086720)]} 0 1
[junit4:junit4]   2> 12264 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10027 (1428393174863183872)]} 0 1
[junit4:junit4]   2> 12264 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[27 (1428393174864232448)]} 0 0
[junit4:junit4]   2> 12267 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10028 (1428393174866329600)]} 0 1
[junit4:junit4]   2> 12267 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[28 (1428393174867378176)]} 0 0
[junit4:junit4]   2> 12269 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10028 (1428393174869475328)]} 0 0
[junit4:junit4]   2> 12271 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[28 (1428393174870523904)]} 0 1
[junit4:junit4]   2> 12272 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10023 (-1428393174872621056)]} 0 0
[junit4:junit4]   2> 12274 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[23 (-1428393174874718208)]} 0 0
[junit4:junit4]   2> 12275 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10023 (-1428393174875766784)]} 0 0
[junit4:junit4]   2> 12277 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[23 (-1428393174877863936)]} 0 0
[junit4:junit4]   2> 12279 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10029 (1428393174878912512)]} 0 1
[junit4:junit4]   2> 12281 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[29 (1428393174881009664)]} 0 1
[junit4:junit4]   2> 12282 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10029 (1428393174882058240)]} 0 1
[junit4:junit4]   2> 12285 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10030 (1428393174885203968)]} 0 1
[junit4:junit4]   2> 12286 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[29 (1428393174886252544)]} 0 1
[junit4:junit4]   2> 12289 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[30 (1428393174889398272)]} 0 1
[junit4:junit4]   2> 12289 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10030 (1428393174889398272)]} 0 1
[junit4:junit4]   2> 12292 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[30 (1428393174892544000)]} 0 1
[junit4:junit4]   2> 12292 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10031 (1428393174893592576)]} 0 0
[junit4:junit4]   2> 12295 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[31 (1428393174895689728)]} 0 1
[junit4:junit4]   2> 12296 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10031 (1428393174896738304)]} 0 1
[junit4:junit4]   2> 12298 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[31 (1428393174898835456)]} 0 1
[junit4:junit4]   2> 12298 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10025 (-1428393174899884032)]} 0 0
[junit4:junit4]   2> 12300 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[25 (-1428393174901981184)]} 0 0
[junit4:junit4]   2> 12301 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10025 (-1428393174903029760)]} 0 0
[junit4:junit4]   2> 12303 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[25 (-1428393174905126912)]} 0 0
[junit4:junit4]   2> 12305 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10032 (1428393174906175488)]} 0 1
[junit4:junit4]   2> 12307 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[32 (1428393174908272640)]} 0 1
[junit4:junit4]   2> 12308 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10032 (1428393174909321216)]} 0 1
[junit4:junit4]   2> 12311 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[32 (1428393174912466944)]} 0 1
[junit4:junit4]   2> 12312 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10033 (1428393174913515520)]} 0 1
[junit4:junit4]   2> 12314 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[33 (1428393174915612672)]} 0 1
[junit4:junit4]   2> 12315 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10033 (1428393174916661248)]} 0 1
[junit4:junit4]   2> 12317 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[33 (1428393174918758400)]} 0 1
[junit4:junit4]   2> 12317 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10026 (-1428393174919806976)]} 0 0
[junit4:junit4]   2> 12320 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[26 (-1428393174922952704)]} 0 0
[junit4:junit4]   2> 12320 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10026 (-1428393174922952704)]} 0 0
[junit4:junit4]   2> 12323 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[26 (-1428393174926098432)]} 0 0
[junit4:junit4]   2> 12324 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10034 (1428393174926098432)]} 0 1
[junit4:junit4]   2> 12327 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10034 (1428393174929244160)]} 0 1
[junit4:junit4]   2> 12327 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[34 (1428393174929244160)]} 0 1
[junit4:junit4]   2> 12329 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10035 (1428393174932389888)]} 0 0
[junit4:junit4]   2> 12331 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[34 (1428393174933438464)]} 0 1
[junit4:junit4]   2> 12333 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10035 (1428393174935535616)]} 0 1
[junit4:junit4]   2> 12333 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[35 (1428393174936584192)]} 0 0
[junit4:junit4]   2> 12336 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10027 (-1428393174939729920)]} 0 0
[junit4:junit4]   2> 12337 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[35 (1428393174939729920)]} 0 1
[junit4:junit4]   2> 12339 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[27 (-1428393174942875648)]} 0 0
[junit4:junit4]   2> 12339 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10027 (-1428393174942875648)]} 0 0
[junit4:junit4]   2> 12342 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[27 (-1428393174946021376)]} 0 0
[junit4:junit4]   2> 12342 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10036 (1428393174946021376)]} 0 0
[junit4:junit4]   2> 12346 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[36 (1428393174949167104)]} 0 1
[junit4:junit4]   2> 12346 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10036 (1428393174949167104)]} 0 1
[junit4:junit4]   2> 12348 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[36 (1428393174952312832)]} 0 0
[junit4:junit4]   2> 12348 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10037 (1428393174952312832)]} 0 0
[junit4:junit4]   2> 12351 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10037 (1428393174955458560)]} 0 0
[junit4:junit4]   2> 12352 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[37 (1428393174955458560)]} 0 1
[junit4:junit4]   2> 12354 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10028 (-1428393174958604288)]} 0 0
[junit4:junit4]   2> 12354 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[37 (1428393174958604288)]} 0 0
[junit4:junit4]   2> 12357 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[28 (-1428393174961750016)]} 0 0
[junit4:junit4]   2> 12357 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10028 (-1428393174961750016)]} 0 0
[junit4:junit4]   2> 12360 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[28 (-1428393174964895744)]} 0 0
[junit4:junit4]   2> 12360 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10038 (1428393174964895744)]} 0 0
[junit4:junit4]   2> 12363 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[38 (1428393174968041472)]} 0 0
[junit4:junit4]   2> 12364 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10038 (1428393174968041472)]} 0 1
[junit4:junit4]   2> 12366 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10029 (-1428393174971187200)]} 0 0
[junit4:junit4]   2> 12367 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[38 (1428393174971187200)]} 0 1
[junit4:junit4]   2> 12369 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10029 (-1428393174974332928)]} 0 0
[junit4:junit4]   2> 12370 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[29 (-1428393174975381504)]} 0 0
[junit4:junit4]   2> 12372 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10039 (1428393174977478656)]} 0 0
[junit4:junit4]   2> 12373 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[29 (-1428393174978527232)]} 0 0
[junit4:junit4]   2> 12376 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10039 (1428393174980624384)]} 0 1
[junit4:junit4]   2> 12377 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[39 (1428393174981672960)]} 0 1
[junit4:junit4]   2> 12379 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10030 (-1428393174984818688)]} 0 0
[junit4:junit4]   2> 12380 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[39 (1428393174984818688)]} 0 1
[junit4:junit4]   2> 12382 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10030 (-1428393174987964416)]} 0 0
[junit4:junit4]   2> 12383 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[30 (-1428393174989012992)]} 0 0
[junit4:junit4]   2> 12385 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10040 (1428393174991110144)]} 0 0
[junit4:junit4]   2> 12386 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[30 (-1428393174992158720)]} 0 0
[junit4:junit4]   2> 12389 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10040 (1428393174994255872)]} 0 1
[junit4:junit4]   2> 12389 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[40 (1428393174995304448)]} 0 0
[junit4:junit4]   2> 12391 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10031 (-1428393174997401600)]} 0 0
[junit4:junit4]   2> 12393 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[40 (1428393174998450176)]} 0 1
[junit4:junit4]   2> 12394 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10031 (-1428393175000547328)]} 0 0
[junit4:junit4]   2> 12395 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[31 (-1428393175001595904)]} 0 0
[junit4:junit4]   2> 12398 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10041 (1428393175003693056)]} 0 0
[junit4:junit4]   2> 12398 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[31 (-1428393175004741632)]} 0 0
[junit4:junit4]   2> 12401 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10041 (1428393175006838784)]} 0 1
[junit4:junit4]   2> 12401 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[41 (1428393175007887360)]} 0 0
[junit4:junit4]   2> 12403 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10033 (-1428393175009984512)]} 0 0
[junit4:junit4]   2> 12405 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[41 (1428393175011033088)]} 0 1
[junit4:junit4]   2> 12406 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10033 (-1428393175013130240)]} 0 0
[junit4:junit4]   2> 12407 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[33 (-1428393175014178816)]} 0 0
[junit4:junit4]   2> 12409 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10042 (1428393175016275968)]} 0 0
[junit4:junit4]   2> 12410 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[33 (-1428393175017324544)]} 0 0
[junit4:junit4]   2> 12413 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10042 (1428393175019421696)]} 0 1
[junit4:junit4]   2> 12413 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[42 (1428393175020470272)]} 0 0
[junit4:junit4]   2> 12417 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[42 (1428393175023616000)]} 0 1
[junit4:junit4]   2> 12417 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10043 (1428393175023616000)]} 0 1
[junit4:junit4]   2> 12419 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10043 (1428393175026761728)]} 0 0
[junit4:junit4]   2> 12420 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[43 (1428393175026761728)]} 0 1
[junit4:junit4]   2> 12422 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10034 (-1428393175029907456)]} 0 0
[junit4:junit4]   2> 12423 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[43 (1428393175029907456)]} 0 1
[junit4:junit4]   2> 12425 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10034 (-1428393175033053184)]} 0 0
[junit4:junit4]   2> 12425 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[34 (-1428393175033053184)]} 0 0
[junit4:junit4]   2> 12428 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[34 (-1428393175036198912)]} 0 0
[junit4:junit4]   2> 12429 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10044 (1428393175036198912)]} 0 1
[junit4:junit4]   2> 12432 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10044 (1428393175039344640)]} 0 1
[junit4:junit4]   2> 12432 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[44 (1428393175039344640)]} 0 1
[junit4:junit4]   2> 12435 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10045 (1428393175042490368)]} 0 1
[junit4:junit4]   2> 12435 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[44 (1428393175043538944)]} 0 0
[junit4:junit4]   2> 12437 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10045 (1428393175045636096)]} 0 0
[junit4:junit4]   2> 12439 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[45 (1428393175046684672)]} 0 1
[junit4:junit4]   2> 12441 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10046 (1428393175048781824)]} 0 1
[junit4:junit4]   2> 12441 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[45 (1428393175049830400)]} 0 0
[junit4:junit4]   2> 12445 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10046 (1428393175052976128)]} 0 1
[junit4:junit4]   2> 12445 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[46 (1428393175052976128)]} 0 1
[junit4:junit4]   2> 12447 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10035 (-1428393175056121856)]} 0 0
[junit4:junit4]   2> 12447 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[46 (1428393175056121856)]} 0 0
[junit4:junit4]   2> 12450 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[35 (-1428393175059267584)]} 0 0
[junit4:junit4]   2> 12451 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10035 (-1428393175059267584)]} 0 1
[junit4:junit4]   2> 12453 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[35 (-1428393175062413312)]} 0 0
[junit4:junit4]   2> 12454 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10047 (1428393175062413312)]} 0 1
[junit4:junit4]   2> 12457 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[47 (1428393175065559040)]} 0 1
[junit4:junit4]   2> 12457 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10047 (1428393175066607616)]} 0 0
[junit4:junit4]   2> 12460 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10041 (-1428393175069753344)]} 0 0
[junit4:junit4]   2> 12461 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[47 (1428393175069753344)]} 0 1
[junit4:junit4]   2> 12463 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10041 (-1428393175072899072)]} 0 0
[junit4:junit4]   2> 12463 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[41 (-1428393175072899072)]} 0 0
[junit4:junit4]   2> 12466 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[41 (-1428393175076044800)]} 0 0
[junit4:junit4]   2> 12467 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10048 (1428393175076044800)]} 0 1
[junit4:junit4]   2> 12470 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[48 (1428393175079190528)]} 0 1
[junit4:junit4]   2> 12470 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10048 (1428393175080239104)]} 0 0
[junit4:junit4]   2> 12473 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10044 (-1428393175083384832)]} 0 0
[junit4:junit4]   2> 12473 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[48 (1428393175083384832)]} 0 0
[junit4:junit4]   2> 12476 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[44 (-1428393175086530560)]} 0 0
[junit4:junit4]   2> 12476 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10044 (-1428393175086530560)]} 0 0
[junit4:junit4]   2> 12479 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[44 (-1428393175089676288)]} 0 0
[junit4:junit4]   2> 12480 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10049 (1428393175089676288)]} 0 1
[junit4:junit4]   2> 12482 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[49 (1428393175092822016)]} 0 0
[junit4:junit4]   2> 12483 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10049 (1428393175092822016)]} 0 1
[junit4:junit4]   2> 12485 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10045 (-1428393175095967744)]} 0 0
[junit4:junit4]   2> 12486 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[49 (1428393175095967744)]} 0 1
[junit4:junit4]   2> 12489 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10045 (-1428393175100162048)]} 0 0
[junit4:junit4]   2> 12489 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[45 (-1428393175100162048)]} 0 0
[junit4:junit4]   2> 12492 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[45 (-1428393175103307776)]} 0 0
[junit4:junit4]   2> 12493 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10050 (1428393175103307776)]} 0 1
[junit4:junit4]   2> 12496 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[50 (1428393175106453504)]} 0 1
[junit4:junit4]   2> 12497 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10050 (1428393175107502080)]} 0 1
[junit4:junit4]   2> 12500 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10051 (1428393175110647808)]} 0 1
[junit4:junit4]   2> 12500 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[50 (1428393175110647808)]} 0 1
[junit4:junit4]   2> 12503 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10051 (1428393175113793536)]} 0 1
[junit4:junit4]   2> 12503 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[51 (1428393175113793536)]} 0 1
[junit4:junit4]   2> 12507 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10052 (1428393175117987840)]} 0 1
[junit4:junit4]   2> 12507 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[51 (1428393175119036416)]} 0 1
[junit4:junit4]   2> 12511 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[52 (1428393175122182144)]} 0 1
[junit4:junit4]   2> 12511 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10052 (1428393175122182144)]} 0 1
[junit4:junit4]   2> 12514 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[52 (1428393175125327872)]} 0 1
[junit4:junit4]   2> 12515 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10053 (1428393175126376448)]} 0 1
[junit4:junit4]   2> 12518 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[53 (1428393175129522176)]} 0 1
[junit4:junit4]   2> 12519 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10053 (1428393175130570752)]} 0 1
[junit4:junit4]   2> 12522 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[53 (1428393175133716480)]} 0 1
[junit4:junit4]   2> 12522 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10046 (-1428393175134765056)]} 0 0
[junit4:junit4]   2> 12526 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[46 (-1428393175137910784)]} 0 1
[junit4:junit4]   2> 12526 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10046 (-1428393175137910784)]} 0 1
[junit4:junit4]   2> 12528 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[46 (-1428393175141056512)]} 0 0
[junit4:junit4]   2> 12529 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10054 (1428393175141056512)]} 0 1
[junit4:junit4]   2> 12532 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[54 (1428393175144202240)]} 0 1
[junit4:junit4]   2> 12533 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10054 (1428393175145250816)]} 0 1
[junit4:junit4]   2> 12535 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[54 (1428393175147347968)]} 0 1
[junit4:junit4]   2> 12535 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10055 (1428393175148396544)]} 0 0
[junit4:junit4]   2> 12538 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[55 (1428393175150493696)]} 0 1
[junit4:junit4]   2> 12539 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10055 (1428393175151542272)]} 0 1
[junit4:junit4]   2> 12541 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[55 (1428393175154688000)]} 0 0
[junit4:junit4]   2> 12542 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10056 (1428393175154688000)]} 0 1
[junit4:junit4]   2> 12545 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[56 (1428393175157833728)]} 0 1
[junit4:junit4]   2> 12546 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10056 (1428393175158882304)]} 0 1
[junit4:junit4]   2> 12547 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[56 (1428393175160979456)]} 0 0
[junit4:junit4]   2> 12548 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10048 (-1428393175162028032)]} 0 0
[junit4:junit4]   2> 12550 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[48 (-1428393175164125184)]} 0 0
[junit4:junit4]   2> 12551 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10048 (-1428393175165173760)]} 0 0
[junit4:junit4]   2> 12553 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[48 (-1428393175167270912)]} 0 0
[junit4:junit4]   2> 12555 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10057 (1428393175168319488)]} 0 1
[junit4:junit4]   2> 12556 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[57 (1428393175170416640)]} 0 0
[junit4:junit4]   2> 12557 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10057 (1428393175171465216)]} 0 0
[junit4:junit4]   2> 12559 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[57 (1428393175173562368)]} 0 0
[junit4:junit4]   2> 12560 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10049 (-1428393175174610944)]} 0 0
[junit4:junit4]   2> 12562 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[49 (-1428393175176708096)]} 0 0
[junit4:junit4]   2> 12563 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10049 (-1428393175177756672)]} 0 0
[junit4:junit4]   2> 12565 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[49 (-1428393175179853824)]} 0 0
[junit4:junit4]   2> 12567 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10058 (1428393175180902400)]} 0 1
[junit4:junit4]   2> 12569 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[58 (1428393175182999552)]} 0 1
[junit4:junit4]   2> 12570 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10058 (1428393175185096704)]} 0 0
[junit4:junit4]   2> 12571 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[58 (1428393175186145280)]} 0 0
[junit4:junit4]   2> 12573 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10052 (-1428393175188242432)]} 0 0
[junit4:junit4]   2> 12574 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[52 (-1428393175189291008)]} 0 0
[junit4:junit4]   2> 12576 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10052 (-1428393175191388160)]} 0 0
[junit4:junit4]   2> 12577 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[52 (-1428393175192436736)]} 0 0
[junit4:junit4]   2> 12580 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[59 (1428393175194533889)]} 0 1
[junit4:junit4]   2> 12580 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10059 (1428393175194533888)]} 0 1
[junit4:junit4]   2> 12582 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10059 (1428393175197679616)]} 0 0
[junit4:junit4]   2> 12583 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[59 (1428393175197679617)]} 0 1
[junit4:junit4]   2> 12586 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10060 (1428393175200825344)]} 0 1
[junit4:junit4]   2> 12586 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[60 (1428393175201873920)]} 0 0
[junit4:junit4]   2> 12589 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10060 (1428393175203971072)]} 0 1
[junit4:junit4]   2> 12590 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[60 (1428393175205019648)]} 0 1
[junit4:junit4]   2> 12592 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10061 (1428393175208165376)]} 0 0
[junit4:junit4]   2> 12593 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[61 (1428393175208165377)]} 0 1
[junit4:junit4]   2> 12596 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[61 (1428393175211311104)]} 0 1
[junit4:junit4]   2> 12596 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10061 (1428393175211311105)]} 0 1
[junit4:junit4]   2> 12599 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[62 (1428393175214456832)]} 0 1
[junit4:junit4]   2> 12599 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10062 (1428393175215505408)]} 0 0
[junit4:junit4]   2> 12601 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[62 (1428393175217602560)]} 0 0
[junit4:junit4]   2> 12603 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10062 (1428393175218651136)]} 0 1
[junit4:junit4]   2> 12604 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[54 (-1428393175220748288)]} 0 0
[junit4:junit4]   2> 12606 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10054 (-1428393175222845440)]} 0 0
[junit4:junit4]   2> 12607 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[54 (-1428393175223894016)]} 0 0
[junit4:junit4]   2> 12609 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10054 (-1428393175225991168)]} 0 0
[junit4:junit4]   2> 12611 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[63 (1428393175227039744)]} 0 1
[junit4:junit4]   2> 12613 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10063 (1428393175229136896)]} 0 1
[junit4:junit4]   2> 12613 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[63 (1428393175230185472)]} 0 0
[junit4:junit4]   2> 12616 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10063 (1428393175232282624)]} 0 1
[junit4:junit4]   2> 12616 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[55 (-1428393175233331200)]} 0 0
[junit4:junit4]   2> 12618 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10055 (-1428393175235428352)]} 0 0
[junit4:junit4]   2> 12619 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[55 (-1428393175236476928)]} 0 0
[junit4:junit4]   2> 12621 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10055 (-1428393175238574080)]} 0 0
[junit4:junit4]   2> 12623 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[64 (1428393175239622656)]} 0 1
[junit4:junit4]   2> 12625 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10064 (1428393175241719808)]} 0 1
[junit4:junit4]   2> 12626 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[64 (1428393175243816960)]} 0 0
[junit4:junit4]   2> 12628 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10064 (1428393175244865536)]} 0 1
[junit4:junit4]   2> 12629 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[62 (-1428393175246962688)]} 0 0
[junit4:junit4]   2> 12631 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10062 (-1428393175249059840)]} 0 0
[junit4:junit4]   2> 12633 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[62 (-1428393175250108416)]} 0 1
[junit4:junit4]   2> 12634 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10062 (-1428393175252205568)]} 0 0
[junit4:junit4]   2> 12636 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[65 (1428393175253254144)]} 0 1
[junit4:junit4]   2> 12638 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10065 (1428393175255351296)]} 0 1
[junit4:junit4]   2> 12639 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[65 (1428393175257448448)]} 0 0
[junit4:junit4]   2> 12640 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10065 (1428393175258497024)]} 0 0
[junit4:junit4]   2> 12642 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[66 (1428393175260594176)]} 0 0
[junit4:junit4]   2> 12644 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10066 (1428393175261642752)]} 0 1
[junit4:junit4]   2> 12646 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[66 (1428393175264788480)]} 0 0
[junit4:junit4]   2> 12648 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10066 (1428393175265837056)]} 0 1
[junit4:junit4]   2> 12650 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[67 (1428393175267934208)]} 0 1
[junit4:junit4]   2> 12651 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10067 (1428393175268982784)]} 0 1
[junit4:junit4]   2> 12653 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[67 (1428393175271079936)]} 0 1
[junit4:junit4]   2> 12653 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10067 (1428393175272128512)]} 0 0
[junit4:junit4]   2> 12656 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[67 (-1428393175275274241)]} 0 0
[junit4:junit4]   2> 12656 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10067 (-1428393175275274240)]} 0 0
[junit4:junit4]   2> 12659 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[67 (-1428393175278419968)]} 0 0
[junit4:junit4]   2> 12659 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10067 (-1428393175278419969)]} 0 0
[junit4:junit4]   2> 12663 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10068 (1428393175281565697)]} 0 1
[junit4:junit4]   2> 12663 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[68 (1428393175281565696)]} 0 1
[junit4:junit4]   2> 12666 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[68 (1428393175284711424)]} 0 1
[junit4:junit4]   2> 12666 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10068 (1428393175284711425)]} 0 1
[junit4:junit4]   2> 12669 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[69 (1428393175287857152)]} 0 1
[junit4:junit4]   2> 12669 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10069 (1428393175288905728)]} 0 0
[junit4:junit4]   2> 12673 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10069 (1428393175292051456)]} 0 1
[junit4:junit4]   2> 12673 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[69 (1428393175292051457)]} 0 1
[junit4:junit4]   2> 12677 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[70 (1428393175296245760)]} 0 1
[junit4:junit4]   2> 12680 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[70 (1428393175299391488)]} 0 1
[junit4:junit4]   2> 12682 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10070 (1428393175301488640)]} 0 1
[junit4:junit4]   2> 12682 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[69 (-1428393175302537216)]} 0 0
[junit4:junit4]   2> 12684 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10070 (1428393175304634368)]} 0 0
[junit4:junit4]   2> 12685 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[69 (-1428393175305682944)]} 0 0
[junit4:junit4]   2> 12687 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10069 (-1428393175307780096)]} 0 0
[junit4:junit4]   2> 12689 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[71 (1428393175308828672)]} 0 1
[junit4:junit4]   2> 12690 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10069 (-1428393175310925824)]} 0 0
[junit4:junit4]   2> 12692 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[71 (1428393175313022976)]} 0 0
[junit4:junit4]   2> 12694 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10071 (1428393175314071552)]} 0 1
[junit4:junit4]   2> 12695 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[70 (-1428393175316168704)]} 0 0
[junit4:junit4]   2> 12696 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10071 (1428393175317217280)]} 0 0
[junit4:junit4]   2> 12698 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[70 (-1428393175319314432)]} 0 0
[junit4:junit4]   2> 12699 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10070 (-1428393175320363008)]} 0 0
[junit4:junit4]   2> 12702 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[72 (1428393175322460160)]} 0 1
[junit4:junit4]   2> 12702 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10070 (-1428393175323508736)]} 0 0
[junit4:junit4]   2> 12704 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[72 (1428393175325605888)]} 0 0
[junit4:junit4]   2> 12706 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10072 (1428393175326654464)]} 0 1
[junit4:junit4]   2> 12707 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[71 (-1428393175328751616)]} 0 0
[junit4:junit4]   2> 12709 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10072 (1428393175330848768)]} 0 0
[junit4:junit4]   2> 12710 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[71 (-1428393175331897344)]} 0 0
[junit4:junit4]   2> 12712 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10071 (-1428393175333994496)]} 0 0
[junit4:junit4]   2> 12714 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[73 (1428393175335043072)]} 0 1
[junit4:junit4]   2> 12715 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10071 (-1428393175337140224)]} 0 0
[junit4:junit4]   2> 12717 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[73 (1428393175339237376)]} 0 0
[junit4:junit4]   2> 12719 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10073 (1428393175340285952)]} 0 1
[junit4:junit4]   2> 12721 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[74 (1428393175342383104)]} 0 1
[junit4:junit4]   2> 12722 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10073 (1428393175343431680)]} 0 1
[junit4:junit4]   2> 12724 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[74 (1428393175345528832)]} 0 1
[junit4:junit4]   2> 12724 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10074 (1428393175346577408)]} 0 0
[junit4:junit4]   2> 12728 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10074 (1428393175349723136)]} 0 1
[junit4:junit4]   2> 12728 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[75 (1428393175349723136)]} 0 1
[junit4:junit4]   2> 12732 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10075 (1428393175353917440)]} 0 1
[junit4:junit4]   2> 12732 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[75 (1428393175353917440)]} 0 1
[junit4:junit4]   2> 12734 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[73 (-1428393175357063168)]} 0 0
[junit4:junit4]   2> 12735 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10075 (1428393175357063168)]} 0 1
[junit4:junit4]   2> 12737 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10073 (-1428393175360208896)]} 0 0
[junit4:junit4]   2> 12737 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[73 (-1428393175360208896)]} 0 0
[junit4:junit4]   2> 12740 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10073 (-1428393175363354624)]} 0 0
[junit4:junit4]   2> 12741 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[76 (1428393175363354624)]} 0 1
[junit4:junit4]   2> 12744 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[76 (1428393175366500352)]} 0 1
[junit4:junit4]   2> 12744 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10076 (1428393175366500352)]} 0 1
[junit4:junit4]   2> 12746 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[75 (-1428393175369646080)]} 0 0
[junit4:junit4]   2> 12748 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10076 (1428393175370694656)]} 0 1
[junit4:junit4]   2> 12749 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[75 (-1428393175372791808)]} 0 0
[junit4:junit4]   2> 12750 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10075 (-1428393175373840384)]} 0 0
[junit4:junit4]   2> 12753 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[77 (1428393175375937536)]} 0 1
[junit4:junit4]   2> 12753 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10075 (-1428393175376986112)]} 0 0
[junit4:junit4]   2> 12756 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[77 (1428393175379083264)]} 0 1
[junit4:junit4]   2> 12757 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10077 (1428393175380131840)]} 0 1
[junit4:junit4]   2> 12759 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[78 (1428393175382228992)]} 0 1
[junit4:junit4]   2> 12760 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10077 (1428393175383277568)]} 0 1
[junit4:junit4]   2> 12761 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[78 (1428393175385374720)]} 0 0
[junit4:junit4]   2> 12762 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10078 (1428393175386423296)]} 0 0
[junit4:junit4]   2> 12765 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10078 (1428393175389569024)]} 0 0
[junit4:junit4]   2> 12766 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[78 (-1428393175389569024)]} 0 1
[junit4:junit4]   2> 12768 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10078 (-1428393175392714752)]} 0 0
[junit4:junit4]   2> 12768 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[78 (-1428393175392714752)]} 0 0
[junit4:junit4]   2> 12771 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10078 (-1428393175395860480)]} 0 0
[junit4:junit4]   2> 12772 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[79 (1428393175395860480)]} 0 1
[junit4:junit4]   2> 12775 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10079 (1428393175399006208)]} 0 1
[junit4:junit4]   2> 12775 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[79 (1428393175399006208)]} 0 1
[junit4:junit4]   2> 12778 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10079 (1428393175402151936)]} 0 1
[junit4:junit4]   2> 12779 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[80 (1428393175403200512)]} 0 1
[junit4:junit4]   2> 12781 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10080 (1428393175405297664)]} 0 1
[junit4:junit4]   2> 12782 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[80 (1428393175406346240)]} 0 1
[junit4:junit4]   2> 12784 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10080 (1428393175408443392)]} 0 1
[junit4:junit4]   2> 12784 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[81 (1428393175409491968)]} 0 0
[junit4:junit4]   2> 12787 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10081 (1428393175411589120)]} 0 1
[junit4:junit4]   2> 12788 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[81 (1428393175412637696)]} 0 1
[junit4:junit4]   2> 12790 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[79 (-1428393175415783424)]} 0 0
[junit4:junit4]   2> 12791 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10081 (1428393175415783424)]} 0 1
[junit4:junit4]   2> 12793 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[79 (-1428393175418929152)]} 0 0
[junit4:junit4]   2> 12793 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10079 (-1428393175418929152)]} 0 0
[junit4:junit4]   2> 12796 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10079 (-1428393175422074880)]} 0 0
[junit4:junit4]   2> 12797 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[82 (1428393175422074880)]} 0 1
[junit4:junit4]   2> 12799 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[82 (1428393175425220608)]} 0 0
[junit4:junit4]   2> 12800 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10082 (1428393175425220608)]} 0 1
[junit4:junit4]   2> 12803 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[83 (1428393175428366336)]} 0 1
[junit4:junit4]   2> 12804 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10082 (1428393175429414912)]} 0 1
[junit4:junit4]   2> 12806 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[83 (1428393175431512064)]} 0 1
[junit4:junit4]   2> 12807 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10083 (1428393175432560640)]} 0 1
[junit4:junit4]   2> 12809 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[84 (1428393175434657792)]} 0 1
[junit4:junit4]   2> 12810 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10083 (1428393175435706368)]} 0 1
[junit4:junit4]   2> 12812 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[84 (1428393175437803520)]} 0 1
[junit4:junit4]   2> 12813 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10084 (1428393175438852096)]} 0 1
[junit4:junit4]   2> 12815 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[85 (1428393175440949248)]} 0 1
[junit4:junit4]   2> 12816 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10084 (1428393175441997824)]} 0 1
[junit4:junit4]   2> 12818 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[85 (1428393175444094976)]} 0 1
[junit4:junit4]   2> 12818 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10085 (1428393175445143552)]} 0 0
[junit4:junit4]   2> 12821 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[86 (1428393175447240704)]} 0 1
[junit4:junit4]   2> 12822 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10085 (1428393175448289280)]} 0 1
[junit4:junit4]   2> 12823 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[86 (1428393175449337856)]} 0 1
[junit4:junit4]   2> 12825 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10086 (1428393175451435008)]} 0 1
[junit4:junit4]   2> 12825 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[86 (-1428393175452483584)]} 0 0
[junit4:junit4]   2> 12828 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10086 (1428393175454580736)]} 0 1
[junit4:junit4]   2> 12828 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[86 (-1428393175455629312)]} 0 0
[junit4:junit4]   2> 12830 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10086 (-1428393175457726464)]} 0 0
[junit4:junit4]   2> 12832 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[87 (1428393175458775040)]} 0 1
[junit4:junit4]   2> 12833 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10086 (-1428393175460872192)]} 0 0
[junit4:junit4]   2> 12836 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[87 (1428393175462969344)]} 0 1
[junit4:junit4]   2> 12837 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10087 (1428393175464017920)]} 0 1
[junit4:junit4]   2> 12838 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[88 (1428393175466115072)]} 0 0
[junit4:junit4]   2> 12840 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10087 (1428393175467163648)]} 0 1
[junit4:junit4]   2> 12842 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[88 (1428393175469260800)]} 0 1
[junit4:junit4]   2> 12843 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10088 (1428393175470309376)]} 0 1
[junit4:junit4]   2> 12844 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[87 (-1428393175472406528)]} 0 0
[junit4:junit4]   2> 12846 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10088 (1428393175473455104)]} 0 1
[junit4:junit4]   2> 12847 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[87 (-1428393175475552256)]} 0 0
[junit4:junit4]   2> 12848 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10087 (-1428393175476600832)]} 0 0
[junit4:junit4]   2> 12850 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[89 (1428393175478697984)]} 0 0
[junit4:junit4]   2> 12851 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10087 (-1428393175479746560)]} 0 0
[junit4:junit4]   2> 12854 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[89 (1428393175481843712)]} 0 1
[junit4:junit4]   2> 12854 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10089 (1428393175482892288)]} 0 0
[junit4:junit4]   2> 12858 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[90 (1428393175486038016)]} 0 1
[junit4:junit4]   2> 12858 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10089 (1428393175486038016)]} 0 1
[junit4:junit4]   2> 12861 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[90 (1428393175489183744)]} 0 1
[junit4:junit4]   2> 12862 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10090 (1428393175490232320)]} 0 1
[junit4:junit4]   2> 12863 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[91 (1428393175492329472)]} 0 0
[junit4:junit4]   2> 12865 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10090 (1428393175493378048)]} 0 1
[junit4:junit4]   2> 12867 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[91 (1428393175495475200)]} 0 1
[junit4:junit4]   2> 12867 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10091 (1428393175496523776)]} 0 0
[junit4:junit4]   2> 12870 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[92 (1428393175499669504)]} 0 0
[junit4:junit4]   2> 12871 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10091 (1428393175499669504)]} 0 1
[junit4:junit4]   2> 12873 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[92 (1428393175502815232)]} 0 0
[junit4:junit4]   2> 12874 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10092 (1428393175502815232)]} 0 1
[junit4:junit4]   2> 12877 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[93 (1428393175505960960)]} 0 1
[junit4:junit4]   2> 12877 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10092 (1428393175507009536)]} 0 0
[junit4:junit4]   2> 12880 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[93 (1428393175509106688)]} 0 1
[junit4:junit4]   2> 12881 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10093 (1428393175510155264)]} 0 1
[junit4:junit4]   2> 12883 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[94 (1428393175512252416)]} 0 1
[junit4:junit4]   2> 12884 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10093 (1428393175513300992)]} 0 1
[junit4:junit4]   2> 12885 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[94 (1428393175515398144)]} 0 0
[junit4:junit4]   2> 12887 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10094 (1428393175516446720)]} 0 1
[junit4:junit4]   2> 12889 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[95 (1428393175518543872)]} 0 1
[junit4:junit4]   2> 12889 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10094 (1428393175519592448)]} 0 0
[junit4:junit4]   2> 12892 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[95 (1428393175521689600)]} 0 1
[junit4:junit4]   2> 12893 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10095 (1428393175522738176)]} 0 1
[junit4:junit4]   2> 12894 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[90 (-1428393175524835328)]} 0 0
[junit4:junit4]   2> 12896 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10095 (1428393175525883904)]} 0 1
[junit4:junit4]   2> 12897 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[90 (-1428393175527981056)]} 0 0
[junit4:junit4]   2> 12898 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10090 (-1428393175529029632)]} 0 0
[junit4:junit4]   2> 12901 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[96 (1428393175531126784)]} 0 1
[junit4:junit4]   2> 12901 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10090 (-1428393175532175360)]} 0 0
[junit4:junit4]   2> 12904 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[96 (1428393175534272512)]} 0 1
[junit4:junit4]   2> 12905 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10096 (1428393175535321088)]} 0 1
[junit4:junit4]   2> 12906 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[97 (1428393175537418240)]} 0 0
[junit4:junit4]   2> 12908 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10096 (1428393175538466816)]} 0 1
[junit4:junit4]   2> 12910 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[97 (1428393175540563968)]} 0 1
[junit4:junit4]   2> 12910 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10097 (1428393175541612544)]} 0 0
[junit4:junit4]   2> 12912 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[91 (-1428393175543709696)]} 0 0
[junit4:junit4]   2> 12913 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10097 (1428393175544758272)]} 0 0
[junit4:junit4]   2> 12915 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[91 (-1428393175546855424)]} 0 0
[junit4:junit4]   2> 12916 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10091 (-1428393175547904000)]} 0 0
[junit4:junit4]   2> 12918 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[98 (1428393175550001152)]} 0 0
[junit4:junit4]   2> 12919 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10091 (-1428393175551049728)]} 0 0
[junit4:junit4]   2> 12921 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[98 (1428393175553146880)]} 0 0
[junit4:junit4]   2> 12923 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10098 (1428393175554195456)]} 0 1
[junit4:junit4]   2> 12925 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[99 (1428393175556292608)]} 0 1
[junit4:junit4]   2> 12925 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10098 (1428393175557341184)]} 0 0
[junit4:junit4]   2> 12928 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[99 (1428393175559438336)]} 0 1
[junit4:junit4]   2> 12929 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10099 (1428393175560486912)]} 0 1
[junit4:junit4]   2> 12930 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[93 (-1428393175562584064)]} 0 0
[junit4:junit4]   2> 12931 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10099 (1428393175563632640)]} 0 0
[junit4:junit4]   2> 12933 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[93 (-1428393175565729792)]} 0 0
[junit4:junit4]   2> 12934 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10093 (-1428393175566778369)]} 0 0
[junit4:junit4]   2> 12935 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[100 (1428393175566778368)]} 0 1
[junit4:junit4]   2> 12937 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[100 (1428393175569924096)]} 0 0
[junit4:junit4]   2> 12937 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10093 (-1428393175569924097)]} 0 0
[junit4:junit4]   2> 12940 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[95 (-1428393175573069825)]} 0 0
[junit4:junit4]   2> 12941 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10100 (1428393175573069824)]} 0 1
[junit4:junit4]   2> 12944 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10100 (1428393175576215552)]} 0 1
[junit4:junit4]   2> 12943 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[95 (-1428393175576215553)]} 0 0
[junit4:junit4]   2> 12946 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10095 (-1428393175579361280)]} 0 0
[junit4:junit4]   2> 12947 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[101 (1428393175579361281)]} 0 1
[junit4:junit4]   2> 12949 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10095 (-1428393175582507008)]} 0 0
[junit4:junit4]   2> 12950 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[101 (1428393175582507009)]} 0 1
[junit4:junit4]   2> 12953 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10101 (1428393175585652736)]} 0 1
[junit4:junit4]   2> 12954 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[102 (1428393175586701312)]} 0 1
[junit4:junit4]   2> 12956 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10101 (1428393175588798464)]} 0 1
[junit4:junit4]   2> 12958 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[102 (1428393175590895616)]} 0 1
[junit4:junit4]   2> 12959 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10102 (1428393175591944192)]} 0 1
[junit4:junit4]   2> 12960 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[101 (-1428393175594041344)]} 0 0
[junit4:junit4]   2> 12963 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10102 (1428393175596138496)]} 0 1
[junit4:junit4]   2> 12963 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[101 (-1428393175597187072)]} 0 0
[junit4:junit4]   2> 12965 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10101 (-1428393175599284224)]} 0 0
[junit4:junit4]   2> 12967 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[103 (1428393175600332800)]} 0 1
[junit4:junit4]   2> 12968 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10101 (-1428393175602429952)]} 0 0
[junit4:junit4]   2> 12970 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[103 (1428393175603478528)]} 0 1
[junit4:junit4]   2> 12972 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10103 (1428393175605575680)]} 0 1
[junit4:junit4]   2> 12973 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[103 (-1428393175607672832)]} 0 0
[junit4:junit4]   2> 12975 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10103 (1428393175608721408)]} 0 1
[junit4:junit4]   2> 12976 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[103 (-1428393175610818560)]} 0 0
[junit4:junit4]   2> 12977 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10103 (-1428393175611867136)]} 0 0
[junit4:junit4]   2> 12980 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[104 (1428393175613964288)]} 0 1
[junit4:junit4]   2> 12980 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10103 (-1428393175615012864)]} 0 0
[junit4:junit4]   2> 12984 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[104 (1428393175618158592)]} 0 1
[junit4:junit4]   2> 12984 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10104 (1428393175618158592)]} 0 1
[junit4:junit4]   2> 12986 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[104 (-1428393175621304320)]} 0 0
[junit4:junit4]   2> 12988 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10104 (1428393175622352896)]} 0 1
[junit4:junit4]   2> 12989 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[104 (-1428393175624450048)]} 0 0
[junit4:junit4]   2> 12990 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10104 (-1428393175625498624)]} 0 0
[junit4:junit4]   2> 12993 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[105 (1428393175627595776)]} 0 1
[junit4:junit4]   2> 12994 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[105 (1428393175629692928)]} 0 0
[junit4:junit4]   2> 12997 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10104 (-1428393175632838656)]} 0 0
[junit4:junit4]   2> 12997 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[106 (1428393175632838656)]} 0 0
[junit4:junit4]   2> 13000 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[106 (1428393175635984384)]} 0 0
[junit4:junit4]   2> 13001 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10105 (1428393175635984384)]} 0 1
[junit4:junit4]   2> 13003 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[107 (1428393175639130112)]} 0 0
[junit4:junit4]   2> 13003 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10105 (1428393175639130112)]} 0 0
[junit4:junit4]   2> 13006 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[107 (1428393175642275840)]} 0 0
[junit4:junit4]   2> 13006 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10106 (1428393175642275840)]} 0 0
[junit4:junit4]   2> 13010 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10106 (1428393175645421568)]} 0 1
[junit4:junit4]   2> 13010 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[108 (1428393175645421568)]} 0 1
[junit4:junit4]   2> 13013 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10107 (1428393175648567296)]} 0 1
[junit4:junit4]   2> 13014 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[108 (1428393175649615872)]} 0 1
[junit4:junit4]   2> 13016 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10107 (1428393175652761600)]} 0 0
[junit4:junit4]   2> 13017 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[109 (1428393175652761600)]} 0 1
[junit4:junit4]   2> 13020 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10108 (1428393175655907328)]} 0 1
[junit4:junit4]   2> 13020 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[109 (1428393175656955904)]} 0 0
[junit4:junit4]   2> 13023 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10108 (1428393175659053056)]} 0 1
[junit4:junit4]   2> 13024 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[110 (1428393175660101632)]} 0 1
[junit4:junit4]   2> 13026 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10109 (1428393175662198784)]} 0 1
[junit4:junit4]   2> 13026 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[110 (1428393175663247360)]} 0 0
[junit4:junit4]   2> 13028 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10109 (1428393175665344512)]} 0 0
[junit4:junit4]   2> 13030 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[111 (1428393175666393088)]} 0 1
[junit4:junit4]   2> 13032 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10110 (1428393175668490240)]} 0 1
[junit4:junit4]   2> 13033 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[111 (1428393175669538816)]} 0 1
[junit4:junit4]   2> 13034 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10110 (1428393175671635968)]} 0 0
[junit4:junit4]   2> 13035 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[106 (-1428393175672684544)]} 0 0
[junit4:junit4]   2> 13037 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10111 (1428393175674781696)]} 0 0
[junit4:junit4]   2> 13038 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[106 (-1428393175675830272)]} 0 0
[junit4:junit4]   2> 13040 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10111 (1428393175677927424)]} 0 0
[junit4:junit4]   2> 13042 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[112 (1428393175678976000)]} 0 1
[junit4:junit4]   2> 13043 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10106 (-1428393175681073152)]} 0 0
[junit4:junit4]   2> 13044 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[112 (1428393175682121728)]} 0 0
[junit4:junit4]   2> 13047 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10106 (-1428393175685267456)]} 0 0
[junit4:junit4]   2> 13047 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[109 (-1428393175685267456)]} 0 0
[junit4:junit4]   2> 13050 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[109 (-1428393175688413184)]} 0 0
[junit4:junit4]   2> 13051 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10112 (1428393175688413184)]} 0 1
[junit4:junit4]   2> 13054 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[113 (1428393175691558912)]} 0 1
[junit4:junit4]   2> 13054 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10112 (1428393175691558912)]} 0 1
[junit4:junit4]   2> 13057 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[113 (1428393175694704640)]} 0 1
[junit4:junit4]   2> 13057 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10109 (-1428393175695753216)]} 0 0
[junit4:junit4]   2> 13060 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10109 (-1428393175698898944)]} 0 0
[junit4:junit4]   2> 13061 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[114 (1428393175698898944)]} 0 1
[junit4:junit4]   2> 13063 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10113 (1428393175702044672)]} 0 0
[junit4:junit4]   2> 13065 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[114 (1428393175703093248)]} 0 1
[junit4:junit4]   2> 13067 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10113 (1428393175705190400)]} 0 1
[junit4:junit4]   2> 13068 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[110 (-1428393175707287552)]} 0 0
[junit4:junit4]   2> 13069 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10114 (1428393175708336128)]} 0 0
[junit4:junit4]   2> 13071 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[110 (-1428393175710433280)]} 0 0
[junit4:junit4]   2> 13073 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10114 (1428393175711481856)]} 0 1
[junit4:junit4]   2> 13075 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10110 (-1428393175714627584)]} 0 0
[junit4:junit4]   2> 13076 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[115 (1428393175714627585)]} 0 1
[junit4:junit4]   2> 13078 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10110 (-1428393175717773312)]} 0 0
[junit4:junit4]   2> 13079 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[115 (1428393175717773313)]} 0 1
[junit4:junit4]   2> 13082 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10115 (1428393175720919040)]} 0 0
[junit4:junit4]   2> 13082 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[111 (-1428393175721967616)]} 0 0
[junit4:junit4]   2> 13085 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10115 (1428393175724064768)]} 0 1
[junit4:junit4]   2> 13085 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[111 (-1428393175725113344)]} 0 0
[junit4:junit4]   2> 13088 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10111 (-1428393175728259073)]} 0 0
[junit4:junit4]   2> 13089 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[116 (1428393175728259072)]} 0 1
[junit4:junit4]   2> 13091 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10111 (-1428393175731404800)]} 0 0
[junit4:junit4]   2> 13092 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[116 (1428393175731404801)]} 0 1
[junit4:junit4]   2> 13094 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[112 (-1428393175734550529)]} 0 0
[junit4:junit4]   2> 13095 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10116 (1428393175734550528)]} 0 1
[junit4:junit4]   2> 13097 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[112 (-1428393175737696256)]} 0 0
[junit4:junit4]   2> 13099 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10116 (1428393175738744832)]} 0 1
[junit4:junit4]   2> 13101 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[117 (1428393175740841984)]} 0 1
[junit4:junit4]   2> 13102 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10112 (-1428393175742939136)]} 0 0
[junit4:junit4]   2> 13104 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[117 (1428393175743987712)]} 0 1
[junit4:junit4]   2> 13105 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10112 (-1428393175746084864)]} 0 0
[junit4:junit4]   2> 13107 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[118 (1428393175747133440)]} 0 1
[junit4:junit4]   2> 13109 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10117 (1428393175749230592)]} 0 1
[junit4:junit4]   2> 13110 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[118 (1428393175750279168)]} 0 1
[junit4:junit4]   2> 13112 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10117 (1428393175752376320)]} 0 1
[junit4:junit4]   2> 13113 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[113 (-1428393175754473472)]} 0 0
[junit4:junit4]   2> 13114 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10118 (1428393175755522048)]} 0 0
[junit4:junit4]   2> 13116 T1155 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13116 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[113 (-1428393175757619200)]} 0 0
[junit4:junit4]   2> 13117 T1155 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[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:20044_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:20044"}
[junit4:junit4]   2> 13118 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10118 (1428393175758667776)]} 0 1
[junit4:junit4]   2> 13119 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[119 (1428393175760764928)]} 0 0
[junit4:junit4]   2> 13120 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10113 (-1428393175761813504)]} 0 0
[junit4:junit4]   2> 13122 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[119 (1428393175763910656)]} 0 0
[junit4:junit4]   2> 13123 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10113 (-1428393175764959232)]} 0 0
[junit4:junit4]   2> 13125 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[114 (-1428393175767056384)]} 0 0
[junit4:junit4]   2> 13127 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10119 (1428393175768104960)]} 0 1
[junit4:junit4]   2> 13128 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[114 (-1428393175770202112)]} 0 0
[junit4:junit4]   2> 13129 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10119 (1428393175771250688)]} 0 0
[junit4:junit4]   2> 13131 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[120 (1428393175773347840)]} 0 0
[junit4:junit4]   2> 13132 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10114 (-1428393175774396416)]} 0 0
[junit4:junit4]   2> 13135 T1174 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> 13135 T1161 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> 13135 T1190 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> 13135 T1154 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> 13135 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[10114 (-1428393175777542144)]} 0 0
[junit4:junit4]   2> 13136 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[120 (1428393175777542145)]} 0 1
[junit4:junit4]   2> 13140 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10120 (1428393175781736449)]} 0 1
[junit4:junit4]   2> 13140 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[121 (1428393175781736448)]} 0 1
[junit4:junit4]   2>  C102_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:20044_, base_url=http://127.0.0.1:20044}
[junit4:junit4]   2> 13152 T1181 C102 P20044 oasc.CachingDirectoryFactory.close Releasing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-1362221872340/jetty2
[junit4:junit4]   2> 13155 T1181 C102 P20044 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@599e5599 lockFactory=org.apache.lucene.store.NativeFSLockFactory@672307b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13155 T1181 C102 P20044 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 13158 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[121 (1428393175784882177)]} 0 7
[junit4:junit4]   2> 13158 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10120 (1428393175784882176)]} 0 7
[junit4:junit4]   2> 13158 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[121 (1428393175784882177)]} 0 16
[junit4:junit4]   2> 13159 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10120 (1428393175784882176)]} 0 17
[junit4:junit4]   2> 13162 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[122 (1428393175804805120)]} 0 1
[junit4:junit4]   2> 13163 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10121 (1428393175805853696)]} 0 1
[junit4:junit4]   2> 13169 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[122 (1428393175808999424)]} 0 1
[junit4:junit4]   2> 13169 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[122 (1428393175808999424)]} 0 4
[junit4:junit4]   2> 13170 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10121 (1428393175808999425)]} 0 1
[junit4:junit4]   2> 13171 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10121 (1428393175808999425)]} 0 6
[junit4:junit4]   2> 13173 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[123 (1428393175816339456)]} 0 1
[junit4:junit4]   2> 13174 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10122 (1428393175817388032)]} 0 1
[junit4:junit4]   2> 13180 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[123 (1428393175820533760)]} 0 1
[junit4:junit4]   2> 13180 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[123 (1428393175820533760)]} 0 4
[junit4:junit4]   2> 13181 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10122 (1428393175821582336)]} 0 1
[junit4:junit4]   2> 13181 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10122 (1428393175821582336)]} 0 4
[junit4:junit4]   2> 13184 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[116 (-1428393175827873792)]} 0 1
[junit4:junit4]   2> 13185 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10123 (1428393175828922368)]} 0 1
[junit4:junit4]   2> 13187 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[116 (-1428393175831019520)]} 0 0
[junit4:junit4]   2> 13188 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[116 (-1428393175831019520)]} 0 2
[junit4:junit4]   2> 13192 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[124 (1428393175836262400)]} 0 1
[junit4:junit4]   2> 13192 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10123 (1428393175833116672)]} 0 1
[junit4:junit4]   2> 13199 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[124 (1428393175840456704)]} 0 1
[junit4:junit4]   2> 13199 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[124 (1428393175840456704)]} 0 4
[junit4:junit4]   2> 13200 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10123 (1428393175833116672)]} 0 12
[junit4:junit4]   2> 13203 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[117 (-1428393175847796736)]} 0 1
[junit4:junit4]   2> 13203 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10116 (-1428393175848845312)]} 0 0
[junit4:junit4]   2> 13210 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10116 (-1428393175851991040)]} 0 1
[junit4:junit4]   2> 13210 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[117 (-1428393175851991041)]} 0 1
[junit4:junit4]   2> 13211 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10116 (-1428393175851991040)]} 0 5
[junit4:junit4]   2> 13211 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[117 (-1428393175851991041)]} 0 5
[junit4:junit4]   2> 13215 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10124 (1428393175860379649)]} 0 1
[junit4:junit4]   2> 13215 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[125 (1428393175860379648)]} 0 1
[junit4:junit4]   2> 13222 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10124 (1428393175864573953)]} 0 1
[junit4:junit4]   2> 13222 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[125 (1428393175864573952)]} 0 1
[junit4:junit4]   2> 13222 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10124 (1428393175864573953)]} 0 4
[junit4:junit4]   2> 13223 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[125 (1428393175864573952)]} 0 5
[junit4:junit4]   2> 13225 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10117 (-1428393175871913984)]} 0 0
[junit4:junit4]   2> 13227 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[126 (1428393175872962560)]} 0 1
[junit4:junit4]   2> 13231 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10117 (-1428393175875059712)]} 0 0
[junit4:junit4]   2> 13232 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10117 (-1428393175875059712)]} 0 4
[junit4:junit4]   2> 13233 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[126 (1428393175876108288)]} 0 1
[junit4:junit4]   2> 13234 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[126 (1428393175876108288)]} 0 5
[junit4:junit4]   2> 13236 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10125 (1428393175882399744)]} 0 1
[junit4:junit4]   2> 13236 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[119 (-1428393175883448320)]} 0 0
[junit4:junit4]   2> 13241 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[119 (-1428393175886594048)]} 0 0
[junit4:junit4]   2> 13242 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10125 (1428393175885545472)]} 0 1
[junit4:junit4]   2> 13242 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[119 (-1428393175886594048)]} 0 3
[junit4:junit4]   2> 13242 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10125 (1428393175885545472)]} 0 4
[junit4:junit4]   2> 13246 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[127 (1428393175892885505)]} 0 1
[junit4:junit4]   2> 13246 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10126 (1428393175892885504)]} 0 1
[junit4:junit4]   2> 13253 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10126 (1428393175897079809)]} 0 1
[junit4:junit4]   2> 13254 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10126 (1428393175897079809)]} 0 5
[junit4:junit4]   2> 13254 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[127 (1428393175897079808)]} 0 1
[junit4:junit4]   2> 13255 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[127 (1428393175897079808)]} 0 6
[junit4:junit4]   2> 13256 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10119 (-1428393175904419840)]} 0 0
[junit4:junit4]   2> 13258 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[120 (-1428393175906516992)]} 0 0
[junit4:junit4]   2> 13262 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10119 (-1428393175907565568)]} 0 0
[junit4:junit4]   2> 13263 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10119 (-1428393175907565568)]} 0 4
[junit4:junit4]   2> 13264 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[120 (-1428393175909662720)]} 0 0
[junit4:junit4]   2> 13265 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[120 (-1428393175909662720)]} 0 4
[junit4:junit4]   2> 13267 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10127 (1428393175914905600)]} 0 1
[junit4:junit4]   2> 13269 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[128 (1428393175917002752)]} 0 1
[junit4:junit4]   2> 13274 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10127 (1428393175919099904)]} 0 1
[junit4:junit4]   2> 13275 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10127 (1428393175919099904)]} 0 5
[junit4:junit4]   2> 13275 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[128 (1428393175920148480)]} 0 1
[junit4:junit4]   2> 13276 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[128 (1428393175920148480)]} 0 5
[junit4:junit4]   2> 13277 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10120 (-1428393175926439936)]} 0 0
[junit4:junit4]   2> 13279 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[121 (-1428393175928537088)]} 0 0
[junit4:junit4]   2> 13283 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10120 (-1428393175929585664)]} 0 0
[junit4:junit4]   2> 13284 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10120 (-1428393175929585664)]} 0 4
[junit4:junit4]   2> 13285 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[121 (-1428393175931682816)]} 0 0
[junit4:junit4]   2> 13286 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[121 (-1428393175931682816)]} 0 4
[junit4:junit4]   2> 13288 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10128 (1428393175936925696)]} 0 1
[junit4:junit4]   2> 13290 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[129 (1428393175939022848)]} 0 1
[junit4:junit4]   2> 13295 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10128 (1428393175941120000)]} 0 1
[junit4:junit4]   2> 13295 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10128 (1428393175941120000)]} 0 4
[junit4:junit4]   2> 13297 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[129 (1428393175943217152)]} 0 1
[junit4:junit4]   2> 13298 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[129 (1428393175943217152)]} 0 5
[junit4:junit4]   2> 13298 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[10121 (-1428393175948460032)]} 0 0
[junit4:junit4]   2> 13301 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[122 (-1428393175951605760)]} 0 0
[junit4:junit4]   2> 13303 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10121 (-1428393175951605760)]} 0 0
[junit4:junit4]   2> 13304 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10121 (-1428393175951605760)]} 0 3
[junit4:junit4]   2> 13307 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[122 (-1428393175954751488)]} 0 0
[junit4:junit4]   2> 13308 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10129 (1428393175957897216)]} 0 1
[junit4:junit4]   2> 13308 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[122 (-1428393175954751488)]} 0 4
[junit4:junit4]   2> 13312 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[130 (1428393175962091520)]} 0 1
[junit4:junit4]   2> 13315 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10129 (1428393175962091520)]} 0 1
[junit4:junit4]   2> 13316 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10129 (1428393175962091520)]} 0 5
[junit4:junit4]   2> 13318 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[130 (1428393175965237248)]} 0 1
[junit4:junit4]   2> 13319 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[130 (1428393175965237248)]} 0 5
[junit4:junit4]   2> 13319 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10122 (-1428393175970480128)]} 0 0
[junit4:junit4]   2> 13322 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[131 (1428393175972577280)]} 0 1
[junit4:junit4]   2> 13324 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10122 (-1428393175973625856)]} 0 0
[junit4:junit4]   2> 13325 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10122 (-1428393175973625856)]} 0 3
[junit4:junit4]   2> 13329 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[131 (1428393175976771584)]} 0 1
[junit4:junit4]   2> 13329 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10130 (1428393175979917312)]} 0 1
[junit4:junit4]   2> 13329 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[131 (1428393175976771584)]} 0 4
[junit4:junit4]   2> 13333 T1144 C103 P62295 /update {wt=javabin&version=2} {delete=[123 (-1428393175984111616)]} 0 1
[junit4:junit4]   2> 13336 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10130 (1428393175984111616)]} 0 1
[junit4:junit4]   2> 13336 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[10130 (1428393175984111616)]} 0 4
[junit4:junit4]   2> 13338 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[123 (-1428393175987257344)]} 0 0
[junit4:junit4]   2> 13339 T1166 C104 P49642 /update {wt=javabin&version=2} {delete=[123 (-1428393175987257344)]} 0 4
[junit4:junit4]   2> 13340 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10131 (1428393175991451648)]} 0 1
[junit4:junit4]   2> 13343 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[132 (1428393175994597376)]} 0 1
[junit4:junit4]   2> 13347 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10131 (1428393175995645952)]} 0 1
[junit4:junit4]   2> 13348 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10131 (1428393175995645952)]} 0 5
[junit4:junit4]   2> 13350 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[132 (1428393175998791680)]} 0 1
[junit4:junit4]   2> 13350 T1145 C103 P62295 /update {wt=javabin&version=2} {delete=[10123 (-1428393176002985984)]} 0 0
[junit4:junit4]   2> 13351 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[132 (1428393175998791680)]} 0 5
[junit4:junit4]   2> 13354 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[133 (1428393176006131712)]} 0 1
[junit4:junit4]   2> 13357 T1180 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10123 (-1428393176007180288)]} 0 0
[junit4:junit4]   2> 13358 T1165 C104 P49642 /update {wt=javabin&version=2} {delete=[10123 (-1428393176007180288)]} 0 4
[junit4:junit4]   2> 13360 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[133 (1428393176009277440)]} 0 1
[junit4:junit4]   2> 13361 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[133 (1428393176009277440)]} 0 5
[junit4:junit4]   2> 13362 T1145 C103 P62295 /update {wt=javabin&version=2} {add=[10132 (1428393176014520320)]} 0 1
[junit4:junit4]   2> 13365 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[134 (1428393176017666048)]} 0 1
[junit4:junit4]   2> 13368 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10132 (1428393176017666048)]} 0 1
[junit4:junit4]   2> 13369 T1166 C104 P49642 /update {wt=javabin&version=2} {add=[10132 (1428393176017666048)]} 0 5
[junit4:junit4]   2> 13372 T1181 C102 P20044 /update {distrib.from=http://127.0.0.1:49642/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[134 (1428393176021860352)]} 0 1
[junit4:junit4]   2> 13372 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[10133 (1428393176025006080)]} 0 1
[junit4:junit4]   2> 13373 T1165 C104 P49642 /update {wt=javabin&version=2} {add=[134 (1428393176021860352)]} 0 5
[junit4:junit4]   2> 13376 T1144 C103 P62295 /update {wt=javabin&version=2} {add=[135 (1428393176029200384)]} 0 1
[junit4:junit4]   2

[...truncated too long message...]

OTE: All tests run in this JVM: [DisMaxRequestHandlerTest, DirectSolrConnectionTest, TestBinaryField, TestSolrCoreProperties, TestPhraseSuggestions, TestSolr4Spatial, TestLFUCache, TestPseudoReturnFields, SuggesterWFSTTest, TestGroupingSearch, SuggesterFSTTest, IndexBasedSpellCheckerTest, TestFastOutputStream, TestConfig, TestZkChroot, TestSolrXMLSerializer, TestQuerySenderListener, TestWordDelimiterFilterFactory, SolrIndexSplitterTest, TestCoreContainer, TestArbitraryIndexDir, DebugComponentTest, DistributedTermsComponentTest, TestBadConfig, TestUpdate, CacheHeaderTest, WordBreakSolrSpellCheckerTest, DocValuesTest, TestBinaryResponseWriter, TestFiltering, TestPHPSerializedResponseWriter, TestDocumentBuilder, TestStressVersions, FileUtilsTest, PrimitiveFieldTypeTest, SortByFunctionTest, ResponseLogComponentTest, TestAtomicUpdateErrorCases, NumericFieldsTest, TestSurroundQueryParser, SampleTest, SOLR749Test, TestPropInject, TestDFRSimilarityFactory, XsltUpdateRequestHandlerTest, TestDefaultSimilarityFactory, TestElisionMultitermQuery, TestAnalyzedSuggestions, CoreAdminHandlerTest, DateMathParserTest, DefaultValueUpdateProcessorTest, RAMDirectoryFactoryTest, TestSearchPerf, FileBasedSpellCheckerTest, TestPartialUpdateDeduplication, SearchHandlerTest, TestIndexingPerformance, LoggingHandlerTest, SolrCmdDistributorTest, TestCharFilters, XmlUpdateRequestHandlerTest, DirectUpdateHandlerOptimizeTest, OutputWriterTest, AutoCommitTest, BasicDistributedZkTest, RecoveryZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.seed=8DDB8EBF93FB5403 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_DO -Dtests.timezone=Arctic/Longyearbyen -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   0.00s J0 | RecoveryZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1191, name=coreLoadExecutor-730-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4]    > 	at java.lang.Thread.getStackTrace(Thread.java:1567)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:986)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
[junit4:junit4]    > 	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:244)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:458)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:733)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:619)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:868)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:951)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:574)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:569)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]    > 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]    > 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[junit4:junit4]    > 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]    > Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/index2241322431tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c069a07)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	... 1 more
[junit4:junit4]    > Throwable #2: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1193, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4]    > 	at java.lang.Thread.getStackTrace(Thread.java:1567)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:986)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
[junit4:junit4]    > 	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
[junit4:junit4]    > 	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:383)
[junit4:junit4]    > 	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:281)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:153)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:409)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:223)
[junit4:junit4]    > Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/index2241322432tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@5da8d28)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] Completed on J0 in 70.99s, 1 test, 2 errors <<< FAILURES!

[...truncated 508 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:381: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:361: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build.xml:183: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/common-build.xml:449: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:1207: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:870: There were test failures: 260 suites, 1090 tests, 2 suite-level errors, 23 ignored (4 assumptions)

Total time: 54 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message