Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2018/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC (asserts: true)
1 tests failed. FAILED: org.apache.solr.cloud.OverseerStatusTest.testDistribSearch Error Message: Error from server at http://127.0.0.1:51400: reload the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error from server at http://127.0.0.1:51400: reload the collection time out:180s at __randomizedtesting.SeedInfo.seed([758330C202FA3706:F465BEDA75A5573A]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:566) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:213) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.invokeCollectionApi(AbstractFullDistribZkTestBase.java:1867) at org.apache.solr.cloud.OverseerStatusTest.doTest(OverseerStatusTest.java:103) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.GeneratedMethodAccessor35.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9420 lines...] [junit4] Suite: org.apache.solr.cloud.OverseerStatusTest [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/init-core-data-001 [junit4] 2> 3394266 T8324 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 3394273 T8324 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 3394275 T8324 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3394277 T8325 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3394377 T8324 oasc.ZkTestServer.run start zk server on port:51390 [junit4] 2> 3394378 T8324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3394381 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3394390 T8332 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4c6b745d name:ZooKeeperConnection Watcher:127.0.0.1:51390 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3394391 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3394391 T8324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3394391 T8324 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3394399 T8324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3394401 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3394404 T8335 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2db3b361 name:ZooKeeperConnection Watcher:127.0.0.1:51390/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3394404 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3394405 T8324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3394405 T8324 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 3394412 T8324 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 3394418 T8324 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 3394423 T8324 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 3394428 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3394428 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3394438 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 3394439 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 3394446 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3394446 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3394452 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3394452 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 3394458 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3394459 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 3394465 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 3394466 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 3394472 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3394473 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3394480 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3394480 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3394487 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3394487 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3394492 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3394493 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3394500 T8324 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3394501 T8324 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 3396184 T8324 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3396234 T8324 oejs.AbstractConnector.doStart Started [email protected]:51393 [junit4] 2> 3396236 T8324 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 3396236 T8324 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3396237 T8324 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002 [junit4] 2> 3396237 T8324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/' [junit4] 2> 3396276 T8324 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/solr.xml [junit4] 2> 3396300 T8324 oasc.CoreContainer.<init> New CoreContainer 2045995334 [junit4] 2> 3396301 T8324 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/] [junit4] 2> 3396301 T8324 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3396301 T8324 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3396302 T8324 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3396302 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3396302 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3396304 T8324 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3396304 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3396304 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3396304 T8324 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3396305 T8324 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3396306 T8324 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3396306 T8324 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3396306 T8324 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3396307 T8324 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3396307 T8324 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51390/solr [junit4] 2> 3396307 T8324 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3396308 T8324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3396309 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3396313 T8347 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@371f8f69 name:ZooKeeperConnection Watcher:127.0.0.1:51390 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3396314 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3396315 T8324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3396322 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3396323 T8350 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4517da04 name:ZooKeeperConnection Watcher:127.0.0.1:51390/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3396324 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3396326 T8324 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 3396332 T8324 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 3396344 T8324 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 3396354 T8324 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 3396363 T8324 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 3396371 T8324 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 3396375 T8324 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51393_ [junit4] 2> 3396377 T8324 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51393_ [junit4] 2> 3396384 T8324 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 3396388 T8324 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 3396394 T8324 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3396399 T8324 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:51393_ [junit4] 2> 3396400 T8324 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 3396407 T8324 oasc.Overseer.start Overseer (id=93039530742317059-127.0.0.1:51393_-n_0000000000) starting [junit4] 2> 3396414 T8324 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 3396444 T8324 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3396446 T8352 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 3396448 T8324 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 3396458 T8324 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 3396463 T8324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3396477 T8351 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 3396482 T8354 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 3396482 T8354 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3396484 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3396484 T8354 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3396498 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51393";, [junit4] 2> "node_name":"127.0.0.1:51393_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 3396500 T8351 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51393";, [junit4] 2> "node_name":"127.0.0.1:51393_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3396501 T8351 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 3396501 T8351 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3396507 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 3397486 T8354 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3397486 T8354 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 3397488 T8354 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3397489 T8354 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3397491 T8354 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3397491 T8354 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/collection1/' [junit4] 2> 3397492 T8354 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 3397493 T8354 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 3397493 T8354 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 3397526 T8354 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3397530 T8354 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3397545 T8354 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3397563 T8354 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3397566 T8354 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3397570 T8354 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3397697 T8354 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3397698 T8354 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3397700 T8354 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3397705 T8354 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3397749 T8354 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3397750 T8354 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3397750 T8354 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/control/data/ [junit4] 2> 3397750 T8354 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d9db567 [junit4] 2> 3397751 T8354 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/control/data [junit4] 2> 3397751 T8354 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/control/data/index/ [junit4] 2> 3397751 T8354 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 3397753 T8354 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/control/data/index [junit4] 2> 3397753 T8354 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=49.1005859375, floorSegmentMB=2.02734375, forceMergeDeletesPctAllowed=8.764058374009874, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7286334398082229 [junit4] 2> 3397754 T8354 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@827f8af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4047049a),segFN=segments_1,generation=1} [junit4] 2> 3397754 T8354 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3397756 T8354 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3397757 T8354 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3397757 T8354 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3397758 T8354 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3397758 T8354 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3397758 T8354 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3397759 T8354 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3397760 T8354 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3397760 T8354 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3397760 T8354 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3397761 T8354 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3397761 T8354 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3397762 T8354 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3397762 T8354 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3397762 T8354 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 3397763 T8354 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 3397763 T8354 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 3397764 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 3397765 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 3397765 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 3397766 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 3397766 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 3397766 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 3397767 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 3397767 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 3397768 T8354 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3397768 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3397768 T8354 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3397776 T8354 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3397777 T8354 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3397778 T8354 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3397779 T8354 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3397780 T8354 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 3397783 T8354 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3397783 T8354 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3397783 T8354 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=84.2490234375, floorSegmentMB=1.8828125, forceMergeDeletesPctAllowed=12.21865475816425, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30350968205331263 [junit4] 2> 3397784 T8354 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@827f8af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4047049a),segFN=segments_1,generation=1} [junit4] 2> 3397784 T8354 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3397784 T8354 oass.SolrIndexSearcher.<init> Opening Searcher@6229295a[collection1] main [junit4] 2> 3397784 T8354 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3397788 T8354 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3397789 T8354 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3397791 T8354 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3397791 T8354 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3397791 T8354 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3397793 T8354 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3397794 T8354 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3397794 T8354 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3397794 T8354 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 3397795 T8354 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3397795 T8355 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6229295a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3397797 T8358 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51393 collection:control_collection shard:shard1 [junit4] 2> 3397798 T8324 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3397798 T8324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3397799 T8324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3397799 T8358 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 3397800 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3397804 T8361 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e88af3a name:ZooKeeperConnection Watcher:127.0.0.1:51390/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3397805 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3397806 T8324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3397808 T8324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3397814 T8358 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3397816 T8324 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3397818 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3397819 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 3397823 T8358 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3397823 T8358 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4563 name=collection1 org.apache.solr.core.SolrCore@3aa54202 url=http://127.0.0.1:51393/collection1 node=127.0.0.1:51393_ C4563_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:51393, node_name=127.0.0.1:51393_, state=down} [junit4] 2> 3397824 T8358 C4563 P51393 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51393/collection1/ [junit4] 2> 3397824 T8358 C4563 P51393 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3397824 T8358 C4563 P51393 oasc.SyncStrategy.syncToMe http://127.0.0.1:51393/collection1/ has no replicas [junit4] 2> 3397825 T8358 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51393/collection1/ shard1 [junit4] 2> 3397825 T8358 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3397838 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3397845 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:51393";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 3397985 T8361 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 3397987 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 3398048 T8358 oasc.ZkController.register We are http://127.0.0.1:51393/collection1/ and leader is http://127.0.0.1:51393/collection1/ [junit4] 2> 3398048 T8358 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51393 [junit4] 2> 3398049 T8358 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3398049 T8358 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 3398049 T8358 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3398052 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3398062 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51393";, [junit4] 2> "node_name":"127.0.0.1:51393_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 3398064 T8351 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51393";, [junit4] 2> "node_name":"127.0.0.1:51393_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3398172 T8361 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 3398172 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 3399636 T8324 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 3399637 T8324 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3399644 T8324 oejs.AbstractConnector.doStart Started [email protected]:51397 [junit4] 2> 3399646 T8324 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 3399646 T8324 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3399647 T8324 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003 [junit4] 2> 3399648 T8324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/' [junit4] 2> 3399708 T8324 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/solr.xml [junit4] 2> 3399741 T8324 oasc.CoreContainer.<init> New CoreContainer 1049098578 [junit4] 2> 3399741 T8324 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/] [junit4] 2> 3399741 T8324 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3399742 T8324 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3399742 T8324 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3399742 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3399743 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3399743 T8324 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3399744 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3399744 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3399745 T8324 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3399745 T8324 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3399745 T8324 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3399746 T8324 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3399747 T8324 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3399747 T8324 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3399748 T8324 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51390/solr [junit4] 2> 3399748 T8324 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3399748 T8324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3399749 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3399753 T8373 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4c3a06aa name:ZooKeeperConnection Watcher:127.0.0.1:51390 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3399754 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3399754 T8324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3399762 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3399765 T8376 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@60d67d41 name:ZooKeeperConnection Watcher:127.0.0.1:51390/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3399765 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3399787 T8324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3400801 T8324 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51397_ [junit4] 2> 3400804 T8324 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51397_ [junit4] 2> 3400816 T8324 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3400827 T8377 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3400827 T8377 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3400829 T8377 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3400829 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3400832 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51397";, [junit4] 2> "node_name":"127.0.0.1:51397_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 3400834 T8351 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51397";, [junit4] 2> "node_name":"127.0.0.1:51397_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3400834 T8351 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 3400834 T8351 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 3400838 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3400838 T8361 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3400839 T8376 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3401830 T8377 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3401830 T8377 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3401832 T8377 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3401833 T8377 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3401836 T8377 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3401837 T8377 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/collection1/' [junit4] 2> 3401838 T8377 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 3401839 T8377 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 3401840 T8377 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 3401874 T8377 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3401879 T8377 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3401899 T8377 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3401923 T8377 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3401927 T8377 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3401932 T8377 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3402089 T8377 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3402090 T8377 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3402092 T8377 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3402097 T8377 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3402147 T8377 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3402148 T8377 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3402148 T8377 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty1/ [junit4] 2> 3402148 T8377 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d9db567 [junit4] 2> 3402149 T8377 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty1 [junit4] 2> 3402149 T8377 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty1/index/ [junit4] 2> 3402149 T8377 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 3402152 T8377 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty1/index [junit4] 2> 3402153 T8377 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=49.1005859375, floorSegmentMB=2.02734375, forceMergeDeletesPctAllowed=8.764058374009874, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7286334398082229 [junit4] 2> 3402154 T8377 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4a0bfae2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21825f9c),segFN=segments_1,generation=1} [junit4] 2> 3402154 T8377 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3402157 T8377 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3402158 T8377 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3402158 T8377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3402159 T8377 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3402159 T8377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3402159 T8377 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3402159 T8377 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3402160 T8377 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3402160 T8377 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3402161 T8377 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3402162 T8377 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3402162 T8377 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3402162 T8377 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3402163 T8377 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3402163 T8377 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 3402165 T8377 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 3402166 T8377 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 3402166 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 3402172 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 3402172 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 3402173 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 3402173 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 3402174 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 3402174 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 3402175 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 3402175 T8377 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3402176 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3402176 T8377 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3402184 T8377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3402185 T8377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3402186 T8377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3402187 T8377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3402188 T8377 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 3402191 T8377 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3402191 T8377 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3402193 T8377 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=84.2490234375, floorSegmentMB=1.8828125, forceMergeDeletesPctAllowed=12.21865475816425, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30350968205331263 [junit4] 2> 3402194 T8377 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4a0bfae2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21825f9c),segFN=segments_1,generation=1} [junit4] 2> 3402195 T8377 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3402196 T8377 oass.SolrIndexSearcher.<init> Opening Searcher@5eef653a[collection1] main [junit4] 2> 3402196 T8377 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3402199 T8377 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3402199 T8377 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3402201 T8377 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3402202 T8377 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3402202 T8377 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3402204 T8377 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3402205 T8377 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3402206 T8377 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3402206 T8377 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 3402207 T8378 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5eef653a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3402208 T8377 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3402211 T8381 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51397 collection:collection1 shard:shard2 [junit4] 2> 3402212 T8324 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3402213 T8324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3402214 T8381 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 3402242 T8381 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 3402248 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3402254 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 3402258 T8381 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3402259 T8381 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4564 name=collection1 org.apache.solr.core.SolrCore@1e003825 url=http://127.0.0.1:51397/collection1 node=127.0.0.1:51397_ C4564_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:51397, node_name=127.0.0.1:51397_, state=down} [junit4] 2> 3402259 T8381 C4564 P51397 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51397/collection1/ [junit4] 2> 3402270 T8381 C4564 P51397 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3402270 T8381 C4564 P51397 oasc.SyncStrategy.syncToMe http://127.0.0.1:51397/collection1/ has no replicas [junit4] 2> 3402270 T8381 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51397/collection1/ shard2 [junit4] 2> 3402272 T8381 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 3402302 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3402310 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:51397";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 3402420 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3402422 T8376 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3402421 T8361 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3402467 T8381 oasc.ZkController.register We are http://127.0.0.1:51397/collection1/ and leader is http://127.0.0.1:51397/collection1/ [junit4] 2> 3402468 T8381 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51397 [junit4] 2> 3402468 T8381 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3402468 T8381 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3402468 T8381 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3402472 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3402476 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51397";, [junit4] 2> "node_name":"127.0.0.1:51397_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 3402477 T8351 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51397";, [junit4] 2> "node_name":"127.0.0.1:51397_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3402591 T8361 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3402591 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3402592 T8376 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 3404107 T8324 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 3404107 T8324 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3404115 T8324 oejs.AbstractConnector.doStart Started [email protected]:51400 [junit4] 2> 3404116 T8324 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 3404117 T8324 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3404117 T8324 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004 [junit4] 2> 3404117 T8324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/' [junit4] 2> 3404155 T8324 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/solr.xml [junit4] 2> 3404186 T8324 oasc.CoreContainer.<init> New CoreContainer 433398968 [junit4] 2> 3404186 T8324 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/] [junit4] 2> 3404187 T8324 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3404188 T8324 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3404188 T8324 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3404188 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3404189 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3404189 T8324 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3404189 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3404190 T8324 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3404190 T8324 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3404191 T8324 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3404191 T8324 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3404192 T8324 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3404192 T8324 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3404192 T8324 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3404192 T8324 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51390/solr [junit4] 2> 3404193 T8324 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3404193 T8324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3404194 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3404198 T8393 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2556e634 name:ZooKeeperConnection Watcher:127.0.0.1:51390 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3404200 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3404200 T8324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3404205 T8324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3404208 T8396 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2e2e81de name:ZooKeeperConnection Watcher:127.0.0.1:51390/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3404208 T8324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3404224 T8324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3405239 T8324 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51400_ [junit4] 2> 3405242 T8324 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51400_ [junit4] 2> 3405254 T8324 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3405263 T8397 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3405264 T8397 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3405266 T8397 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3405266 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3405269 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51400";, [junit4] 2> "node_name":"127.0.0.1:51400_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 3405270 T8351 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51400";, [junit4] 2> "node_name":"127.0.0.1:51400_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3405271 T8351 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 3405271 T8351 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3405275 T8361 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3405275 T8376 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3405275 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3405275 T8396 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3406267 T8397 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3406267 T8397 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3406268 T8397 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3406269 T8397 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3406271 T8397 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3406271 T8397 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/collection1/' [junit4] 2> 3406273 T8397 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 3406273 T8397 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 3406274 T8397 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 3406300 T8397 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3406305 T8397 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3406320 T8397 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3406340 T8397 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3406344 T8397 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3406348 T8397 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3406484 T8397 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3406485 T8397 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3406500 T8397 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3406506 T8397 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3406557 T8397 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3406557 T8397 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3406557 T8397 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty2/ [junit4] 2> 3406557 T8397 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d9db567 [junit4] 2> 3406558 T8397 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty2 [junit4] 2> 3406558 T8397 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty2/index/ [junit4] 2> 3406558 T8397 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 3406560 T8397 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001/tempDir-001/jetty2/index [junit4] 2> 3406561 T8397 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=49.1005859375, floorSegmentMB=2.02734375, forceMergeDeletesPctAllowed=8.764058374009874, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7286334398082229 [junit4] 2> 3406561 T8397 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@236453cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24dd9f47),segFN=segments_1,generation=1} [junit4] 2> 3406562 T8397 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3406565 T8397 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3406565 T8397 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3406565 T8397 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3406566 T8397 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3406566 T8397 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3406566 T8397 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3406567 T8397 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3406567 T8397 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3406567 T8397 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3406568 T8397 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3406569 T8397 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3406570 T8397 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3406571 T8397 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3406571 T8397 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3406572 T8397 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 3406573 T8397 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 3406573 T8397 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 3406574 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 3406575 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 3406576 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 3406576 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 3406577 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 3406577 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 3406577 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 3406578 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 3406579 T8397 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3406580 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3406580 T8397 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3406588 T8397 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3406589 T8397 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3406590 T8397 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3406592 T8397 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3406592 T8397 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 3406595 T8397 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3406595 T8397 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3406597 T8397 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=84.2490234375, floorSegmentMB=1.8828125, forceMergeDeletesPctAllowed=12.21865475816425, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30350968205331263 [junit4] 2> 3406598 T8397 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@236453cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24dd9f47),segFN=segments_1,generation=1} [junit4] 2> 3406598 T8397 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3406598 T8397 oass.SolrIndexSearcher.<init> Opening Searcher@6e1b7dd7[collection1] main [junit4] 2> 3406598 T8397 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3406601 T8397 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3406602 T8397 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3406603 T8397 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3406604 T8397 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3406604 T8397 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3406605 T8397 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3406606 T8397 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3406606 T8397 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3406606 T8397 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 3406607 T8398 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e1b7dd7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3406608 T8397 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3406610 T8401 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51400 collection:collection1 shard:shard1 [junit4] 2> 3406611 T8324 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3406611 T8324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3406640 T8401 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 3406654 T8401 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3406657 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3406659 T8401 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3406660 T8401 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4565 name=collection1 org.apache.solr.core.SolrCore@171b81f7 url=http://127.0.0.1:51400/collection1 node=127.0.0.1:51400_ C4565_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:51400, node_name=127.0.0.1:51400_, state=down} [junit4] 2> 3406660 T8401 C4565 P51400 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51400/collection1/ [junit4] 2> 3406661 T8401 C4565 P51400 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3406661 T8401 C4565 P51400 oasc.SyncStrategy.syncToMe http://127.0.0.1:51400/collection1/ has no replicas [junit4] 2> 3406661 T8401 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51400/collection1/ shard1 [junit4] 2> 3406662 T8401 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 3406662 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1"} current state version: 7 [junit4] 2> 3406675 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3406682 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:51400";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 7 [junit4] 2> 3406795 T8361 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3406795 T8376 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3406796 T8350 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3406799 T8396 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 3406845 T8401 oasc.ZkController.register We are http://127.0.0.1:51400/collection1/ and leader is http://127.0.0.1:51400/collection1/ [junit4] 2> 3406845 T8401 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51400 [junit4] 2> 3406845 T8401 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3406845 T8401 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3406846 T8401 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3406852 T8350 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3406855 T8351 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51400";, [junit4] 2> "node_name":"127.0.0.1:51400_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 8 [junit4] 2> 3406857 T8351 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51400";, [junit4] 2> "node_name":"127.0.0.1:51400_", [...truncated too long message...] .util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369) [junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:264) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> [junit4] 2> 3631670 T8461 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 3631685 T8324 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> 3636672 T8324 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 3636675 T8324 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:51390 51390 [junit4] 2> 3636706 T8325 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:51390 51390 [junit4] 2> 3636912 T8325 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/live_nodes [junit4] 2> 5 /solr/overseer/queue [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerStatusTest -Dtests.method=testDistribSearch -Dtests.seed=758330C202FA3706 -Dtests.slow=true -Dtests.locale=sr_BA_#Latn -Dtests.timezone=Asia/Shanghai -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 243s | OverseerStatusTest.testDistribSearch <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error from server at http://127.0.0.1:51400: reload the collection time out:180s [junit4] > at __randomizedtesting.SeedInfo.seed([758330C202FA3706:F465BEDA75A5573A]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:566) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:213) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.invokeCollectionApi(AbstractFullDistribZkTestBase.java:1867) [junit4] > at org.apache.solr.cloud.OverseerStatusTest.doTest(OverseerStatusTest.java:103) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 3636924 T8324 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerStatusTest-758330C202FA3706-001 [junit4] 2> 242667 T8323 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50), sim=DefaultSimilarity, locale=sr_BA_#Latn, timezone=Asia/Shanghai [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_20 (64-bit)/cpus=2,threads=1,free=175356040,total=294940672 [junit4] 2> NOTE: All tests run in this JVM: [TestValueSourceCache, CacheHeaderTest, CloudMLTQParserTest, TestManagedResourceStorage, TestFieldCollectionResource, FileBasedSpellCheckerTest, TestFoldingMultitermQuery, JsonLoaderTest, QueryEqualityTest, CoreMergeIndexesAdminHandlerTest, SpellCheckCollatorTest, ShardSplitTest, NotRequiredUniqueKeyTest, TestOverriddenPrefixQueryForCustomFieldType, TestNoOpRegenerator, TestCloudManagedSchema, StatsComponentTest, DistributedQueryComponentCustomSortTest, TestSolrQueryParser, TestConfigReload, TestPostingsSolrHighlighter, TestStressUserVersions, DistribDocExpirationUpdateProcessorTest, TestSchemaManager, TestStressReorder, LoggingHandlerTest, TestUpdate, DateMathParserTest, TestSimpleQParserPlugin, TestCodecSupport, TestBulkSchemaAPI, TestQuerySenderNoQuery, TestRestManager, MergeStrategyTest, SolrCmdDistributorTest, TestInitQParser, DistributedSuggestComponentTest, TestShortCircuitedRequests, TestMaxScoreQueryParser, TestIndexingPerformance, AssignTest, IndexSchemaRuntimeFieldTest, TestIntervalFaceting, UnloadDistributedZkTest, AutoCommitTest, AliasIntegrationTest, TestReload, TestImplicitCoreProperties, TestDefaultStatsCache, TestSortingResponseWriter, TestMergePolicyConfig, TestBlobHandler, SuggesterTSTTest, CopyFieldTest, TestHashPartitioner, TestModifyConfFiles, TestFieldTypeResource, TestOrdValues, TestUtils, TestAnalyzedSuggestions, ResponseLogComponentTest, SliceStateTest, TestChildDocTransformer, TestSchemaNameResource, TestComplexPhraseQParserPlugin, DistanceFunctionTest, AnalyticsMergeStrategyTest, TestSolrXmlPersistor, DebugComponentTest, FastVectorHighlighterTest, SharedFSAutoReplicaFailoverTest, TestExtendedDismaxParser, LeaderElectionIntegrationTest, AnalyticsQueryTest, TestDynamicFieldCollectionResource, DOMUtilTest, TestLuceneMatchVersion, HdfsRecoveryZkTest, TestIBSimilarityFactory, TestNRTOpen, TestDefaultSearchFieldResource, DateFieldTest, TestCopyFieldCollectionResource, AddSchemaFieldsUpdateProcessorFactoryTest, TestDocumentBuilder, HdfsCollectionsAPIDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, DateRangeFieldTest, TestInitParams, DeleteShardTest, DocValuesTest, TestManagedSynonymFilterFactory, SpellCheckComponentTest, OutputWriterTest, ClusterStateUpdateTest, TestStressLucene, TestCSVResponseWriter, HdfsSyncSliceTest, ExitableDirectoryReaderTest, CircularListTest, TestSolrDynamicMBean, PrimUtilsTest, SolrRequestParserTest, TestDocSet, ChangedSchemaMergeTest, TestSolrIndexConfig, TriLevelCompositeIdRoutingTest, TestArbitraryIndexDir, PreAnalyzedFieldTest, CloudExitableDirectoryReaderTest, BadCopyFieldTest, DistributedQueryComponentOptimizationTest, TestDistributedGrouping, ShardRoutingCustomTest, RollingRestartTest, TestSerializedLuceneMatchVersion, UpdateRequestProcessorFactoryTest, FullSolrCloudDistribCmdsTest, SyncSliceTest, DistributedExpandComponentTest, StatelessScriptUpdateProcessorFactoryTest, JSONWriterTest, ZkStateWriterTest, WordBreakSolrSpellCheckerTest, TestCoreDiscovery, BasicZkTest, TestManagedSchemaDynamicFieldResource, TestRandomMergePolicy, TestAtomicUpdateErrorCases, TestFreeTextSuggestions, TestJoin, NumericFieldsTest, TestGroupingSearch, FieldMutatingUpdateProcessorTest, MBeansHandlerTest, TestSolrDeletionPolicy2, TestSuggestSpellingConverter, ClusterStateTest, BasicDistributedZkTest, TermVectorComponentDistributedTest, TestFiltering, TestRTGBase, TestXIncludeConfig, SolrCoreTest, LeaderElectionTest, TestManagedStopFilterFactory, CursorMarkTest, CurrencyFieldOpenExchangeTest, QueryElevationComponentTest, RegexBoostProcessorTest, PathHierarchyTokenizerFactoryTest, AnalysisErrorHandlingTest, SuggesterWFSTTest, TestSolrXml, XmlUpdateRequestHandlerTest, TermsComponentTest, TestSurroundQueryParser, DocValuesMultiTest, TestSolrJ, TestExactSharedStatsCache, HdfsBasicDistributedZk2Test, HighlighterConfigTest, DirectUpdateHandlerOptimizeTest, HdfsUnloadDistributedZkTest, TestSort, TestRequestStatusCollectionAPI, VMParamsZkACLAndCredentialsProvidersTest, TestSolrQueryParserDefaultOperatorResource, ZkNodePropsTest, FacetPivotSmallTest, DistributedQueueTest, LegacyHTMLStripCharFilterTest, RemoteQueryErrorTest, DistributedFacetPivotSmallTest, TestCloudPivotFacet, PluginInfoTest, DistributedDebugComponentTest, SolrTestCaseJ4Test, TestCollectionAPI, ShardRoutingTest, TestLMJelinekMercerSimilarityFactory, DocumentAnalysisRequestHandlerTest, DistributedFacetPivotLargeTest, TestBlendedInfixSuggestions, CSVRequestHandlerTest, SimpleCollectionCreateDeleteTest, TestInfoStreamLogging, TestRawResponseWriter, TestStressRecovery, TestConfigOverlay, TestPartialUpdateDeduplication, TestQuerySenderListener, PeerSyncTest, TestQueryUtils, TestReplicationHandler, TestSystemIdResolver, SSLMigrationTest, TestFuzzyAnalyzedSuggestions, TestIndexSearcher, DisMaxRequestHandlerTest, TestPhraseSuggestions, TestCollapseQParserPlugin, AtomicUpdatesTest, OverseerStatusTest] [junit4] Completed in 243.22s, 1 test, 1 error <<< FAILURES! [...truncated 787 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:187: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1349: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956: There were test failures: 459 suites, 1856 tests, 1 error, 37 ignored (21 assumptions) Total time: 176 minutes 37 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC (asserts: true) Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
