You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/01/10 11:40:31 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.7.0_10) - Build # 3677
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/3677/
Java: 32bit/jdk1.7.0_10 -client -XX:+UseConcMarkSweepGC
1 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=531, 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=531, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
at __randomizedtesting.SeedInfo.seed([88BB4DC6D5972E6E]:0)
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:517)
at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:983)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:875)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:867)
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:377)
at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:273)
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@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/index83208323tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a1bfbd)
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 8671 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4] 2> 0 T472 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4] 2> 3 T472 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1357813743350
[junit4:junit4] 2> 4 T472 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 5 T473 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 6 T473 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 6 T473 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 6 T473 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 7 T473 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 7 T473 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4] 2> 105 T472 oasc.ZkTestServer.run start zk server on port:59829
[junit4:junit4] 2> 105 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4028a8
[junit4:junit4] 2> 107 T478 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 107 T478 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 107 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47615
[junit4:junit4] 2> 107 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47615
[junit4:junit4] 2> 109 T476 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 109 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 150 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0000 with negotiated timeout 10000 for client /127.0.0.1:47615
[junit4:junit4] 2> 150 T478 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0000, negotiated timeout = 10000
[junit4:junit4] 2> 151 T479 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4028a8 name:ZooKeeperConnection Watcher:127.0.0.1:59829 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 151 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 151 T472 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 155 T477 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c24017efa0000
[junit4:junit4] 2> 155 T479 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 156 T474 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:47615 which had sessionid 0x13c24017efa0000
[junit4:junit4] 2> 155 T472 oaz.ZooKeeper.close Session: 0x13c24017efa0000 closed
[junit4:junit4] 2> 156 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@123368c
[junit4:junit4] 2> 157 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 157 T480 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 158 T480 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 158 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47616
[junit4:junit4] 2> 158 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47616
[junit4:junit4] 2> 159 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0001 with negotiated timeout 10000 for client /127.0.0.1:47616
[junit4:junit4] 2> 159 T480 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0001, negotiated timeout = 10000
[junit4:junit4] 2> 159 T481 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@123368c name:ZooKeeperConnection Watcher:127.0.0.1:59829/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 159 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 160 T472 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 165 T472 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 167 T472 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 169 T472 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 171 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 172 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 178 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4] 2> 179 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 188 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4] 2> 189 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 192 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4] 2> 192 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 195 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4] 2> 196 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 199 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 199 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 201 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 202 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 205 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 206 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 208 T472 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4] 2> 209 T472 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 212 T477 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c24017efa0001
[junit4:junit4] 2> 212 T481 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 212 T472 oaz.ZooKeeper.close Session: 0x13c24017efa0001 closed
[junit4:junit4] 2> 212 T474 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:47616 which had sessionid 0x13c24017efa0001
[junit4:junit4] 2> 271 T472 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 276 T472 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45285
[junit4:junit4] 2> 276 T472 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 277 T472 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 277 T472 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559
[junit4:junit4] 2> 277 T472 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/solr.xml
[junit4:junit4] 2> 278 T472 oasc.CoreContainer.<init> New CoreContainer 30791695
[junit4:junit4] 2> 278 T472 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/'
[junit4:junit4] 2> 278 T472 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/'
[junit4:junit4] 2> 294 T472 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 294 T472 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 295 T472 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 295 T472 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 295 T472 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 295 T472 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 296 T472 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 296 T472 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 296 T472 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 297 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 309 T472 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 333 T472 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59829/solr
[junit4:junit4] 2> 334 T472 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 334 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@d552cf
[junit4:junit4] 2> 335 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 335 T491 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 336 T491 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 336 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47617
[junit4:junit4] 2> 337 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47617
[junit4:junit4] 2> 337 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0002 with negotiated timeout 20000 for client /127.0.0.1:47617
[junit4:junit4] 2> 338 T491 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0002, negotiated timeout = 20000
[junit4:junit4] 2> 339 T492 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d552cf name:ZooKeeperConnection Watcher:127.0.0.1:59829 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 339 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 340 T477 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c24017efa0002
[junit4:junit4] 2> 341 T474 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:47617 which had sessionid 0x13c24017efa0002
[junit4:junit4] 2> 341 T472 oaz.ZooKeeper.close Session: 0x13c24017efa0002 closed
[junit4:junit4] 2> 341 T492 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 342 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 344 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d45314
[junit4:junit4] 2> 345 T493 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 345 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 345 T493 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 346 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47618
[junit4:junit4] 2> 346 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47618
[junit4:junit4] 2> 347 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0003 with negotiated timeout 20000 for client /127.0.0.1:47618
[junit4:junit4] 2> 347 T493 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0003, negotiated timeout = 20000
[junit4:junit4] 2> 347 T494 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d45314 name:ZooKeeperConnection Watcher:127.0.0.1:59829/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 347 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 352 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 354 T472 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 355 T472 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45285_
[junit4:junit4] 2> 356 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:delete cxid:0x7 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:45285_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:45285_
[junit4:junit4] 2> 357 T472 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45285_
[junit4:junit4] 2> 359 T472 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 363 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:delete cxid:0x15 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4] 2> 365 T472 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 369 T472 oasc.Overseer.start Overseer (id=88985681484447747-127.0.0.1:45285_-n_0000000000) starting
[junit4:junit4] 2> 370 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:create cxid:0x1a zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 371 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:create cxid:0x1b zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 372 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:create cxid:0x1c zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 373 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:create cxid:0x1d zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 374 T496 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4] 2> 375 T472 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 376 T472 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 378 T495 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 388 T497 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/collection1
[junit4:junit4] 2> 388 T497 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 389 T497 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 389 T497 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 390 T497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/collection1/'
[junit4:junit4] 2> 391 T497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/collection1/lib/README' to classloader
[junit4:junit4] 2> 391 T497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 463 T497 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4] 2> 552 T497 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 554 T497 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 559 T497 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 1297 T497 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 1304 T497 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 1306 T497 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 1331 T497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1334 T497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1337 T497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1341 T497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1343 T497 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1357813743559/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data/
[junit4:junit4] 2> 1343 T497 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f39788
[junit4:junit4] 2> 1344 T497 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 1346 T497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data forceNew:false
[junit4:junit4] 2> 1347 T497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data
[junit4:junit4] 2> 1347 T497 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data/index/
[junit4:junit4] 2> 1347 T497 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 1348 T497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data/index forceNew:false
[junit4:junit4] 2> 1349 T497 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@7504c1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16ca2e6)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 1350 T497 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 1350 T497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data/index
[junit4:junit4] 2> 1350 T497 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 1351 T497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 1351 T497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 1352 T497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 1352 T497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 1353 T497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 1353 T497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 1353 T497 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 1354 T497 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 1354 T497 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 1355 T497 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 1355 T497 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 1356 T497 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 1356 T497 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 1356 T497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 1357 T497 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 1363 T497 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 1363 T497 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1363 T497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1364 T497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1364 T497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1364 T497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1365 T497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1365 T497 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1365 T497 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1366 T497 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 1366 T497 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 1368 T497 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 1370 T497 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 1371 T497 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 1373 T497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data
[junit4:junit4] 2> 1374 T497 oass.SolrIndexSearcher.<init> Opening Searcher@d75cc9 main
[junit4:junit4] 2> 1374 T497 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 1375 T497 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 1375 T497 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 1421 T497 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 1446 T498 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d75cc9 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 1457 T497 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 1457 T497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 1458 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 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> 1892 T495 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 1893 T495 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:45285_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:45285"}
[junit4:junit4] 2> 1893 T495 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 1894 T495 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 1895 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 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> 1901 T494 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> 2461 T497 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 2461 T497 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:45285 collection:control_collection shard:shard1
[junit4:junit4] 2> 2462 T497 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 2469 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 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> 2470 T497 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 2470 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 2473 T497 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 2473 T497 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 2473 T497 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:45285/collection1/
[junit4:junit4] 2> 2474 T497 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 2474 T497 oasc.SyncStrategy.syncToMe http://127.0.0.1:45285/collection1/ has no replicas
[junit4:junit4] 2> 2474 T497 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:45285/collection1/
[junit4:junit4] 2> 2475 T497 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 2501 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3407 T495 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3439 T494 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> 3456 T497 oasc.ZkController.register We are http://127.0.0.1:45285/collection1/ and leader is http://127.0.0.1:45285/collection1/
[junit4:junit4] 2> 3457 T497 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:45285
[junit4:junit4] 2> 3457 T497 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 3457 T497 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 3457 T497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 3459 T497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3460 T472 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 3460 T472 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 3461 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 3464 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 3474 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@107cf24
[junit4:junit4] 2> 3475 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3475 T499 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3476 T499 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 3476 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47619
[junit4:junit4] 2> 3476 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47619
[junit4:junit4] 2> 3477 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0004 with negotiated timeout 10000 for client /127.0.0.1:47619
[junit4:junit4] 2> 3477 T499 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0004, negotiated timeout = 10000
[junit4:junit4] 2> 3477 T500 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@107cf24 name:ZooKeeperConnection Watcher:127.0.0.1:59829/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3477 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3478 T472 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3480 T472 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 3532 T472 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 3537 T472 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50239
[junit4:junit4] 2> 3538 T472 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 3538 T472 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 3538 T472 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826
[junit4:junit4] 2> 3539 T472 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/solr.xml
[junit4:junit4] 2> 3539 T472 oasc.CoreContainer.<init> New CoreContainer 13575923
[junit4:junit4] 2> 3539 T472 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/'
[junit4:junit4] 2> 3540 T472 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/'
[junit4:junit4] 2> 3562 T472 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 3562 T472 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 3563 T472 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 3563 T472 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 3564 T472 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 3564 T472 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 3564 T472 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 3565 T472 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 3565 T472 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 3566 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 3578 T472 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 3596 T472 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59829/solr
[junit4:junit4] 2> 3596 T472 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 3597 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@8d3129
[junit4:junit4] 2> 3598 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3599 T510 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3599 T510 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 3600 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47620
[junit4:junit4] 2> 3600 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47620
[junit4:junit4] 2> 3601 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0005 with negotiated timeout 20000 for client /127.0.0.1:47620
[junit4:junit4] 2> 3601 T510 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0005, negotiated timeout = 20000
[junit4:junit4] 2> 3601 T511 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8d3129 name:ZooKeeperConnection Watcher:127.0.0.1:59829 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3601 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3603 T477 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c24017efa0005
[junit4:junit4] 2> 3603 T511 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 3603 T474 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:47620 which had sessionid 0x13c24017efa0005
[junit4:junit4] 2> 3603 T472 oaz.ZooKeeper.close Session: 0x13c24017efa0005 closed
[junit4:junit4] 2> 3604 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 3615 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d79045
[junit4:junit4] 2> 3616 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 3616 T512 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 3616 T512 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 3616 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47621
[junit4:junit4] 2> 3617 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47621
[junit4:junit4] 2> 3618 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0006 with negotiated timeout 20000 for client /127.0.0.1:47621
[junit4:junit4] 2> 3618 T512 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0006, negotiated timeout = 20000
[junit4:junit4] 2> 3618 T513 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d79045 name:ZooKeeperConnection Watcher:127.0.0.1:59829/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 3618 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 3619 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3620 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3628 T472 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50239_
[junit4:junit4] 2> 3628 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0006 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:50239_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:50239_
[junit4:junit4] 2> 3629 T472 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50239_
[junit4:junit4] 2> 3631 T494 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 3631 T500 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 3632 T494 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 3634 T472 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 3639 T514 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/collection1
[junit4:junit4] 2> 3639 T514 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 3640 T514 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 3640 T514 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 3641 T514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/collection1/'
[junit4:junit4] 2> 3641 T514 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/collection1/lib/README' to classloader
[junit4:junit4] 2> 3642 T514 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 3700 T514 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4] 2> 3787 T514 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 3788 T514 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 3798 T514 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 4517 T514 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 4529 T514 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 4532 T514 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 4546 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 4557 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 4563 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 4565 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 4567 T514 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1357813746826/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1/
[junit4:junit4] 2> 4567 T514 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f39788
[junit4:junit4] 2> 4567 T514 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 4569 T514 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1 forceNew:false
[junit4:junit4] 2> 4569 T514 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1
[junit4:junit4] 2> 4569 T514 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1/index/
[junit4:junit4] 2> 4570 T514 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 4570 T514 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1/index forceNew:false
[junit4:junit4] 2> 4572 T514 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@14445ef lockFactory=org.apache.lucene.store.NativeFSLockFactory@a5ebfc)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 4572 T514 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 4573 T514 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1/index
[junit4:junit4] 2> 4573 T514 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 4575 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 4575 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 4575 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 4576 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 4576 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 4576 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 4577 T514 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 4577 T514 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 4578 T514 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 4578 T514 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 4579 T514 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 4579 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 4580 T514 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 4580 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 4581 T514 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 4581 T514 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 4582 T514 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4582 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4582 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4583 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4583 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4584 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4584 T514 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4585 T514 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 4585 T514 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 4586 T514 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 4588 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 4596 T514 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 4597 T514 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 4599 T514 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1
[junit4:junit4] 2> 4599 T514 oass.SolrIndexSearcher.<init> Opening Searcher@1bb381d main
[junit4:junit4] 2> 4600 T514 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 4600 T514 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 4600 T514 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 4605 T514 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 4630 T515 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bb381d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 4644 T514 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 4644 T514 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 4948 T495 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4948 T495 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:45285_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:45285"}
[junit4:junit4] 2> 4952 T495 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:50239_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50239"}
[junit4:junit4] 2> 4952 T495 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4] 2> 4952 T495 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 4956 T513 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> 4956 T500 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> 4956 T494 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> 5646 T514 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 5646 T514 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50239 collection:collection1 shard:shard1
[junit4:junit4] 2> 5647 T514 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 5684 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0006 type:delete cxid:0x43 zxid:0x5a txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4] 2> 5685 T514 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 5685 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0006 type:create cxid:0x44 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5687 T514 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 5687 T514 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 5687 T514 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50239/collection1/
[junit4:junit4] 2> 5687 T514 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 5687 T514 oasc.SyncStrategy.syncToMe http://127.0.0.1:50239/collection1/ has no replicas
[junit4:junit4] 2> 5688 T514 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50239/collection1/
[junit4:junit4] 2> 5688 T514 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 5692 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0006 type:create cxid:0x4e zxid:0x5f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 6466 T495 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6478 T494 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> 6478 T513 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> 6478 T500 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> 6497 T514 oasc.ZkController.register We are http://127.0.0.1:50239/collection1/ and leader is http://127.0.0.1:50239/collection1/
[junit4:junit4] 2> 6497 T514 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50239
[junit4:junit4] 2> 6497 T514 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 6498 T514 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 6498 T514 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 6501 T514 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6503 T472 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 6503 T472 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 6504 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 6578 T472 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 6580 T472 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53964
[junit4:junit4] 2> 6580 T472 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 6581 T472 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 6581 T472 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865
[junit4:junit4] 2> 6581 T472 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/solr.xml
[junit4:junit4] 2> 6582 T472 oasc.CoreContainer.<init> New CoreContainer 18626043
[junit4:junit4] 2> 6582 T472 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/'
[junit4:junit4] 2> 6582 T472 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/'
[junit4:junit4] 2> 6606 T472 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 6607 T472 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 6607 T472 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 6607 T472 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 6608 T472 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 6608 T472 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 6609 T472 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 6609 T472 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 6610 T472 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 6610 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 6623 T472 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 6644 T472 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59829/solr
[junit4:junit4] 2> 6644 T472 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 6645 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@198565
[junit4:junit4] 2> 6646 T525 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 6646 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 6646 T525 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 6647 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47622
[junit4:junit4] 2> 6647 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47622
[junit4:junit4] 2> 6648 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0007 with negotiated timeout 20000 for client /127.0.0.1:47622
[junit4:junit4] 2> 6648 T525 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0007, negotiated timeout = 20000
[junit4:junit4] 2> 6648 T526 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@198565 name:ZooKeeperConnection Watcher:127.0.0.1:59829 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 6649 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 6650 T477 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c24017efa0007
[junit4:junit4] 2> 6651 T526 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 6651 T472 oaz.ZooKeeper.close Session: 0x13c24017efa0007 closed
[junit4:junit4] 2> 6651 T474 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:47622 which had sessionid 0x13c24017efa0007
[junit4:junit4] 2> 6651 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 6654 T472 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59829/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@a1ea7d
[junit4:junit4] 2> 6655 T527 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59829. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 6655 T527 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59829, initiating session
[junit4:junit4] 2> 6655 T474 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:47623
[junit4:junit4] 2> 6655 T472 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 6656 T474 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:47623
[junit4:junit4] 2> 6656 T476 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c24017efa0008 with negotiated timeout 20000 for client /127.0.0.1:47623
[junit4:junit4] 2> 6656 T527 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59829, sessionid = 0x13c24017efa0008, negotiated timeout = 20000
[junit4:junit4] 2> 6663 T528 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a1ea7d name:ZooKeeperConnection Watcher:127.0.0.1:59829/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 6663 T472 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 6664 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0008 type:create cxid:0x1 zxid:0x6c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 6665 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0008 type:create cxid:0x2 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 6666 T472 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53964_
[junit4:junit4] 2> 6668 T477 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c24017efa0008 type:delete cxid:0x4 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:53964_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:53964_
[junit4:junit4] 2> 6668 T472 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53964_
[junit4:junit4] 2> 6671 T494 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> 6671 T513 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> 6672 T500 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 6672 T500 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> 6689 T513 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 6690 T494 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 6690 T472 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 6697 T529 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/collection1
[junit4:junit4] 2> 6697 T529 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 6698 T529 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 6698 T529 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 6699 T529 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/collection1/'
[junit4:junit4] 2> 6700 T529 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/collection1/lib/README' to classloader
[junit4:junit4] 2> 6700 T529 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 6757 T529 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_41
[junit4:junit4] 2> 6837 T529 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 6839 T529 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 6843 T529 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 7594 T529 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 7615 T529 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 7619 T529 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 7635 T529 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 7638 T529 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 7648 T529 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 7649 T529 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 7650 T529 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1357813749865/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2/
[junit4:junit4] 2> 7651 T529 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f39788
[junit4:junit4] 2> 7651 T529 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 7652 T529 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2 forceNew:false
[junit4:junit4] 2> 7652 T529 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2
[junit4:junit4] 2> 7652 T529 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2/index/
[junit4:junit4] 2> 7652 T529 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4] 2> 7653 T529 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2/index forceNew:false
[junit4:junit4] 2> 7654 T529 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b909aa lockFactory=org.apache.lucene.store.NativeFSLockFactory@131218e)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 7654 T529 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 7655 T529 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2/index
[junit4:junit4] 2> 7655 T529 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 7656 T529 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 7656 T529 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 7657 T529 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 7657 T529 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 7657 T529 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 7657 T529 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 7658 T529 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 7658 T529 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 7659 T529 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 7659 T529 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 7660 T529 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 7660 T529 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 7660 T529 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 7660 T529 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 7661 T529 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 7661 T529 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 7662 T529 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7662 T529 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7662 T529 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7663 T529 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7663 T529 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7664 T529 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7664 T529 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7665 T529 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 7665 T529 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 7665 T529 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 7668 T529 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 7670 T529 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 7681 T529 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 7683 T529 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2
[junit4:junit4] 2> 7683 T529 oass.SolrIndexSearcher.<init> Opening Searcher@19bd8fb main
[junit4:junit4] 2> 7684 T529 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 7684 T529 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 7685 T529 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 7689 T529 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 7710 T530 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19bd8fb main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 7723 T529 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 7723 T529 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 7986 T495 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7987 T495 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:50239_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:50239"}
[junit4:junit4] 2> 7989 T495 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:53964_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:53964"}
[junit4:junit4] 2> 7990 T495 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 7990 T495 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 7995 T500 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> 7995 T528 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> 7995 T494 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> 7995 T513 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> 8726 T529 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 8727 T529 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53964 collection:collection1 shard:shard1
[junit4:junit4] 2> 8730 T529 oasc.ZkController.register We are http://127.0.0.1:53964/collection1/ and leader is http://127.0.0.1:50239/collection1/
[junit4:junit4] 2> 8730 T529 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53964
[junit4:junit4] 2> 8731 T529 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 8731 T529 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C27 name=collection1 org.apache.solr.core.SolrCore@1a5f6fc url=http://127.0.0.1:53964/collection1 node=127.0.0.1:53964_ C27_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, state=down, core=collection1, collection=collection1, node_name=127.0.0.1:53964_, base_url=http://127.0.0.1:53964}
[junit4:junit4] 2> 8732 T531 C27 P53964 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 8733 T531 C27 P53964 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 8733 T529 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8733 T531 C27 P53964 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 8734 T531 C27 P53964 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 8734 T472 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4] 2> 8735 T531 C27 P53964 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 8735 T472 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 8736 T472 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 8740 T472 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C28 name=collection1 org.apache.solr.core.SolrCore@1b65ac5 url=http://127.0.0.1:45285/collection1 node=127.0.0.1:45285_ C28_STATE=coll:control_collection core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:45285_, base_url=http://127.0.0.1:45285, leader=true}
[junit4:junit4] 2> 8757 T490 C28 P45285 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/control/data
[junit4:junit4] 2> 8809 T490 C28 P45285 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@7504c1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16ca2e6)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 8810 T490 C28 P45285 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 8812 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10001 (1423770912925155328)]} 0 56
[junit4:junit4] 2> 8812 T487 C28 P45285 /update {wt=javabin&version=2} {add=[1 (1423770912925155329)]} 0 56
[junit4:junit4] 2> ASYNC NEW_CORE C29 name=collection1 org.apache.solr.core.SolrCore@15a8848 url=http://127.0.0.1:50239/collection1 node=127.0.0.1:50239_ C29_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:50239_, base_url=http://127.0.0.1:50239, leader=true}
[junit4:junit4] 2> 8830 T508 C29 P50239 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty1
[junit4:junit4] 2> 8882 T508 C29 P50239 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@14445ef lockFactory=org.apache.lucene.store.NativeFSLockFactory@a5ebfc)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 8883 T508 C29 P50239 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 8900 T507 C29 P50239 /update {wt=javabin&version=2} {add=[1 (1423770913001701376)]} 0 70
[junit4:junit4] 2> 8901 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10001 (1423770913000652800)]} 0 72
[junit4:junit4] 2> 8904 T488 C28 P45285 /update {wt=javabin&version=2} {add=[2 (1423770913078247424)]} 0 2
[junit4:junit4] 2> 8904 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10002 (1423770913078247425)]} 0 1
[junit4:junit4] 2> 8906 T509 C29 P50239 /update {wt=javabin&version=2} {add=[2 (1423770913081393152)]} 0 1
[junit4:junit4] 2> 8907 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10002 (1423770913081393153)]} 0 1
[junit4:junit4] 2> 8908 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[2 (-1423770913083490304)]} 0 0
[junit4:junit4] 2> 8909 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10002 (-1423770913083490305)]} 0 1
[junit4:junit4] 2> 8910 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[2 (-1423770913085587456)]} 0 0
[junit4:junit4] 2> 8911 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10002 (-1423770913085587457)]} 0 1
[junit4:junit4] 2> 8913 T488 C28 P45285 /update {wt=javabin&version=2} {add=[3 (1423770913087684608)]} 0 1
[junit4:junit4] 2> 8913 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10003 (1423770913088733184)]} 0 0
[junit4:junit4] 2> 8915 T507 C29 P50239 /update {wt=javabin&version=2} {add=[3 (1423770913090830336)]} 0 0
[junit4:junit4] 2> 8916 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10003 (1423770913090830337)]} 0 1
[junit4:junit4] 2> 8918 T487 C28 P45285 /update {wt=javabin&version=2} {add=[4 (1423770913092927488)]} 0 1
[junit4:junit4] 2> 8918 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10004 (1423770913093976064)]} 0 1
[junit4:junit4] 2> 8920 T509 C29 P50239 /update {wt=javabin&version=2} {add=[4 (1423770913095024640)]} 0 1
[junit4:junit4] 2> 8920 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10004 (1423770913096073216)]} 0 0
[junit4:junit4] 2> 8923 T488 C28 P45285 /update {wt=javabin&version=2} {add=[5 (1423770913098170368)]} 0 1
[junit4:junit4] 2> 8923 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10005 (1423770913098170369)]} 0 1
[junit4:junit4] 2> 8925 T508 C29 P50239 /update {wt=javabin&version=2} {add=[5 (1423770913100267520)]} 0 1
[junit4:junit4] 2> 8925 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10005 (1423770913101316096)]} 0 0
[junit4:junit4] 2> 8928 T487 C28 P45285 /update {wt=javabin&version=2} {add=[6 (1423770913103413248)]} 0 1
[junit4:junit4] 2> 8929 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10006 (1423770913104461824)]} 0 1
[junit4:junit4] 2> 8931 T507 C29 P50239 /update {wt=javabin&version=2} {add=[6 (1423770913106558976)]} 0 1
[junit4:junit4] 2> 8931 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10006 (1423770913107607552)]} 0 0
[junit4:junit4] 2> 8934 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10007 (1423770913109704705)]} 0 1
[junit4:junit4] 2> 8935 T488 C28 P45285 /update {wt=javabin&version=2} {add=[7 (1423770913109704704)]} 0 2
[junit4:junit4] 2> 8937 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10007 (1423770913112850432)]} 0 1
[junit4:junit4] 2> 8939 T507 C29 P50239 /update {wt=javabin&version=2} {add=[7 (1423770913113899008)]} 0 2
[junit4:junit4] 2> 8940 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10004 (-1423770913115996160)]} 0 1
[junit4:junit4] 2> 8941 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[4 (-1423770913118093312)]} 0 0
[junit4:junit4] 2> 8942 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10004 (-1423770913119141888)]} 0 0
[junit4:junit4] 2> 8943 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[4 (-1423770913120190464)]} 0 0
[junit4:junit4] 2> 8945 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10008 (1423770913121239040)]} 0 1
[junit4:junit4] 2> 8945 T488 C28 P45285 /update {wt=javabin&version=2} {add=[8 (1423770913122287616)]} 0 0
[junit4:junit4] 2> 8948 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10008 (1423770913124384768)]} 0 1
[junit4:junit4] 2> 8948 T508 C29 P50239 /update {wt=javabin&version=2} {add=[8 (1423770913125433344)]} 0 0
[junit4:junit4] 2> 8950 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10005 (-1423770913127530496)]} 0 0
[junit4:junit4] 2> 8950 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[5 (-1423770913127530497)]} 0 0
[junit4:junit4] 2> 8952 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10005 (-1423770913129627648)]} 0 0
[junit4:junit4] 2> 8952 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[5 (-1423770913129627649)]} 0 0
[junit4:junit4] 2> 8955 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10009 (1423770913131724800)]} 0 1
[junit4:junit4] 2> 8955 T488 C28 P45285 /update {wt=javabin&version=2} {add=[9 (1423770913131724801)]} 0 1
[junit4:junit4] 2> 8967 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10009 (1423770913144307712)]} 0 1
[junit4:junit4] 2> 8967 T509 C29 P50239 /update {wt=javabin&version=2} {add=[9 (1423770913145356288)]} 0 0
[junit4:junit4] 2> 8970 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10010 (1423770913147453440)]} 0 1
[junit4:junit4] 2> 8971 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10 (1423770913148502016)]} 0 1
[junit4:junit4] 2> 8973 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10010 (1423770913150599168)]} 0 1
[junit4:junit4] 2> 8974 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10 (1423770913151647744)]} 0 1
[junit4:junit4] 2> 8976 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10006 (-1423770913153744896)]} 0 1
[junit4:junit4] 2> 8976 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[6 (-1423770913154793472)]} 0 0
[junit4:junit4] 2> 8977 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10006 (-1423770913155842048)]} 0 0
[junit4:junit4] 2> 8978 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[6 (-1423770913156890624)]} 0 0
[junit4:junit4] 2> 8980 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10011 (1423770913157939200)]} 0 1
[junit4:junit4] 2> 8981 T490 C28 P45285 /update {wt=javabin&version=2} {add=[11 (1423770913158987776)]} 0 1
[junit4:junit4] 2> 8982 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10011 (1423770913161084928)]} 0 0
[junit4:junit4] 2> 8983 T509 C29 P50239 /update {wt=javabin&version=2} {add=[11 (1423770913162133504)]} 0 0
[junit4:junit4] 2> 8985 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10012 (1423770913164230656)]} 0 0
[junit4:junit4] 2> 8986 T488 C28 P45285 /update {wt=javabin&version=2} {add=[12 (1423770913165279232)]} 0 0
[junit4:junit4] 2> 8988 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10012 (1423770913166327808)]} 0 1
[junit4:junit4] 2> 8988 T508 C29 P50239 /update {wt=javabin&version=2} {add=[12 (1423770913167376384)]} 0 0
[junit4:junit4] 2> 8990 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10009 (-1423770913168424960)]} 0 1
[junit4:junit4] 2> 8990 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[9 (-1423770913169473536)]} 0 0
[junit4:junit4] 2> 8991 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10009 (-1423770913170522112)]} 0 0
[junit4:junit4] 2> 8992 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[9 (-1423770913171570688)]} 0 0
[junit4:junit4] 2> 8995 T488 C28 P45285 /update {wt=javabin&version=2} {add=[13 (1423770913173667840)]} 0 1
[junit4:junit4] 2> 8996 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10013 (1423770913172619264)]} 0 3
[junit4:junit4] 2> 8999 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10013 (1423770913177862145)]} 0 1
[junit4:junit4] 2> 9001 T508 C29 P50239 /update {wt=javabin&version=2} {add=[13 (1423770913177862144)]} 0 3
[junit4:junit4] 2> 9001 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10011 (-1423770913181007872)]} 0 0
[junit4:junit4] 2> 9003 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[11 (-1423770913183105024)]} 0 1
[junit4:junit4] 2> 9003 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10011 (-1423770913183105024)]} 0 0
[junit4:junit4] 2> 9005 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[11 (-1423770913185202176)]} 0 1
[junit4:junit4] 2> 9006 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10014 (1423770913185202176)]} 0 1
[junit4:junit4] 2> 9007 T489 C28 P45285 /update {wt=javabin&version=2} {add=[14 (1423770913186250752)]} 0 1
[junit4:junit4] 2> 9008 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10014 (1423770913188347904)]} 0 0
[junit4:junit4] 2> 9009 T508 C29 P50239 /update {wt=javabin&version=2} {add=[14 (1423770913189396480)]} 0 0
[junit4:junit4] 2> 9012 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10015 (1423770913191493632)]} 0 1
[junit4:junit4] 2> 9012 T490 C28 P45285 /update {wt=javabin&version=2} {add=[15 (1423770913191493633)]} 0 1
[junit4:junit4] 2> 9015 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10015 (1423770913194639360)]} 0 1
[junit4:junit4] 2> 9015 T507 C29 P50239 /update {wt=javabin&version=2} {add=[15 (1423770913194639361)]} 0 1
[junit4:junit4] 2> 9018 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10016 (1423770913197785088)]} 0 1
[junit4:junit4] 2> 9018 T489 C28 P45285 /update {wt=javabin&version=2} {add=[16 (1423770913197785089)]} 0 1
[junit4:junit4] 2> 9021 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10016 (1423770913200930816)]} 0 1
[junit4:junit4] 2> 9022 T509 C29 P50239 /update {wt=javabin&version=2} {add=[16 (1423770913201979392)]} 0 1
[junit4:junit4] 2> 9023 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10013 (-1423770913204076544)]} 0 0
[junit4:junit4] 2> 9024 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[13 (-1423770913205125120)]} 0 0
[junit4:junit4] 2> 9026 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10013 (-1423770913207222272)]} 0 1
[junit4:junit4] 2> 9027 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[13 (-1423770913207222273)]} 0 1
[junit4:junit4] 2> 9029 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10017 (1423770913209319424)]} 0 1
[junit4:junit4] 2> 9029 T489 C28 P45285 /update {wt=javabin&version=2} {add=[17 (1423770913210368000)]} 0 0
[junit4:junit4] 2> 9040 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10017 (1423770913219805184)]} 0 2
[junit4:junit4] 2> 9041 T507 C29 P50239 /update {wt=javabin&version=2} {add=[17 (1423770913221902336)]} 0 1
[junit4:junit4] 2> 9042 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10017 (-1423770913223999488)]} 0 0
[junit4:junit4] 2> 9044 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[17 (-1423770913225048064)]} 0 1
[junit4:junit4] 2> 9044 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10017 (-1423770913226096640)]} 0 0
[junit4:junit4] 2> 9045 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[17 (-1423770913227145216)]} 0 0
[junit4:junit4] 2> 9047 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10018 (1423770913228193792)]} 0 1
[junit4:junit4] 2> 9049 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10018 (1423770913230290944)]} 0 1
[junit4:junit4] 2> 9049 T489 C28 P45285 /update {wt=javabin&version=2} {add=[18 (1423770913229242368)]} 0 2
[junit4:junit4] 2> 9051 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10018 (-1423770913233436672)]} 0 0
[junit4:junit4] 2> 9051 T508 C29 P50239 /update {wt=javabin&version=2} {add=[18 (1423770913233436672)]} 0 0
[junit4:junit4] 2> 9053 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10018 (-1423770913234485248)]} 0 1
[junit4:junit4] 2> 9053 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[18 (-1423770913235533824)]} 0 0
[junit4:junit4] 2> 9055 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[18 (-1423770913237630976)]} 0 0
[junit4:junit4] 2> 9055 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10019 (1423770913236582400)]} 0 1
[junit4:junit4] 2> 9057 T489 C28 P45285 /update {wt=javabin&version=2} {add=[19 (1423770913239728128)]} 0 0
[junit4:junit4] 2> 9057 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10019 (1423770913239728128)]} 0 0
[junit4:junit4] 2> 9059 T509 C29 P50239 /update {wt=javabin&version=2} {add=[19 (1423770913241825280)]} 0 0
[junit4:junit4] 2> 9060 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10020 (1423770913241825280)]} 0 1
[junit4:junit4] 2> 9062 T490 C28 P45285 /update {wt=javabin&version=2} {add=[20 (1423770913243922432)]} 0 1
[junit4:junit4] 2> 9062 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10020 (1423770913244971008)]} 0 1
[junit4:junit4] 2> 9064 T508 C29 P50239 /update {wt=javabin&version=2} {add=[20 (1423770913246019584)]} 0 1
[junit4:junit4] 2> 9064 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10020 (-1423770913247068160)]} 0 0
[junit4:junit4] 2> 9066 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[20 (-1423770913249165312)]} 0 0
[junit4:junit4] 2> 9067 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10020 (-1423770913250213888)]} 0 0
[junit4:junit4] 2> 9068 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[20 (-1423770913251262464)]} 0 0
[junit4:junit4] 2> 9070 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10021 (1423770913253359617)]} 0 0
[junit4:junit4] 2> 9070 T487 C28 P45285 /update {wt=javabin&version=2} {add=[21 (1423770913253359616)]} 0 0
[junit4:junit4] 2> 9072 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10021 (1423770913255456768)]} 0 0
[junit4:junit4] 2> 9072 T509 C29 P50239 /update {wt=javabin&version=2} {add=[21 (1423770913255456769)]} 0 0
[junit4:junit4] 2> 9075 T488 C28 P45285 /update {wt=javabin&version=2} {add=[22 (1423770913257553920)]} 0 1
[junit4:junit4] 2> 9075 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10022 (1423770913257553921)]} 0 1
[junit4:junit4] 2> 9077 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10022 (1423770913260699649)]} 0 0
[junit4:junit4] 2> 9077 T507 C29 P50239 /update {wt=javabin&version=2} {add=[22 (1423770913260699648)]} 0 0
[junit4:junit4] 2> 9079 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10021 (-1423770913262796800)]} 0 0
[junit4:junit4] 2> 9079 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[21 (-1423770913262796801)]} 0 0
[junit4:junit4] 2> 9081 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10021 (-1423770913264893952)]} 0 0
[junit4:junit4] 2> 9081 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[21 (-1423770913264893953)]} 0 0
[junit4:junit4] 2> 9083 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10023 (1423770913266991104)]} 0 0
[junit4:junit4] 2> 9084 T489 C28 P45285 /update {wt=javabin&version=2} {add=[23 (1423770913266991105)]} 0 1
[junit4:junit4] 2> 9086 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10023 (1423770913270136832)]} 0 0
[junit4:junit4] 2> 9087 T509 C29 P50239 /update {wt=javabin&version=2} {add=[23 (1423770913270136833)]} 0 1
[junit4:junit4] 2> 9088 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10023 (-1423770913272233984)]} 0 0
[junit4:junit4] 2> 9089 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[23 (-1423770913273282560)]} 0 0
[junit4:junit4] 2> 9091 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10023 (-1423770913275379712)]} 0 0
[junit4:junit4] 2> 9092 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[23 (-1423770913275379713)]} 0 0
[junit4:junit4] 2> 9094 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10024 (1423770913277476864)]} 0 1
[junit4:junit4] 2> 9102 T490 C28 P45285 /update {wt=javabin&version=2} {add=[24 (1423770913278525440)]} 0 8
[junit4:junit4] 2> 9103 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10024 (1423770913286914048)]} 0 1
[junit4:junit4] 2> 9105 T508 C29 P50239 /update {wt=javabin&version=2} {add=[24 (1423770913289011200)]} 0 1
[junit4:junit4] 2> 9105 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10025 (1423770913290059776)]} 0 0
[junit4:junit4] 2> 9107 T487 C28 P45285 /update {wt=javabin&version=2} {add=[25 (1423770913292156928)]} 0 1
[junit4:junit4] 2> 9108 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10025 (1423770913292156928)]} 0 1
[junit4:junit4] 2> 9109 T509 C29 P50239 /update {wt=javabin&version=2} {add=[25 (1423770913294254080)]} 0 0
[junit4:junit4] 2> 9110 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10026 (1423770913295302656)]} 0 0
[junit4:junit4] 2> 9112 T488 C28 P45285 /update {wt=javabin&version=2} {add=[26 (1423770913296351232)]} 0 1
[junit4:junit4] 2> 9113 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10026 (1423770913297399808)]} 0 1
[junit4:junit4] 2> 9114 T507 C29 P50239 /update {wt=javabin&version=2} {add=[26 (1423770913298448384)]} 0 1
[junit4:junit4] 2> 9115 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10025 (-1423770913300545536)]} 0 0
[junit4:junit4] 2> 9116 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[25 (-1423770913301594112)]} 0 0
[junit4:junit4] 2> 9118 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10025 (-1423770913303691264)]} 0 1
[junit4:junit4] 2> 9118 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[25 (-1423770913303691265)]} 0 0
[junit4:junit4] 2> 9120 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10027 (1423770913305788416)]} 0 0
[junit4:junit4] 2> 9121 T488 C28 P45285 /update {wt=javabin&version=2} {add=[27 (1423770913305788417)]} 0 1
[junit4:junit4] 2> 9123 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10027 (1423770913307885568)]} 0 1
[junit4:junit4] 2> 9123 T509 C29 P50239 /update {wt=javabin&version=2} {add=[27 (1423770913307885569)]} 0 1
[junit4:junit4] 2> 9125 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10028 (1423770913309982720)]} 0 1
[junit4:junit4] 2> 9126 T487 C28 P45285 /update {wt=javabin&version=2} {add=[28 (1423770913311031296)]} 0 1
[junit4:junit4] 2> 9129 T507 C29 P50239 /update {wt=javabin&version=2} {add=[28 (1423770913314177024)]} 0 1
[junit4:junit4] 2> 9130 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10028 (1423770913313128448)]} 0 3
[junit4:junit4] 2> 9131 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[27 (-1423770913317322752)]} 0 0
[junit4:junit4] 2> 9132 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10027 (-1423770913318371328)]} 0 0
[junit4:junit4] 2> 9133 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[27 (-1423770913319419904)]} 0 0
[junit4:junit4] 2> 9134 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10027 (-1423770913320468480)]} 0 0
[junit4:junit4] 2> 9136 T489 C28 P45285 /update {wt=javabin&version=2} {add=[29 (1423770913321517056)]} 0 1
[junit4:junit4] 2> 9137 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10029 (1423770913322565632)]} 0 1
[junit4:junit4] 2> 9138 T508 C29 P50239 /update {wt=javabin&version=2} {add=[29 (1423770913324662784)]} 0 0
[junit4:junit4] 2> 9139 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10029 (1423770913325711360)]} 0 0
[junit4:junit4] 2> 9140 T490 C28 P45285 /update {wt=javabin&version=2} {add=[30 (1423770913326759936)]} 0 0
[junit4:junit4] 2> 9142 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10030 (1423770913328857088)]} 0 1
[junit4:junit4] 2> 9143 T507 C29 P50239 /update {wt=javabin&version=2} {add=[30 (1423770913328857088)]} 0 1
[junit4:junit4] 2> 9147 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10030 (1423770913333051392)]} 0 1
[junit4:junit4] 2> 9147 T489 C28 P45285 /update {wt=javabin&version=2} {add=[31 (1423770913332002816)]} 0 2
[junit4:junit4] 2> 9149 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10031 (1423770913336197120)]} 0 0
[junit4:junit4] 2> 9149 T509 C29 P50239 /update {wt=javabin&version=2} {add=[31 (1423770913336197120)]} 0 0
[junit4:junit4] 2> 9159 T490 C28 P45285 /update {wt=javabin&version=2} {add=[32 (1423770913346682880)]} 0 8
[junit4:junit4] 2> 9159 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10031 (1423770913338294272)]} 0 8
[junit4:junit4] 2> 9162 T508 C29 P50239 /update {wt=javabin&version=2} {add=[32 (1423770913348780032)]} 0 1
[junit4:junit4] 2> 9163 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10032 (1423770913349828608)]} 0 1
[junit4:junit4] 2> 9165 T489 C28 P45285 /update {wt=javabin&version=2} {add=[33 (1423770913351925760)]} 0 1
[junit4:junit4] 2> 9167 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10032 (1423770913352974336)]} 0 2
[junit4:junit4] 2> 9167 T507 C29 P50239 /update {wt=javabin&version=2} {add=[33 (1423770913355071488)]} 0 0
[junit4:junit4] 2> 9169 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10033 (1423770913357168640)]} 0 0
[junit4:junit4] 2> 9170 T490 C28 P45285 /update {wt=javabin&version=2} {add=[34 (1423770913357168641)]} 0 1
[junit4:junit4] 2> 9172 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10033 (1423770913360314368)]} 0 1
[junit4:junit4] 2> 9172 T509 C29 P50239 /update {wt=javabin&version=2} {add=[34 (1423770913360314369)]} 0 0
[junit4:junit4] 2> 9175 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[31 (-1423770913363460096)]} 0 1
[junit4:junit4] 2> 9175 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10034 (1423770913362411520)]} 0 1
[junit4:junit4] 2> 9177 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[31 (-1423770913365557248)]} 0 0
[junit4:junit4] 2> 9177 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10034 (1423770913365557249)]} 0 0
[junit4:junit4] 2> 9179 T487 C28 P45285 /update {wt=javabin&version=2} {add=[35 (1423770913367654400)]} 0 0
[junit4:junit4] 2> 9179 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10031 (-1423770913367654401)]} 0 0
[junit4:junit4] 2> 9182 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10031 (-1423770913370800128)]} 0 1
[junit4:junit4] 2> 9182 T509 C29 P50239 /update {wt=javabin&version=2} {add=[35 (1423770913369751552)]} 0 1
[junit4:junit4] 2> 9184 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[32 (-1423770913372897281)]} 0 0
[junit4:junit4] 2> 9185 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10035 (1423770913372897280)]} 0 1
[junit4:junit4] 2> 9186 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[32 (-1423770913374994432)]} 0 0
[junit4:junit4] 2> 9187 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10035 (1423770913376043008)]} 0 0
[junit4:junit4] 2> 9189 T487 C28 P45285 /update {wt=javabin&version=2} {add=[36 (1423770913377091584)]} 0 1
[junit4:junit4] 2> 9189 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10032 (-1423770913378140160)]} 0 0
[junit4:junit4] 2> 9191 T509 C29 P50239 /update {wt=javabin&version=2} {add=[36 (1423770913379188736)]} 0 1
[junit4:junit4] 2> 9191 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10032 (-1423770913380237312)]} 0 0
[junit4:junit4] 2> 9193 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[36 (-1423770913382334464)]} 0 0
[junit4:junit4] 2> 9193 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10036 (1423770913382334465)]} 0 0
[junit4:junit4] 2> 9197 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10036 (1423770913385480192)]} 0 1
[junit4:junit4] 2> 9195 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[36 (-1423770913384431616)]} 0 1
[junit4:junit4] 2> 9199 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10036 (-1423770913388625920)]} 0 0
[junit4:junit4] 2> 9201 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10036 (-1423770913390723072)]} 0 0
[junit4:junit4] 2> 9202 T490 C28 P45285 /update {wt=javabin&version=2} {add=[37 (1423770913390723072)]} 0 1
[junit4:junit4] 2> 9204 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10037 (1423770913393868800)]} 0 1
[junit4:junit4] 2> 9204 T509 C29 P50239 /update {wt=javabin&version=2} {add=[37 (1423770913393868800)]} 0 0
[junit4:junit4] 2> 9206 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[37 (-1423770913395965952)]} 0 0
[junit4:junit4] 2> 9206 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10037 (1423770913395965952)]} 0 0
[junit4:junit4] 2> 9208 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[37 (-1423770913398063104)]} 0 0
[junit4:junit4] 2> 9209 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10037 (-1423770913399111680)]} 0 0
[junit4:junit4] 2> 9211 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10037 (-1423770913401208832)]} 0 0
[junit4:junit4] 2> 9212 T490 C28 P45285 /update {wt=javabin&version=2} {add=[38 (1423770913401208832)]} 0 2
[junit4:junit4] 2> 9214 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10038 (1423770913403305984)]} 0 1
[junit4:junit4] 2> 9215 T507 C29 P50239 /update {wt=javabin&version=2} {add=[38 (1423770913404354560)]} 0 1
[junit4:junit4] 2> 9217 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10038 (1423770913407500288)]} 0 0
[junit4:junit4] 2> 9218 T489 C28 P45285 /update {wt=javabin&version=2} {add=[39 (1423770913407500288)]} 0 1
[junit4:junit4] 2> 9221 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10039 (1423770913410646016)]} 0 1
[junit4:junit4] 2> 9230 T507 C29 P50239 /update {wt=javabin&version=2} {add=[39 (1423770913410646016)]} 0 10
[junit4:junit4] 2> 9232 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10039 (1423770913422180352)]} 0 1
[junit4:junit4] 2> 9233 T490 C28 P45285 /update {wt=javabin&version=2} {add=[40 (1423770913423228928)]} 0 1
[junit4:junit4] 2> 9235 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10040 (1423770913425326080)]} 0 1
[junit4:junit4] 2> 9235 T508 C29 P50239 /update {wt=javabin&version=2} {add=[40 (1423770913426374656)]} 0 1
[junit4:junit4] 2> 9238 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10040 (1423770913428471808)]} 0 1
[junit4:junit4] 2> 9238 T489 C28 P45285 /update {wt=javabin&version=2} {add=[41 (1423770913429520384)]} 0 1
[junit4:junit4] 2> 9241 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10041 (1423770913431617536)]} 0 1
[junit4:junit4] 2> 9241 T509 C29 P50239 /update {wt=javabin&version=2} {add=[41 (1423770913432666112)]} 0 1
[junit4:junit4] 2> 9244 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[39 (-1423770913435811840)]} 0 0
[junit4:junit4] 2> 9245 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10041 (1423770913435811840)]} 0 1
[junit4:junit4] 2> 9247 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[39 (-1423770913437908992)]} 0 1
[junit4:junit4] 2> 9247 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10039 (-1423770913438957568)]} 0 0
[junit4:junit4] 2> 9249 T489 C28 P45285 /update {wt=javabin&version=2} {add=[42 (1423770913441054720)]} 0 0
[junit4:junit4] 2> 9249 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10039 (-1423770913441054720)]} 0 0
[junit4:junit4] 2> 9252 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10042 (1423770913444200448)]} 0 0
[junit4:junit4] 2> 9254 T508 C29 P50239 /update {wt=javabin&version=2} {add=[42 (1423770913444200448)]} 0 2
[junit4:junit4] 2> 9255 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10042 (1423770913447346176)]} 0 0
[junit4:junit4] 2> 9257 T490 C28 P45285 /update {wt=javabin&version=2} {add=[43 (1423770913449443328)]} 0 1
[junit4:junit4] 2> 9258 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10043 (1423770913450491904)]} 0 0
[junit4:junit4] 2> 9262 T509 C29 P50239 /update {wt=javabin&version=2} {add=[43 (1423770913453637632)]} 0 1
[junit4:junit4] 2> 9264 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10043 (1423770913455734784)]} 0 1
[junit4:junit4] 2> 9267 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[42 (-1423770913459929088)]} 0 0
[junit4:junit4] 2> 9268 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10042 (-1423770913460977664)]} 0 1
[junit4:junit4] 2> 9270 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[42 (-1423770913463074816)]} 0 0
[junit4:junit4] 2> 9270 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10042 (-1423770913463074817)]} 0 0
[junit4:junit4] 2> 9273 T490 C28 P45285 /update {wt=javabin&version=2} {add=[44 (1423770913465171968)]} 0 1
[junit4:junit4] 2> 9275 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10044 (1423770913466220544)]} 0 2
[junit4:junit4] 2> 9276 T508 C29 P50239 /update {wt=javabin&version=2} {add=[44 (1423770913469366272)]} 0 0
[junit4:junit4] 2> 9285 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10044 (1423770913477754880)]} 0 1
[junit4:junit4] 2> 9286 T489 C28 P45285 /update {wt=javabin&version=2} {add=[45 (1423770913477754880)]} 0 2
[junit4:junit4] 2> 9288 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10045 (1423770913480900608)]} 0 1
[junit4:junit4] 2> 9289 T509 C29 P50239 /update {wt=javabin&version=2} {add=[45 (1423770913481949184)]} 0 1
[junit4:junit4] 2> 9290 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10045 (1423770913482997760)]} 0 1
[junit4:junit4] 2> 9291 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[43 (-1423770913484046336)]} 0 1
[junit4:junit4] 2> 9292 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10043 (-1423770913486143488)]} 0 0
[junit4:junit4] 2> 9292 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[43 (-1423770913486143488)]} 0 0
[junit4:junit4] 2> 9294 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10043 (-1423770913488240640)]} 0 0
[junit4:junit4] 2> 9294 T489 C28 P45285 /update {wt=javabin&version=2} {add=[46 (1423770913488240640)]} 0 0
[junit4:junit4] 2> 9297 T508 C29 P50239 /update {wt=javabin&version=2} {add=[46 (1423770913490337792)]} 0 1
[junit4:junit4] 2> 9297 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10046 (1423770913490337792)]} 0 1
[junit4:junit4] 2> 9299 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[46 (-1423770913493483520)]} 0 0
[junit4:junit4] 2> 9299 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10046 (1423770913493483520)]} 0 0
[junit4:junit4] 2> 9301 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[46 (-1423770913495580672)]} 0 0
[junit4:junit4] 2> 9301 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10046 (-1423770913495580672)]} 0 0
[junit4:junit4] 2> 9303 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10046 (-1423770913497677824)]} 0 0
[junit4:junit4] 2> 9303 T489 C28 P45285 /update {wt=javabin&version=2} {add=[47 (1423770913497677824)]} 0 0
[junit4:junit4] 2> 9317 T507 C29 P50239 /update {wt=javabin&version=2} {add=[47 (1423770913511309312)]} 0 1
[junit4:junit4] 2> 9318 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10047 (1423770913513406464)]} 0 0
[junit4:junit4] 2> 9319 T490 C28 P45285 /update {wt=javabin&version=2} {add=[48 (1423770913514455040)]} 0 0
[junit4:junit4] 2> 9321 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10047 (1423770913515503616)]} 0 1
[junit4:junit4] 2> 9321 T508 C29 P50239 /update {wt=javabin&version=2} {add=[48 (1423770913516552192)]} 0 0
[junit4:junit4] 2> 9323 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[47 (-1423770913518649345)]} 0 0
[junit4:junit4] 2> 9323 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10048 (1423770913518649344)]} 0 0
[junit4:junit4] 2> 9326 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[47 (-1423770913520746496)]} 0 1
[junit4:junit4] 2> 9326 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10048 (1423770913520746497)]} 0 1
[junit4:junit4] 2> 9328 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10047 (-1423770913523892225)]} 0 0
[junit4:junit4] 2> 9328 T487 C28 P45285 /update {wt=javabin&version=2} {add=[49 (1423770913523892224)]} 0 0
[junit4:junit4] 2> 9330 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10047 (-1423770913525989376)]} 0 0
[junit4:junit4] 2> 9330 T507 C29 P50239 /update {wt=javabin&version=2} {add=[49 (1423770913525989377)]} 0 0
[junit4:junit4] 2> 9332 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10049 (1423770913528086528)]} 0 0
[junit4:junit4] 2> 9332 T488 C28 P45285 /update {wt=javabin&version=2} {add=[50 (1423770913528086529)]} 0 0
[junit4:junit4] 2> 9335 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10049 (1423770913530183680)]} 0 1
[junit4:junit4] 2> 9335 T508 C29 P50239 /update {wt=javabin&version=2} {add=[50 (1423770913530183681)]} 0 1
[junit4:junit4] 2> 9337 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10050 (1423770913533329408)]} 0 0
[junit4:junit4] 2> 9337 T487 C28 P45285 /update {wt=javabin&version=2} {add=[51 (1423770913533329409)]} 0 0
[junit4:junit4] 2> 9339 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10050 (1423770913535426560)]} 0 0
[junit4:junit4] 2> 9339 T509 C29 P50239 /update {wt=javabin&version=2} {add=[51 (1423770913535426561)]} 0 0
[junit4:junit4] 2> 9342 T489 C28 P45285 /update {wt=javabin&version=2} {add=[52 (1423770913537523712)]} 0 1
[junit4:junit4] 2> 9343 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10051 (1423770913538572288)]} 0 2
[junit4:junit4] 2> 9344 T508 C29 P50239 /update {wt=javabin&version=2} {add=[52 (1423770913540669440)]} 0 0
[junit4:junit4] 2> 9346 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10051 (1423770913541718016)]} 0 1
[junit4:junit4] 2> 9347 T490 C28 P45285 /update {wt=javabin&version=2} {add=[53 (1423770913542766592)]} 0 1
[junit4:junit4] 2> 9349 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10052 (1423770913545912320)]} 0 0
[junit4:junit4] 2> 9350 T509 C29 P50239 /update {wt=javabin&version=2} {add=[53 (1423770913545912320)]} 0 1
[junit4:junit4] 2> 9352 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10052 (1423770913548009472)]} 0 1
[junit4:junit4] 2> 9352 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[52 (-1423770913549058048)]} 0 0
[junit4:junit4] 2> 9354 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[52 (-1423770913551155200)]} 0 0
[junit4:junit4] 2> 9354 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10053 (1423770913551155200)]} 0 0
[junit4:junit4] 2> 9356 T490 C28 P45285 /update {wt=javabin&version=2} {add=[54 (1423770913553252352)]} 0 0
[junit4:junit4] 2> 9357 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10053 (1423770913554300928)]} 0 0
[junit4:junit4] 2> 9358 T508 C29 P50239 /update {wt=javabin&version=2} {add=[54 (1423770913555349504)]} 0 0
[junit4:junit4] 2> 9359 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10052 (-1423770913556398080)]} 0 0
[junit4:junit4] 2> 9360 T489 C28 P45285 /update {wt=javabin&version=2} {add=[55 (1423770913557446656)]} 0 0
[junit4:junit4] 2> 9361 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10052 (-1423770913558495232)]} 0 0
[junit4:junit4] 2> 9363 T509 C29 P50239 /update {wt=javabin&version=2} {add=[55 (1423770913559543808)]} 0 1
[junit4:junit4] 2> 9363 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10054 (1423770913560592384)]} 0 0
[junit4:junit4] 2> 9366 T490 C28 P45285 /update {wt=javabin&version=2} {add=[56 (1423770913562689536)]} 0 1
[junit4:junit4] 2> 9374 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10054 (1423770913562689536)]} 0 9
[junit4:junit4] 2> 9376 T507 C29 P50239 /update {wt=javabin&version=2} {add=[56 (1423770913573175296)]} 0 1
[junit4:junit4] 2> 9377 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10055 (1423770913574223872)]} 0 1
[junit4:junit4] 2> 9378 T489 C28 P45285 /update {wt=javabin&version=2} {add=[57 (1423770913576321024)]} 0 0
[junit4:junit4] 2> 9380 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10055 (1423770913577369600)]} 0 1
[junit4:junit4] 2> 9382 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10056 (1423770913580515328)]} 0 0
[junit4:junit4] 2> 9385 T508 C29 P50239 /update {wt=javabin&version=2} {add=[57 (1423770913579466752)]} 0 5
[junit4:junit4] 2> 9385 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10056 (1423770913583661056)]} 0 0
[junit4:junit4] 2> 9387 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[53 (-1423770913584709632)]} 0 1
[junit4:junit4] 2> 9387 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10057 (1423770913585758208)]} 0 0
[junit4:junit4] 2> 9388 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[53 (-1423770913586806784)]} 0 0
[junit4:junit4] 2> 9390 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10057 (1423770913587855360)]} 0 1
[junit4:junit4] 2> 9391 T489 C28 P45285 /update {wt=javabin&version=2} {add=[58 (1423770913588903936)]} 0 1
[junit4:junit4] 2> 9392 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10053 (-1423770913591001088)]} 0 0
[junit4:junit4] 2> 9393 T507 C29 P50239 /update {wt=javabin&version=2} {add=[58 (1423770913592049664)]} 0 1
[junit4:junit4] 2> 9394 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10053 (-1423770913592049665)]} 0 1
[junit4:junit4] 2> 9395 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[54 (-1423770913594146816)]} 0 0
[junit4:junit4] 2> 9396 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10058 (1423770913594146817)]} 0 1
[junit4:junit4] 2> 9397 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[54 (-1423770913596243968)]} 0 1
[junit4:junit4] 2> 9398 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10058 (1423770913597292544)]} 0 0
[junit4:junit4] 2> 9400 T489 C28 P45285 /update {wt=javabin&version=2} {add=[59 (1423770913597292544)]} 0 2
[junit4:junit4] 2> 9400 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10054 (-1423770913599389696)]} 0 0
[junit4:junit4] 2> 9402 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10054 (-1423770913601486849)]} 0 0
[junit4:junit4] 2> 9402 T509 C29 P50239 /update {wt=javabin&version=2} {add=[59 (1423770913601486848)]} 0 0
[junit4:junit4] 2> 9405 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10059 (1423770913603584000)]} 0 1
[junit4:junit4] 2> 9405 T487 C28 P45285 /update {wt=javabin&version=2} {add=[60 (1423770913604632576)]} 0 1
[junit4:junit4] 2> 9407 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10059 (1423770913606729728)]} 0 0
[junit4:junit4] 2> 9408 T508 C29 P50239 /update {wt=javabin&version=2} {add=[60 (1423770913606729729)]} 0 1
[junit4:junit4] 2> 9409 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10060 (1423770913608826880)]} 0 0
[junit4:junit4] 2> 9410 T488 C28 P45285 /update {wt=javabin&version=2} {add=[61 (1423770913609875456)]} 0 0
[junit4:junit4] 2> 9412 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10060 (1423770913610924032)]} 0 1
[junit4:junit4] 2> 9412 T507 C29 P50239 /update {wt=javabin&version=2} {add=[61 (1423770913611972608)]} 0 0
[junit4:junit4] 2> 9414 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10061 (1423770913613021184)]} 0 1
[junit4:junit4] 2> 9414 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[56 (-1423770913614069760)]} 0 0
[junit4:junit4] 2> 9416 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10061 (1423770913616166912)]} 0 1
[junit4:junit4] 2> 9417 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[56 (-1423770913617215488)]} 0 0
[junit4:junit4] 2> 9418 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10056 (-1423770913618264064)]} 0 0
[junit4:junit4] 2> 9420 T488 C28 P45285 /update {wt=javabin&version=2} {add=[62 (1423770913619312640)]} 0 1
[junit4:junit4] 2> 9421 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10056 (-1423770913621409792)]} 0 0
[junit4:junit4] 2> 9423 T508 C29 P50239 /update {wt=javabin&version=2} {add=[62 (1423770913622458368)]} 0 1
[junit4:junit4] 2> 9424 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10062 (1423770913623506944)]} 0 1
[junit4:junit4] 2> 9426 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10062 (1423770913626652672)]} 0 1
[junit4:junit4] 2> 9426 T487 C28 P45285 /update {wt=javabin&version=2} {add=[63 (1423770913625604096)]} 0 1
[junit4:junit4] 2> 9428 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10063 (1423770913628749824)]} 0 0
[junit4:junit4] 2> 9436 T507 C29 P50239 /update {wt=javabin&version=2} {add=[63 (1423770913628749824)]} 0 8
[junit4:junit4] 2> 9437 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10063 (1423770913638187008)]} 0 0
[junit4:junit4] 2> 9439 T488 C28 P45285 /update {wt=javabin&version=2} {add=[64 (1423770913639235584)]} 0 1
[junit4:junit4] 2> 9439 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10064 (1423770913640284160)]} 0 0
[junit4:junit4] 2> 9441 T509 C29 P50239 /update {wt=javabin&version=2} {add=[64 (1423770913642381312)]} 0 0
[junit4:junit4] 2> 9442 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10064 (1423770913642381313)]} 0 1
[junit4:junit4] 2> 9443 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[57 (-1423770913644478464)]} 0 0
[junit4:junit4] 2> 9444 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10057 (-1423770913645527040)]} 0 0
[junit4:junit4] 2> 9446 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[57 (-1423770913647624192)]} 0 0
[junit4:junit4] 2> 9446 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10057 (-1423770913647624193)]} 0 0
[junit4:junit4] 2> 9449 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10065 (1423770913649721345)]} 0 1
[junit4:junit4] 2> 9449 T488 C28 P45285 /update {wt=javabin&version=2} {add=[65 (1423770913649721344)]} 0 1
[junit4:junit4] 2> 9452 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10065 (1423770913652867072)]} 0 1
[junit4:junit4] 2> 9452 T508 C29 P50239 /update {wt=javabin&version=2} {add=[65 (1423770913652867073)]} 0 1
[junit4:junit4] 2> 9454 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10058 (-1423770913656012800)]} 0 0
[junit4:junit4] 2> 9454 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[58 (-1423770913656012801)]} 0 0
[junit4:junit4] 2> 9457 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10058 (-1423770913659158528)]} 0 1
[junit4:junit4] 2> 9457 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[58 (-1423770913659158529)]} 0 0
[junit4:junit4] 2> 9459 T490 C28 P45285 /update {wt=javabin&version=2} {add=[66 (1423770913661255680)]} 0 0
[junit4:junit4] 2> 9461 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10066 (1423770913662304256)]} 0 0
[junit4:junit4] 2> 9462 T508 C29 P50239 /update {wt=javabin&version=2} {add=[66 (1423770913663352832)]} 0 1
[junit4:junit4] 2> 9463 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10066 (1423770913665449984)]} 0 0
[junit4:junit4] 2> 9465 T487 C28 P45285 /update {wt=javabin&version=2} {add=[67 (1423770913666498560)]} 0 1
[junit4:junit4] 2> 9466 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10067 (1423770913667547136)]} 0 1
[junit4:junit4] 2> 9467 T507 C29 P50239 /update {wt=javabin&version=2} {add=[67 (1423770913668595712)]} 0 1
[junit4:junit4] 2> 9468 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10067 (1423770913669644288)]} 0 1
[junit4:junit4] 2> 9469 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[63 (-1423770913671741440)]} 0 1
[junit4:junit4] 2> 9470 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10063 (-1423770913671741441)]} 0 1
[junit4:junit4] 2> 9470 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[63 (-1423770913672790016)]} 0 0
[junit4:junit4] 2> 9471 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10063 (-1423770913673838592)]} 0 0
[junit4:junit4] 2> 9473 T487 C28 P45285 /update {wt=javabin&version=2} {add=[68 (1423770913674887168)]} 0 1
[junit4:junit4] 2> 9474 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10068 (1423770913675935744)]} 0 1
[junit4:junit4] 2> 9476 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10068 (1423770913678032897)]} 0 1
[junit4:junit4] 2> 9478 T508 C29 P50239 /update {wt=javabin&version=2} {add=[68 (1423770913678032896)]} 0 3
[junit4:junit4] 2> 9480 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10069 (1423770913682227200)]} 0 1
[junit4:junit4] 2> 9481 T490 C28 P45285 /update {wt=javabin&version=2} {add=[69 (1423770913683275776)]} 0 1
[junit4:junit4] 2> 9482 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10069 (1423770913685372928)]} 0 0
[junit4:junit4] 2> 9483 T509 C29 P50239 /update {wt=javabin&version=2} {add=[69 (1423770913685372929)]} 0 1
[junit4:junit4] 2> 9484 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10068 (-1423770913687470080)]} 0 0
[junit4:junit4] 2> 9485 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[68 (-1423770913688518656)]} 0 0
[junit4:junit4] 2> 9486 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10068 (-1423770913689567232)]} 0 0
[junit4:junit4] 2> 9487 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[68 (-1423770913690615808)]} 0 0
[junit4:junit4] 2> 9488 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10070 (1423770913691664384)]} 0 0
[junit4:junit4] 2> 9496 T490 C28 P45285 /update {wt=javabin&version=2} {add=[70 (1423770913699004416)]} 0 8
[junit4:junit4] 2> 9498 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10070 (1423770913701101568)]} 0 1
[junit4:junit4] 2> 9498 T508 C29 P50239 /update {wt=javabin&version=2} {add=[70 (1423770913702150144)]} 0 0
[junit4:junit4] 2> 9499 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10070 (-1423770913703198720)]} 0 0
[junit4:junit4] 2> 9500 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[70 (-1423770913704247296)]} 0 0
[junit4:junit4] 2> 9501 T495 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9501 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10070 (-1423770913705295872)]} 0 0
[junit4:junit4] 2> 9502 T495 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:53964_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:53964"}
[junit4:junit4] 2> 9502 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[70 (-1423770913706344448)]} 0 0
[junit4:junit4] 2> 9505 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10071 (1423770913707393024)]} 0 2
[junit4:junit4] 2> 9505 T487 C28 P45285 /update {wt=javabin&version=2} {add=[71 (1423770913708441600)]} 0 1
[junit4:junit4] 2> 9507 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10071 (1423770913711587328)]} 0 0
[junit4:junit4] 2> 9508 T507 C29 P50239 /update {wt=javabin&version=2} {add=[71 (1423770913711587329)]} 0 1
[junit4:junit4] 2> 9509 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10072 (1423770913713684480)]} 0 0
[junit4:junit4] 2> 9510 T488 C28 P45285 /update {wt=javabin&version=2} {add=[72 (1423770913714733056)]} 0 0
[junit4:junit4] 2> 9512 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10072 (1423770913715781632)]} 0 1
[junit4:junit4] 2> 9512 T508 C29 P50239 /update {wt=javabin&version=2} {add=[72 (1423770913716830208)]} 0 0
[junit4:junit4] 2> 9514 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10073 (1423770913718927360)]} 0 0
[junit4:junit4] 2> 9515 T490 C28 P45285 /update {wt=javabin&version=2} {add=[73 (1423770913718927361)]} 0 1
[junit4:junit4] 2> 9517 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10073 (1423770913721024512)]} 0 1
[junit4:junit4] 2> 9517 T509 C29 P50239 /update {wt=javabin&version=2} {add=[73 (1423770913722073088)]} 0 0
[junit4:junit4] 2> 9518 T494 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> 9519 T500 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> 9519 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10073 (-1423770913724170240)]} 0 0
[junit4:junit4] 2> 9519 T528 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> 9519 T513 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> 9519 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[73 (-1423770913724170241)]} 0 0
[junit4:junit4] 2> 9521 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10073 (-1423770913726267392)]} 0 0
[junit4:junit4] 2> 9521 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[73 (-1423770913726267393)]} 0 0
[junit4:junit4] 2> 9523 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10074 (1423770913727315968)]} 0 1
[junit4:junit4] 2> 9524 T490 C28 P45285 /update {wt=javabin&version=2} {add=[74 (1423770913728364544)]} 0 1
[junit4:junit4] 2> C27_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:53964_, base_url=http://127.0.0.1:53964}
[junit4:junit4] 2> 9538 T524 C27 P53964 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2
[junit4:junit4] 2> 9591 T524 C27 P53964 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b909aa lockFactory=org.apache.lucene.store.NativeFSLockFactory@131218e)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 9592 T524 C27 P53964 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 9595 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10074 (1423770913730461696)]} 0 57
[junit4:junit4] 2> 9596 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[74 (1423770913730461697)]} 0 59
[junit4:junit4] 2> 9596 T508 C29 P50239 /update {wt=javabin&version=2} {add=[74 (1423770913730461697)]} 0 71
[junit4:junit4] 2> 9598 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10074 (1423770913730461696)]} 0 73
[junit4:junit4] 2> 9599 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[74 (-1423770913808056320)]} 0 0
[junit4:junit4] 2> 9600 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10074 (-1423770913809104896)]} 0 0
[junit4:junit4] 2> 9603 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[74 (-1423770913810153472)]} 0 0
[junit4:junit4] 2> 9604 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10074 (-1423770913811202048)]} 0 0
[junit4:junit4] 2> 9604 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[74 (-1423770913810153472)]} 0 3
[junit4:junit4] 2> 9615 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10074 (-1423770913811202048)]} 0 13
[junit4:junit4] 2> 9617 T487 C28 P45285 /update {wt=javabin&version=2} {add=[75 (1423770913825882112)]} 0 1
[junit4:junit4] 2> 9617 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10075 (1423770913826930688)]} 0 0
[junit4:junit4] 2> 9622 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[75 (1423770913829027840)]} 0 1
[junit4:junit4] 2> 9622 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10075 (1423770913829027841)]} 0 1
[junit4:junit4] 2> 9622 T507 C29 P50239 /update {wt=javabin&version=2} {add=[75 (1423770913829027840)]} 0 3
[junit4:junit4] 2> 9623 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10075 (1423770913829027841)]} 0 4
[junit4:junit4] 2> 9625 T488 C28 P45285 /update {wt=javabin&version=2} {add=[76 (1423770913835319296)]} 0 0
[junit4:junit4] 2> 9626 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10076 (1423770913835319297)]} 0 1
[junit4:junit4] 2> 9631 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[76 (1423770913837416448)]} 0 1
[junit4:junit4] 2> 9631 T508 C29 P50239 /update {wt=javabin&version=2} {add=[76 (1423770913837416448)]} 0 4
[junit4:junit4] 2> 9632 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10076 (1423770913838465024)]} 0 2
[junit4:junit4] 2> 9633 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10076 (1423770913838465024)]} 0 5
[junit4:junit4] 2> 9634 T487 C28 P45285 /update {wt=javabin&version=2} {add=[77 (1423770913843707904)]} 0 1
[junit4:junit4] 2> 9635 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10077 (1423770913845805056)]} 0 0
[junit4:junit4] 2> 9639 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[77 (1423770913846853632)]} 0 1
[junit4:junit4] 2> 9640 T509 C29 P50239 /update {wt=javabin&version=2} {add=[77 (1423770913846853632)]} 0 4
[junit4:junit4] 2> 9641 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10077 (1423770913848950784)]} 0 1
[junit4:junit4] 2> 9641 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10077 (1423770913848950784)]} 0 4
[junit4:junit4] 2> 9642 T488 C28 P45285 /update {wt=javabin&version=2} {add=[78 (1423770913853145088)]} 0 0
[junit4:junit4] 2> 9645 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10078 (1423770913854193664)]} 0 2
[junit4:junit4] 2> 9646 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[78 (1423770913855242240)]} 0 0
[junit4:junit4] 2> 9647 T507 C29 P50239 /update {wt=javabin&version=2} {add=[78 (1423770913855242240)]} 0 3
[junit4:junit4] 2> 9649 T487 C28 P45285 /update {wt=javabin&version=2} {add=[79 (1423770913859436544)]} 0 1
[junit4:junit4] 2> 9652 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10078 (1423770913858387968)]} 0 0
[junit4:junit4] 2> 9652 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10078 (1423770913858387968)]} 0 5
[junit4:junit4] 2> 9654 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[79 (1423770913862582272)]} 0 1
[junit4:junit4] 2> 9660 T508 C29 P50239 /update {wt=javabin&version=2} {add=[79 (1423770913862582272)]} 0 9
[junit4:junit4] 2> 9661 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10079 (1423770913865728000)]} 0 7
[junit4:junit4] 2> 9662 T488 C28 P45285 /update {wt=javabin&version=2} {add=[80 (1423770913874116608)]} 0 0
[junit4:junit4] 2> 9666 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10079 (1423770913875165184)]} 0 0
[junit4:junit4] 2> 9667 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10079 (1423770913875165184)]} 0 4
[junit4:junit4] 2> 9669 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10080 (1423770913881456640)]} 0 0
[junit4:junit4] 2> 9672 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[80 (1423770913876213760)]} 0 1
[junit4:junit4] 2> 9672 T509 C29 P50239 /update {wt=javabin&version=2} {add=[80 (1423770913876213760)]} 0 8
[junit4:junit4] 2> 9674 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10080 (1423770913883553792)]} 0 1
[junit4:junit4] 2> 9674 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[76 (-1423770913886699520)]} 0 0
[junit4:junit4] 2> 9674 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10080 (1423770913883553792)]} 0 3
[junit4:junit4] 2> 9677 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10076 (-1423770913888796672)]} 0 1
[junit4:junit4] 2> 9678 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[76 (-1423770913888796672)]} 0 0
[junit4:junit4] 2> 9685 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[76 (-1423770913888796672)]} 0 9
[junit4:junit4] 2> 9686 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10076 (-1423770913898233856)]} 0 0
[junit4:junit4] 2> 9687 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10076 (-1423770913898233856)]} 0 2
[junit4:junit4] 2> 9687 T488 C28 P45285 /update {wt=javabin&version=2} {add=[81 (1423770913900331008)]} 0 0
[junit4:junit4] 2> 9689 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10081 (1423770913902428160)]} 0 0
[junit4:junit4] 2> 9692 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[81 (1423770913902428160)]} 0 1
[junit4:junit4] 2> 9692 T508 C29 P50239 /update {wt=javabin&version=2} {add=[81 (1423770913902428160)]} 0 3
[junit4:junit4] 2> 9694 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10081 (1423770913904525312)]} 0 1
[junit4:junit4] 2> 9694 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10081 (1423770913904525312)]} 0 3
[junit4:junit4] 2> 9694 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[80 (-1423770913907671040)]} 0 0
[junit4:junit4] 2> 9696 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10080 (-1423770913909768192)]} 0 0
[junit4:junit4] 2> 9698 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[80 (-1423770913909768192)]} 0 0
[junit4:junit4] 2> 9698 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[80 (-1423770913909768192)]} 0 2
[junit4:junit4] 2> 9699 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10080 (-1423770913911865344)]} 0 0
[junit4:junit4] 2> 9700 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10080 (-1423770913911865344)]} 0 2
[junit4:junit4] 2> 9701 T488 C28 P45285 /update {wt=javabin&version=2} {add=[82 (1423770913913962496)]} 0 1
[junit4:junit4] 2> 9702 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10082 (1423770913916059648)]} 0 0
[junit4:junit4] 2> 9705 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[82 (1423770913917108224)]} 0 0
[junit4:junit4] 2> 9706 T507 C29 P50239 /update {wt=javabin&version=2} {add=[82 (1423770913917108224)]} 0 3
[junit4:junit4] 2> 9707 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10082 (1423770913918156800)]} 0 1
[junit4:junit4] 2> 9707 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10082 (1423770913918156800)]} 0 3
[junit4:junit4] 2> 9708 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[81 (-1423770913922351104)]} 0 0
[junit4:junit4] 2> 9709 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10081 (-1423770913923399680)]} 0 0
[junit4:junit4] 2> 9711 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[81 (-1423770913924448256)]} 0 0
[junit4:junit4] 2> 9712 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[81 (-1423770913924448256)]} 0 2
[junit4:junit4] 2> 9712 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10081 (-1423770913925496832)]} 0 0
[junit4:junit4] 2> 9713 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10081 (-1423770913925496832)]} 0 2
[junit4:junit4] 2> 9714 T488 C28 P45285 /update {wt=javabin&version=2} {add=[83 (1423770913928642560)]} 0 0
[junit4:junit4] 2> 9715 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10083 (1423770913929691136)]} 0 1
[junit4:junit4] 2> 9719 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[83 (1423770913930739712)]} 0 1
[junit4:junit4] 2> 9719 T509 C29 P50239 /update {wt=javabin&version=2} {add=[83 (1423770913930739712)]} 0 3
[junit4:junit4] 2> 9720 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10083 (1423770913931788288)]} 0 0
[junit4:junit4] 2> 9720 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10083 (1423770913931788288)]} 0 3
[junit4:junit4] 2> 9721 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[83 (-1423770913935982592)]} 0 0
[junit4:junit4] 2> 9722 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10083 (-1423770913937031168)]} 0 0
[junit4:junit4] 2> 9725 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[83 (-1423770913938079744)]} 0 0
[junit4:junit4] 2> 9725 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[83 (-1423770913938079744)]} 0 2
[junit4:junit4] 2> 9726 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10083 (-1423770913939128320)]} 0 0
[junit4:junit4] 2> 9727 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10083 (-1423770913939128320)]} 0 3
[junit4:junit4] 2> 9729 T488 C28 P45285 /update {wt=javabin&version=2} {add=[84 (1423770913942274048)]} 0 2
[junit4:junit4] 2> 9729 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10084 (1423770913943322624)]} 0 1
[junit4:junit4] 2> 9734 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10084 (1423770913946468353)]} 0 1
[junit4:junit4] 2> 9734 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[84 (1423770913946468352)]} 0 1
[junit4:junit4] 2> 9742 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10084 (1423770913946468353)]} 0 11
[junit4:junit4] 2> 9743 T508 C29 P50239 /update {wt=javabin&version=2} {add=[84 (1423770913946468352)]} 0 12
[junit4:junit4] 2> 9745 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10084 (-1423770913961148416)]} 0 0
[junit4:junit4] 2> 9746 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[84 (-1423770913962196992)]} 0 1
[junit4:junit4] 2> 9749 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10084 (-1423770913963245568)]} 0 0
[junit4:junit4] 2> 9749 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[84 (-1423770913964294144)]} 0 0
[junit4:junit4] 2> 9750 T506 C29 P50239 /update {wt=javabin&version=2} {delete=[10084 (-1423770913963245568)]} 0 3
[junit4:junit4] 2> 9750 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[84 (-1423770913964294144)]} 0 2
[junit4:junit4] 2> 9753 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10085 (1423770913968488448)]} 0 0
[junit4:junit4] 2> 9753 T488 C28 P45285 /update {wt=javabin&version=2} {add=[85 (1423770913968488449)]} 0 1
[junit4:junit4] 2> 9758 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[85 (1423770913971634177)]} 0 1
[junit4:junit4] 2> 9759 T508 C29 P50239 /update {wt=javabin&version=2} {add=[85 (1423770913971634177)]} 0 4
[junit4:junit4] 2> 9761 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10085 (1423770913971634176)]} 0 4
[junit4:junit4] 2> 9762 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10085 (1423770913971634176)]} 0 7
[junit4:junit4] 2> 9762 T487 C28 P45285 /update {wt=javabin&version=2} {add=[86 (1423770913978974208)]} 0 1
[junit4:junit4] 2> 9765 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10086 (1423770913981071360)]} 0 1
[junit4:junit4] 2> 9768 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[86 (1423770913982119936)]} 0 1
[junit4:junit4] 2> 9769 T506 C29 P50239 /update {wt=javabin&version=2} {add=[86 (1423770913982119936)]} 0 5
[junit4:junit4] 2> 9772 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[85 (-1423770913989459968)]} 0 0
[junit4:junit4] 2> 9772 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10086 (1423770913985265664)]} 0 0
[junit4:junit4] 2> 9773 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10086 (1423770913985265664)]} 0 6
[junit4:junit4] 2> 9775 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10085 (-1423770913992605696)]} 0 0
[junit4:junit4] 2> 9776 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[85 (-1423770913991557120)]} 0 0
[junit4:junit4] 2> 9777 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[85 (-1423770913991557120)]} 0 3
[junit4:junit4] 2> 9779 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10085 (-1423770913995751424)]} 0 0
[junit4:junit4] 2> 9780 T487 C28 P45285 /update {wt=javabin&version=2} {add=[87 (1423770913996800000)]} 0 1
[junit4:junit4] 2> 9780 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10085 (-1423770913995751424)]} 0 3
[junit4:junit4] 2> 9783 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10087 (1423770914000994304)]} 0 0
[junit4:junit4] 2> 9786 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[87 (1423770913999945728)]} 0 1
[junit4:junit4] 2> 9787 T506 C29 P50239 /update {wt=javabin&version=2} {add=[87 (1423770913999945728)]} 0 5
[junit4:junit4] 2> 9789 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10087 (1423770914004140032)]} 0 1
[junit4:junit4] 2> 9790 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10087 (1423770914004140032)]} 0 4
[junit4:junit4] 2> 9790 T489 C28 P45285 /update {wt=javabin&version=2} {add=[88 (1423770914007285760)]} 0 1
[junit4:junit4] 2> 9793 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10088 (1423770914010431488)]} 0 1
[junit4:junit4] 2> 9795 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[88 (1423770914010431488)]} 0 0
[junit4:junit4] 2> 9796 T508 C29 P50239 /update {wt=javabin&version=2} {add=[88 (1423770914010431488)]} 0 4
[junit4:junit4] 2> 9798 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10088 (1423770914013577216)]} 0 1
[junit4:junit4] 2> 9798 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10088 (1423770914013577216)]} 0 3
[junit4:junit4] 2> 9799 T487 C28 P45285 /update {wt=javabin&version=2} {add=[89 (1423770914016722944)]} 0 1
[junit4:junit4] 2> 9800 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10089 (1423770914018820096)]} 0 0
[junit4:junit4] 2> 9811 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[89 (1423770914019868672)]} 0 7
[junit4:junit4] 2> 9811 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10089 (1423770914021965824)]} 0 0
[junit4:junit4] 2> 9811 T506 C29 P50239 /update {wt=javabin&version=2} {add=[89 (1423770914019868672)]} 0 10
[junit4:junit4] 2> 9812 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10089 (1423770914021965824)]} 0 10
[junit4:junit4] 2> 9814 T489 C28 P45285 /update {wt=javabin&version=2} {add=[90 (1423770914033500160)]} 0 0
[junit4:junit4] 2> 9816 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10090 (1423770914034548736)]} 0 1
[junit4:junit4] 2> 9819 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[90 (1423770914035597312)]} 0 1
[junit4:junit4] 2> 9819 T508 C29 P50239 /update {wt=javabin&version=2} {add=[90 (1423770914035597312)]} 0 3
[junit4:junit4] 2> 9820 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10090 (1423770914037694464)]} 0 0
[junit4:junit4] 2> 9820 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10090 (1423770914037694464)]} 0 2
[junit4:junit4] 2> 9821 T487 C28 P45285 /update {wt=javabin&version=2} {add=[91 (1423770914040840192)]} 0 0
[junit4:junit4] 2> 9823 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10091 (1423770914041888768)]} 0 1
[junit4:junit4] 2> 9826 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[91 (1423770914042937344)]} 0 1
[junit4:junit4] 2> 9826 T506 C29 P50239 /update {wt=javabin&version=2} {add=[91 (1423770914042937344)]} 0 3
[junit4:junit4] 2> 9828 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10091 (1423770914045034496)]} 0 1
[junit4:junit4] 2> 9828 T488 C28 P45285 /update {wt=javabin&version=2} {add=[92 (1423770914048180224)]} 0 0
[junit4:junit4] 2> 9828 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10091 (1423770914045034496)]} 0 3
[junit4:junit4] 2> 9831 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10092 (1423770914051325952)]} 0 0
[junit4:junit4] 2> 9833 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[92 (1423770914050277376)]} 0 1
[junit4:junit4] 2> 9833 T508 C29 P50239 /update {wt=javabin&version=2} {add=[92 (1423770914050277376)]} 0 3
[junit4:junit4] 2> 9835 T487 C28 P45285 /update {wt=javabin&version=2} {add=[93 (1423770914055520256)]} 0 0
[junit4:junit4] 2> 9836 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10092 (1423770914053423104)]} 0 2
[junit4:junit4] 2> 9836 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10092 (1423770914053423104)]} 0 3
[junit4:junit4] 2> 9839 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10093 (1423770914058665984)]} 0 1
[junit4:junit4] 2> 9841 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[93 (1423770914058665984)]} 0 0
[junit4:junit4] 2> 9842 T506 C29 P50239 /update {wt=javabin&version=2} {add=[93 (1423770914058665984)]} 0 5
[junit4:junit4] 2> 9844 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10093 (1423770914061811712)]} 0 1
[junit4:junit4] 2> 9844 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[92 (-1423770914064957440)]} 0 0
[junit4:junit4] 2> 9845 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10093 (1423770914061811712)]} 0 4
[junit4:junit4] 2> 9847 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10092 (-1423770914068103168)]} 0 0
[junit4:junit4] 2> 9848 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[92 (-1423770914068103168)]} 0 0
[junit4:junit4] 2> 9849 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[92 (-1423770914068103168)]} 0 2
[junit4:junit4] 2> 9851 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10092 (-1423770914070200320)]} 0 0
[junit4:junit4] 2> 9852 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[10092 (-1423770914070200320)]} 0 3
[junit4:junit4] 2> 9852 T487 C28 P45285 /update {wt=javabin&version=2} {add=[94 (1423770914072297472)]} 0 1
[junit4:junit4] 2> 9855 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10094 (1423770914075443200)]} 0 1
[junit4:junit4] 2> 9858 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[94 (1423770914075443200)]} 0 1
[junit4:junit4] 2> 9874 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10094 (1423770914078588928)]} 0 15
[junit4:junit4] 2> 9875 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10094 (1423770914078588928)]} 0 18
[junit4:junit4] 2> 9875 T506 C29 P50239 /update {wt=javabin&version=2} {add=[94 (1423770914075443200)]} 0 21
[junit4:junit4] 2> 9877 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10095 (1423770914099560448)]} 0 0
[junit4:junit4] 2> 9878 T489 C28 P45285 /update {wt=javabin&version=2} {add=[95 (1423770914099560449)]} 0 1
[junit4:junit4] 2> 9882 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10095 (1423770914101657600)]} 0 0
[junit4:junit4] 2> 9882 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[95 (1423770914102706176)]} 0 0
[junit4:junit4] 2> 9883 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10095 (1423770914101657600)]} 0 4
[junit4:junit4] 2> 9883 T509 C29 P50239 /update {wt=javabin&version=2} {add=[95 (1423770914102706176)]} 0 3
[junit4:junit4] 2> 9885 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10094 (-1423770914107949057)]} 0 0
[junit4:junit4] 2> 9885 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[94 (-1423770914107949056)]} 0 0
[junit4:junit4] 2> 9888 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[94 (-1423770914110046208)]} 0 0
[junit4:junit4] 2> 9888 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10094 (-1423770914110046209)]} 0 0
[junit4:junit4] 2> 9889 T506 C29 P50239 /update {wt=javabin&version=2} {delete=[94 (-1423770914110046208)]} 0 2
[junit4:junit4] 2> 9889 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10094 (-1423770914110046209)]} 0 2
[junit4:junit4] 2> 9891 T488 C28 P45285 /update {wt=javabin&version=2} {add=[96 (1423770914114240512)]} 0 0
[junit4:junit4] 2> 9892 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10096 (1423770914115289088)]} 0 0
[junit4:junit4] 2> 9896 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[96 (1423770914116337664)]} 0 1
[junit4:junit4] 2> 9896 T508 C29 P50239 /update {wt=javabin&version=2} {add=[96 (1423770914116337664)]} 0 3
[junit4:junit4] 2> 9896 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10096 (1423770914117386240)]} 0 0
[junit4:junit4] 2> 9897 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10096 (1423770914117386240)]} 0 3
[junit4:junit4] 2> 9899 T487 C28 P45285 /update {wt=javabin&version=2} {add=[97 (1423770914121580544)]} 0 1
[junit4:junit4] 2> 9900 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10097 (1423770914122629120)]} 0 1
[junit4:junit4] 2> 9903 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[97 (1423770914124726272)]} 0 0
[junit4:junit4] 2> 9904 T506 C29 P50239 /update {wt=javabin&version=2} {add=[97 (1423770914124726272)]} 0 3
[junit4:junit4] 2> 9904 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10097 (1423770914125774848)]} 0 0
[junit4:junit4] 2> 9905 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10097 (1423770914125774848)]} 0 3
[junit4:junit4] 2> 9907 T489 C28 P45285 /update {wt=javabin&version=2} {add=[98 (1423770914129969152)]} 0 1
[junit4:junit4] 2> 9907 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10098 (1423770914129969153)]} 0 1
[junit4:junit4] 2> 9911 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[98 (1423770914133114880)]} 0 0
[junit4:junit4] 2> 9912 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10098 (1423770914133114881)]} 0 1
[junit4:junit4] 2> 9912 T508 C29 P50239 /update {wt=javabin&version=2} {add=[98 (1423770914133114880)]} 0 3
[junit4:junit4] 2> 9912 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10098 (1423770914133114881)]} 0 3
[junit4:junit4] 2> 9914 T487 C28 P45285 /update {wt=javabin&version=2} {add=[99 (1423770914138357760)]} 0 0
[junit4:junit4] 2> 9915 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10099 (1423770914138357761)]} 0 1
[junit4:junit4] 2> 9919 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10099 (1423770914141503488)]} 0 1
[junit4:junit4] 2> 9920 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10099 (1423770914141503488)]} 0 2
[junit4:junit4] 2> 9921 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[99 (1423770914141503489)]} 0 2
[junit4:junit4] 2> 9921 T506 C29 P50239 /update {wt=javabin&version=2} {add=[99 (1423770914141503489)]} 0 5
[junit4:junit4] 2> 9922 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10099 (-1423770914146746368)]} 0 1
[junit4:junit4] 2> 9923 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[99 (-1423770914147794944)]} 0 0
[junit4:junit4] 2> 9926 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10099 (-1423770914148843520)]} 0 0
[junit4:junit4] 2> 9932 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[99 (-1423770914149892096)]} 0 0
[junit4:junit4] 2> 9932 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10099 (-1423770914148843520)]} 0 8
[junit4:junit4] 2> 9933 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[99 (-1423770914149892096)]} 0 8
[junit4:junit4] 2> 9935 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10100 (1423770914159329280)]} 0 1
[junit4:junit4] 2> 9935 T488 C28 P45285 /update {wt=javabin&version=2} {add=[100 (1423770914160377856)]} 0 0
[junit4:junit4] 2> 9940 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10100 (1423770914162475008)]} 0 1
[junit4:junit4] 2> 9940 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[100 (1423770914162475009)]} 0 1
[junit4:junit4] 2> 9940 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10100 (1423770914162475008)]} 0 3
[junit4:junit4] 2> 9940 T506 C29 P50239 /update {wt=javabin&version=2} {add=[100 (1423770914162475009)]} 0 3
[junit4:junit4] 2> 9942 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10101 (1423770914167717889)]} 0 0
[junit4:junit4] 2> 9943 T489 C28 P45285 /update {wt=javabin&version=2} {add=[101 (1423770914167717888)]} 0 1
[junit4:junit4] 2> 9947 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10101 (1423770914169815040)]} 0 1
[junit4:junit4] 2> 9947 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[101 (1423770914169815041)]} 0 1
[junit4:junit4] 2> 9947 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10101 (1423770914169815040)]} 0 3
[junit4:junit4] 2> 9949 T509 C29 P50239 /update {wt=javabin&version=2} {add=[101 (1423770914169815041)]} 0 5
[junit4:junit4] 2> 9951 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10102 (1423770914176106496)]} 0 1
[junit4:junit4] 2> 9955 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10102 (1423770914179252224)]} 0 0
[junit4:junit4] 2> 9955 T488 C28 P45285 /update {wt=javabin&version=2} {add=[102 (1423770914181349376)]} 0 0
[junit4:junit4] 2> 9956 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10102 (1423770914179252224)]} 0 3
[junit4:junit4] 2> 9958 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10103 (1423770914184495104)]} 0 0
[junit4:junit4] 2> 9960 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[102 (1423770914183446528)]} 0 1
[junit4:junit4] 2> 9960 T506 C29 P50239 /update {wt=javabin&version=2} {add=[102 (1423770914183446528)]} 0 3
[junit4:junit4] 2> 9979 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10103 (1423770914186592256)]} 0 1
[junit4:junit4] 2> 9979 T489 C28 P45285 /update {wt=javabin&version=2} {add=[103 (1423770914205466624)]} 0 1
[junit4:junit4] 2> 9979 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10103 (1423770914186592256)]} 0 19
[junit4:junit4] 2> 9982 T487 C28 P45285 /update {wt=javabin&version=2} {add=[10104 (1423770914208612352)]} 0 1
[junit4:junit4] 2> 9984 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[103 (1423770914208612352)]} 0 1
[junit4:junit4] 2> 9984 T509 C29 P50239 /update {wt=javabin&version=2} {add=[103 (1423770914208612352)]} 0 3
[junit4:junit4] 2> 9986 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10104 (1423770914211758080)]} 0 1
[junit4:junit4] 2> 9987 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10104 (1423770914211758080)]} 0 3
[junit4:junit4] 2> 9988 T488 C28 P45285 /update {wt=javabin&version=2} {add=[104 (1423770914213855232)]} 0 2
[junit4:junit4] 2> 9989 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[10101 (-1423770914215952384)]} 0 1
[junit4:junit4] 2> 9992 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[104 (1423770914218049536)]} 0 0
[junit4:junit4] 2> 9992 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10101 (-1423770914218049537)]} 0 0
[junit4:junit4] 2> 9992 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10101 (-1423770914218049537)]} 0 2
[junit4:junit4] 2> 9992 T506 C29 P50239 /update {wt=javabin&version=2} {add=[104 (1423770914218049536)]} 0 3
[junit4:junit4] 2> 9994 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[101 (-1423770914222243840)]} 0 0
[junit4:junit4] 2> 9995 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10105 (1423770914222243841)]} 0 1
[junit4:junit4] 2> 9998 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[101 (-1423770914224340992)]} 0 0
[junit4:junit4] 2> 9999 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[101 (-1423770914224340992)]} 0 3
[junit4:junit4] 2> 9999 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10105 (1423770914225389568)]} 0 0
[junit4:junit4] 2> 10000 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10105 (1423770914225389568)]} 0 3
[junit4:junit4] 2> 10007 T488 C28 P45285 /update {wt=javabin&version=2} {add=[105 (1423770914234826752)]} 0 1
[junit4:junit4] 2> 10008 T490 C28 P45285 /update {wt=javabin&version=2} {add=[10106 (1423770914236923904)]} 0 0
[junit4:junit4] 2> 10012 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10106 (1423770914239021056)]} 0 0
[junit4:junit4] 2> 10013 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[105 (1423770914237972480)]} 0 2
[junit4:junit4] 2> 10013 T509 C29 P50239 /update {wt=javabin&version=2} {add=[10106 (1423770914239021056)]} 0 3
[junit4:junit4] 2> 10013 T506 C29 P50239 /update {wt=javabin&version=2} {add=[105 (1423770914237972480)]} 0 4
[junit4:junit4] 2> 10015 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[10103 (-1423770914244263936)]} 0 0
[junit4:junit4] 2> 10017 T487 C28 P45285 /update {wt=javabin&version=2} {add=[106 (1423770914245312512)]} 0 1
[junit4:junit4] 2> 10021 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10103 (-1423770914248458241)]} 0 1
[junit4:junit4] 2> 10021 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10103 (-1423770914248458241)]} 0 2
[junit4:junit4] 2> 10021 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[106 (1423770914248458240)]} 0 0
[junit4:junit4] 2> 10022 T508 C29 P50239 /update {wt=javabin&version=2} {add=[106 (1423770914248458240)]} 0 3
[junit4:junit4] 2> 10023 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10107 (1423770914252652544)]} 0 0
[junit4:junit4] 2> 10024 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[103 (-1423770914253701120)]} 0 1
[junit4:junit4] 2> 10027 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[103 (-1423770914255798272)]} 0 0
[junit4:junit4] 2> 10028 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10107 (1423770914254749696)]} 0 1
[junit4:junit4] 2> 10028 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[103 (-1423770914255798272)]} 0 3
[junit4:junit4] 2> 10028 T506 C29 P50239 /update {wt=javabin&version=2} {add=[10107 (1423770914254749696)]} 0 3
[junit4:junit4] 2> 10030 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10104 (-1423770914259992577)]} 0 0
[junit4:junit4] 2> 10031 T487 C28 P45285 /update {wt=javabin&version=2} {add=[107 (1423770914259992576)]} 0 1
[junit4:junit4] 2> 10034 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10104 (-1423770914262089728)]} 0 0
[junit4:junit4] 2> 10034 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10104 (-1423770914262089728)]} 0 2
[junit4:junit4] 2> 10036 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[107 (1423770914263138304)]} 0 1
[junit4:junit4] 2> 10036 T509 C29 P50239 /update {wt=javabin&version=2} {add=[107 (1423770914263138304)]} 0 3
[junit4:junit4] 2> 10037 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10108 (1423770914266284032)]} 0 1
[junit4:junit4] 2> 10038 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[104 (-1423770914268381184)]} 0 0
[junit4:junit4] 2> 10041 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10108 (1423770914269429760)]} 0 1
[junit4:junit4] 2> 10041 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[104 (-1423770914270478336)]} 0 0
[junit4:junit4] 2> 10041 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10108 (1423770914269429760)]} 0 2
[junit4:junit4] 2> 10042 T506 C29 P50239 /update {wt=javabin&version=2} {delete=[104 (-1423770914270478336)]} 0 2
[junit4:junit4] 2> 10044 T489 C28 P45285 /update {wt=javabin&version=2} {delete=[10105 (-1423770914274672640)]} 0 1
[junit4:junit4] 2> 10044 T487 C28 P45285 /update {wt=javabin&version=2} {add=[108 (1423770914274672641)]} 0 0
[junit4:junit4] 2> 10047 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10105 (-1423770914275721216)]} 0 0
[junit4:junit4] 2> 10047 T508 C29 P50239 /update {wt=javabin&version=2} {delete=[10105 (-1423770914275721216)]} 0 2
[junit4:junit4] 2> 10048 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[108 (1423770914276769792)]} 0 0
[junit4:junit4] 2> 10049 T509 C29 P50239 /update {wt=javabin&version=2} {add=[108 (1423770914276769792)]} 0 3
[junit4:junit4] 2> 10050 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10109 (1423770914279915520)]} 0 1
[junit4:junit4] 2> 10051 T490 C28 P45285 /update {wt=javabin&version=2} {delete=[105 (-1423770914282012672)]} 0 0
[junit4:junit4] 2> 10055 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10109 (1423770914284109824)]} 0 1
[junit4:junit4] 2> 10061 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[105 (-1423770914285158400)]} 0 6
[junit4:junit4] 2> 10061 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10109 (1423770914284109824)]} 0 9
[junit4:junit4] 2> 10062 T506 C29 P50239 /update {wt=javabin&version=2} {delete=[105 (-1423770914285158400)]} 0 8
[junit4:junit4] 2> 10064 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10110 (1423770914295644160)]} 0 0
[junit4:junit4] 2> 10065 T487 C28 P45285 /update {wt=javabin&version=2} {add=[109 (1423770914296692736)]} 0 0
[junit4:junit4] 2> 10069 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10110 (1423770914297741312)]} 0 1
[junit4:junit4] 2> 10069 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10110 (1423770914297741312)]} 0 3
[junit4:junit4] 2> 10070 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[109 (1423770914299838464)]} 0 0
[junit4:junit4] 2> 10070 T509 C29 P50239 /update {wt=javabin&version=2} {add=[109 (1423770914299838464)]} 0 2
[junit4:junit4] 2> 10071 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10106 (-1423770914302984192)]} 0 0
[junit4:junit4] 2> 10073 T490 C28 P45285 /update {wt=javabin&version=2} {add=[110 (1423770914304032768)]} 0 1
[junit4:junit4] 2> 10075 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10106 (-1423770914305081344)]} 0 0
[junit4:junit4] 2> 10075 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10106 (-1423770914305081344)]} 0 2
[junit4:junit4] 2> 10077 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[110 (1423770914307178496)]} 0 1
[junit4:junit4] 2> 10077 T506 C29 P50239 /update {wt=javabin&version=2} {add=[110 (1423770914307178496)]} 0 3
[junit4:junit4] 2> 10078 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10111 (1423770914309275648)]} 0 1
[junit4:junit4] 2> 10079 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[106 (-1423770914311372800)]} 0 0
[junit4:junit4] 2> 10082 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10111 (1423770914311372800)]} 0 1
[junit4:junit4] 2> 10082 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10111 (1423770914311372800)]} 0 3
[junit4:junit4] 2> 10083 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[106 (-1423770914313469952)]} 0 1
[junit4:junit4] 2> 10083 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[106 (-1423770914313469952)]} 0 2
[junit4:junit4] 2> 10084 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10107 (-1423770914316615680)]} 0 0
[junit4:junit4] 2> 10087 T490 C28 P45285 /update {wt=javabin&version=2} {add=[111 (1423770914317664256)]} 0 2
[junit4:junit4] 2> 10088 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10107 (-1423770914318712832)]} 0 0
[junit4:junit4] 2> 10088 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10107 (-1423770914318712832)]} 0 2
[junit4:junit4] 2> 10092 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10112 (1423770914323955712)]} 0 1
[junit4:junit4] 2> 10093 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[111 (1423770914321858560)]} 0 1
[junit4:junit4] 2> 10093 T506 C29 P50239 /update {wt=javabin&version=2} {add=[111 (1423770914321858560)]} 0 4
[junit4:junit4] 2> 10096 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[107 (-1423770914329198592)]} 0 1
[junit4:junit4] 2> 10098 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10112 (1423770914327101440)]} 0 1
[junit4:junit4] 2> 10098 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10112 (1423770914327101440)]} 0 4
[junit4:junit4] 2> 10106 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[107 (-1423770914330247168)]} 0 0
[junit4:junit4] 2> 10107 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[107 (-1423770914330247168)]} 0 10
[junit4:junit4] 2> 10107 T488 C28 P45285 /update {wt=javabin&version=2} {delete=[10112 (-1423770914340732928)]} 0 0
[junit4:junit4] 2> 10109 T490 C28 P45285 /update {wt=javabin&version=2} {add=[112 (1423770914341781504)]} 0 1
[junit4:junit4] 2> 10111 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10112 (-1423770914342830080)]} 0 1
[junit4:junit4] 2> 10111 T507 C29 P50239 /update {wt=javabin&version=2} {delete=[10112 (-1423770914342830080)]} 0 2
[junit4:junit4] 2> 10113 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10113 (1423770914347024384)]} 0 0
[junit4:junit4] 2> 10115 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[112 (1423770914344927232)]} 0 1
[junit4:junit4] 2> 10115 T506 C29 P50239 /update {wt=javabin&version=2} {add=[112 (1423770914344927232)]} 0 4
[junit4:junit4] 2> 10117 T487 C28 P45285 /update {wt=javabin&version=2} {delete=[112 (-1423770914351218688)]} 0 0
[junit4:junit4] 2> 10118 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10113 (1423770914350170112)]} 0 0
[junit4:junit4] 2> 10119 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10113 (1423770914350170112)]} 0 3
[junit4:junit4] 2> 10121 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[112 (-1423770914353315840)]} 0 1
[junit4:junit4] 2> 10121 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10114 (1423770914355412992)]} 0 0
[junit4:junit4] 2> 10121 T509 C29 P50239 /update {wt=javabin&version=2} {delete=[112 (-1423770914353315840)]} 0 2
[junit4:junit4] 2> 10123 T490 C28 P45285 /update {wt=javabin&version=2} {add=[113 (1423770914357510144)]} 0 0
[junit4:junit4] 2> 10166 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10114 (1423770914357510144)]} 0 0
[junit4:junit4] 2> 10166 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[113 (1423770914359607296)]} 0 0
[junit4:junit4] 2> 10167 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10114 (1423770914357510144)]} 0 44
[junit4:junit4] 2> 10168 T506 C29 P50239 /update {wt=javabin&version=2} {add=[113 (1423770914359607296)]} 0 43
[junit4:junit4] 2> 10170 T489 C28 P45285 /update {wt=javabin&version=2} {add=[10115 (1423770914405744640)]} 0 1
[junit4:junit4] 2> 10171 T487 C28 P45285 /update {wt=javabin&version=2} {add=[114 (1423770914407841792)]} 0 1
[junit4:junit4] 2> 10175 T522 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10115 (1423770914408890368)]} 0 1
[junit4:junit4] 2> 10175 T523 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[114 (1423770914409938944)]} 0 0
[junit4:junit4] 2> 10176 T508 C29 P50239 /update {wt=javabin&version=2} {add=[10115 (1423770914408890368)]} 0 4
[junit4:junit4] 2> 10182 T509 C29 P50239 /update {wt=javabin&version=2} {add=[114 (1423770914409938944)]} 0 9
[junit4:junit4] 2> 10184 T488 C28 P45285 /update {wt=javabin&version=2} {add=[10116 (1423770914421473280)]} 0 0
[junit4:junit4] 2> 10185 T490 C28 P45285 /update {wt=javabin&version=2} {add=[115 (1423770914421473281)]} 0 1
[junit4:junit4] 2> 10189 T524 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10116 (1423770914423570432)]} 0 1
[junit4:junit4] 2> 10189 T521 C27 P53964 /update {distrib.from=http://127.0.0.1:50239/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[115 (1423770914424619008)]} 0 0
[junit4:junit4] 2> 10190 T507 C29 P50239 /update {wt=javabin&version=2} {add=[10116 (1423770914423570432)]} 0 4
[junit4:junit4] 2>
[...truncated too long message...]
losing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2/index
[junit4:junit4] 2> 43814 T472 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2
[junit4:junit4] 2> 43814 T472 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1357813743350/jetty2
[junit4:junit4] 2> 43815 T717 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88985681484447754-127.0.0.1:53964_-n_0000000003) am no longer a leader.
[junit4:junit4] 2> 43815 T477 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c24017efa000a
[junit4:junit4] 2> 43816 T644 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 43816 T474 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:47638 which had sessionid 0x13c24017efa000a
[junit4:junit4] 2> 43818 T500 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4:junit4] 2> 43818 T500 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4:junit4] 2> 43818 T644 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 43818 T472 oaz.ZooKeeper.close Session: 0x13c24017efa000a closed
[junit4:junit4] 2> 43818 T644 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 43819 T644 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 43841 T472 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4:junit4] 2> 43892 T477 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c24017efa0004
[junit4:junit4] 2> 43893 T500 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 43893 T474 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:47619 which had sessionid 0x13c24017efa0004
[junit4:junit4] 2> 43893 T472 oaz.ZooKeeper.close Session: 0x13c24017efa0004 closed
[junit4:junit4] 2> 43895 T472 oazs.ZooKeeperServer.shutdown shutting down
[junit4:junit4] 2> 43896 T472 oazs.SessionTrackerImpl.shutdown Shutting down
[junit4:junit4] 2> 43896 T472 oazs.PrepRequestProcessor.shutdown Shutting down
[junit4:junit4] 2> 43896 T477 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4] 2> 43896 T472 oazs.SyncRequestProcessor.shutdown Shutting down
[junit4:junit4] 2> 43897 T476 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4] 2> 43897 T472 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4] 2> 43898 T472 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:59829 59829
[junit4:junit4] 2> 43899 T474 oazs.NIOServerCnxnFactory.run NIOServerCnxn factory exited run method
[junit4:junit4] 2> 43900 T472 oazs.ZooKeeperServer.shutdown shutting down
[junit4:junit4] 2> 43900 T472 oazs.SessionTrackerImpl.shutdown Shutting down
[junit4:junit4] 2> 43900 T472 oazs.PrepRequestProcessor.shutdown Shutting down
[junit4:junit4] 2> 43901 T472 oazs.SyncRequestProcessor.shutdown Shutting down
[junit4:junit4] 2> 43901 T472 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4] 2> 43901 T472 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 45285
[junit4:junit4] 2> 43922 T472 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 43925 T472 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> 43945 T471 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 4 leaked thread(s).
[junit4:junit4] 2> 44655 T475 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4] 2> 44880 T494 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 44880 T494 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 44880 T494 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 44992 T513 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 44993 T513 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 44993 T513 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=en_AU, timezone=Asia/Ujung_Pandang
[junit4:junit4] 2> NOTE: Linux 3.2.0-35-generic i386/Oracle Corporation 1.7.0_10 (32-bit)/cpus=8,threads=1,free=26070288,total=211263488
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestReplicationHandler, OpenExchangeRatesOrgProviderTest, BasicDistributedZk2Test, TestCharFilters, PingRequestHandlerTest, TestFoldingMultitermQuery, RecoveryZkTest]
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryZkTest -Dtests.seed=88BB4DC6D5972E6E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_AU -Dtests.timezone=Asia/Ujung_Pandang -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR 0.00s J1 | RecoveryZkTest (suite) <<<
[junit4:junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=531, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([88BB4DC6D5972E6E]:0)
[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:517)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:983)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:875)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:867)
[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:377)
[junit4:junit4] > at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:273)
[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@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/index83208323tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a1bfbd)
[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 J1 in 45.33s, 1 test, 1 error <<< FAILURES!
[...truncated 647 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:353: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1176: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:841: There were test failures: 247 suites, 1032 tests, 1 suite-level error, 9 ignored (4 assumptions)
Total time: 33 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_10 -client -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure