Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1959/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed. FAILED: org.apache.solr.cloud.ShardSplitTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:54432 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:54432 at __randomizedtesting.SeedInfo.seed([1F3E02927BC56CFA:976A3D48D5390102]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) at org.apache.solr.cloud.ShardSplitTest.splitShard(ShardSplitTest.java:496) at org.apache.solr.cloud.ShardSplitTest.incompleteOrOverlappingCustomRangeTest(ShardSplitTest.java:107) at org.apache.solr.cloud.ShardSplitTest.test(ShardSplitTest.java:83) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915) 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) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:150) at java.net.SocketInputStream.read(SocketInputStream.java:121) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:464) ... 47 more Build Log: [...truncated 9935 lines...] [junit4] Suite: org.apache.solr.cloud.ShardSplitTest [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/init-core-data-001 [junit4] 2> 4922362 T18979 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 4922368 T18979 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4922369 T18980 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 4922470 T18979 oasc.ZkTestServer.run start zk server on port:54422 [junit4] 2> 4922470 T18979 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4922471 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4922478 T18987 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@569385f4 name:ZooKeeperConnection Watcher:127.0.0.1:54422 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4922479 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4922479 T18979 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4922480 T18979 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 4922490 T18979 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4922491 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4922496 T18990 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@109e427a name:ZooKeeperConnection Watcher:127.0.0.1:54422/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4922496 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4922496 T18979 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4922498 T18979 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 4922505 T18979 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 4922510 T18979 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 4922515 T18979 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 4922521 T18979 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> 4922521 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4922531 T18979 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 4922532 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 4922539 T18979 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> 4922540 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4922547 T18979 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> 4922548 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 4922553 T18979 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> 4922554 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 4922560 T18979 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> 4922561 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 4922570 T18979 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> 4922571 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4922578 T18979 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> 4922581 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4922592 T18979 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> 4922594 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4922604 T18979 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> 4922605 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4922612 T18979 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> 4922612 T18979 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 4924528 T18979 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/cores/collection1 [junit4] 2> 4924531 T18979 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4924537 T18979 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@253f438a{/,null,AVAILABLE} [junit4] 2> 4924638 T18979 oejs.AbstractConnector.doStart Started ServerConnector@7f1c2850{HTTP/1.1}{127.0.0.1:54425} [junit4] 2> 4924639 T18979 oejs.Server.doStart Started @4928151ms [junit4] 2> 4924639 T18979 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4924640 T18979 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4924640 T18979 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.ShardSplitTest 1F3E02927BC56CFA-001/control-001 [junit4] 2> 4924641 T18979 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/' [junit4] 2> 4924702 T18979 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/solr.xml [junit4] 2> 4924717 T18979 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/cores [junit4] 2> 4924718 T18979 oasc.CoreContainer.<init> New CoreContainer 1389024096 [junit4] 2> 4924718 T18979 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/] [junit4] 2> 4924718 T18979 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4924719 T18979 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4924719 T18979 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4924719 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4924719 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4924720 T18979 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4924720 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4924720 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4924723 T18979 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4924724 T18979 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4924724 T18979 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4924725 T18979 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4924726 T18979 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4924726 T18979 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4924726 T18979 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4924728 T18979 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54422/solr [junit4] 2> 4924728 T18979 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4924729 T18979 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4924732 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4924737 T19004 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e951751 name:ZooKeeperConnection Watcher:127.0.0.1:54422 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4924737 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4924738 T18979 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4924743 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4924746 T19007 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32b2246d name:ZooKeeperConnection Watcher:127.0.0.1:54422/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4924747 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4924751 T18979 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 4924760 T18979 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 4924767 T18979 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 4924776 T18979 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 4924788 T18979 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 4924804 T18979 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 4924810 T18979 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54425_ [junit4] 2> 4924813 T18979 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54425_ [junit4] 2> 4924827 T18979 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 4924836 T18979 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 4924843 T18979 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4924848 T18979 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:54425_ [junit4] 2> 4924848 T18979 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 4924854 T18979 oasc.Overseer.start Overseer (id=93230773849423875-127.0.0.1:54425_-n_0000000000) starting [junit4] 2> 4924862 T18979 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 4924887 T18979 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4924890 T19009 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 4924893 T18979 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 4924900 T18979 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 4924905 T18979 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4924916 T19008 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 4924919 T18979 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/cores [junit4] 2> 4924920 T18979 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/cores/collection1/ [junit4] 2> 4924921 T18979 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4924923 T19011 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 4924924 T19011 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4924925 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4924925 T19011 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4924937 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54425";, [junit4] 2> "node_name":"127.0.0.1:54425_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 4924938 T19008 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54425";, [junit4] 2> "node_name":"127.0.0.1:54425_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4924939 T19008 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 4924939 T19008 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4924942 T19007 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> 4925927 T19011 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4925927 T19011 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 4925930 T19011 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4925931 T19011 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4925934 T19011 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4925935 T19011 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/cores/collection1/' [junit4] 2> 4925959 T19011 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4925966 T19011 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4925983 T19011 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4925986 T19011 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4925999 T19011 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4926004 T19011 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4926012 T19011 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4926185 T19011 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4926187 T19011 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4926188 T19011 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4926193 T19011 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4926260 T19011 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4926261 T19011 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4926261 T19011 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/control-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/control/data/ [junit4] 2> 4926262 T19011 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b77d280 [junit4] 2> 4926263 T19011 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/control/data [junit4] 2> 4926263 T19011 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.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/control/data/index/ [junit4] 2> 4926263 T19011 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 4926264 T19011 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/control/data/index [junit4] 2> 4926265 T19011 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=25.91015625, floorSegmentMB=0.3681640625, forceMergeDeletesPctAllowed=12.962435137139606, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 4926266 T19011 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24032508 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72ec61db),segFN=segments_1,generation=1} [junit4] 2> 4926266 T19011 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4926271 T19011 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4926272 T19011 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4926272 T19011 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4926273 T19011 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4926273 T19011 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4926274 T19011 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4926275 T19011 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4926275 T19011 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4926275 T19011 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4926276 T19011 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4926277 T19011 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4926277 T19011 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4926278 T19011 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4926279 T19011 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4926280 T19011 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4926281 T19011 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4926283 T19011 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4926283 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4926285 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4926286 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4926286 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4926287 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4926287 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4926288 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4926288 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4926289 T19011 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4926289 T19011 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4926297 T19011 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4926299 T19011 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4926300 T19011 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4926302 T19011 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4926302 T19011 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4926305 T19011 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4926306 T19011 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4926306 T19011 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 4926307 T19011 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24032508 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72ec61db),segFN=segments_1,generation=1} [junit4] 2> 4926307 T19011 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4926308 T19011 oass.SolrIndexSearcher.<init> Opening Searcher@69825627[collection1] main [junit4] 2> 4926308 T19011 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4926312 T19011 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4926312 T19011 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4926314 T19011 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4926314 T19011 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4926314 T19011 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4926316 T19011 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4926317 T19011 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4926317 T19011 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4926317 T19011 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4926318 T19011 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4926318 T19012 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@69825627[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4926319 T19015 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54425 collection:control_collection shard:shard1 [junit4] 2> 4926320 T18979 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 4926321 T18979 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4926322 T19015 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 4926322 T18979 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4926325 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4926328 T19018 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e33e860 name:ZooKeeperConnection Watcher:127.0.0.1:54422/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4926328 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4926329 T18979 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4926330 T18979 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4926335 T19015 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 4926338 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4926339 T19015 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4926340 T19015 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C9038 name=collection1 org.apache.solr.core.SolrCore@7f80b3d9 url=http://127.0.0.1:54425/collection1 node=127.0.0.1:54425_ C9038_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:54425, node_name=127.0.0.1:54425_, state=down} [junit4] 2> 4926340 T19015 C9038 P54425 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54425/collection1/ [junit4] 2> 4926340 T19015 C9038 P54425 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4926339 T18979 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4926341 T19015 C9038 P54425 oasc.SyncStrategy.syncToMe http://127.0.0.1:54425/collection1/ has no replicas [junit4] 2> 4926342 T19015 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54425/collection1/ shard1 [junit4] 2> 4926342 T19015 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4926343 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 4926370 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4926373 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:54425";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 4926483 T19018 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> 4926485 T19007 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> 4926530 T19015 oasc.ZkController.register We are http://127.0.0.1:54425/collection1/ and leader is http://127.0.0.1:54425/collection1/ [junit4] 2> 4926530 T19015 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54425 [junit4] 2> 4926531 T19015 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4926531 T19015 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 4926531 T19015 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4926535 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4926543 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54425";, [junit4] 2> "node_name":"127.0.0.1:54425_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 4926545 T19008 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54425";, [junit4] 2> "node_name":"127.0.0.1:54425_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4926653 T19018 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> 4926653 T19007 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> 4928313 T18979 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/cores/collection1 [junit4] 2> 4928316 T18979 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 4928319 T18979 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4928323 T18979 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@4e164419{/,null,AVAILABLE} [junit4] 2> 4928324 T18979 oejs.AbstractConnector.doStart Started ServerConnector@6443700f{HTTP/1.1}{127.0.0.1:54429} [junit4] 2> 4928325 T18979 oejs.Server.doStart Started @4931837ms [junit4] 2> 4928325 T18979 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4928325 T18979 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4928326 T18979 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.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001 [junit4] 2> 4928327 T18979 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/' [junit4] 2> 4928387 T18979 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.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/solr.xml [junit4] 2> 4928399 T18979 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/cores [junit4] 2> 4928399 T18979 oasc.CoreContainer.<init> New CoreContainer 626756809 [junit4] 2> 4928399 T18979 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/] [junit4] 2> 4928400 T18979 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4928401 T18979 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4928402 T18979 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4928402 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4928402 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4928402 T18979 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4928403 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4928403 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4928403 T18979 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4928404 T18979 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4928405 T18979 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4928405 T18979 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4928406 T18979 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4928406 T18979 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4928407 T18979 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4928407 T18979 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54422/solr [junit4] 2> 4928408 T18979 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4928409 T18979 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4928411 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4928414 T19032 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54f87eb8 name:ZooKeeperConnection Watcher:127.0.0.1:54422 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4928415 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4928415 T18979 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4928420 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4928425 T19035 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@57c19811 name:ZooKeeperConnection Watcher:127.0.0.1:54422/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4928425 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4928451 T18979 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4929468 T18979 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54429_ [junit4] 2> 4929471 T18979 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54429_ [junit4] 2> 4929488 T18979 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4929497 T18979 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/cores [junit4] 2> 4929499 T18979 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/cores/collection1/ [junit4] 2> 4929499 T18979 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4929503 T19036 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4929504 T19036 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4929505 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4929505 T19036 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4929508 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54429";, [junit4] 2> "node_name":"127.0.0.1:54429_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 4929510 T19008 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54429";, [junit4] 2> "node_name":"127.0.0.1:54429_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4929511 T19008 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 4929511 T19008 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 4929514 T19035 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4929515 T19007 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4929515 T19018 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4930511 T19036 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4930512 T19036 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4930515 T19036 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4930516 T19036 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4930521 T19036 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4930521 T19036 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/cores/collection1/' [junit4] 2> 4930553 T19036 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4930561 T19036 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4930585 T19036 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4930593 T19036 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4930613 T19036 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4930619 T19036 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4930626 T19036 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4930771 T19036 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4930773 T19036 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4930773 T19036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4930784 T19036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4930856 T19036 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4930857 T19036 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4930857 T19036 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-1-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty1/ [junit4] 2> 4930857 T19036 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b77d280 [junit4] 2> 4930858 T19036 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty1 [junit4] 2> 4930858 T19036 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.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty1/index/ [junit4] 2> 4930858 T19036 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 4930859 T19036 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty1/index [junit4] 2> 4930859 T19036 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=25.91015625, floorSegmentMB=0.3681640625, forceMergeDeletesPctAllowed=12.962435137139606, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 4930860 T19036 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@669fe179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cb6d2e1),segFN=segments_1,generation=1} [junit4] 2> 4930861 T19036 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4930864 T19036 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4930864 T19036 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4930865 T19036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4930865 T19036 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4930865 T19036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4930866 T19036 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4930866 T19036 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4930866 T19036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4930867 T19036 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4930867 T19036 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4930868 T19036 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4930868 T19036 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4930869 T19036 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4930869 T19036 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4930870 T19036 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4930871 T19036 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4930871 T19036 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4930872 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4930878 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4930879 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4930880 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4930880 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4930881 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4930882 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4930882 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4930883 T19036 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4930883 T19036 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4930891 T19036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4930893 T19036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4930894 T19036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4930896 T19036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4930896 T19036 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4930898 T19036 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4930899 T19036 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4930899 T19036 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 4930900 T19036 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@669fe179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cb6d2e1),segFN=segments_1,generation=1} [junit4] 2> 4930900 T19036 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4930900 T19036 oass.SolrIndexSearcher.<init> Opening Searcher@66818a69[collection1] main [junit4] 2> 4930900 T19036 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4930907 T19036 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4930907 T19036 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4930908 T19036 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4930909 T19036 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4930909 T19036 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4930910 T19036 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4930910 T19036 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4930911 T19036 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4930911 T19036 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4930914 T19037 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@66818a69[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4930914 T19036 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4930918 T19040 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54429 collection:collection1 shard:shard2 [junit4] 2> 4930919 T18979 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 4930920 T18979 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4930921 T19040 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 4930941 T19040 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 4930948 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4930951 T19040 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4930952 T19040 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C9039 name=collection1 org.apache.solr.core.SolrCore@486f3db2 url=http://127.0.0.1:54429/collection1 node=127.0.0.1:54429_ C9039_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:54429, node_name=127.0.0.1:54429_, state=down} [junit4] 2> 4930952 T19040 C9039 P54429 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54429/collection1/ [junit4] 2> 4930953 T19040 C9039 P54429 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4930953 T19040 C9039 P54429 oasc.SyncStrategy.syncToMe http://127.0.0.1:54429/collection1/ has no replicas [junit4] 2> 4930954 T19040 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54429/collection1/ shard2 [junit4] 2> 4930954 T19040 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 4930954 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 4930968 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4930972 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:54429";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 4931092 T19018 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4931094 T19035 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4931094 T19007 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4931125 T19040 oasc.ZkController.register We are http://127.0.0.1:54429/collection1/ and leader is http://127.0.0.1:54429/collection1/ [junit4] 2> 4931126 T19040 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54429 [junit4] 2> 4931127 T19040 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4931128 T19040 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 4931128 T19040 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4931134 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4931142 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54429";, [junit4] 2> "node_name":"127.0.0.1:54429_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 4931146 T19008 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54429";, [junit4] 2> "node_name":"127.0.0.1:54429_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4931253 T19007 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4931256 T19035 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4931256 T19018 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4933035 T18979 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/cores/collection1 [junit4] 2> 4933038 T18979 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 4933040 T18979 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4933045 T18979 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@438476d1{/,null,AVAILABLE} [junit4] 2> 4933046 T18979 oejs.AbstractConnector.doStart Started ServerConnector@63a5bad8{HTTP/1.1}{127.0.0.1:54432} [junit4] 2> 4933046 T18979 oejs.Server.doStart Started @4936558ms [junit4] 2> 4933046 T18979 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4933047 T18979 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4933047 T18979 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.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001 [junit4] 2> 4933048 T18979 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/' [junit4] 2> 4933109 T18979 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.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/solr.xml [junit4] 2> 4933121 T18979 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/cores [junit4] 2> 4933123 T18979 oasc.CoreContainer.<init> New CoreContainer 1994926032 [junit4] 2> 4933123 T18979 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/] [junit4] 2> 4933123 T18979 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4933124 T18979 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4933124 T18979 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4933124 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4933127 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4933127 T18979 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4933129 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4933129 T18979 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4933129 T18979 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4933130 T18979 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4933131 T18979 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4933132 T18979 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4933132 T18979 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4933132 T18979 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4933133 T18979 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4933133 T18979 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54422/solr [junit4] 2> 4933134 T18979 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4933135 T18979 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4933137 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4933142 T19054 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@39772f92 name:ZooKeeperConnection Watcher:127.0.0.1:54422 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4933142 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4933143 T18979 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4933147 T18979 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4933150 T19057 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4141d785 name:ZooKeeperConnection Watcher:127.0.0.1:54422/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4933150 T18979 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4933171 T18979 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4934183 T18979 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54432_ [junit4] 2> 4934185 T18979 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54432_ [junit4] 2> 4934197 T18979 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4934202 T18979 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/cores [junit4] 2> 4934204 T18979 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/cores/collection1/ [junit4] 2> 4934205 T18979 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4934207 T19058 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4934207 T19058 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4934209 T19007 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4934209 T19058 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4934212 T19008 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54432";, [junit4] 2> "node_name":"127.0.0.1:54432_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 4934213 T19008 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54432";, [junit4] 2> "node_name":"127.0.0.1:54432_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4934213 T19008 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 4934214 T19008 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4934217 T19007 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4934217 T19035 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4934217 T19018 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4934217 T19057 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4935211 T19058 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4935213 T19058 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4935215 T19058 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4935215 T19058 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4935218 T19058 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4935218 T19058 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/cores/collection1/' [junit4] 2> 4935243 T19058 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4935249 T19058 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4935260 T19058 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4935264 T19058 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4935282 T19058 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4935287 T19058 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4935297 T19058 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4935431 T19058 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4935433 T19058 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4935435 T19058 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4935440 T19058 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4935509 T19058 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4935509 T19058 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4935510 T19058 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/shard-2-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty2/ [junit4] 2> 4935510 T19058 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b77d280 [junit4] 2> 4935511 T19058 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty2 [junit4] 2> 4935511 T19058 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.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty2/index/ [junit4] 2> 4935512 T19058 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 4935513 T19058 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 1F3E02927BC56CFA-001/tempDir-001/jetty2/index [junit4] 2> 4935513 T19058 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=25.91015625, floorSegmentMB=0.3681640625, forceMergeDeletesPctAllowed=12.962435137139606, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 4935515 T19058 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3736f5ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cbd7670),segFN=segments_1,generation=1} [junit4] 2> 4935516 T19058 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4935523 T19058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4935524 T19058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4935525 T19058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4935525 T19058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4935527 T19058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4935527 T19058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4935528 T19058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4935529 T19058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4935529 T19058 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4935530 T19058 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4935531 T19058 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4935532 T19058 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4935534 T19058 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4935534 T19058 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4935535 T19058 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4935536 T19058 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4935537 T19058 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4935538 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4936166 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4936167 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4936168 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4936168 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4936169 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4936169 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4936170 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4936171 T19058 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4936171 T19058 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4936180 T19058 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4936181 T19058 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4936184 T19058 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4936185 T19058 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4936186 T19058 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4936188 T19058 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4936189 T19058 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4936189 T19058 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 4936190 T19058 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3736f5ae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cbd7670),segFN=segments_1,generation=1} [junit4] 2> 4936190 T19058 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4936190 T19058 oass.SolrIndexSearcher.<init> Opening Searcher@1763ba52[collection1] main [junit4] 2> 4936191 T19058 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4936194 T19058 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4936195 T19058 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4936197 T19058 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4936197 T19058 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4936197 T19058 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4936200 T19058 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4936200 T19058 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4936201 T19058 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4936201 T19058 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4936203 T19059 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1763ba52[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4936204 T19058 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4936206 T19062 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54432 collection:collection1 shard:shard1 [junit4] 2> 4936207 T18979 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 4936208 T18979 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4936209 T19062 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 4936233 T19062 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 4936238 T19062 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4936239 T19062 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C9040 name=collection1 org.apache.solr.core.SolrCore@7796104e url=http://127.0.0.1:54432/collection1 node=127.0.0.1:54432_ C9040_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http: [...truncated too long message...] sTest, TestReRankQParserPlugin, TestDistributedMissingSort, TestPostingsSolrHighlighter, TestNRTOpen, SortByFunctionTest, TestSearchPerf, DirectUpdateHandlerTest, CoreAdminHandlerTest, TestFreeTextSuggestions, LukeRequestHandlerTest, DistributedFacetPivotLargeTest, HdfsDirectoryFactoryTest, HdfsDirectoryTest, AliasIntegrationTest, TestCloudSchemaless, TestFieldSortValues, TestReplicationHandler, TestChildDocTransformer, DateMathParserTest, TestJmxMonitoredMap, TestCoreDiscovery, TestExceedMaxTermLength, DistanceFunctionTest, TestSchemaNameResource, TimeZoneUtilsTest, BasicFunctionalityTest, TestRandomMergePolicy, TestManagedResourceStorage, DistributedFacetPivotLongTailTest, DocumentBuilderTest, HdfsWriteToMultipleCollectionsTest, TestClusterStateMutator, TestBulkSchemaAPI, OverseerTest, TestCSVResponseWriter, TestSolrDeletionPolicy1, TestUniqueKeyFieldResource, AtomicUpdatesTest, DistributedFacetPivotSmallAdvancedTest, ResponseLogComponentTest, AnalyticsMergeStrategyTest, TestRecovery, TestCollectionAPI, TestFileDictionaryLookup, SpatialFilterTest, RAMDirectoryFactoryTest, TestInitParams, OpenExchangeRatesOrgProviderTest, SOLR749Test, MBeansHandlerTest, BadIndexSchemaTest, HdfsBasicDistributedZk2Test, NoCacheHeaderTest, PrimUtilsTest, AutoCommitTest, TestBadConfig, TestAnalyzeInfixSuggestions, ZkCLITest, HdfsUnloadDistributedZkTest, TestExpandComponent, TestObjectReleaseTracker, SolrCmdDistributorTest, XsltUpdateRequestHandlerTest, TestSchemaManager, CursorPagingTest, DocValuesTest, ZkNodePropsTest, LeaderElectionIntegrationTest, ConvertedLegacyTest, TestSolrQueryParserDefaultOperatorResource, ShowFileRequestHandlerTest, TestPerFieldSimilarity, TestSolrConfigHandlerCloud, TestRandomFaceting, TestLFUCache, StressHdfsTest, LeaderElectionTest, MultiTermTest, TestMiniSolrCloudCluster, TestSolrDynamicMBean, FastVectorHighlighterTest, SolrCoreTest, URLClassifyProcessorTest, BJQParserTest, JsonLoaderTest, TestSchemaSimilarityResource, SystemInfoHandlerTest, CustomCollectionTest, RemoteQueryErrorTest, SuggesterFSTTest, HdfsSyncSliceTest, TestHashPartitioner, TestPseudoReturnFields, QueryParsingTest, TestRealTimeGet, IndexBasedSpellCheckerTest, ResourceLoaderTest, TestDistributedSearch, HttpPartitionTest, TestManagedSchemaFieldTypeResource, TestStandardQParsers, TestSweetSpotSimilarityFactory, InfoHandlerTest, TestIndexingPerformance, TestReplicationHandlerBackup, SuggesterWFSTTest, DateFieldTest, DistributedDebugComponentTest, TestFuzzyAnalyzedSuggestions, TestFastOutputStream, TestMissingGroups, AssignTest, TestStressVersions, BlockDirectoryTest, HighlighterTest, TestDFRSimilarityFactory, LeaderInitiatedRecoveryOnCommitTest, LeaderFailoverAfterPartitionTest, OverseerRolesTest, CurrencyFieldOpenExchangeTest, SpellCheckComponentTest, TestMergePolicyConfig, TestDocBasedVersionConstraints, RankQueryTest, ZkSolrClientTest, RequestHandlersTest, TestLMJelinekMercerSimilarityFactory, AddBlockUpdateTest, StatelessScriptUpdateProcessorFactoryTest, TestTolerantSearch, TestNonDefinedSimilarityFactory, TestLazyCores, RollingRestartTest, DistributedSuggestComponentTest, DocExpirationUpdateProcessorFactoryTest, AnalysisErrorHandlingTest, TestDynamicLoading, DocValuesMissingTest, DistributedExpandComponentTest, TestOverriddenPrefixQueryForCustomFieldType, TestHighlightDedupGrouping, SaslZkACLProviderTest, TestCollationFieldDocValues, TestCustomSort, QueryResultKeyTest, TermsComponentTest, PolyFieldTest, TestExtendedDismaxParser, UnloadDistributedZkTest, TestRecoveryHdfs, ClusterStateTest, TestLRUStatsCache, SimpleFacetsTest, TermVectorComponentTest, BadComponentTest, TestSolrDeletionPolicy2, TestQueryTypes, SharedFSAutoReplicaFailoverTest, TestSolrIndexConfig, TestSolr4Spatial, DistanceUnitsTest, UpdateParamsTest, TestFastLRUCache, TestShardHandlerFactory, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestRestManager, TestBinaryField, TestCharFilters, HdfsBasicDistributedZkTest, SolrIndexSplitterTest, CollectionsAPIAsyncDistributedZkTest, TestSolrJ, DistribCursorPagingTest, ShardRoutingCustomTest, TestDynamicFieldCollectionResource, SampleTest, BasicDistributedZk2Test, BasicZkTest, TestWriterPerf, ChaosMonkeySafeLeaderTest, TestCursorMarkWithoutUniqueKey, AddSchemaFieldsUpdateProcessorFactoryTest, TestConfig, MergeStrategyTest, TestQueryUtils, TestWordDelimiterFilterFactory, TestFieldTypeResource, HdfsCollectionsAPIDistributedZkTest, DOMUtilTest, TestOrdValues, CircularListTest, TestBlobHandler, CSVRequestHandlerTest, TestStressUserVersions, SuggestComponentTest, FacetPivotSmallTest, BasicDistributedZkTest, SpellCheckCollatorTest, TestFoldingMultitermQuery, ChangedSchemaMergeTest, DistributedQueryElevationComponentTest, TestPivotHelperCode, RecoveryZkTest, TestRemoteStreaming, TestCollapseQParserPlugin, TestHighFrequencyDictionaryFactory, TestLRUCache, TestValueSourceCache, ChaosMonkeyNothingIsSafeTest, TestFieldTypeCollectionResource, TestFaceting, DistributedFacetPivotWhiteBoxTest, ReplicationFactorTest, QueryEqualityTest, DistributedFacetPivotSmallTest, TestCloudManagedSchemaConcurrent, CollectionsAPIDistributedZkTest, TestCloudPivotFacet, AnalyticsQueryTest, OverseerCollectionProcessorTest, UUIDUpdateProcessorFallbackTest, HdfsRecoveryZkTest, TestRandomDVFaceting, SyncSliceTest, OpenCloseCoreStressTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestZkChroot, TestDistributedGrouping, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestJoin, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, TestCoreContainer, StatsComponentTest, TestGroupingSearch, QueryElevationComponentTest, PeerSyncTest, TestSort, TestFiltering, TestFunctionQuery, TestIndexSearcher, SoftAutoCommitTest, CurrencyFieldXmlFileTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SolrRequestParserTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, TestTrie, TestCSVLoader, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, DocValuesMultiTest, SolrInfoMBeanTest, DebugComponentTest, CacheHeaderTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, TestBinaryResponseWriter, HighlighterConfigTest, TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, SolrIndexConfigTest, TestStressRecovery, TestDocSet, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, ExternalFileFieldSortTest, TestSolrCoreProperties, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, EchoParamsTest, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, ScriptEngineTest, PluginInfoTest, PreAnalyzedFieldTest, TestSystemIdResolver, TestSuggestSpellingConverter, SpellingQueryConverterTest, TestUtils, TestDocumentBuilder, SliceStateTest, UUIDFieldTest, FileUtilsTest, TestRTGBase, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteReplicaTest, DeleteShardTest, DistribDocExpirationUpdateProcessorTest, DistributedQueueTest, ExternalCollectionsTest, MigrateRouteKeyTest, MultiThreadedOCPTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest, RecoveryAfterSoftCommitTest, SSLMigrationTest, ShardSplitTest] [junit4] Completed in 334.92s, 1 test, 1 error <<< FAILURES! [...truncated 246 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1348: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956: There were test failures: 461 suites, 1845 tests, 1 error, 51 ignored (20 assumptions) Total time: 140 minutes 10 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC 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]
