Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/2333/
1 tests failed. FAILED: org.apache.solr.cloud.TestModifyConfFiles.testDistribSearch Error Message: expected:<[Error from server at https://127.0.0.1:30923/lxp/collection1: ]No file name specifi...> but was:<[]No file name specifi...> Stack Trace: org.junit.ComparisonFailure: expected:<[Error from server at https://127.0.0.1:30923/lxp/collection1: ]No file name specifi...> but was:<[]No file name specifi...> at __randomizedtesting.SeedInfo.seed([EEE03A5A0FCA6D73:6F06B44278950D4F]:0) at org.junit.Assert.assertEquals(Assert.java:125) at org.junit.Assert.assertEquals(Assert.java:147) at org.apache.solr.cloud.TestModifyConfFiles.doTest(TestModifyConfFiles.java:65) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) 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 8917 lines...] [junit4] Suite: org.apache.solr.cloud.TestModifyConfFiles [junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/init-core-data-001 [junit4] 2> 20793 T21 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true) [junit4] 2> 20795 T21 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /lxp/ [junit4] 2> 20806 T21 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 20834 T21 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 20843 T22 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 21145 T21 oasc.ZkTestServer.run start zk server on port:38703 [junit4] 2> 21156 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 21339 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 21581 T29 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7bb7dbb8 name:ZooKeeperConnection Watcher:127.0.0.1:38703 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 21582 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 21582 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 21597 T21 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 21738 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 21740 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 21743 T32 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4d1e1137 name:ZooKeeperConnection Watcher:127.0.0.1:38703/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 21744 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 21744 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 21755 T21 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 21762 T21 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 21768 T21 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 21772 T21 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 21778 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 21827 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 21836 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 21837 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 21843 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 21845 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 21851 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 21852 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 21857 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 21858 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 21864 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 21865 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 21870 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 21872 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 21877 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 21878 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 21883 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 21885 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 21890 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 21891 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 21897 T21 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 21898 T21 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 21935 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 21937 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 21940 T35 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@59af553 name:ZooKeeperConnection Watcher:127.0.0.1:38703/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 21940 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 21941 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 25091 T21 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 25843 T21 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 25887 T21 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:16458 [junit4] 2> 26213 T21 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 26215 T21 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 26215 T21 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002 [junit4] 2> 26216 T21 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/' [junit4] 2> 26278 T21 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/solr.xml [junit4] 2> 26385 T21 oasc.CoreContainer.<init> New CoreContainer 1108094794 [junit4] 2> 26387 T21 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/] [junit4] 2> 26514 T21 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 26515 T21 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 26516 T21 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 26517 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 26518 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 26519 T21 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 26519 T21 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 26520 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 26521 T21 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 26522 T21 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 26528 T21 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 26533 T21 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 26534 T21 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 26534 T21 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 26535 T21 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38703/solr [junit4] 2> 26612 T21 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 26613 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 26615 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 26621 T48 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ec486a1 name:ZooKeeperConnection Watcher:127.0.0.1:38703 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 26622 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 26623 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 26652 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 26656 T51 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d2d739d name:ZooKeeperConnection Watcher:127.0.0.1:38703/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 26656 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 26687 T21 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 26715 T21 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 26729 T21 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 26735 T21 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 26741 T21 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 26771 T21 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 26775 T21 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:16458_lxp [junit4] 2> 26784 T21 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:16458_lxp [junit4] 2> 26790 T21 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 26795 T21 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 26799 T21 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 26833 T21 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:16458_lxp [junit4] 2> 26834 T21 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 26838 T21 oasc.Overseer.start Overseer (id=92976179687587844-127.0.0.1:16458_lxp-n_0000000000) starting [junit4] 2> 26881 T21 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 26935 T21 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 26965 T53 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 26967 T21 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 26971 T21 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 26975 T21 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 27012 T52 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 27044 T55 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 27045 T55 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 27067 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 27067 T55 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 27080 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "numShards":"1", [junit4] 2> "node_name":"127.0.0.1:16458_lxp", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:16458/lxp", [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null} [junit4] 2> 27106 T52 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 27141 T52 oasc.Overseer$ClusterStateUpdater.createCollection state version control_collection 1 [junit4] 2> 27160 T52 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 27186 T51 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> 28069 T55 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 28069 T55 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 28071 T55 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 28072 T55 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 28074 T55 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 28075 T55 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/collection1/' [junit4] 2> 28078 T55 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 28079 T55 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 28081 T55 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 28150 T55 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 28172 T55 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 28256 T55 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 28325 T55 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 28328 T55 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 28424 T55 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 28997 T55 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 29043 T55 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 29046 T55 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 29081 T55 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 29089 T55 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 29096 T55 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 29099 T55 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 29100 T55 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 29100 T55 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 29102 T55 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 29103 T55 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 29104 T55 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 29104 T55 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 29105 T55 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-002/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/control/data/ [junit4] 2> 29106 T55 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e0c5fbd [junit4] 2> 29125 T55 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/control/data [junit4] 2> 29126 T55 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/control/data/index/ [junit4] 2> 29126 T55 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 29127 T55 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/control/data/index [junit4] 2> 29127 T55 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35233955023662655] [junit4] 2> 29129 T55 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@500a6814 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5b372219),segFN=segments_1,generation=1} [junit4] 2> 29129 T55 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 29137 T55 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 29267 T55 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 29267 T55 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 29268 T55 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 29268 T55 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 29268 T55 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 29269 T55 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 29270 T55 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 29270 T55 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 29281 T55 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29283 T55 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29284 T55 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29285 T55 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 29286 T55 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 29288 T55 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 29289 T55 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 29290 T55 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 29291 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 29293 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 29294 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 29296 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 29297 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 29298 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 29299 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 29300 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 29301 T55 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 29303 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 29304 T55 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 29335 T55 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29338 T55 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29340 T55 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29343 T55 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 29350 T55 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 29351 T55 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 29352 T55 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.9404296875, floorSegmentMB=2.150390625, forceMergeDeletesPctAllowed=20.819487139008714, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.735580037737609 [junit4] 2> 29353 T55 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@500a6814 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5b372219),segFN=segments_1,generation=1} [junit4] 2> 29354 T55 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 29354 T55 oass.SolrIndexSearcher.<init> Opening Searcher@78ded0e7[collection1] main [junit4] 2> 29355 T55 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 29357 T55 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 29357 T55 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 29376 T55 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 29376 T55 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 29377 T55 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 29378 T55 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 29379 T55 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 29380 T55 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 29384 T55 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 29385 T55 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 29385 T55 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 29394 T55 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 29397 T56 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78ded0e7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 29399 T55 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 29401 T59 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:16458/lxp collection:control_collection shard:shard1 [junit4] 2> 29402 T21 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2 [junit4] 2> 29402 T21 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 29452 T59 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 29465 T59 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 29469 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 29470 T59 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 29470 T59 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 29475 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} [junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@2831bb22 url=https://127.0.0.1:16458/lxp/collection1 node=127.0.0.1:16458_lxp C1_STATE=coll:control_collection core:collection1 props:{core=collection1, state=down, node_name=127.0.0.1:16458_lxp, base_url=https://127.0.0.1:16458/lxp} [junit4] 2> 29483 T59 C1 P16458 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:16458/lxp/collection1/ [junit4] 2> 29484 T59 C1 P16458 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 29485 T59 C1 P16458 oasc.SyncStrategy.syncToMe https://127.0.0.1:16458/lxp/collection1/ has no replicas [junit4] 2> 29485 T59 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:16458/lxp/collection1/ shard1 [junit4] 2> 29490 T51 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> 29507 T59 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 29517 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 29525 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"https://127.0.0.1:16458/lxp", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} [junit4] 2> 29574 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 29575 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 29578 T62 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@db44916 name:ZooKeeperConnection Watcher:127.0.0.1:38703/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 29578 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 29579 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 29581 T21 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 29590 T21 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 29591 T21 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 29591 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 29592 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 29595 T65 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@49049c10 name:ZooKeeperConnection Watcher:127.0.0.1:38703/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 29596 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 29596 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 29600 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 29603 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":2} [junit4] 2> 29604 T52 oasc.Overseer$ClusterStateUpdater.buildCollection Building a new collection: collection1 [junit4] 2> 29605 T52 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 29605 T52 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 29611 T52 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 29614 T62 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> 29614 T51 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> 29672 T59 oasc.ZkController.register We are https://127.0.0.1:16458/lxp/collection1/ and leader is https://127.0.0.1:16458/lxp/collection1/ [junit4] 2> 29672 T59 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:16458/lxp [junit4] 2> 29673 T59 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 29673 T59 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 29674 T59 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 29678 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 29687 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "state":"active", [junit4] 2> "numShards":"2", [junit4] 2> "node_name":"127.0.0.1:16458_lxp", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:16458/lxp", [junit4] 2> "roles":null, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1"} [junit4] 2> 29795 T51 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> 29795 T62 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> 31491 T21 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 31492 T21 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 31501 T21 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 31506 T21 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:56545 [junit4] 2> 31510 T21 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 31511 T21 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 31511 T21 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003 [junit4] 2> 31512 T21 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/' [junit4] 2> 31547 T21 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/solr.xml [junit4] 2> 31633 T21 oasc.CoreContainer.<init> New CoreContainer 664387537 [junit4] 2> 31634 T21 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/] [junit4] 2> 31635 T21 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 31636 T21 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 31636 T21 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 31636 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 31637 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 31637 T21 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 31638 T21 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 31638 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 31639 T21 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 31639 T21 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 31643 T21 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 31646 T21 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 31646 T21 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 31647 T21 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 31647 T21 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38703/solr [junit4] 2> 31648 T21 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 31648 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 31649 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 31652 T77 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ff2e2f name:ZooKeeperConnection Watcher:127.0.0.1:38703 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 31653 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 31654 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 31658 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 31660 T80 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3972e29f name:ZooKeeperConnection Watcher:127.0.0.1:38703/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 31661 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 31673 T21 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 32683 T21 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56545_lxp [junit4] 2> 32685 T21 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56545_lxp [junit4] 2> 32690 T21 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 32707 T81 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 32708 T81 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 32710 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 32712 T81 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 32712 T81 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 32713 T81 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 32719 T81 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 32720 T81 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 32722 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "numShards":"2", [junit4] 2> "node_name":"127.0.0.1:56545_lxp", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:56545/lxp", [junit4] 2> "roles":null, [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null} [junit4] 2> 32728 T52 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 32729 T52 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 32737 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 32738 T52 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 32739 T80 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 32740 T80 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 33720 T81 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 33721 T81 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 33722 T81 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 33723 T81 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 33724 T81 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 33725 T81 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/collection1/' [junit4] 2> 33726 T81 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 33727 T81 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 33728 T81 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 33764 T81 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 33768 T81 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 33888 T81 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 33939 T81 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 33942 T81 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 34038 T81 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 34577 T81 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 34600 T81 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 34604 T81 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 34647 T81 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 34656 T81 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 34665 T81 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 34668 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 34668 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 34669 T81 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 34672 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 34672 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 34673 T81 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 34674 T81 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 34675 T81 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-003/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty1/ [junit4] 2> 34675 T81 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e0c5fbd [junit4] 2> 34677 T81 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty1 [junit4] 2> 34678 T81 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty1/index/ [junit4] 2> 34678 T81 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 34679 T81 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty1/index [junit4] 2> 34680 T81 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35233955023662655] [junit4] 2> 34681 T81 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2166f93f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f75a83a),segFN=segments_1,generation=1} [junit4] 2> 34682 T81 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 34697 T81 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 34698 T81 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 34698 T81 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 34699 T81 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 34699 T81 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 34700 T81 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 34701 T81 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 34702 T81 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 34703 T81 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 34706 T81 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 34708 T81 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 34711 T81 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 34713 T81 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 34716 T81 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 34718 T81 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 34737 T81 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 34740 T81 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 34742 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 34745 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 34748 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 34751 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 34753 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 34756 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 34758 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 34760 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 34763 T81 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 34766 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 34768 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 34794 T81 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 34798 T81 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 34801 T81 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 34804 T81 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 34811 T81 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 34812 T81 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 34815 T81 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.9404296875, floorSegmentMB=2.150390625, forceMergeDeletesPctAllowed=20.819487139008714, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.735580037737609 [junit4] 2> 34816 T81 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2166f93f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f75a83a),segFN=segments_1,generation=1} [junit4] 2> 34817 T81 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 34818 T81 oass.SolrIndexSearcher.<init> Opening Searcher@2c27973[collection1] main [junit4] 2> 34818 T81 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 34821 T81 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 34821 T81 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 34823 T81 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 34823 T81 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 34824 T81 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 34826 T81 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 34827 T81 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 34827 T81 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 34828 T81 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 34828 T81 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 34833 T82 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c27973[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 34835 T81 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 34837 T85 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56545/lxp collection:collection1 shard:shard2 [junit4] 2> 34838 T21 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2 [junit4] 2> 34839 T21 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 34839 T85 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 34852 T85 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 34857 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 34858 T85 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 34859 T85 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@683e60e7 url=https://127.0.0.1:56545/lxp/collection1 node=127.0.0.1:56545_lxp C2_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, node_name=127.0.0.1:56545_lxp, base_url=https://127.0.0.1:56545/lxp} [junit4] 2> 34859 T85 C2 P56545 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56545/lxp/collection1/ [junit4] 2> 34861 T85 C2 P56545 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 34861 T85 C2 P56545 oasc.SyncStrategy.syncToMe https://127.0.0.1:56545/lxp/collection1/ has no replicas [junit4] 2> 34862 T85 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56545/lxp/collection1/ shard2 [junit4] 2> 34863 T85 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 34866 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} [junit4] 2> 34875 T52 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 34877 T80 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 34879 T80 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 34890 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"https://127.0.0.1:56545/lxp", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} [junit4] 2> 34897 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 34899 T52 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 34901 T80 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 34904 T80 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 34927 T85 oasc.ZkController.register We are https://127.0.0.1:56545/lxp/collection1/ and leader is https://127.0.0.1:56545/lxp/collection1/ [junit4] 2> 34928 T85 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56545/lxp [junit4] 2> 34929 T85 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 34929 T85 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 34930 T85 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 34934 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 34944 T85 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 34944 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "state":"active", [junit4] 2> "numShards":"2", [junit4] 2> "node_name":"127.0.0.1:56545_lxp", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:56545/lxp", [junit4] 2> "roles":null, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard2"} [junit4] 2> 34959 T52 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 34961 T80 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 34963 T80 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 36430 T21 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 36432 T21 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 36442 T21 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 36446 T21 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:59500 [junit4] 2> 36449 T21 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 36449 T21 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 36450 T21 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004 [junit4] 2> 36450 T21 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/' [junit4] 2> 36483 T21 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/solr.xml [junit4] 2> 36550 T21 oasc.CoreContainer.<init> New CoreContainer 544586943 [junit4] 2> 36551 T21 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/] [junit4] 2> 36552 T21 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 36552 T21 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 36553 T21 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 36553 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 36554 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 36554 T21 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 36554 T21 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 36555 T21 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 36555 T21 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 36555 T21 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 36559 T21 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 36562 T21 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 36562 T21 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 36563 T21 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 36563 T21 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38703/solr [junit4] 2> 36564 T21 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 36564 T21 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 36565 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 36568 T97 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f7b8f60 name:ZooKeeperConnection Watcher:127.0.0.1:38703 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 36568 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 36569 T21 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 36573 T21 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 36575 T100 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@297978c5 name:ZooKeeperConnection Watcher:127.0.0.1:38703/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 36576 T21 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 36587 T21 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 37597 T21 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59500_lxp [junit4] 2> 37599 T21 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59500_lxp [junit4] 2> 37606 T21 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 37620 T101 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 37621 T101 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 37624 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 37625 T101 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 37626 T101 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 37627 T101 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 37629 T101 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 37629 T101 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 37630 T52 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "numShards":"2", [junit4] 2> "node_name":"127.0.0.1:59500_lxp", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:59500/lxp", [junit4] 2> "roles":null, [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null} [junit4] 2> 37636 T52 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 37638 T52 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 37647 T52 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 37649 T100 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 37649 T80 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 37650 T100 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 37651 T80 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 38630 T101 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 38631 T101 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 38633 T101 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 38633 T101 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 38635 T101 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 38635 T101 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/collection1/' [junit4] 2> 38638 T101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 38639 T101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 38640 T101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 38680 T101 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 38684 T101 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 38736 T101 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 38799 T101 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 38802 T101 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 38885 T101 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 39395 T101 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 39416 T101 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 39420 T101 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 39460 T101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 39468 T101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 39476 T101 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 39479 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 39480 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 39480 T101 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 39483 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 39484 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 39485 T101 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 39485 T101 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 39486 T101 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-004/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty2/ [junit4] 2> 39487 T101 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e0c5fbd [junit4] 2> 39488 T101 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty2 [junit4] 2> 39489 T101 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty2/index/ [junit4] 2> 39489 T101 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 39490 T101 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty2/index [junit4] 2> 39491 T101 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35233955023662655] [junit4] 2> 39493 T101 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15732057 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39503ac1),segFN=segments_1,generation=1} [junit4] 2> 39493 T101 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 39515 T101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 39516 T101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 39517 T101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 39518 T101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 39518 T101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 39519 T101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 39520 T101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 39522 T101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 39522 T101 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 39526 T101 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 39529 T101 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 39532 T101 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 39536 T101 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 39539 T101 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 39543 T101 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 39547 T101 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 39550 T101 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 39552 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 39555 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 39557 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 39559 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 39560 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 39562 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 39564 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 39565 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 39567 T101 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 39569 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 39570 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 39588 T101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 39590 T101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 39594 T101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 39596 T101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 39601 T101 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 39601 T101 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 39603 T101 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.9404296875, floorSegmentMB=2.150390625, forceMergeDeletesPctAllowed=20.819487139008714, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.735580037737609 [junit4] 2> 39605 T101 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15732057 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39503ac1),segFN=segments_1,generation=1} [junit4] 2> 39605 T101 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 39606 T101 oass.SolrIndexSearcher.<init> Opening Searcher@3c92a1e4[collection1] main [junit4] 2> 39606 T101 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 39608 T101 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 39609 T101 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 39610 T101 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 39610 T101 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 39611 T101 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 39613 T101 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 39613 T101 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 39614 T101 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 39614 T101 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 39615 T101 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 39618 T102 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c92a1e4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 39619 T101 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 39620 T105 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:59500/lxp collection:collection1 shard:shard1 [junit4] 2> 39621 T21 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2 [junit4] 2> 39622 T21 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 39622 T105 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 39632 T105 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 39636 T51 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 39636 T105 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 39637 T105 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@6215d145 url=https://127.0.0.1:59500/lxp/collection1 node=127.0.0.1:59500_lxp C3_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, node_name=127.0.0.1:59500_lxp, base_url=https://127.0.0.1:59500/lxp} [junit4] 2> 39637 T105 C3 P59500 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:59500/lxp/collection1/ [junit4] 2> 39638 T105 C3 P59500 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to m [...truncated too long message...] ne.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 75389 T21 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 75389 T21 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty4/index [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty4/index;done=false>>] [junit4] 2> 75389 T21 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty4/index [junit4] 2> 75390 T21 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty4 [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty4;done=false>>] [junit4] 2> 75390 T21 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001/tempDir-001/jetty4 [junit4] 2> 75391 T21 oasc.Overseer.close Overseer (id=92976179687587854-127.0.0.1:45247_lxp-n_0000000004) closing [junit4] 2> 75392 T179 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:45247_lxp [junit4] 2> 75394 T143 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 75395 T21 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/lxp,null} [junit4] 2> 75947 T21 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 75952 T21 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:38703 38703 [junit4] 2> 76245 T22 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:38703 38703 [junit4] 2> 76369 T22 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 6 /solr/aliases.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/live_nodes [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 5 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestModifyConfFiles -Dtests.method=testDistribSearch -Dtests.seed=EEE03A5A0FCA6D73 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=en_PH -Dtests.timezone=Europe/Amsterdam -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 55.7s J2 | TestModifyConfFiles.testDistribSearch <<< [junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[Error from server at https://127.0.0.1:30923/lxp/collection1: ]No file name specifi...> but was:<[]No file name specifi...> [junit4] > at __randomizedtesting.SeedInfo.seed([EEE03A5A0FCA6D73:6F06B44278950D4F]:0) [junit4] > at org.apache.solr.cloud.TestModifyConfFiles.doTest(TestModifyConfFiles.java:65) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 76462 T21 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.cloud.TestModifyConfFiles-EEE03A5A0FCA6D73-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=en_PH, timezone=Europe/Amsterdam [junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=118370832,total=235929600 [junit4] 2> NOTE: All tests run in this JVM: [TestOrdValues, DisMaxRequestHandlerTest, TestModifyConfFiles] [junit4] Completed on J2 in 55.76s, 1 test, 1 failure <<< FAILURES! [...truncated 1439 lines...] BUILD FAILED /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:529: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:477: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:190: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:508: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1363: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:970: There were test failures: 450 suites, 1845 tests, 1 failure, 60 ignored (23 assumptions) Total time: 64 minutes 12 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #2329 Archived 5 artifacts Archive block size is 32768 Received 0 blocks and 200909117 bytes Compression is 0.0% Took 37 sec Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org