Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1989/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
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:54456/gkj/w Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:54456/gkj/w at __randomizedtesting.SeedInfo.seed([CEBCCE06C7E35678:46E8F1DC691F3B80]: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:131) 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 10003 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 CEBCCE06C7E35678-001/init-core-data-001 [junit4] 2> 4379165 T19550 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /gkj/w [junit4] 2> 4379171 T19550 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4379172 T19551 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 4379273 T19550 oasc.ZkTestServer.run start zk server on port:54445 [junit4] 2> 4379273 T19550 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4379278 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4379297 T19558 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12c63837 name:ZooKeeperConnection Watcher:127.0.0.1:54445 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4379298 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4379298 T19550 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4379298 T19550 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 4379312 T19550 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4379314 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4379318 T19561 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@673cb833 name:ZooKeeperConnection Watcher:127.0.0.1:54445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4379318 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4379319 T19550 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4379319 T19550 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 4379329 T19550 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 4379336 T19550 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 4379343 T19550 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 4379354 T19550 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> 4379355 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4379367 T19550 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> 4379369 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 4379378 T19550 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> 4379379 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4379390 T19550 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> 4379394 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 4379409 T19550 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> 4379410 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 4379419 T19550 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> 4379420 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 4379429 T19550 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> 4379430 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4379439 T19550 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> 4379440 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4379448 T19550 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> 4379450 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4379459 T19550 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> 4379460 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4379468 T19550 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> 4379469 T19550 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 4382220 T19550 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 CEBCCE06C7E35678-001/control-001/cores/collection1 [junit4] 2> 4382224 T19550 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4382229 T19550 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@166fed2e{/gkj/w,null,AVAILABLE} [junit4] 2> 4382245 T19550 oejs.AbstractConnector.doStart Started ServerConnector@1989d5aa{HTTP/1.1}{127.0.0.1:54448} [junit4] 2> 4382246 T19550 oejs.Server.doStart Started @4386985ms [junit4] 2> 4382246 T19550 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4382247 T19550 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4382247 T19550 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 CEBCCE06C7E35678-001/control-001 [junit4] 2> 4382248 T19550 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/control-001/' [junit4] 2> 4382288 T19550 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 CEBCCE06C7E35678-001/control-001/solr.xml [junit4] 2> 4382299 T19550 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 CEBCCE06C7E35678-001/control-001/cores [junit4] 2> 4382300 T19550 oasc.CoreContainer.<init> New CoreContainer 1950148643 [junit4] 2> 4382300 T19550 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 CEBCCE06C7E35678-001/control-001/] [junit4] 2> 4382301 T19550 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4382301 T19550 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4382301 T19550 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4382302 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4382302 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4382303 T19550 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4382303 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4382303 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4382303 T19550 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4382304 T19550 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4382304 T19550 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4382305 T19550 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4382306 T19550 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4382306 T19550 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4382306 T19550 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4382307 T19550 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54445/solr [junit4] 2> 4382307 T19550 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4382308 T19550 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4382309 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4382312 T19575 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5c43181e name:ZooKeeperConnection Watcher:127.0.0.1:54445 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4382313 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4382313 T19550 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4382318 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4382320 T19578 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d6a8309 name:ZooKeeperConnection Watcher:127.0.0.1:54445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4382320 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4382324 T19550 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 4382332 T19550 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 4382343 T19550 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 4382354 T19550 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 4382363 T19550 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 4382372 T19550 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 4382376 T19550 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54448_gkj%2Fw [junit4] 2> 4382378 T19550 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54448_gkj%2Fw [junit4] 2> 4382385 T19550 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 4382390 T19550 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 4382394 T19550 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4382397 T19550 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:54448_gkj%2Fw [junit4] 2> 4382398 T19550 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 4382404 T19550 oasc.Overseer.start Overseer (id=93297871034187779-127.0.0.1:54448_gkj%2Fw-n_0000000000) starting [junit4] 2> 4382414 T19550 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 4382443 T19550 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4382445 T19580 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 4382449 T19579 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 4382451 T19550 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 4382459 T19550 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 4382463 T19550 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4382478 T19550 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 CEBCCE06C7E35678-001/control-001/cores [junit4] 2> 4382480 T19550 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/control-001/cores/collection1/ [junit4] 2> 4382480 T19550 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4382483 T19582 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 4382483 T19582 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4382485 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4382485 T19582 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4382497 T19579 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:54448/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54448_gkj%2Fw", [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> 4382498 T19579 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:54448/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54448_gkj%2Fw", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4382499 T19579 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 4382499 T19579 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4382502 T19578 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> 4383488 T19582 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4383488 T19582 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 4383490 T19582 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4383490 T19582 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4383493 T19582 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4383493 T19582 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/control-001/cores/collection1/' [junit4] 2> 4383513 T19582 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4383519 T19582 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4383532 T19582 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4383538 T19582 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4383550 T19582 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4383553 T19582 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4383572 T19582 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4383672 T19582 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4383672 T19582 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4383673 T19582 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4383678 T19582 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4383737 T19582 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4383737 T19582 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4383738 T19582 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 CEBCCE06C7E35678-001/control-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/control/data/ [junit4] 2> 4383738 T19582 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ba8609e [junit4] 2> 4383738 T19582 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/control/data [junit4] 2> 4383739 T19582 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 CEBCCE06C7E35678-001/tempDir-001/control/data/index/ [junit4] 2> 4383739 T19582 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 CEBCCE06C7E35678-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 4383740 T19582 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/control/data/index [junit4] 2> 4383740 T19582 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=19.787109375, floorSegmentMB=0.8623046875, forceMergeDeletesPctAllowed=6.712606435918835, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5231234664335903 [junit4] 2> 4383742 T19582 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3f5fc39 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ca77c1b),segFN=segments_1,generation=1} [junit4] 2> 4383742 T19582 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4383746 T19582 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4383746 T19582 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4383747 T19582 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4383747 T19582 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4383747 T19582 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4383747 T19582 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4383748 T19582 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4383748 T19582 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4383749 T19582 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4383749 T19582 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4383750 T19582 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4383750 T19582 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4383750 T19582 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4383751 T19582 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4383752 T19582 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4383753 T19582 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4383753 T19582 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4383753 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4383755 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4383755 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4383756 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4383756 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4383757 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4383757 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4383758 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4383759 T19582 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4383759 T19582 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4383767 T19582 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4383768 T19582 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4383769 T19582 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4383771 T19582 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4383771 T19582 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4383774 T19582 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4383774 T19582 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4383774 T19582 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=56.0927734375, floorSegmentMB=1.5146484375, forceMergeDeletesPctAllowed=10.571957983766943, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 4383775 T19582 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3f5fc39 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ca77c1b),segFN=segments_1,generation=1} [junit4] 2> 4383775 T19582 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4383775 T19582 oass.SolrIndexSearcher.<init> Opening Searcher@43507ec0[collection1] main [junit4] 2> 4383775 T19582 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4383778 T19582 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4383778 T19582 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4383780 T19582 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4383780 T19582 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4383780 T19582 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4383781 T19582 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4383782 T19582 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4383782 T19582 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4383783 T19582 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4383785 T19582 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4383785 T19583 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@43507ec0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4383786 T19586 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54448/gkj/w collection:control_collection shard:shard1 [junit4] 2> 4383787 T19550 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 4383787 T19550 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4383788 T19586 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 4383788 T19550 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4383789 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4383793 T19589 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7996e3d5 name:ZooKeeperConnection Watcher:127.0.0.1:54445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4383794 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4383795 T19550 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4383797 T19550 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4383801 T19586 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 4383807 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4383808 T19586 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4383808 T19586 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3435 name=collection1 org.apache.solr.core.SolrCore@210fb31c url=http://127.0.0.1:54448/gkj/w/collection1 node=127.0.0.1:54448_gkj%2Fw C3435_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:54448/gkj/w, node_name=127.0.0.1:54448_gkj%2Fw, state=down} [junit4] 2> 4383808 T19586 C3435 P54448 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54448/gkj/w/collection1/ [junit4] 2> 4383809 T19586 C3435 P54448 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4383810 T19586 C3435 P54448 oasc.SyncStrategy.syncToMe http://127.0.0.1:54448/gkj/w/collection1/ has no replicas [junit4] 2> 4383810 T19586 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54448/gkj/w/collection1/ shard1 [junit4] 2> 4383810 T19586 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4383808 T19550 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4383811 T19550 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 4383813 T19550 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4383820 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4383820 T19579 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> 4383822 T19592 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9bee173 name:ZooKeeperConnection Watcher:127.0.0.1:54445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4383822 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4383823 T19550 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4383836 T19579 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 1 [junit4] 2> 4383836 T19579 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 4383858 T19578 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> 4383870 T19589 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> 4383874 T19593 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4383876 T19579 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 4383887 T19594 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> 4383894 T19579 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:54448/gkj/w";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 3 [junit4] 2> 4383899 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4384004 T19594 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> 4384004 T19578 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> 4384033 T19586 oasc.ZkController.register We are http://127.0.0.1:54448/gkj/w/collection1/ and leader is http://127.0.0.1:54448/gkj/w/collection1/ [junit4] 2> 4384033 T19586 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54448/gkj/w [junit4] 2> 4384033 T19586 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4384033 T19586 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 4384034 T19586 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4384037 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4384047 T19579 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:54448/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54448_gkj%2Fw", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 4384049 T19579 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:54448/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54448_gkj%2Fw", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4384158 T19594 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> 4384158 T19578 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> 4385635 T19550 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 CEBCCE06C7E35678-001/shard-1-001/cores/collection1 [junit4] 2> 4385642 T19550 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 4385643 T19550 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4385647 T19550 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@53dd04aa{/gkj/w,null,AVAILABLE} [junit4] 2> 4385648 T19550 oejs.AbstractConnector.doStart Started ServerConnector@662a54d8{HTTP/1.1}{127.0.0.1:54453} [junit4] 2> 4385650 T19550 oejs.Server.doStart Started @4390389ms [junit4] 2> 4385652 T19550 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4385652 T19550 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4385653 T19550 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 CEBCCE06C7E35678-001/shard-1-001 [junit4] 2> 4385654 T19550 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/shard-1-001/' [junit4] 2> 4385703 T19550 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/shard-1-001/solr.xml [junit4] 2> 4385717 T19550 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 CEBCCE06C7E35678-001/shard-1-001/cores [junit4] 2> 4385720 T19550 oasc.CoreContainer.<init> New CoreContainer 1022215460 [junit4] 2> 4385720 T19550 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 CEBCCE06C7E35678-001/shard-1-001/] [junit4] 2> 4385721 T19550 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4385721 T19550 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4385722 T19550 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4385722 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4385722 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4385722 T19550 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4385723 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4385723 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4385723 T19550 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4385724 T19550 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4385724 T19550 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4385724 T19550 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4385725 T19550 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4385725 T19550 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4385725 T19550 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4385725 T19550 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54445/solr [junit4] 2> 4385726 T19550 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4385726 T19550 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4385729 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4385956 T19608 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3b154cd8 name:ZooKeeperConnection Watcher:127.0.0.1:54445 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4385957 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4385957 T19550 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4385963 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4385967 T19611 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e9cb5 name:ZooKeeperConnection Watcher:127.0.0.1:54445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4385968 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4385989 T19550 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4387006 T19550 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54453_gkj%2Fw [junit4] 2> 4387008 T19550 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54453_gkj%2Fw [junit4] 2> 4387018 T19550 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4387024 T19550 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 CEBCCE06C7E35678-001/shard-1-001/cores [junit4] 2> 4387025 T19550 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/shard-1-001/cores/collection1/ [junit4] 2> 4387026 T19550 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4387033 T19612 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4387033 T19612 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4387037 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4387039 T19612 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 4387039 T19612 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 4387040 T19579 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:54453/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54453_gkj%2Fw", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 4387040 T19612 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 4387041 T19579 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:54453/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54453_gkj%2Fw", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4387042 T19579 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 4387042 T19579 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 4387044 T19612 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 4387044 T19612 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4387047 T19579 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 4387049 T19611 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 4387050 T19611 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 4388046 T19612 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4388047 T19612 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4388049 T19612 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4388049 T19612 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4388054 T19612 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4388054 T19612 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/shard-1-001/cores/collection1/' [junit4] 2> 4388091 T19612 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4388097 T19612 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4388113 T19612 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4388147 T19612 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4388156 T19612 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4388159 T19612 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4388167 T19612 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4388275 T19612 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4388276 T19612 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4388277 T19612 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4388282 T19612 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4388363 T19612 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4388363 T19612 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4388364 T19612 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 CEBCCE06C7E35678-001/shard-1-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/jetty1/ [junit4] 2> 4388364 T19612 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ba8609e [junit4] 2> 4388365 T19612 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/jetty1 [junit4] 2> 4388366 T19612 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 CEBCCE06C7E35678-001/tempDir-001/jetty1/index/ [junit4] 2> 4388366 T19612 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 CEBCCE06C7E35678-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 4388367 T19612 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/jetty1/index [junit4] 2> 4388368 T19612 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=19.787109375, floorSegmentMB=0.8623046875, forceMergeDeletesPctAllowed=6.712606435918835, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5231234664335903 [junit4] 2> 4388368 T19612 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@54b36834 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a7db0d9),segFN=segments_1,generation=1} [junit4] 2> 4388369 T19612 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4388376 T19612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4388377 T19612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4388377 T19612 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4388378 T19612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4388379 T19612 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4388379 T19612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4388381 T19612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4388382 T19612 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4388383 T19612 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4388383 T19612 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4388384 T19612 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4388384 T19612 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4388385 T19612 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4388385 T19612 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4388386 T19612 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4388386 T19612 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4388387 T19612 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4388388 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4388391 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4388391 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4388392 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4388392 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4388393 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4388393 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4388394 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4388394 T19612 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4388395 T19612 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4388402 T19612 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4388403 T19612 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4388404 T19612 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4388406 T19612 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4388406 T19612 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4388409 T19612 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4388410 T19612 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4388410 T19612 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=56.0927734375, floorSegmentMB=1.5146484375, forceMergeDeletesPctAllowed=10.571957983766943, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 4388411 T19612 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@54b36834 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a7db0d9),segFN=segments_1,generation=1} [junit4] 2> 4388412 T19612 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4388412 T19612 oass.SolrIndexSearcher.<init> Opening Searcher@155ce42a[collection1] main [junit4] 2> 4388412 T19612 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4388415 T19612 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4388416 T19612 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4388419 T19612 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4388419 T19612 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4388420 T19612 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4388421 T19612 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4388421 T19612 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4388422 T19612 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4388422 T19612 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4388423 T19612 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4388423 T19613 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@155ce42a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4388424 T19616 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54453/gkj/w collection:collection1 shard:shard2 [junit4] 2> 4388425 T19550 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 4388426 T19550 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4388428 T19616 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 4388442 T19616 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 4388446 T19616 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4388446 T19616 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3436 name=collection1 org.apache.solr.core.SolrCore@24640972 url=http://127.0.0.1:54453/gkj/w/collection1 node=127.0.0.1:54453_gkj%2Fw C3436_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:54453/gkj/w, node_name=127.0.0.1:54453_gkj%2Fw, state=down} [junit4] 2> 4388446 T19616 C3436 P54453 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54453/gkj/w/collection1/ [junit4] 2> 4388447 T19616 C3436 P54453 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4388448 T19616 C3436 P54453 oasc.SyncStrategy.syncToMe http://127.0.0.1:54453/gkj/w/collection1/ has no replicas [junit4] 2> 4388448 T19616 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54453/gkj/w/collection1/ shard2 [junit4] 2> 4388448 T19616 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 4388458 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4388460 T19579 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 4388462 T19579 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 4388467 T19611 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 4388473 T19579 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:54453/gkj/w";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 4388475 T19579 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 4388477 T19617 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 4388478 T19611 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 4388480 T19617 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 4388489 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4388509 T19616 oasc.ZkController.register We are http://127.0.0.1:54453/gkj/w/collection1/ and leader is http://127.0.0.1:54453/gkj/w/collection1/ [junit4] 2> 4388510 T19616 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54453/gkj/w [junit4] 2> 4388510 T19616 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4388511 T19616 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 4388512 T19616 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4388515 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4388517 T19579 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:54453/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54453_gkj%2Fw", [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> 4388519 T19579 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:54453/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54453_gkj%2Fw", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4388526 T19579 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 4388529 T19617 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 4388537 T19616 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 4388540 T19617 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 4390411 T19550 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 CEBCCE06C7E35678-001/shard-2-001/cores/collection1 [junit4] 2> 4390414 T19550 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 4390416 T19550 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4390422 T19550 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@7b40eee{/gkj/w,null,AVAILABLE} [junit4] 2> 4390423 T19550 oejs.AbstractConnector.doStart Started ServerConnector@25452210{HTTP/1.1}{127.0.0.1:54456} [junit4] 2> 4390423 T19550 oejs.Server.doStart Started @4395162ms [junit4] 2> 4390423 T19550 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4390424 T19550 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4390424 T19550 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 CEBCCE06C7E35678-001/shard-2-001 [junit4] 2> 4390424 T19550 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/shard-2-001/' [junit4] 2> 4390468 T19550 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 CEBCCE06C7E35678-001/shard-2-001/solr.xml [junit4] 2> 4390501 T19550 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 CEBCCE06C7E35678-001/shard-2-001/cores [junit4] 2> 4390502 T19550 oasc.CoreContainer.<init> New CoreContainer 1759797257 [junit4] 2> 4390502 T19550 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 CEBCCE06C7E35678-001/shard-2-001/] [junit4] 2> 4390502 T19550 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4390503 T19550 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4390503 T19550 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4390503 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4390504 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4390504 T19550 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4390504 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4390504 T19550 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4390505 T19550 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4390505 T19550 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4390505 T19550 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4390506 T19550 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4390506 T19550 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4390507 T19550 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4390508 T19550 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4390509 T19550 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54445/solr [junit4] 2> 4390511 T19550 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4390512 T19550 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4390516 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4390521 T19631 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7be23e95 name:ZooKeeperConnection Watcher:127.0.0.1:54445 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4390522 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4390524 T19550 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4390531 T19550 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4390535 T19634 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@581d8219 name:ZooKeeperConnection Watcher:127.0.0.1:54445/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4390535 T19550 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4390556 T19550 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4391574 T19550 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54456_gkj%2Fw [junit4] 2> 4391578 T19550 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54456_gkj%2Fw [junit4] 2> 4391594 T19550 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4391601 T19550 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 CEBCCE06C7E35678-001/shard-2-001/cores [junit4] 2> 4391603 T19550 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/shard-2-001/cores/collection1/ [junit4] 2> 4391604 T19550 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4391613 T19635 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4391613 T19635 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4391618 T19578 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4391620 T19635 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 4391621 T19635 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 4391621 T19579 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:54456/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54456_gkj%2Fw", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 4391622 T19635 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 4391622 T19579 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:54456/gkj/w";, [junit4] 2> "node_name":"127.0.0.1:54456_gkj%2Fw", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4391623 T19579 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 4391624 T19579 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4391627 T19579 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 4391627 T19635 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 4391627 T19635 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4391629 T19617 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 4391629 T19634 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 4391630 T19617 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 4391632 T19634 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 4392630 T19635 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4392631 T19635 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4392634 T19635 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4392635 T19635 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4392642 T19635 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4392642 T19635 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/shard-2-001/cores/collection1/' [junit4] 2> 4392673 T19635 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4392678 T19635 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4392689 T19635 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4392693 T19635 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4392707 T19635 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4392711 T19635 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4392715 T19635 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4392829 T19635 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4392831 T19635 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4392832 T19635 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4392840 T19635 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4392908 T19635 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4392908 T19635 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4392909 T19635 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 CEBCCE06C7E35678-001/shard-2-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/jetty2/ [junit4] 2> 4392909 T19635 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ba8609e [junit4] 2> 4392910 T19635 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/jetty2 [junit4] 2> 4392910 T19635 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 CEBCCE06C7E35678-001/tempDir-001/jetty2/index/ [junit4] 2> 4392910 T19635 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 CEBCCE06C7E35678-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 4392911 T19635 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001/tempDir-001/jetty2/index [junit4] 2> 4392911 T19635 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=19.787109375, floorSegmentMB=0.8623046875, forceMergeDeletesPctAllowed=6.712606435918835, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5231234664335903 [junit4] 2> 4392912 T19635 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@19cb4234 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d68a67c),segFN=segments_1,generation=1} [junit4] 2> 4392912 T19635 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4392917 T19635 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4392918 T19635 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4392919 T19635 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4392919 T19635 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4392919 T19635 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4392919 T19635 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4392920 T19635 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4392921 T19635 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4392921 T19635 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4392922 T19635 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4392923 T19635 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4392923 T19635 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4392924 T19635 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4392924 T19635 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4392925 T19635 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4392926 T19635 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4392927 T19635 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4392928 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4392930 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4392930 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4392931 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4392931 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4392932 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4392932 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4392933 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4392933 T19635 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4392934 T19635 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4392942 T19635 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4392943 T19635 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4392944 T19635 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4392946 T19635 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4392946 T19635 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4392948 T19635 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4392948 T19635 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4392949 T19635 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapp [...truncated too long message...] g temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest CEBCCE06C7E35678-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=DefaultSimilarity, locale=el_CY, timezone=Africa/Nairobi [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_31 (64-bit)/cpus=2,threads=1,free=111252816,total=489684992 [junit4] 2> NOTE: All tests run in this JVM: [ShardRoutingTest, TestExactSharedStatsCache, OverseerStatusTest, JsonLoaderTest, AssignTest, TestMergePolicyConfig, TestBinaryResponseWriter, TestSurroundQueryParser, TestManagedSchemaFieldResource, OverseerTest, TestQuerySenderListener, TestCloudPivotFacet, TestExtendedDismaxParser, ShowFileRequestHandlerTest, TestRebalanceLeaders, DocExpirationUpdateProcessorFactoryTest, TestComponentsName, BinaryUpdateRequestHandlerTest, TestJmxIntegration, TestBlobHandler, TestSolrConfigHandlerConcurrent, TestHighlightDedupGrouping, CacheHeaderTest, IndexBasedSpellCheckerTest, CSVRequestHandlerTest, TestCoreContainer, AnalyticsMergeStrategyTest, TestDistributedGrouping, TestStressRecovery, TestIndexSearcher, HdfsUnloadDistributedZkTest, ClusterStateTest, FileUtilsTest, TestBulkSchemaAPI, DistributedMLTComponentTest, FullSolrCloudDistribCmdsTest, TestOmitPositions, TestIntervalFaceting, NumericFieldsTest, DocumentBuilderTest, DeleteReplicaTest, TestSystemIdResolver, DistributedFacetPivotSmallTest, FieldMutatingUpdateProcessorTest, TestQueryTypes, TestWordDelimiterFilterFactory, SharedFSAutoReplicaFailoverUtilsTest, RAMDirectoryFactoryTest, ZkSolrClientTest, TestSolrCoreProperties, PrimitiveFieldTypeTest, TestReqParamsAPI, RequiredFieldsTest, OutputWriterTest, TestLMJelinekMercerSimilarityFactory, TestSearcherReuse, TestPHPSerializedResponseWriter, SampleTest, TestQuerySenderNoQuery, TestLRUStatsCache, TestRandomDVFaceting, OpenCloseCoreStressTest, HdfsDirectoryTest, TestBulkSchemaConcurrent, TestDistributedMissingSort, TestStressLucene, BadCopyFieldTest, TermVectorComponentDistributedTest, AnalysisErrorHandlingTest, DistribJoinFromCollectionTest, ZkCLITest, ResourceLoaderTest, SpellPossibilityIteratorTest, TestSerializedLuceneMatchVersion, TestManagedSchemaDynamicFieldResource, TestCollationFieldDocValues, TestCharFilters, TestRecovery, ZkNodePropsTest, DistributedQueryComponentCustomSortTest, SolrRequestParserTest, TestSortingResponseWriter, MoreLikeThisHandlerTest, FacetPivotSmallTest, SimplePostToolTest, SuggesterTSTTest, HttpPartitionTest, SchemaVersionSpecificBehaviorTest, JSONWriterTest, TestSearchPerf, CustomCollectionTest, SolrCoreTest, TestArbitraryIndexDir, SolrCmdDistributorTest, DistribDocExpirationUpdateProcessorTest, UnloadDistributedZkTest, TestMiniSolrCloudCluster, TestLMDirichletSimilarityFactory, TestCollationField, DistributedQueueTest, TestCloudManagedSchemaConcurrent, ChaosMonkeyNothingIsSafeTest, TestStandardQParsers, TestExpandComponent, InfoHandlerTest, TestFastLRUCache, TestRealTimeGet, ChaosMonkeySafeLeaderTest, TestRandomFaceting, SolrIndexSplitterTest, TestSolr4Spatial, CurrencyFieldXmlFileTest, AnalyticsQueryTest, DirectSolrConnectionTest, EnumFieldTest, AtomicUpdatesTest, HdfsDirectoryFactoryTest, TestManagedSchema, TermsComponentTest, TestBinaryField, MigrateRouteKeyTest, DebugComponentTest, TestDocBasedVersionConstraints, LeaderFailoverAfterPartitionTest, TestClusterStateMutator, TestCSVLoader, TestConfigReload, TestSort, CoreAdminCreateDiscoverTest, PathHierarchyTokenizerFactoryTest, TestSimpleQParserPlugin, PeerSyncTest, SSLMigrationTest, SolrPluginUtilsTest, TestBadConfig, TestAnalyzeInfixSuggestions, DistanceFunctionTest, SyncSliceTest, TestObjectReleaseTracker, SuggestComponentTest, TestCloudInspectUtil, TestSchemaVersionResource, FileBasedSpellCheckerTest, SolrXmlInZkTest, TestLFUCache, ClusterStateUpdateTest, TestFunctionQuery, TestSolrDeletionPolicy1, TestCollectionAPI, SpatialRPTFieldTypeTest, DirectUpdateHandlerTest, PluginInfoTest, TriLevelCompositeIdRoutingTest, HardAutoCommitTest, TestReplicationHandler, TestXIncludeConfig, QueryElevationComponentTest, ResponseLogComponentTest, TestSolrQueryParserResource, TestZkChroot, OverseerRolesTest, UUIDFieldTest, DistributedFacetPivotLongTailTest, RemoteQueryErrorTest, PingRequestHandlerTest, SolrCoreCheckLockOnStartupTest, TestInitQParser, TestDocSet, ChangedSchemaMergeTest, XsltUpdateRequestHandlerTest, TestRTGBase, TestSuggestSpellingConverter, CurrencyFieldOpenExchangeTest, TestSolrIndexConfig, TestQueryUtils, RollingRestartTest, OverseerCollectionProcessorTest, SynonymTokenizerTest, MinimalSchemaTest, TestSolrDeletionPolicy2, DocValuesTest, TestRemoteStreaming, TestFoldingMultitermQuery, SimpleFacetsTest, QueryEqualityTest, MergeStrategyTest, SimpleCollectionCreateDeleteTest, OpenExchangeRatesOrgProviderTest, TestComplexPhraseQParserPlugin, OverriddenZkACLAndCredentialsProvidersTest, TestDFRSimilarityFactory, TestFieldSortValues, WordBreakSolrSpellCheckerTest, LeaderElectionTest, CircularListTest, CollectionsAPIDistributedZkTest, TestConfigSets, TestStressReorder, QueryParsingTest, SolrInfoMBeanTest, SaslZkACLProviderTest, UpdateRequestProcessorFactoryTest, ZkControllerTest, PreAnalyzedUpdateProcessorTest, ParsingFieldUpdateProcessorsTest, TestFieldResource, RecoveryAfterSoftCommitTest, SpellingQueryConverterTest, TestMaxScoreQueryParser, HdfsChaosMonkeySafeLeaderTest, StatsComponentTest, DocValuesMultiTest, SharedFSAutoReplicaFailoverTest, CloudMLTQParserTest, TestCodecSupport, ActionThrottleTest, ScriptEngineTest, TestReversedWildcardFilterFactory, SoftAutoCommitTest, TestChildDocTransformer, UniqFieldsUpdateProcessorFactoryTest, TestStressVersions, BasicDistributedZkTest, BasicDistributedZk2Test, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, ShardRoutingCustomTest, TestDistributedSearch, AutoCommitTest, TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest, TestJoin, TestReload, DistributedTermsComponentTest, TestRangeQuery, SpellCheckComponentTest, TestGroupingSearch, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, BasicFunctionalityTest, TestLazyCores, HighlighterTest, DistributedQueryElevationComponentTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, SuggesterTest, TestTrie, SpatialFilterTest, SuggesterWFSTTest, PolyFieldTest, NoCacheHeaderTest, TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, TestValueSourceCache, TermVectorComponentTest, TestIndexingPerformance, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, IndexSchemaTest, ReturnFieldsTest, TestCSVResponseWriter, MBeansHandlerTest, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, SearchHandlerTest, HighlighterConfigTest, SOLR749Test, UpdateParamsTest, AlternateDirectoryTest, CopyFieldTest, SolrIndexConfigTest, BadComponentTest, MultiTermTest, TestElisionMultitermQuery, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, EchoParamsTest, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TimeZoneUtilsTest, TestFastOutputStream, URLClassifyProcessorTest, DateMathParserTest, PreAnalyzedFieldTest, PrimUtilsTest, DateFieldTest, DOMUtilTest, TestSolrJ, TestLRUCache, TestUtils, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, CursorPagingTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestTolerantSearch, AliasIntegrationTest, AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest, ExternalCollectionsTest, LeaderInitiatedRecoveryOnCommitTest, MultiThreadedOCPTest, OutOfBoxZkACLAndCredentialsProvidersTest, ReplicationFactorTest, ShardSplitTest] [junit4] Completed in 335.06s, 1 test, 1 error <<< FAILURES! [...truncated 336 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:508: 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: 466 suites, 1859 tests, 1 error, 51 ignored (20 assumptions) Total time: 134 minutes 40 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC 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]
