Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1933/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.cloud.ShardSplitTest.testDistribSearch Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:52004/m/q Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:52004/m/q at __randomizedtesting.SeedInfo.seed([915AB212503397C4:10BC3C0A276CF7F8]: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:532) at org.apache.solr.cloud.ShardSplitTest.incompleteOrOverlappingCustomRangeTest(ShardSplitTest.java:150) at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:102) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) at sun.reflect.GeneratedMethodAccessor41.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) 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) ... 45 more Build Log: [...truncated 9258 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 915AB212503397C4-001/init-core-data-001 [junit4] 2> 2393960 T10044 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /m/q [junit4] 2> 2393964 T10044 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 2393965 T10044 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2393966 T10045 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 2394067 T10044 oasc.ZkTestServer.run start zk server on port:51994 [junit4] 2> 2394068 T10044 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 2394071 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2394084 T10052 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b22ac13 name:ZooKeeperConnection Watcher:127.0.0.1:51994 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2394085 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2394086 T10044 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 2394087 T10044 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 2394094 T10044 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 2394096 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2394100 T10055 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33d4b88 name:ZooKeeperConnection Watcher:127.0.0.1:51994/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2394101 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2394101 T10044 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 2394102 T10044 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 2394112 T10044 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 2394121 T10044 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 2394129 T10044 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 2394136 T10044 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> 2394137 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2394150 T10044 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> 2394151 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 2394159 T10044 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> 2394160 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2394168 T10044 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> 2394169 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 2394178 T10044 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> 2394179 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 2394188 T10044 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> 2394188 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 2394198 T10044 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> 2394199 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2394208 T10044 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> 2394210 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2394219 T10044 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> 2394220 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2394227 T10044 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> 2394228 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2394235 T10044 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> 2394235 T10044 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 2396225 T10044 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 2396229 T10044 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@3c7b09bc{/m/q,null,AVAILABLE} [junit4] 2> 2396233 T10044 oejs.AbstractConnector.doStart Started ServerConnector@3b9ee31f{HTTP/1.1}{127.0.0.1:51997} [junit4] 2> 2396234 T10044 oejs.Server.doStart Started @2399702ms [junit4] 2> 2396235 T10044 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 2396235 T10044 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2396236 T10044 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 915AB212503397C4-001/tempDir-002 [junit4] 2> 2396236 T10044 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-002/' [junit4] 2> 2396284 T10044 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 915AB212503397C4-001/tempDir-002/solr.xml [junit4] 2> 2396302 T10044 oasc.CoreContainer.<init> New CoreContainer 1792617145 [junit4] 2> 2396302 T10044 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 915AB212503397C4-001/tempDir-002/] [junit4] 2> 2396302 T10044 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2396303 T10044 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2396303 T10044 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2396303 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2396304 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 2396304 T10044 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2396304 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2396304 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2396305 T10044 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2396306 T10044 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2396306 T10044 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 2396306 T10044 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2396307 T10044 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2396307 T10044 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2396308 T10044 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2396308 T10044 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51994/solr [junit4] 2> 2396308 T10044 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2396309 T10044 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 2396310 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2396313 T10069 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6e8630d1 name:ZooKeeperConnection Watcher:127.0.0.1:51994 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2396314 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2396314 T10044 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 2396319 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2396323 T10072 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@248732fd name:ZooKeeperConnection Watcher:127.0.0.1:51994/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2396323 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2396328 T10044 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 2396334 T10044 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 2396343 T10044 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 2396352 T10044 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 2396361 T10044 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 2396373 T10044 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 2396377 T10044 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51997_m%2Fq [junit4] 2> 2396379 T10044 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51997_m%2Fq [junit4] 2> 2396388 T10044 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 2396396 T10044 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 2396403 T10044 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 2396409 T10044 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:51997_m%2Fq [junit4] 2> 2396410 T10044 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 2396417 T10044 oasc.Overseer.start Overseer (id=93156651609751555-127.0.0.1:51997_m%2Fq-n_0000000000) starting [junit4] 2> 2396426 T10044 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 2396458 T10044 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2396461 T10074 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 2396463 T10073 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 2396465 T10044 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 2396472 T10044 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 2396476 T10044 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2396490 T10076 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 2396491 T10076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2396493 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2396493 T10076 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2396508 T10073 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:51997/m/q";, [junit4] 2> "node_name":"127.0.0.1:51997_m%2Fq", [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> 2396510 T10073 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:51997/m/q";, [junit4] 2> "node_name":"127.0.0.1:51997_m%2Fq", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2396510 T10073 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 2396510 T10073 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 2396515 T10072 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> 2397495 T10076 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2397496 T10076 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 2397500 T10076 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2397500 T10076 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 2397503 T10076 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2397504 T10076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-002/collection1/' [junit4] 2> 2397505 T10076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 2397506 T10076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 2397507 T10076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 2397546 T10076 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 2397553 T10076 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 2397565 T10076 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 2397585 T10076 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2397595 T10076 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2397599 T10076 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 2397607 T10076 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 2397725 T10076 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 2397728 T10076 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2397728 T10076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2397734 T10076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2397813 T10076 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2397814 T10076 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2397814 T10076 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 915AB212503397C4-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/control/data/ [junit4] 2> 2397815 T10076 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@33d42514 [junit4] 2> 2397815 T10076 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/control/data [junit4] 2> 2397815 T10076 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 915AB212503397C4-001/tempDir-001/control/data/index/ [junit4] 2> 2397816 T10076 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 915AB212503397C4-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 2397817 T10076 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/control/data/index [junit4] 2> 2397817 T10076 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2397818 T10076 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@734f0dff lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c7fa27),segFN=segments_1,generation=1} [junit4] 2> 2397818 T10076 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2397823 T10076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2397824 T10076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 2397824 T10076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2397824 T10076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2397825 T10076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2397825 T10076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 2397826 T10076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2397826 T10076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2397826 T10076 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2397827 T10076 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2397827 T10076 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2397828 T10076 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2397828 T10076 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2397828 T10076 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 2397829 T10076 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 2397831 T10076 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 2397831 T10076 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 2397832 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 2397834 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 2397835 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 2397835 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 2397836 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 2397836 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 2397836 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 2397837 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 2397837 T10076 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2397838 T10076 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 2397846 T10076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2397848 T10076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2397849 T10076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2397851 T10076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2397852 T10076 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 2397856 T10076 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2397857 T10076 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2397857 T10076 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=40.8505859375, floorSegmentMB=0.73046875, forceMergeDeletesPctAllowed=14.591857526335355, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4301845861037237 [junit4] 2> 2397858 T10076 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@734f0dff lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c7fa27),segFN=segments_1,generation=1} [junit4] 2> 2397858 T10076 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2397858 T10076 oass.SolrIndexSearcher.<init> Opening Searcher@63638812[collection1] main [junit4] 2> 2397858 T10076 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 2397861 T10076 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2397861 T10076 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2397863 T10076 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2397863 T10076 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2397864 T10076 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2397865 T10076 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 2397866 T10076 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2397866 T10076 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2397866 T10076 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 2397867 T10076 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2397867 T10077 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@63638812[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2397869 T10080 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51997/m/q collection:control_collection shard:shard1 [junit4] 2> 2397870 T10044 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2397870 T10044 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2397871 T10080 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 2397871 T10044 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 2397876 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2397880 T10083 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d37e18c name:ZooKeeperConnection Watcher:127.0.0.1:51994/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2397881 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2397882 T10044 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 2397885 T10044 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2397894 T10080 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 2397896 T10044 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2397916 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2397917 T10080 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2397917 T10080 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7401 name=collection1 org.apache.solr.core.SolrCore@49ce1981 url=http://127.0.0.1:51997/m/q/collection1 node=127.0.0.1:51997_m%2Fq C7401_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:51997/m/q, node_name=127.0.0.1:51997_m%2Fq, state=down} [junit4] 2> 2397918 T10080 C7401 P51997 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51997/m/q/collection1/ [junit4] 2> 2397918 T10080 C7401 P51997 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2397919 T10080 C7401 P51997 oasc.SyncStrategy.syncToMe http://127.0.0.1:51997/m/q/collection1/ has no replicas [junit4] 2> 2397920 T10080 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51997/m/q/collection1/ shard1 [junit4] 2> 2397920 T10080 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2397921 T10073 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> 2397942 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2397946 T10073 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:51997/m/q";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 2398058 T10072 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> 2398060 T10083 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> 2398100 T10080 oasc.ZkController.register We are http://127.0.0.1:51997/m/q/collection1/ and leader is http://127.0.0.1:51997/m/q/collection1/ [junit4] 2> 2398101 T10080 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51997/m/q [junit4] 2> 2398101 T10080 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2398101 T10080 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 2398101 T10080 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2398106 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2398114 T10073 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:51997/m/q";, [junit4] 2> "node_name":"127.0.0.1:51997_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 2398115 T10073 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:51997/m/q";, [junit4] 2> "node_name":"127.0.0.1:51997_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2398222 T10083 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> 2398232 T10072 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> 2400294 T10044 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 2400296 T10044 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 2400307 T10044 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@6c4058f5{/m/q,null,AVAILABLE} [junit4] 2> 2400310 T10044 oejs.AbstractConnector.doStart Started ServerConnector@3ffa2f7f{HTTP/1.1}{127.0.0.1:52001} [junit4] 2> 2400310 T10044 oejs.Server.doStart Started @2403778ms [junit4] 2> 2400310 T10044 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 2400313 T10044 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2400313 T10044 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 915AB212503397C4-001/tempDir-003 [junit4] 2> 2400313 T10044 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-003/' [junit4] 2> 2400369 T10044 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 915AB212503397C4-001/tempDir-003/solr.xml [junit4] 2> 2400396 T10044 oasc.CoreContainer.<init> New CoreContainer 2043726087 [junit4] 2> 2400397 T10044 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 915AB212503397C4-001/tempDir-003/] [junit4] 2> 2400398 T10044 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2400399 T10044 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2400399 T10044 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2400400 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2400400 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 2400401 T10044 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2400401 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2400402 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2400402 T10044 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2400403 T10044 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2400403 T10044 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 2400404 T10044 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2400405 T10044 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2400405 T10044 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2400406 T10044 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2400407 T10044 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51994/solr [junit4] 2> 2400408 T10044 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2400408 T10044 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 2400411 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2400425 T10097 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a0f0d9e name:ZooKeeperConnection Watcher:127.0.0.1:51994 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2400425 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2400426 T10044 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 2400431 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2400435 T10100 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3c3a1db0 name:ZooKeeperConnection Watcher:127.0.0.1:51994/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2400435 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2400457 T10044 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2401476 T10044 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52001_m%2Fq [junit4] 2> 2401479 T10044 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52001_m%2Fq [junit4] 2> 2401513 T10044 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 2401530 T10101 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 2401530 T10101 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2401533 T10101 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2401533 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2401541 T10073 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:52001/m/q";, [junit4] 2> "node_name":"127.0.0.1:52001_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 2401543 T10073 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:52001/m/q";, [junit4] 2> "node_name":"127.0.0.1:52001_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 2401544 T10073 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 2401544 T10073 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 2401550 T10083 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2401550 T10100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2401550 T10072 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2402535 T10101 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2402535 T10101 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 2402537 T10101 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2402537 T10101 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2402542 T10101 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2402542 T10101 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-003/collection1/' [junit4] 2> 2402544 T10101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 2402546 T10101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 2402546 T10101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 2402602 T10101 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 2402610 T10101 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 2402631 T10101 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 2402641 T10101 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2402657 T10101 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2402661 T10101 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 2402671 T10101 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 2402853 T10101 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 2402854 T10101 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2402855 T10101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2402860 T10101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2402965 T10101 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2402966 T10101 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2402966 T10101 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 915AB212503397C4-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/jetty1/ [junit4] 2> 2402967 T10101 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@33d42514 [junit4] 2> 2402968 T10101 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/jetty1 [junit4] 2> 2402969 T10101 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 915AB212503397C4-001/tempDir-001/jetty1/index/ [junit4] 2> 2402969 T10101 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 915AB212503397C4-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 2402969 T10101 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/jetty1/index [junit4] 2> 2402970 T10101 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2402971 T10101 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e3699b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e8dced9),segFN=segments_1,generation=1} [junit4] 2> 2402972 T10101 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2402980 T10101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2402981 T10101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 2402981 T10101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2402982 T10101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2402982 T10101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2402982 T10101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 2402983 T10101 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2402983 T10101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2402984 T10101 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2402985 T10101 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2402986 T10101 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2402987 T10101 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2402988 T10101 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2402990 T10101 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 2402990 T10101 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 2402991 T10101 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 2402992 T10101 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 2402993 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 2402995 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 2402996 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 2402998 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 2402999 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 2403000 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 2403001 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 2403002 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 2403003 T10101 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2403004 T10101 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 2403016 T10101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2403019 T10101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2403023 T10101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2403025 T10101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2403026 T10101 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 2403032 T10101 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2403032 T10101 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2403033 T10101 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=40.8505859375, floorSegmentMB=0.73046875, forceMergeDeletesPctAllowed=14.591857526335355, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4301845861037237 [junit4] 2> 2403034 T10101 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e3699b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e8dced9),segFN=segments_1,generation=1} [junit4] 2> 2403035 T10101 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2403036 T10101 oass.SolrIndexSearcher.<init> Opening Searcher@fd067c7[collection1] main [junit4] 2> 2403036 T10101 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2403041 T10101 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2403041 T10101 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2403044 T10101 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2403044 T10101 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2403045 T10101 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2403048 T10101 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 2403049 T10101 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2403049 T10101 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2403050 T10101 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 2403052 T10101 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2403055 T10102 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fd067c7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2403061 T10105 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52001/m/q collection:collection1 shard:shard2 [junit4] 2> 2403067 T10044 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2403068 T10044 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2403081 T10105 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 2403167 T10105 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 2403186 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2403188 T10105 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2403189 T10105 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7402 name=collection1 org.apache.solr.core.SolrCore@d3cfdb1 url=http://127.0.0.1:52001/m/q/collection1 node=127.0.0.1:52001_m%2Fq C7402_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:52001/m/q, node_name=127.0.0.1:52001_m%2Fq, state=down} [junit4] 2> 2403189 T10105 C7402 P52001 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52001/m/q/collection1/ [junit4] 2> 2403190 T10105 C7402 P52001 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2403191 T10105 C7402 P52001 oasc.SyncStrategy.syncToMe http://127.0.0.1:52001/m/q/collection1/ has no replicas [junit4] 2> 2403192 T10105 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52001/m/q/collection1/ shard2 [junit4] 2> 2403192 T10105 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 2403194 T10073 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 2403204 T10083 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2403204 T10072 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2403205 T10100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2403227 T10106 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2403238 T10073 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:52001/m/q";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 2403383 T10083 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2403384 T10100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2403441 T10105 oasc.ZkController.register We are http://127.0.0.1:52001/m/q/collection1/ and leader is http://127.0.0.1:52001/m/q/collection1/ [junit4] 2> 2403444 T10105 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52001/m/q [junit4] 2> 2403445 T10105 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2403445 T10105 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 2403446 T10105 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2403451 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2403460 T10073 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:52001/m/q";, [junit4] 2> "node_name":"127.0.0.1:52001_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 2403463 T10073 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:52001/m/q";, [junit4] 2> "node_name":"127.0.0.1:52001_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 2403580 T10083 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2403580 T10100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2403581 T10072 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2406298 T10044 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 2406300 T10044 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 2406309 T10044 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@6e931{/m/q,null,AVAILABLE} [junit4] 2> 2406310 T10044 oejs.AbstractConnector.doStart Started ServerConnector@6b864681{HTTP/1.1}{127.0.0.1:52004} [junit4] 2> 2406311 T10044 oejs.Server.doStart Started @2409779ms [junit4] 2> 2406312 T10044 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 2406313 T10044 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2406314 T10044 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 915AB212503397C4-001/tempDir-004 [junit4] 2> 2406314 T10044 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-004/' [junit4] 2> 2406384 T10044 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 915AB212503397C4-001/tempDir-004/solr.xml [junit4] 2> 2406410 T10044 oasc.CoreContainer.<init> New CoreContainer 161064411 [junit4] 2> 2406410 T10044 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 915AB212503397C4-001/tempDir-004/] [junit4] 2> 2406411 T10044 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2406411 T10044 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2406411 T10044 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2406412 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2406413 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 2406414 T10044 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2406415 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2406415 T10044 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2406416 T10044 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2406416 T10044 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2406416 T10044 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 2406417 T10044 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2406417 T10044 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2406418 T10044 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2406419 T10044 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2406419 T10044 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51994/solr [junit4] 2> 2406419 T10044 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2406420 T10044 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 2406422 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2406426 T10120 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@763ac119 name:ZooKeeperConnection Watcher:127.0.0.1:51994 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2406426 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2406427 T10044 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 2406447 T10044 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2406451 T10123 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e557ccc name:ZooKeeperConnection Watcher:127.0.0.1:51994/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2406451 T10044 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2406468 T10044 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2407480 T10044 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52004_m%2Fq [junit4] 2> 2407483 T10044 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52004_m%2Fq [junit4] 2> 2407495 T10044 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 2407513 T10124 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 2407513 T10124 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2407516 T10072 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2407516 T10124 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2407521 T10073 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:52004/m/q";, [junit4] 2> "node_name":"127.0.0.1:52004_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 7 [junit4] 2> 2407524 T10073 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:52004/m/q";, [junit4] 2> "node_name":"127.0.0.1:52004_m%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 2407524 T10073 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 2407524 T10073 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 2407530 T10072 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 2407533 T10123 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 2407535 T10100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 2407540 T10083 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 2408520 T10124 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2408520 T10124 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 2408522 T10124 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2408523 T10124 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2408527 T10124 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2408528 T10124 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-004/collection1/' [junit4] 2> 2408530 T10124 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 2408532 T10124 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 2408532 T10124 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest%20915AB212503397C4-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 2408574 T10124 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 2408581 T10124 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 2408600 T10124 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 2408606 T10124 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2408616 T10124 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2408623 T10124 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 2408632 T10124 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 2408796 T10124 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 2408798 T10124 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2408800 T10124 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2408811 T10124 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2408903 T10124 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2408904 T10124 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2408905 T10124 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 915AB212503397C4-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/jetty2/ [junit4] 2> 2408905 T10124 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@33d42514 [junit4] 2> 2408906 T10124 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/jetty2 [junit4] 2> 2408906 T10124 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 915AB212503397C4-001/tempDir-001/jetty2/index/ [junit4] 2> 2408907 T10124 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 915AB212503397C4-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 2408907 T10124 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001/tempDir-001/jetty2/index [junit4] 2> 2408908 T10124 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2408930 T10124 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@381b1bbb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@622f097c),segFN=segments_1,generation=1} [junit4] 2> 2408930 T10124 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2408938 T10124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2408938 T10124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 2408939 T10124 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2408939 T10124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2408940 T10124 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2408940 T10124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 2408941 T10124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2408941 T10124 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2408941 T10124 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2408942 T10124 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2408943 T10124 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2408943 T10124 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2408944 T10124 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2408944 T10124 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 2408945 T10124 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 2408946 T10124 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 2408947 T10124 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 2408948 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 2408951 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 2408952 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 2408953 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 2408954 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 2408954 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 2408955 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 2408956 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 2408956 T10124 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2408957 T10124 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 2408966 T10124 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2408968 T10124 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2408969 T10124 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2408972 T10124 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2408972 T10124 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 2408976 T10124 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2408976 T10124 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2408977 T10124 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=40.8505859375, floorSegmentMB=0.73046875, forceMergeDeletesPctAllowed=14.591857526335355, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4301845861037237 [junit4] 2> 2408977 T10124 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@381b1bbb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@622f097c),segFN=segments_1,generation=1} [junit4] 2> 2408978 T10124 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2408978 T10124 oass.SolrIndexSearcher.<init> Opening Searcher@31952323[collection1] main [junit4] 2> 2408978 T10124 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2408982 T10124 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2408982 T10124 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2408984 T10124 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2408984 T10124 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2408985 T10124 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2408987 T10124 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 2408988 T10124 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2408988 T10124 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2408988 T10124 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 2408990 T10125 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@31952323[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2408991 T10124 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2408993 T10128 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52004/m/q collection:collection1 shard:shard1 [junit4] 2> 2408994 T10044 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2408995 T10044 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2408995 T10128 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 2409014 T10128 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 2409017 T10128 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2409017 T10128 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7403 name=collection1 org.apache.solr.core.SolrCore@48691c65 url=http://127.0.0.1:52004/m/q/collection1 node=127.0.0.1:52004_m%2Fq C7403_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:52004/m/q, node_name=127.0.0.1:52004_m%2 [...truncated too long message...] 14) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) [junit4] > at org.apache.solr.cloud.ShardSplitTest.splitShard(ShardSplitTest.java:532) [junit4] > at org.apache.solr.cloud.ShardSplitTest.incompleteOrOverlappingCustomRangeTest(ShardSplitTest.java:150) [junit4] > at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:102) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:150) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:121) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) [junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) [junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:464) [junit4] > ... 45 more [junit4] 2> 2782869 T10044 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest 915AB212503397C4-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=es_CL, timezone=America/Inuvik [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=2,threads=1,free=198536840,total=434790400 [junit4] 2> NOTE: All tests run in this JVM: [TestComplexPhraseQParserPlugin, TestSolrConfigHandlerConcurrent, TestPerFieldSimilarity, SyncSliceTest, AlternateDirectoryTest, TestStressVersions, TestFieldTypeResource, TestNRTOpen, TestCoreContainer, RecoveryZkTest, XmlUpdateRequestHandlerTest, TestFieldResource, DistributedDebugComponentTest, DistributedQueryElevationComponentTest, ClusterStateTest, TestSystemIdResolver, TestRTGBase, TestFastWriter, TestStressRecovery, AddBlockUpdateTest, HttpPartitionTest, TestRemoteStreaming, TestGroupingSearch, TestCharFilters, IndexSchemaTest, DocValuesMissingTest, BadIndexSchemaTest, OverseerCollectionProcessorTest, TestIndexingPerformance, TestFiltering, StandardRequestHandlerTest, TestFreeTextSuggestions, DOMUtilTest, CoreContainerCoreInitFailuresTest, SOLR749Test, PreAnalyzedFieldTest, AssignTest, TestMergePolicyConfig, TestCloudSchemaless, TestDocumentBuilder, TestBlobHandler, TestReplicaProperties, ShowFileRequestHandlerTest, DistributedFacetPivotLongTailTest, TestCloudManagedSchema, PolyFieldTest, SpellPossibilityIteratorTest, InfoHandlerTest, ScriptEngineTest, LeaderElectionIntegrationTest, TestComponentsName, TermsComponentTest, TestBinaryResponseWriter, DocumentBuilderTest, JsonLoaderTest, TestInitParams, TestCloudInspectUtil, TestBadConfig, TestSuggestSpellingConverter, SharedFSAutoReplicaFailoverTest, TestFileDictionaryLookup, IgnoreCommitOptimizeUpdateProcessorFactoryTest, OutOfBoxZkACLAndCredentialsProvidersTest, CoreAdminRequestStatusTest, AnalyticsQueryTest, QueryParsingTest, TermVectorComponentDistributedTest, TestCodecSupport, TestQueryUtils, TestCollectionAPI, TestSolrXml, HardAutoCommitTest, TestDefaultSearchFieldResource, TestDynamicFieldCollectionResource, TestHighlightDedupGrouping, UniqFieldsUpdateProcessorFactoryTest, TestPhraseSuggestions, SpellCheckCollatorTest, SpellCheckComponentTest, TestCopyFieldCollectionResource, DistributedFacetPivotSmallTest, AnalysisErrorHandlingTest, SharedFSAutoReplicaFailoverUtilsTest, TestArbitraryIndexDir, CachingDirectoryFactoryTest, SynonymTokenizerTest, EchoParamsTest, SystemInfoHandlerTest, TestClassNameShortening, TestLMDirichletSimilarityFactory, RemoteQueryErrorTest, ResponseLogComponentTest, DocExpirationUpdateProcessorFactoryTest, TestExtendedDismaxParser, TestClusterStateMutator, SpellingQueryConverterTest, TestDefaultStatsCache, CursorMarkTest, TestDocSet, DocumentAnalysisRequestHandlerTest, ChaosMonkeyNothingIsSafeTest, TestReversedWildcardFilterFactory, TestReload, FastVectorHighlighterTest, ExternalFileFieldSortTest, DistributedFacetPivotWhiteBoxTest, SignatureUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, TestFoldingMultitermQuery, TestAddFieldRealTimeGet, BufferStoreTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSerializedLuceneMatchVersion, TestBlendedInfixSuggestions, TestSolrCoreProperties, StressHdfsTest, TestRandomDVFaceting, QueryElevationComponentTest, TestSolrQueryParserDefaultOperatorResource, DateFieldTest, TestJoin, TestDFRSimilarityFactory, TestManagedSynonymFilterFactory, XsltUpdateRequestHandlerTest, TestDefaultSimilarityFactory, ResourceLoaderTest, TestSolr4Spatial, TestSweetSpotSimilarityFactory, TestLuceneMatchVersion, TimeZoneUtilsTest, ConvertedLegacyTest, TestRebalanceLeaders, PluginInfoTest, HdfsDirectoryFactoryTest, TestValueSourceCache, TestCloudManagedSchemaConcurrent, AnalyticsMergeStrategyTest, ParsingFieldUpdateProcessorsTest, TestAnalyzedSuggestions, ExternalCollectionsTest, OverriddenZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, TestSchemaNameResource, TestBinaryField, CustomCollectionTest, CloudMLTQParserTest, TestMiniSolrCloudCluster, AutoCommitTest, OverseerTest, TestIndexSearcher, TermVectorComponentTest, AsyncMigrateRouteKeyTest, UnloadDistributedZkTest, ShardRoutingCustomTest, HdfsDirectoryTest, FullSolrCloudDistribCmdsTest, UpdateParamsTest, DistributedExpandComponentTest, DistributedQueryComponentCustomSortTest, BlockDirectoryTest, CircularListTest, TestSolrIndexConfig, TestSolr4Spatial2, TestFunctionQuery, DocValuesMultiTest, TestWordDelimiterFilterFactory, UpdateRequestProcessorFactoryTest, LoggingHandlerTest, CoreAdminCreateDiscoverTest, TestSolrDeletionPolicy2, AliasIntegrationTest, TestOverriddenPrefixQueryForCustomFieldType, TestSolrJ, DistribDocExpirationUpdateProcessorTest, AnalysisAfterCoreReloadTest, DeleteShardTest, TestReRankQParserPlugin, TestCoreDiscovery, TestSolrQueryParser, TestManagedSchema, MoreLikeThisHandlerTest, TestSurroundQueryParser, TestExceedMaxTermLength, SoftAutoCommitTest, DistributedQueueTest, BasicFunctionalityTest, TestBulkSchemaConcurrent, TestInitQParser, TestElisionMultitermQuery, SolrIndexSplitterTest, TestPartialUpdateDeduplication, TestSchemaVersionResource, SimpleCollectionCreateDeleteTest, TestDocBasedVersionConstraints, TestConfig, TestUpdate, TestQueryTypes, TestReplicationHandlerBackup, PingRequestHandlerTest, PrimUtilsTest, ShardSplitTest] [junit4] Completed in 388.96s, 1 test, 1 error <<< FAILURES! [...truncated 800 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:187: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1348: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956: There were test failures: 463 suites, 1871 tests, 1 error, 52 ignored (20 assumptions) Total time: 127 minutes 4 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC 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]
