Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/1953/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC (asserts: true)
1 tests failed. FAILED: org.apache.solr.cloud.TestModifyConfFiles.testDistribSearch Error Message: expected:<[Error from server at http://127.0.0.1:57071/collection1: ]No file name specifi...> but was:<[]No file name specifi...> Stack Trace: org.junit.ComparisonFailure: expected:<[Error from server at http://127.0.0.1:57071/collection1: ]No file name specifi...> but was:<[]No file name specifi...> at __randomizedtesting.SeedInfo.seed([EE18DF1D8D0FA780:6FFE5105FA50C7BC]: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:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9216 lines...] [junit4] Suite: org.apache.solr.cloud.TestModifyConfFiles [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/init-core-data-001 [junit4] 2> 613695 T2341 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 613695 T2341 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 613701 T2341 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 613702 T2341 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 613704 T2342 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 613805 T2341 oasc.ZkTestServer.run start zk server on port:57054 [junit4] 2> 613805 T2341 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 613808 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 613822 T2349 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@767ce6ed name:ZooKeeperConnection Watcher:127.0.0.1:57054 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 613822 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 613823 T2341 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 613823 T2341 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 613834 T2341 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 613837 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 613839 T2352 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3cb4bf79 name:ZooKeeperConnection Watcher:127.0.0.1:57054/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 613840 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 613841 T2341 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 613841 T2341 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 613850 T2341 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 613858 T2341 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 613862 T2341 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 613867 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 613867 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 613874 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 613875 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 613880 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 613881 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 613905 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 613905 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 613912 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 613912 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 613917 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 613917 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 613922 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 613922 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 613927 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 613928 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 613933 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 613933 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 613937 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 613937 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 613942 T2341 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 613942 T2341 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 615662 T2341 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 615687 T2341 oejs.AbstractConnector.doStart Started [email protected]:57057 [junit4] 2> 615691 T2341 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 615691 T2341 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 615691 T2341 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002 [junit4] 2> 615692 T2341 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/' [junit4] 2> 615734 T2341 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/solr.xml [junit4] 2> 615760 T2341 oasc.CoreContainer.<init> New CoreContainer 1288876512 [junit4] 2> 615761 T2341 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/] [junit4] 2> 615762 T2341 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 615763 T2341 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 615763 T2341 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 615763 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 615765 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 615765 T2341 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 615765 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 615767 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 615767 T2341 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 615767 T2341 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 615768 T2341 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 615768 T2341 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 615769 T2341 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 615770 T2341 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 615770 T2341 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57054/solr [junit4] 2> 615771 T2341 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 615771 T2341 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 615773 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 615777 T2364 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1169eac1 name:ZooKeeperConnection Watcher:127.0.0.1:57054 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 615779 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 615779 T2341 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 615797 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 615797 T2367 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e12463c name:ZooKeeperConnection Watcher:127.0.0.1:57054/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 615798 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 615809 T2341 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 615817 T2341 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 615831 T2341 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 615839 T2341 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 615846 T2341 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 615853 T2341 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 615863 T2341 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57057_ [junit4] 2> 615865 T2341 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57057_ [junit4] 2> 615875 T2341 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 615879 T2341 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 615884 T2341 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 615889 T2341 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:57057_ [junit4] 2> 615889 T2341 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 615895 T2341 oasc.Overseer.start Overseer (id=92976446105387011-127.0.0.1:57057_-n_0000000000) starting [junit4] 2> 615902 T2341 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 615930 T2341 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 615931 T2369 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 615933 T2341 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 615941 T2341 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 615945 T2341 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 615956 T2368 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 615962 T2371 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 615962 T2371 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 615964 T2371 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 615964 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 615971 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:57057";, [junit4] 2> "node_name":"127.0.0.1:57057_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 615971 T2368 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 615972 T2368 oasc.Overseer$ClusterStateUpdater.createCollection state version control_collection 1 [junit4] 2> 615972 T2368 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 615984 T2367 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> 616965 T2371 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 616966 T2371 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 616967 T2371 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 616967 T2371 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 616972 T2371 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 616972 T2371 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/collection1/' [junit4] 2> 616974 T2371 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 616974 T2371 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 616975 T2371 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 617008 T2371 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 617014 T2371 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 617039 T2371 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 617054 T2371 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 617057 T2371 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 617064 T2371 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 617605 T2371 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 617616 T2371 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 617618 T2371 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 617652 T2371 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 617658 T2371 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 617663 T2371 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 617666 T2371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 617667 T2371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 617667 T2371 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 617670 T2371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 617671 T2371 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 617672 T2371 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 617672 T2371 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 617672 T2371 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/control/data/ [junit4] 2> 617672 T2371 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@48e57671 [junit4] 2> 617673 T2371 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/control/data [junit4] 2> 617673 T2371 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/control/data/index/ [junit4] 2> 617674 T2371 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 617674 T2371 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/control/data/index [junit4] 2> 617675 T2371 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 617676 T2371 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@99289ed lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@687cb5ae),segFN=segments_1,generation=1} [junit4] 2> 617676 T2371 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 617682 T2371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 617683 T2371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 617684 T2371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 617684 T2371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 617685 T2371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 617685 T2371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 617685 T2371 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 617686 T2371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 617686 T2371 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 617687 T2371 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 617687 T2371 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 617688 T2371 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 617689 T2371 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 617689 T2371 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 617690 T2371 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 617690 T2371 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 617691 T2371 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 617691 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 617695 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 617695 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 617695 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 617696 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 617696 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 617696 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 617697 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 617697 T2371 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 617697 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 617698 T2371 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 617704 T2371 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 617706 T2371 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 617707 T2371 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 617708 T2371 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 617712 T2371 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 617713 T2371 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 617713 T2371 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=39.0341796875, floorSegmentMB=1.5908203125, forceMergeDeletesPctAllowed=3.320889329101049, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35446127003369987 [junit4] 2> 617715 T2371 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@99289ed lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@687cb5ae),segFN=segments_1,generation=1} [junit4] 2> 617715 T2371 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 617716 T2371 oass.SolrIndexSearcher.<init> Opening Searcher@7e6783e6[collection1] main [junit4] 2> 617717 T2371 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 617720 T2371 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 617720 T2371 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 617722 T2371 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 617722 T2371 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 617722 T2371 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 617725 T2371 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 617726 T2371 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 617727 T2371 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 617738 T2371 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 617738 T2371 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 617738 T2371 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 617738 T2371 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 617739 T2371 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 617739 T2372 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e6783e6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 617741 T2375 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:57057 collection:control_collection shard:shard1 [junit4] 2> 617741 T2341 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 617742 T2341 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 617743 T2375 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 617743 T2341 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 617745 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 617750 T2378 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2562c464 name:ZooKeeperConnection Watcher:127.0.0.1:57054/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 617750 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 617751 T2341 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 617753 T2341 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 617759 T2375 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 617765 T2341 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 617765 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 617771 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} [junit4] 2> 617772 T2375 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 617772 T2375 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C320 name=collection1 org.apache.solr.core.SolrCore@3aa03985 url=http://127.0.0.1:57057/collection1 node=127.0.0.1:57057_ C320_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:57057, node_name=127.0.0.1:57057_, state=down} [junit4] 2> 617772 T2375 C320 P57057 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:57057/collection1/ [junit4] 2> 617773 T2375 C320 P57057 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 617773 T2375 C320 P57057 oasc.SyncStrategy.syncToMe http://127.0.0.1:57057/collection1/ has no replicas [junit4] 2> 617773 T2375 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:57057/collection1/ shard1 [junit4] 2> 617773 T2375 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 617807 T2367 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> 617807 T2378 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> 617839 T2368 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":"http://127.0.0.1:57057";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} [junit4] 2> 617843 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 617953 T2367 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> 617953 T2378 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> 618003 T2375 oasc.ZkController.register We are http://127.0.0.1:57057/collection1/ and leader is http://127.0.0.1:57057/collection1/ [junit4] 2> 618004 T2375 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:57057 [junit4] 2> 618004 T2375 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 618004 T2375 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 618004 T2375 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 618007 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 618013 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:57057";, [junit4] 2> "node_name":"127.0.0.1:57057_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 618122 T2378 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> 618123 T2367 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> 619516 T2341 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 619518 T2341 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 619523 T2341 oejs.AbstractConnector.doStart Started [email protected]:57061 [junit4] 2> 619524 T2341 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 619524 T2341 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 619525 T2341 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003 [junit4] 2> 619525 T2341 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/' [junit4] 2> 619561 T2341 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/solr.xml [junit4] 2> 619587 T2341 oasc.CoreContainer.<init> New CoreContainer 2146435704 [junit4] 2> 619588 T2341 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/] [junit4] 2> 619589 T2341 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 619590 T2341 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 619591 T2341 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 619591 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 619592 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 619593 T2341 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 619593 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 619593 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 619594 T2341 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 619595 T2341 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 619595 T2341 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 619596 T2341 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 619597 T2341 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 619597 T2341 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 619598 T2341 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57054/solr [junit4] 2> 619599 T2341 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 619600 T2341 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 619602 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 619607 T2390 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7b8ec1bb name:ZooKeeperConnection Watcher:127.0.0.1:57054 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 619608 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 619609 T2341 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 619615 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 619618 T2393 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2893884a name:ZooKeeperConnection Watcher:127.0.0.1:57054/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 619618 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 619636 T2341 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 620650 T2341 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57061_ [junit4] 2> 620653 T2341 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57061_ [junit4] 2> 620673 T2341 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 620683 T2394 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 620683 T2394 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 620685 T2394 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 620685 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 620696 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:57061";, [junit4] 2> "node_name":"127.0.0.1:57061_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 620697 T2368 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 620697 T2368 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 1 [junit4] 2> 620697 T2368 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 620704 T2367 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 620705 T2393 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 620705 T2378 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 621686 T2394 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 621686 T2394 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 621688 T2394 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 621688 T2394 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 621690 T2394 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 621691 T2394 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/collection1/' [junit4] 2> 621694 T2394 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 621695 T2394 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 621695 T2394 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 621732 T2394 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 621735 T2394 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 621750 T2394 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 621759 T2394 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 621765 T2394 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 621771 T2394 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 621873 T2394 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 621880 T2394 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 621881 T2394 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 621915 T2394 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 621920 T2394 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 621925 T2394 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 621929 T2394 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 621931 T2394 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 621931 T2394 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 621934 T2394 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 621935 T2394 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 621935 T2394 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 621935 T2394 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 621935 T2394 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty1/ [junit4] 2> 621936 T2394 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@48e57671 [junit4] 2> 621937 T2394 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty1 [junit4] 2> 621937 T2394 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty1/index/ [junit4] 2> 621937 T2394 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 621938 T2394 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty1/index [junit4] 2> 621938 T2394 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 621939 T2394 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@58376b05 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20e52b45),segFN=segments_1,generation=1} [junit4] 2> 621939 T2394 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 621946 T2394 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 621947 T2394 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 621947 T2394 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 621948 T2394 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 621948 T2394 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 621948 T2394 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 621949 T2394 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 621949 T2394 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 621949 T2394 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 621950 T2394 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 621951 T2394 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 621952 T2394 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 621952 T2394 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 621953 T2394 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 621955 T2394 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 621957 T2394 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 621958 T2394 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 621959 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 621963 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 621963 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 621964 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 621965 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 621966 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 621968 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 621969 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 621970 T2394 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 621972 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 621973 T2394 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 621984 T2394 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 621987 T2394 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 621990 T2394 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 621991 T2394 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 621994 T2394 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 621994 T2394 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 621995 T2394 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=39.0341796875, floorSegmentMB=1.5908203125, forceMergeDeletesPctAllowed=3.320889329101049, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35446127003369987 [junit4] 2> 621997 T2394 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@58376b05 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20e52b45),segFN=segments_1,generation=1} [junit4] 2> 621997 T2394 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 621997 T2394 oass.SolrIndexSearcher.<init> Opening Searcher@495f0fb1[collection1] main [junit4] 2> 621998 T2394 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 622000 T2394 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 622000 T2394 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 622001 T2394 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 622002 T2394 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 622002 T2394 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 622005 T2394 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 622005 T2394 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 622005 T2394 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 622006 T2394 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 622006 T2394 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 622007 T2395 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@495f0fb1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 622007 T2394 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 622010 T2398 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:57061 collection:collection1 shard:shard2 [junit4] 2> 622011 T2341 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 622012 T2341 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 622012 T2398 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 622023 T2398 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 622027 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 622029 T2398 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 622030 T2398 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C321 name=collection1 org.apache.solr.core.SolrCore@15685133 url=http://127.0.0.1:57061/collection1 node=127.0.0.1:57061_ C321_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:57061, node_name=127.0.0.1:57061_, state=down} [junit4] 2> 622030 T2398 C321 P57061 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:57061/collection1/ [junit4] 2> 622030 T2398 C321 P57061 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 622031 T2398 C321 P57061 oasc.SyncStrategy.syncToMe http://127.0.0.1:57061/collection1/ has no replicas [junit4] 2> 622032 T2398 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:57061/collection1/ shard2 [junit4] 2> 622032 T2398 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 622046 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} [junit4] 2> 622057 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:57061";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} [junit4] 2> 622062 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 622165 T2378 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 622166 T2393 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 622167 T2367 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 622206 T2398 oasc.ZkController.register We are http://127.0.0.1:57061/collection1/ and leader is http://127.0.0.1:57061/collection1/ [junit4] 2> 622206 T2398 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:57061 [junit4] 2> 622207 T2398 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 622207 T2398 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 622207 T2398 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 622213 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 622221 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:57061";, [junit4] 2> "node_name":"127.0.0.1:57061_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 622330 T2393 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 622331 T2378 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 622331 T2367 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 623746 T2341 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 623747 T2341 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 623756 T2341 oejs.AbstractConnector.doStart Started [email protected]:57064 [junit4] 2> 623757 T2341 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 623757 T2341 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 623758 T2341 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004 [junit4] 2> 623758 T2341 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/' [junit4] 2> 623799 T2341 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/solr.xml [junit4] 2> 623816 T2341 oasc.CoreContainer.<init> New CoreContainer 394279645 [junit4] 2> 623816 T2341 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/] [junit4] 2> 623817 T2341 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 623817 T2341 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 623818 T2341 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 623818 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 623818 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 623819 T2341 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 623819 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 623819 T2341 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 623819 T2341 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 623820 T2341 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 623820 T2341 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 623821 T2341 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 623821 T2341 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 623821 T2341 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 623821 T2341 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57054/solr [junit4] 2> 623822 T2341 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 623822 T2341 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 623823 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 623828 T2410 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@61bca8b name:ZooKeeperConnection Watcher:127.0.0.1:57054 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 623829 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 623830 T2341 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 623838 T2341 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 623843 T2413 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77b85a00 name:ZooKeeperConnection Watcher:127.0.0.1:57054/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 623843 T2341 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 623859 T2341 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 624871 T2341 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57064_ [junit4] 2> 624875 T2341 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57064_ [junit4] 2> 624895 T2341 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 624908 T2414 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 624908 T2414 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 624910 T2414 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 624910 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 624922 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:57064";, [junit4] 2> "node_name":"127.0.0.1:57064_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 624922 T2368 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 624923 T2368 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 624931 T2367 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 624931 T2413 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 624932 T2393 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 624933 T2378 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 625911 T2414 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 625912 T2414 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 625914 T2414 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 625915 T2414 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 625923 T2414 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 625923 T2414 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/collection1/' [junit4] 2> 625924 T2414 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 625924 T2414 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 625925 T2414 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 625968 T2414 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 625975 T2414 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 626009 T2414 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 626023 T2414 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 626028 T2414 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 626035 T2414 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 626169 T2414 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 626181 T2414 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 626182 T2414 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 626218 T2414 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 626225 T2414 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 626231 T2414 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 626235 T2414 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 626236 T2414 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 626236 T2414 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 626240 T2414 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 626240 T2414 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 626240 T2414 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 626240 T2414 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 626241 T2414 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty2/ [junit4] 2> 626241 T2414 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@48e57671 [junit4] 2> 626242 T2414 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty2 [junit4] 2> 626242 T2414 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty2/index/ [junit4] 2> 626242 T2414 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 626243 T2414 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty2/index [junit4] 2> 626243 T2414 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 626244 T2414 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3152be4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4ccb0fcc),segFN=segments_1,generation=1} [junit4] 2> 626245 T2414 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 626252 T2414 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 626252 T2414 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 626253 T2414 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 626253 T2414 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 626254 T2414 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 626255 T2414 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 626256 T2414 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 626257 T2414 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 626257 T2414 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 626258 T2414 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 626259 T2414 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 626259 T2414 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 626260 T2414 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 626260 T2414 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 626261 T2414 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 626262 T2414 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 626262 T2414 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 626263 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 626266 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 626266 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 626267 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 626267 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 626267 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 626268 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 626268 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 626269 T2414 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 626269 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 626269 T2414 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 626276 T2414 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 626277 T2414 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 626279 T2414 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 626281 T2414 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 626284 T2414 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 626284 T2414 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 626286 T2414 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=39.0341796875, floorSegmentMB=1.5908203125, forceMergeDeletesPctAllowed=3.320889329101049, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35446127003369987 [junit4] 2> 626287 T2414 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3152be4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4ccb0fcc),segFN=segments_1,generation=1} [junit4] 2> 626288 T2414 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 626288 T2414 oass.SolrIndexSearcher.<init> Opening Searcher@18103f6f[collection1] main [junit4] 2> 626288 T2414 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 626293 T2414 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 626294 T2414 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 626296 T2414 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 626296 T2414 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 626297 T2414 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 626300 T2414 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 626301 T2414 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 626301 T2414 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 626302 T2414 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 626302 T2414 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 626304 T2414 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 626306 T2415 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18103f6f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 626310 T2418 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:57064 collection:collection1 shard:shard1 [junit4] 2> 626313 T2418 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 626319 T2341 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 626319 T2341 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 626328 T2418 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 626338 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 626346 T2418 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 626346 T2418 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C322 name=collection1 org.apache.solr.core.SolrCore@218fae2d url=http://127.0.0.1:57064/collection1 node=127.0.0.1:57064_ C322_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:57064, node_name=127.0.0.1:57064_, state=down} [junit4] 2> 626347 T2418 C322 P57064 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:57064/collection1/ [junit4] 2> 626348 T2418 C322 P57064 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 626349 T2418 C322 P57064 oasc.SyncStrategy.syncToMe http://127.0.0.1:57064/collection1/ has no replicas [junit4] 2> 626349 T2418 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:57064/collection1/ shard1 [junit4] 2> 626350 T2418 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 626352 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1"} [junit4] 2> 626369 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 626372 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:57064";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} [junit4] 2> 626483 T2413 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 626484 T2367 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 626485 T2393 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 626485 T2378 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 626527 T2418 oasc.ZkController.register We are http://127.0.0.1:57064/collection1/ and leader is http://127.0.0.1:57064/collection1/ [junit4] 2> 626527 T2418 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:57064 [junit4] 2> 626528 T2418 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 626528 T2418 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 626528 T2418 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 626531 T2367 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 626583 T2368 oasc.Overseer$ClusterStateUpdater.processMessage processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:57064";, [junit4] 2> "node_name":"127.0.0.1:57064_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 626719 T2413 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 626719 T2378 oascc.ZkSt [...truncated too long message...] .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> 659903 T2341 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 659903 T2341 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty4/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty4/index;done=false>>] [junit4] 2> 659903 T2341 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty4/index [junit4] 2> 659905 T2341 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty4 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty4;done=false>>] [junit4] 2> 659905 T2341 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001/tempDir-001/jetty4 [junit4] 2> 659906 T2341 oasc.Overseer.close Overseer (id=92976446105387020-127.0.0.1:57071_-n_0000000004) closing [junit4] 2> 659906 T2482 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:57071_ [junit4] 2> 661410 T2469 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 661429 T2341 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> 666412 T2341 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 666418 T2341 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57054 57054 [junit4] 2> 666431 T2342 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57054 57054 [junit4] 2> 666681 T2342 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/live_nodes [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestModifyConfFiles -Dtests.method=testDistribSearch -Dtests.seed=EE18DF1D8D0FA780 -Dtests.slow=true -Dtests.locale=mk_MK -Dtests.timezone=SystemV/CST6CDT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 53.0s | TestModifyConfFiles.testDistribSearch <<< [junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[Error from server at http://127.0.0.1:57071/collection1: ]No file name specifi...> but was:<[]No file name specifi...> [junit4] > at __randomizedtesting.SeedInfo.seed([EE18DF1D8D0FA780:6FFE5105FA50C7BC]: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> 666712 T2341 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestModifyConfFiles-EE18DF1D8D0FA780-001 [junit4] 2> 53027 T2340 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=DefaultSimilarity, locale=mk_MK, timezone=SystemV/CST6CDT [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_20 (64-bit)/cpus=2,threads=1,free=101577056,total=291504128 [junit4] 2> NOTE: All tests run in this JVM: [HdfsRecoveryZkTest, TestCollectionAPI, EchoParamsTest, OverseerRolesTest, TestPhraseSuggestions, CacheHeaderTest, TestManagedStopFilterFactory, TestPostingsSolrHighlighter, TestXIncludeConfig, ClusterStateUpdateTest, SolrXmlInZkTest, BadIndexSchemaTest, HdfsLockFactoryTest, SuggesterTSTTest, BadCopyFieldTest, DocumentBuilderTest, AddSchemaFieldsUpdateProcessorFactoryTest, ReturnFieldsTest, IndexSchemaTest, TestReplicaProperties, SortByFunctionTest, LukeRequestHandlerTest, DirectSolrConnectionTest, PrimUtilsTest, SolrRequestParserTest, StressHdfsTest, SharedFSAutoReplicaFailoverUtilsTest, WordBreakSolrSpellCheckerTest, TestNonNRTOpen, InfoHandlerTest, TestMergePolicyConfig, BasicDistributedZkTest, TestExceedMaxTermLength, HdfsWriteToMultipleCollectionsTest, SpellCheckComponentTest, MultiThreadedOCPTest, OverriddenZkACLAndCredentialsProvidersTest, VMParamsZkACLAndCredentialsProvidersTest, TestRandomMergePolicy, SpatialFilterTest, ScriptEngineTest, DocValuesTest, TestModifyConfFiles] [junit4] Completed in 53.32s, 1 test, 1 failure <<< FAILURES! [...truncated 1276 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:529: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:477: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:190: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:508: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1363: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:970: There were test failures: 450 suites, 1845 tests, 1 failure, 36 ignored (21 assumptions) Total time: 157 minutes 13 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC (asserts: true) Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
