Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1951/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC (asserts: true)
1 tests failed. REGRESSION: org.apache.solr.cloud.BasicDistributedZkTest.testDistribSearch Error Message: commitWithin did not work on node: http://127.0.0.1:51216/collection1 expected:<68> but was:<67> Stack Trace: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:51216/collection1 expected:<68> but was:<67> at __randomizedtesting.SeedInfo.seed([FA1FFC092382FF19:7BF9721154DD9F25]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:345) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.GeneratedMethodAccessor63.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11264 lines...] [junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/init-core-data-001 [junit4] 2> 3222757 T8209 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 3222767 T8209 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 3222769 T8209 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3222770 T8210 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3222871 T8209 oasc.ZkTestServer.run start zk server on port:51205 [junit4] 2> 3222873 T8209 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3222877 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3222886 T8217 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@462db69a name:ZooKeeperConnection Watcher:127.0.0.1:51205 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3222887 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3222887 T8209 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3222888 T8209 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3222895 T8211 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x149d60af2490000, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3222897 T8209 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3222901 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3222907 T8220 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5003f263 name:ZooKeeperConnection Watcher:127.0.0.1:51205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3222907 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3222908 T8209 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3222908 T8209 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 3222914 T8209 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 3222918 T8209 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 3222922 T8209 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 3222927 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3222927 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3222933 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 3222934 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 3222938 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3222939 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3222943 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3222944 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 3222949 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3222950 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 3222955 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 3222955 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 3222963 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3222964 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3222969 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3222970 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3222976 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3222977 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3222983 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3222983 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3222989 T8209 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3222990 T8209 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 3224907 T8209 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3224914 T8209 oejs.AbstractConnector.doStart Started [email protected]:51208 [junit4] 2> 3224916 T8209 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3224916 T8209 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3224916 T8209 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002 [junit4] 2> 3224917 T8209 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/' [junit4] 2> 3224959 T8209 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/solr.xml [junit4] 2> 3225008 T8209 oasc.CoreContainer.<init> New CoreContainer 1783541615 [junit4] 2> 3225008 T8209 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/] [junit4] 2> 3225009 T8209 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3225010 T8209 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3225011 T8209 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3225012 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3225012 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3225013 T8209 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3225013 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3225013 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3225014 T8209 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3225014 T8209 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3225014 T8209 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3225016 T8209 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3225016 T8209 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3225016 T8209 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3225017 T8209 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51205/solr [junit4] 2> 3225017 T8209 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3225018 T8209 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3225020 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3225027 T8232 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a134f6b name:ZooKeeperConnection Watcher:127.0.0.1:51205 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3225027 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3225029 T8209 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3225038 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3225040 T8235 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@173747d1 name:ZooKeeperConnection Watcher:127.0.0.1:51205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3225040 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3225044 T8209 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 3225054 T8209 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 3225061 T8209 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 3225066 T8209 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 3225073 T8209 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 3225081 T8209 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 3225083 T8209 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51208_ [junit4] 2> 3225084 T8209 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51208_ [junit4] 2> 3225088 T8209 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 3225094 T8209 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 3225099 T8209 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3225102 T8209 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:51208_ [junit4] 2> 3225103 T8209 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 3225107 T8209 oasc.Overseer.start Overseer (id=92840609840693251-127.0.0.1:51208_-n_0000000000) starting [junit4] 2> 3225113 T8209 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 3225133 T8209 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3225134 T8237 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 3225139 T8209 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 3225148 T8209 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 3225152 T8209 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3225164 T8236 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 3225165 T8239 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 3225166 T8239 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3225167 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3225167 T8239 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3225174 T8236 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51208";, [junit4] 2> "node_name":"127.0.0.1:51208_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3225174 T8236 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 3225174 T8236 oasc.Overseer$ClusterStateUpdater.createCollection state version control_collection 1 [junit4] 2> 3225176 T8236 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3225181 T8235 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> 3226169 T8239 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3226170 T8239 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 3226171 T8239 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3226172 T8239 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3226174 T8239 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3226175 T8239 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/collection1/' [junit4] 2> 3226176 T8239 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 3226177 T8239 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 3226177 T8239 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 3226206 T8239 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3226212 T8239 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3226234 T8239 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3226250 T8239 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3226254 T8239 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3226258 T8239 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3226411 T8239 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3226424 T8239 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3226425 T8239 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3226459 T8239 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3226463 T8239 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3226467 T8239 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3226470 T8239 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3226470 T8239 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3226470 T8239 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3226473 T8239 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3226473 T8239 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3226473 T8239 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3226473 T8239 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3226473 T8239 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/control/data/ [junit4] 2> 3226474 T8239 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1234cc7c [junit4] 2> 3226474 T8239 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/control/data [junit4] 2> 3226475 T8239 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/control/data/index/ [junit4] 2> 3226475 T8239 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 3226475 T8239 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/control/data/index [junit4] 2> 3226476 T8239 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.419929685534856] [junit4] 2> 3226476 T8239 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ac9b953 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cf49846),segFN=segments_1,generation=1} [junit4] 2> 3226477 T8239 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3226480 T8239 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3226480 T8239 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3226481 T8239 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3226481 T8239 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3226482 T8239 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3226482 T8239 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3226483 T8239 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3226483 T8239 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3226483 T8239 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3226484 T8239 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3226485 T8239 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3226485 T8239 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3226485 T8239 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3226486 T8239 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3226487 T8239 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3226488 T8239 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3226488 T8239 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3226489 T8239 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3226489 T8239 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3226489 T8239 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3226490 T8239 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3226500 T8239 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3226502 T8239 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3226503 T8239 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3226504 T8239 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3226507 T8239 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3226507 T8239 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3226508 T8239 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 3226508 T8239 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ac9b953 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cf49846),segFN=segments_1,generation=1} [junit4] 2> 3226508 T8239 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3226509 T8239 oass.SolrIndexSearcher.<init> Opening Searcher@290da964[collection1] main [junit4] 2> 3226509 T8239 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3226512 T8239 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3226512 T8239 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3226514 T8239 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3226514 T8239 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3226515 T8239 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3226516 T8239 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3226517 T8239 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3226518 T8239 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 3226527 T8239 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 3226527 T8239 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3226527 T8239 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3226530 T8239 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3226530 T8240 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@290da964[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3226532 T8243 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51208 collection:control_collection shard:shard1 [junit4] 2> 3226533 T8209 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3226533 T8209 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3226534 T8209 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3226539 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3226540 T8246 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f2007a7 name:ZooKeeperConnection Watcher:127.0.0.1:51205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3226541 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3226541 T8209 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3226543 T8243 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 3226546 T8209 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3226557 T8209 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3226557 T8209 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 3226558 T8209 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3226557 T8243 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3226560 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3226564 T8249 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1686a54d name:ZooKeeperConnection Watcher:127.0.0.1:51205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3226565 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3226565 T8209 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3226566 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3226567 T8243 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3226568 T8243 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4763 name=collection1 org.apache.solr.core.SolrCore@1a9a37a5 url=http://127.0.0.1:51208/collection1 node=127.0.0.1:51208_ C4763_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:51208, node_name=127.0.0.1:51208_, state=down} [junit4] 2> 3226568 T8243 C4763 P51208 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51208/collection1/ [junit4] 2> 3226569 T8243 C4763 P51208 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3226569 T8243 C4763 P51208 oasc.SyncStrategy.syncToMe http://127.0.0.1:51208/collection1/ has no replicas [junit4] 2> 3226569 T8243 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51208/collection1/ shard1 [junit4] 2> 3226570 T8243 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3226574 T8211 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x149d60af2490005, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3226595 T8236 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1 [junit4] 2> 3226596 T8236 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 3226596 T8236 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 3226601 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3226603 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 3226608 T8235 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> 3226608 T8246 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> 3226643 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3226646 T8246 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> 3226648 T8235 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> 3226695 T8243 oasc.ZkController.register We are http://127.0.0.1:51208/collection1/ and leader is http://127.0.0.1:51208/collection1/ [junit4] 2> 3226695 T8243 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51208 [junit4] 2> 3226695 T8243 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3226696 T8243 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 3226696 T8243 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3226699 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3226712 T8236 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51208";, [junit4] 2> "node_name":"127.0.0.1:51208_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3226819 T8246 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> 3226819 T8235 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> 3228506 T8209 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 3228507 T8209 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3228510 T8209 oejs.AbstractConnector.doStart Started [email protected]:51213 [junit4] 2> 3228512 T8209 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3228512 T8209 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3228513 T8209 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003 [junit4] 2> 3228513 T8209 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/' [junit4] 2> 3228575 T8209 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/solr.xml [junit4] 2> 3228618 T8209 oasc.CoreContainer.<init> New CoreContainer 167997258 [junit4] 2> 3228618 T8209 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/] [junit4] 2> 3228619 T8209 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3228619 T8209 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3228620 T8209 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3228621 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3228622 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3228622 T8209 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3228623 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3228623 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3228624 T8209 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3228624 T8209 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3228624 T8209 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3228625 T8209 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3228625 T8209 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3228626 T8209 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3228626 T8209 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51205/solr [junit4] 2> 3228626 T8209 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3228627 T8209 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3228628 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3228632 T8261 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@207bcafa name:ZooKeeperConnection Watcher:127.0.0.1:51205 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3228633 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3228633 T8209 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3228638 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3228641 T8264 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4426426e name:ZooKeeperConnection Watcher:127.0.0.1:51205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3228641 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3228661 T8209 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3229678 T8209 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51213_ [junit4] 2> 3229682 T8209 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51213_ [junit4] 2> 3229702 T8209 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3229721 T8265 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3229722 T8265 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3229726 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3229728 T8265 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 3229728 T8265 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 3229730 T8265 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 3229735 T8265 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 3229735 T8265 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3229752 T8236 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51213";, [junit4] 2> "node_name":"127.0.0.1:51213_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3229763 T8236 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 3229766 T8236 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 3229782 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3229784 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3229786 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3229788 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 3230737 T8265 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3230737 T8265 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3230740 T8265 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3230741 T8265 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3230749 T8265 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3230749 T8265 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/collection1/' [junit4] 2> 3230753 T8265 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 3230754 T8265 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 3230755 T8265 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 3230791 T8265 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3230795 T8265 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3230814 T8265 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3230831 T8265 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3230834 T8265 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3230842 T8265 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3231000 T8265 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3231007 T8265 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3231008 T8265 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3231027 T8265 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3231030 T8265 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3231035 T8265 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3231036 T8265 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3231036 T8265 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3231037 T8265 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3231038 T8265 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3231038 T8265 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3231039 T8265 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3231039 T8265 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3231039 T8265 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty1/ [junit4] 2> 3231039 T8265 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1234cc7c [junit4] 2> 3231040 T8265 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty1 [junit4] 2> 3231040 T8265 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty1/index/ [junit4] 2> 3231041 T8265 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 3231041 T8265 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty1/index [junit4] 2> 3231042 T8265 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.419929685534856] [junit4] 2> 3231042 T8265 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68e74b55 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@785d5143),segFN=segments_1,generation=1} [junit4] 2> 3231043 T8265 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3231046 T8265 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3231047 T8265 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3231048 T8265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3231048 T8265 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3231048 T8265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3231049 T8265 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3231049 T8265 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3231049 T8265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3231050 T8265 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3231050 T8265 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3231051 T8265 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3231052 T8265 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3231052 T8265 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3231053 T8265 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3231053 T8265 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3231053 T8265 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3231054 T8265 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3231055 T8265 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3231055 T8265 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3231056 T8265 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3231056 T8265 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3231065 T8265 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3231067 T8265 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3231068 T8265 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3231069 T8265 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3231073 T8265 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3231073 T8265 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3231074 T8265 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 3231075 T8265 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68e74b55 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@785d5143),segFN=segments_1,generation=1} [junit4] 2> 3231076 T8265 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3231076 T8265 oass.SolrIndexSearcher.<init> Opening Searcher@30536041[collection1] main [junit4] 2> 3231076 T8265 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3231079 T8265 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3231079 T8265 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3231080 T8265 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3231080 T8265 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3231080 T8265 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3231083 T8265 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3231083 T8265 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3231083 T8265 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3231083 T8265 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3231087 T8265 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3231088 T8266 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30536041[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3231091 T8269 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51213 collection:collection1 shard:shard2 [junit4] 2> 3231096 T8209 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3231096 T8209 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3231113 T8269 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 3231195 T8269 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 3231210 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3231211 T8269 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3231212 T8269 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4764 name=collection1 org.apache.solr.core.SolrCore@58aa5416 url=http://127.0.0.1:51213/collection1 node=127.0.0.1:51213_ C4764_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:51213, node_name=127.0.0.1:51213_, state=down} [junit4] 2> 3231212 T8269 C4764 P51213 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51213/collection1/ [junit4] 2> 3231212 T8269 C4764 P51213 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3231212 T8269 C4764 P51213 oasc.SyncStrategy.syncToMe http://127.0.0.1:51213/collection1/ has no replicas [junit4] 2> 3231212 T8269 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51213/collection1/ shard2 [junit4] 2> 3231213 T8269 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 3231251 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3231253 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3231254 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 3231287 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3231289 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3231291 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3231292 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 3231340 T8269 oasc.ZkController.register We are http://127.0.0.1:51213/collection1/ and leader is http://127.0.0.1:51213/collection1/ [junit4] 2> 3231342 T8269 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51213 [junit4] 2> 3231342 T8269 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3231342 T8269 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3231343 T8269 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3231346 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3231361 T8236 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51213";, [junit4] 2> "node_name":"127.0.0.1:51213_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3231361 T8269 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 3231379 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3231380 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3231382 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 3233153 T8209 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 3233154 T8209 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3233160 T8209 oejs.AbstractConnector.doStart Started [email protected]:51216 [junit4] 2> 3233162 T8209 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3233163 T8209 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3233163 T8209 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004 [junit4] 2> 3233165 T8209 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/' [junit4] 2> 3233207 T8209 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/solr.xml [junit4] 2> 3233250 T8209 oasc.CoreContainer.<init> New CoreContainer 519044381 [junit4] 2> 3233251 T8209 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/] [junit4] 2> 3233252 T8209 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3233253 T8209 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3233253 T8209 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3233255 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3233256 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3233256 T8209 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3233257 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3233257 T8209 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3233258 T8209 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3233258 T8209 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3233258 T8209 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3233259 T8209 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3233259 T8209 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3233260 T8209 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3233260 T8209 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51205/solr [junit4] 2> 3233260 T8209 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3233261 T8209 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3233263 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3233267 T8281 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d194630 name:ZooKeeperConnection Watcher:127.0.0.1:51205 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3233268 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3233268 T8209 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3233273 T8209 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3233276 T8284 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54dc0be1 name:ZooKeeperConnection Watcher:127.0.0.1:51205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3233276 T8209 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3233295 T8209 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3234317 T8209 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51216_ [junit4] 2> 3234322 T8209 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51216_ [junit4] 2> 3234343 T8209 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3234360 T8285 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3234360 T8285 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3234365 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3234375 T8285 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 3234375 T8285 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 3234377 T8285 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 3234381 T8236 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51216";, [junit4] 2> "node_name":"127.0.0.1:51216_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3234384 T8285 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 3234384 T8285 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3234395 T8236 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 3234398 T8236 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3234417 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3234419 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3234419 T8284 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3234421 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 3234423 T8284 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 3235387 T8285 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3235387 T8285 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3235389 T8285 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3235390 T8285 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3235394 T8285 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3235394 T8285 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/collection1/' [junit4] 2> 3235397 T8285 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 3235398 T8285 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 3235399 T8285 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 3235433 T8285 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3235437 T8285 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3235451 T8285 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3235464 T8285 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3235470 T8285 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3235481 T8285 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3235649 T8285 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3235660 T8285 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3235662 T8285 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3235693 T8285 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3235698 T8285 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3235703 T8285 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3235709 T8285 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3235710 T8285 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3235710 T8285 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3235714 T8285 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3235714 T8285 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3235714 T8285 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3235715 T8285 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3235715 T8285 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty2/ [junit4] 2> 3235715 T8285 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1234cc7c [junit4] 2> 3235716 T8285 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty2 [junit4] 2> 3235716 T8285 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty2/index/ [junit4] 2> 3235717 T8285 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 3235717 T8285 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001/tempDir-001/jetty2/index [junit4] 2> 3235717 T8285 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.419929685534856] [junit4] 2> 3235718 T8285 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60435cef lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c2846ed),segFN=segments_1,generation=1} [junit4] 2> 3235719 T8285 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3235724 T8285 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3235725 T8285 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3235725 T8285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3235725 T8285 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3235725 T8285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3235726 T8285 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3235726 T8285 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3235727 T8285 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3235727 T8285 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3235728 T8285 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3235728 T8285 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3235729 T8285 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3235730 T8285 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3235731 T8285 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3235732 T8285 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3235732 T8285 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3235732 T8285 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3235733 T8285 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3235734 T8285 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3235735 T8285 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3235736 T8285 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3235749 T8285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3235753 T8285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3235755 T8285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3235758 T8285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3235762 T8285 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3235762 T8285 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3235764 T8285 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 3235765 T8285 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60435cef lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c2846ed),segFN=segments_1,generation=1} [junit4] 2> 3235765 T8285 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3235765 T8285 oass.SolrIndexSearcher.<init> Opening Searcher@2695d829[collection1] main [junit4] 2> 3235766 T8285 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3235770 T8285 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3235771 T8285 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3235775 T8285 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3235776 T8285 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3235776 T8285 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3235780 T8285 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3235780 T8285 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3235780 T8285 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3235780 T8285 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3235788 T8285 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3235788 T8286 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2695d829[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3235791 T8289 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51216 collection:collection1 shard:shard1 [junit4] 2> 3235792 T8209 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3235794 T8209 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3235795 T8289 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 3235806 T8289 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3235817 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3235819 T8289 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3235819 T8289 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4765 name=collection1 org.apache.solr.core.SolrCore@6cec22e0 url=http://127.0.0.1:51216/collection1 node=127.0.0.1:51216_ C4765_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:51216, node_name=127.0.0.1:51216_, state=down} [junit4] 2> 3235819 T8289 C4765 P51216 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51216/collection1/ [junit4] 2> 3235819 T8289 C4765 P51216 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3235820 T8289 C4765 P51216 oasc.SyncStrategy.syncToMe http://127.0.0.1:51216/collection1/ has no replicas [junit4] 2> 3235820 T8289 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51216/collection1/ shard1 [junit4] 2> 3235820 T8289 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 3235845 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3235851 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3235858 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 6 [junit4] 2> 3235859 T8284 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3235862 T8284 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 6 [junit4] 2> 3235876 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3235877 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3235879 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3235881 T8284 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3235882 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 7 [junit4] 2> 3235884 T8284 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 7 [junit4] 2> 3235889 T8289 oasc.ZkController.register We are http://127.0.0.1:51216/collection1/ and leader is http://127.0.0.1:51216/collection1/ [junit4] 2> 3235889 T8289 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51216 [junit4] 2> 3235890 T8289 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3235890 T8289 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3235890 T8289 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3235893 T8235 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3235903 T8289 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 7 [junit4] 2> 3235906 T8236 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51216";, [junit4] 2> "node_name":"127.0.0.1:51216_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3235920 T8236 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3235921 T8264 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3235922 T8284 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3235923 T8264 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 8 [junit4] 2> 3235925 T8284 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 8 [junit4] 2> 3237740 T8209 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [...truncated too long message...] 2> 3282437 T8361 oascc.ConnectionManager.process zkClient has disconnected [junit4] 2> 3282581 T8210 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 7 /solr/aliases.json [junit4] 2> 7 /solr/clusterstate.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 7 /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=BasicDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=FA1FFC092382FF19 -Dtests.slow=true -Dtests.locale=nl_BE -Dtests.timezone=Asia/Taipei -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 60.1s | BasicDistributedZkTest.testDistribSearch <<< [junit4] > Throwable #1: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:51216/collection1 expected:<68> but was:<67> [junit4] > at __randomizedtesting.SeedInfo.seed([FA1FFC092382FF19:7BF9721154DD9F25]:0) [junit4] > at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:345) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 3282876 T8209 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest-FA1FFC092382FF19-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapFixedInterval), n_l1=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=Asserting), intDefault=PostingsFormat(name=LuceneVarGapFixedInterval), n_td1=PostingsFormat(name=LuceneVarGapFixedInterval), n_d1=PostingsFormat(name=Asserting), range_facet_l=PostingsFormat(name=Asserting), n_f1=PostingsFormat(name=LuceneVarGapFixedInterval), n_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), oddField_s=BlockTreeOrds(blocksize=128), n_tf1=PostingsFormat(name=Asserting), a_t=PostingsFormat(name=Asserting), id=BlockTreeOrds(blocksize=128), text=FST50, timestamp=PostingsFormat(name=Asserting), foo_i=PostingsFormat(name=LuceneVarGapFixedInterval), n_dt1=PostingsFormat(name=LuceneVarGapFixedInterval), a_i1=PostingsFormat(name=Asserting), n_ti1=BlockTreeOrds(blocksize=128), rnd_b=BlockTreeOrds(blocksize=128), _version_=PostingsFormat(name=LuceneVarGapFixedInterval), foo_b=FST50, n_tdt1=PostingsFormat(name=LuceneVarGapFixedInterval), foo_d=BlockTreeOrds(blocksize=128), foo_f=FST50}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=nl_BE, timezone=Asia/Taipei [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_20 (64-bit)/cpus=2,threads=1,free=125541432,total=378019840 [junit4] 2> NOTE: All tests run in this JVM: [TestManagedSchemaDynamicFieldResource, TestCSVResponseWriter, TestStressRecovery, SolrIndexConfigTest, TestSchemaNameResource, LoggingHandlerTest, TestDistribDocBasedVersion, UnloadDistributedZkTest, OpenCloseCoreStressTest, SimpleMLTQParserTest, DateFieldTest, SimplePostToolTest, TestBM25SimilarityFactory, CoreContainerCoreInitFailuresTest, AliasIntegrationTest, TestShortCircuitedRequests, CoreAdminCreateDiscoverTest, TestHighlightDedupGrouping, CacheHeaderTest, TestManagedSynonymFilterFactory, HdfsLockFactoryTest, TestDistributedSearch, CollectionsAPIDistributedZkTest, MBeansHandlerTest, TestRandomDVFaceting, DistributedFacetPivotSmallAdvancedTest, OutputWriterTest, TestCollationField, FullSolrCloudDistribCmdsTest, TestFieldResource, DocValuesTest, RankQueryTest, TestSimpleQParserPlugin, SuggesterFSTTest, ExternalCollectionsTest, TestReRankQParserPlugin, TriLevelCompositeIdRoutingTest, ResourceLoaderTest, ReturnFieldsTest, CurrencyFieldOpenExchangeTest, TestBulkSchemaAPI, TestRawResponseWriter, StressHdfsTest, HdfsSyncSliceTest, DistributedQueryElevationComponentTest, DistributedExpandComponentTest, TestRealTimeGet, FileUtilsTest, TestPostingsSolrHighlighter, TestStressReorder, LegacyHTMLStripCharFilterTest, MigrateRouteKeyTest, TestRestManager, TestAnalyzedSuggestions, TestUtils, TestPseudoReturnFields, HighlighterConfigTest, BlockCacheTest, DistributedQueueTest, TestDFRSimilarityFactory, DirectUpdateHandlerTest, EnumFieldTest, TestRecovery, TestCSVLoader, CopyFieldTest, TestBulkSchemaConcurrent, PrimUtilsTest, BasicZkTest, DocumentAnalysisRequestHandlerTest, DistributedQueryComponentCustomSortTest, DateRangeFieldTest, TestSolrXmlPersistor, XsltUpdateRequestHandlerTest, HdfsBasicDistributedZkTest, TestComponentsName, DocumentBuilderTest, TestXIncludeConfig, FieldAnalysisRequestHandlerTest, TestSuggestSpellingConverter, HdfsUnloadDistributedZkTest, TestJmxMonitoredMap, ExternalFileFieldSortTest, DistributedQueryComponentOptimizationTest, TestDynamicFieldResource, StatelessScriptUpdateProcessorFactoryTest, TestFiltering, BasicDistributedZk2Test, TestMultiCoreConfBootstrap, CursorPagingTest, NumericFieldsTest, UniqFieldsUpdateProcessorFactoryTest, DistributedFacetPivotLongTailTest, PreAnalyzedFieldTest, TestCollationFieldDocValues, TestIBSimilarityFactory, TestConfigOverlay, TestSolrIndexConfig, BadCopyFieldTest, TestNRTOpen, DOMUtilTest, AnalyticsMergeStrategyTest, TestRTGBase, TestOverriddenPrefixQueryForCustomFieldType, TestReloadAndDeleteDocs, TestPHPSerializedResponseWriter, ResponseLogComponentTest, TestAddFieldRealTimeGet, TestConfig, TestConfigReload, TestDistributedGrouping, DocExpirationUpdateProcessorFactoryTest, TestMergePolicyConfig, BinaryUpdateRequestHandlerTest, TestLuceneMatchVersion, TestZkChroot, PrimitiveFieldTypeTest, HighlighterMaxOffsetTest, SOLR749Test, SyncSliceTest, TestRandomMergePolicy, TestLMDirichletSimilarityFactory, TestSolrXml, TestReplicaProperties, XmlUpdateRequestHandlerTest, ExitableDirectoryReaderTest, TestQueryTypes, MultiThreadedOCPTest, OverseerTest, HdfsRecoveryZkTest, SchemaVersionSpecificBehaviorTest, VMParamsZkACLAndCredentialsProvidersTest, CircularListTest, TestCloudManagedSchema, DirectSolrSpellCheckerTest, TestSolrDeletionPolicy2, BasicFunctionalityTest, TestRemoteStreaming, SolrCoreCheckLockOnStartupTest, PolyFieldTest, TestSolr4Spatial2, AddBlockUpdateTest, JSONWriterTest, TestSolrConfigHandlerConcurrent, SystemInfoHandlerTest, DebugComponentTest, TestIndexingPerformance, TestDynamicFieldCollectionResource, TestManagedSchemaFieldTypeResource, UUIDUpdateProcessorFallbackTest, AtomicUpdatesTest, RegexBoostProcessorTest, AutoCommitTest, TestDefaultSimilarityFactory, StatsComponentTest, IndexBasedSpellCheckerTest, TestPivotHelperCode, TestNoOpRegenerator, TestCodecSupport, TestManagedSchema, TestPhraseSuggestions, TestFastLRUCache, ParsingFieldUpdateProcessorsTest, TestExpandComponent, TestWordDelimiterFilterFactory, PluginInfoTest, TestFileDictionaryLookup, SignatureUpdateProcessorFactoryTest, SolrTestCaseJ4Test, FileBasedSpellCheckerTest, TestSolrConfigHandler, HdfsBasicDistributedZk2Test, AnalysisAfterCoreReloadTest, RemoteQueryErrorTest, QueryParsingTest, TestCollectionAPI, TestStressLucene, PeerSyncTest, ConnectionManagerTest, OverseerStatusTest, TestNonDefinedSimilarityFactory, TermVectorComponentDistributedTest, TestSolrDynamicMBean, ZkControllerTest, TestSchemaVersionResource, TestSolrQueryParserDefaultOperatorResource, ClusterStateTest, RequiredFieldsTest, DistributedTermsComponentTest, TimeZoneUtilsTest, ClusterStateUpdateTest, BadIndexSchemaTest, HighlighterTest, TestValueSourceCache, TestFaceting, TestStressUserVersions, TestInfoStreamLogging, BadComponentTest, ShardRoutingCustomTest, TestIndexSearcher, TestFastOutputStream, TestSolr4Spatial, CSVRequestHandlerTest, ResponseHeaderTest, TestSolrQueryParser, TestSolrDeletionPolicy1, TestPartialUpdateDeduplication, TestFieldCollectionResource, TestShardHandlerFactory, SolrPluginUtilsTest, QueryEqualityTest, TestAtomicUpdateErrorCases, SoftAutoCommitTest, DeleteReplicaTest, TestSearchPerf, TestFreeTextSuggestions, BasicDistributedZkTest] [junit4] Completed in 60.18s, 1 test, 1 failure <<< FAILURES! [...truncated 718 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:515: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:463: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:186: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:508: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1345: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:952: There were test failures: 449 suites, 1822 tests, 1 failure, 35 ignored (20 assumptions) Total time: 172 minutes 32 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC (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]
