Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10976/ Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores Error Message: Suite timeout exceeded (>= 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). at __randomizedtesting.SeedInfo.seed([2727D089E057FA9A]:0) REGRESSION: org.apache.solr.core.TestLazyCores.testRace Error Message: Test abandoned because suite timeout was reached. Stack Trace: java.lang.Exception: Test abandoned because suite timeout was reached. at __randomizedtesting.SeedInfo.seed([2727D089E057FA9A]:0) REGRESSION: org.apache.solr.schema.TestCloudSchemaless.testDistribSearch Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:55508/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:55508/collection1 at __randomizedtesting.SeedInfo.seed([2727D089E057FA9A:A6C15E9197089AA6]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206) at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54) at org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865) at sun.reflect.GeneratedMethodAccessor55.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) at java.lang.reflect.Method.invoke(Method.java:619) 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:43) 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:853) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:163) at java.net.SocketInputStream.read(SocketInputStream.java:133) at com.ibm.jsse2.a.a(a.java:156) at com.ibm.jsse2.a.a(a.java:260) at com.ibm.jsse2.qc.a(qc.java:616) at com.ibm.jsse2.qc.a(qc.java:432) at com.ibm.jsse2.e.read(e.java:53) 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.HttpSolrServer.executeMethod(HttpSolrServer.java:449) ... 46 more Build Log: [...truncated 12198 lines...] [junit4] Suite: org.apache.solr.schema.TestCloudSchemaless [junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/init-core-data-001 [junit4] 2> 3844629 T10435 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 3844629 T10435 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 3844632 T10435 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 3844633 T10435 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3844633 T10436 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3844733 T10435 oasc.ZkTestServer.run start zk server on port:49684 [junit4] 2> 3844735 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3844738 T10442 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3def3319 name:ZooKeeperConnection Watcher:127.0.0.1:49684 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3844739 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3844739 T10435 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3844744 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3844745 T10444 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ce1c2d8 name:ZooKeeperConnection Watcher:127.0.0.1:49684/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3844746 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3844747 T10435 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 3844751 T10435 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 3844755 T10435 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 3844758 T10435 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 3844763 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-schemaless.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3844765 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3844770 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema-add-schema-fields-update-processor.xml to /configs/conf1/schema.xml [junit4] 2> 3844772 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 3844776 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3844777 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3844781 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3844782 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 3844787 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3844788 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 3844793 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 3844794 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 3844799 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3844800 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3844804 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3844805 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3844809 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3844810 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3844815 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3844816 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3844820 T10435 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3844822 T10435 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 3844828 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3844830 T10446 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fffcfaf6 name:ZooKeeperConnection Watcher:127.0.0.1:49684/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3844830 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3845183 T10435 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3845187 T10435 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3845188 T10435 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:36560 [junit4] 2> 3845190 T10435 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3845190 T10435 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3845191 T10435 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002 [junit4] 2> 3845192 T10435 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/' [junit4] 2> 3845200 T10435 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/solr.xml [junit4] 2> 3845237 T10435 oasc.CoreContainer.<init> New CoreContainer -1892586044 [junit4] 2> 3845238 T10435 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/] [junit4] 2> 3845240 T10435 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3845240 T10435 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3845240 T10435 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3845241 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3845242 T10435 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3845242 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3845242 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3845243 T10435 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3845243 T10435 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3845246 T10435 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3845248 T10435 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3845248 T10435 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3845249 T10435 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3845249 T10435 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49684/solr [junit4] 2> 3845250 T10435 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3845251 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3845252 T10457 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c524ff56 name:ZooKeeperConnection Watcher:127.0.0.1:49684 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3845252 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3845255 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3845257 T10459 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@49ab8781 name:ZooKeeperConnection Watcher:127.0.0.1:49684/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3845257 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3845260 T10435 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 3845265 T10435 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 3845268 T10435 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 3845272 T10435 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 3845277 T10435 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 3845283 T10435 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 3845286 T10435 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36560_ [junit4] 2> 3845288 T10435 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36560_ [junit4] 2> 3845292 T10435 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 3845295 T10435 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 3845299 T10435 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:36560_ [junit4] 2> 3845301 T10435 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 3845303 T10435 oasc.Overseer.start Overseer (id=92240507297857540-127.0.0.1:36560_-n_0000000000) starting [junit4] 2> 3845306 T10435 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 3845315 T10461 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 3845315 T10435 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 3845318 T10435 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 3845320 T10435 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3845324 T10460 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 3845330 T10462 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 3845330 T10462 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3845332 T10462 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3845332 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3845335 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3845336 T10460 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:36560", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:36560_", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":null} [junit4] 2> 3845337 T10460 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 3845337 T10460 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3845340 T10463 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> 3846333 T10462 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3846333 T10462 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 3846334 T10462 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3846334 T10462 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3846336 T10462 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3846336 T10462 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/' [junit4] 2> 3846336 T10462 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 3846337 T10462 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 3846337 T10462 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 3846367 T10462 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 3846401 T10462 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3846402 T10462 oass.ManagedIndexSchemaFactory.create The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead [junit4] 2> 3846403 T10462 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3846407 T10462 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 3846436 T10462 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3846438 T10462 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema [junit4] 2> 3846441 T10462 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak [junit4] 2> 3846444 T10462 oass.ManagedIndexSchemaFactory.zkUgradeToManagedSchema After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak [junit4] 2> 3846444 T10462 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3846445 T10462 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 3846445 T10462 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/data/ [junit4] 2> 3846445 T10462 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 3846446 T10462 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/data [junit4] 2> 3846446 T10462 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/data/index/ [junit4] 2> 3846447 T10462 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3846447 T10462 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/data/index [junit4] 2> 3846448 T10462 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=34.9873046875, floorSegmentMB=0.2275390625, forceMergeDeletesPctAllowed=16.420750475281547, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3851745 T10462 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b8a2228; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3851746 T10462 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3851747 T10462 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 3851782 T10462 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3851783 T10462 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3851783 T10462 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3851784 T10462 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3851785 T10462 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3851785 T10462 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3851786 T10462 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3851786 T10462 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3851787 T10462 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3851787 T10462 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3851788 T10462 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3851788 T10462 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3851791 T10462 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3851793 T10462 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3851795 T10462 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3851797 T10462 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3851799 T10462 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3851802 T10462 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3851802 T10462 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3851803 T10462 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=72.9794921875, floorSegmentMB=2.197265625, forceMergeDeletesPctAllowed=1.2536592992795692, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3851803 T10462 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b8a2228; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3851804 T10462 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3851804 T10462 oass.SolrIndexSearcher.<init> Opening Searcher@b46e1efd[collection1] main [junit4] 2> 3851804 T10462 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3851805 T10462 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3851806 T10462 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3851806 T10462 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3851807 T10462 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3851807 T10462 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3851807 T10462 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3851808 T10462 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3851808 T10462 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 3851811 T10462 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 3851811 T10462 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3851812 T10462 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3851812 T10462 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 3851818 T10464 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b46e1efd[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 3851818 T10462 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3851820 T10467 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:36560 collection:control_collection shard:shard1 [junit4] 2> 3851820 T10435 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 3851821 T10435 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3851821 T10467 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 3851828 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3851829 T10469 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8cb2a12c name:ZooKeeperConnection Watcher:127.0.0.1:49684/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3851830 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3851832 T10467 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3851832 T10435 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3851834 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3851835 T10467 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3851836 T10467 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 3851836 T10435 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> ASYNC NEW_CORE C2591 name=collection1 org.apache.solr.core.SolrCore@334567c9 url=https://127.0.0.1:36560/collection1 node=127.0.0.1:36560_ C2591_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:36560, core=collection1, node_name=127.0.0.1:36560_} [junit4] 2> 3851836 T10467 C2591 P36560 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:36560/collection1/ [junit4] 2> 3851836 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3851838 T10467 C2591 P36560 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3851839 T10467 C2591 P36560 oasc.SyncStrategy.syncToMe https://127.0.0.1:36560/collection1/ has no replicas [junit4] 2> 3851840 T10467 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:36560/collection1/ shard1 [junit4] 2> 3851841 T10467 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3851843 T10463 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> 3851843 T10470 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> 3851845 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3851847 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3851950 T10470 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> 3851950 T10463 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> 3851996 T10467 oasc.ZkController.register We are https://127.0.0.1:36560/collection1/ and leader is https://127.0.0.1:36560/collection1/ [junit4] 2> 3851997 T10467 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:36560 [junit4] 2> 3851997 T10467 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3851997 T10467 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 3851998 T10467 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3851999 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3851999 T10467 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3852001 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3852002 T10460 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:36560", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:36560_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 3852105 T10463 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> 3852105 T10470 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> 3852181 T10435 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 3852182 T10435 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3852185 T10435 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3852185 T10435 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:42036 [junit4] 2> 3852187 T10435 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3852188 T10435 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3852188 T10435 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003 [junit4] 2> 3852189 T10435 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/' [junit4] 2> 3852196 T10435 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/solr.xml [junit4] 2> 3852229 T10435 oasc.CoreContainer.<init> New CoreContainer -600630304 [junit4] 2> 3852229 T10435 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/] [junit4] 2> 3852231 T10435 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3852231 T10435 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3852232 T10435 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3852232 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3852232 T10435 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3852233 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3852233 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3852234 T10435 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3852235 T10435 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3852236 T10435 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3852238 T10435 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3852238 T10435 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3852239 T10435 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3852240 T10435 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49684/solr [junit4] 2> 3852240 T10435 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3852241 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3852242 T10481 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e9002bd5 name:ZooKeeperConnection Watcher:127.0.0.1:49684 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852243 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3852244 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3852245 T10483 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fbf40400 name:ZooKeeperConnection Watcher:127.0.0.1:49684/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852246 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3852251 T10435 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3853254 T10435 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42036_ [junit4] 2> 3853256 T10435 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42036_ [junit4] 2> 3853267 T10485 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3853268 T10485 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3853269 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3853269 T10485 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3853271 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3853272 T10460 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:42036", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:42036_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":null} [junit4] 2> 3853272 T10460 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4] [junit4] 2> 3853273 T10460 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4 [junit4] 2> 3853377 T10484 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> 3853377 T10463 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> 3853377 T10470 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> 3854270 T10485 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3854270 T10485 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3854271 T10485 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3854272 T10485 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3854273 T10485 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3854274 T10485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/' [junit4] 2> 3854275 T10485 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 3854276 T10485 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 3854277 T10485 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 3854320 T10485 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 3854364 T10485 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3854366 T10485 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 3854370 T10485 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 3854412 T10485 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3854414 T10485 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3854414 T10485 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 3854414 T10485 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/data/ [junit4] 2> 3854415 T10485 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 3854416 T10485 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/data [junit4] 2> 3854416 T10485 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/data/index/ [junit4] 2> 3854417 T10485 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3854417 T10485 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/data/index [junit4] 2> 3854418 T10485 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=34.9873046875, floorSegmentMB=0.2275390625, forceMergeDeletesPctAllowed=16.420750475281547, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3854428 T10485 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4223ed67; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3854429 T10485 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3854430 T10485 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 3854430 T10485 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3854431 T10485 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3854431 T10485 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3854431 T10485 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3854431 T10485 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3854432 T10485 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3854432 T10485 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3854432 T10485 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3854432 T10485 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3854433 T10485 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3854433 T10485 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3854433 T10485 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3854434 T10485 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3854435 T10485 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3854436 T10485 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3854437 T10485 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3854439 T10485 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3854441 T10485 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3854441 T10485 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3854441 T10485 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=72.9794921875, floorSegmentMB=2.197265625, forceMergeDeletesPctAllowed=1.2536592992795692, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3854442 T10485 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4223ed67; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3854442 T10485 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3854443 T10485 oass.SolrIndexSearcher.<init> Opening Searcher@a29b992b[collection1] main [junit4] 2> 3854443 T10485 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3854444 T10485 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3854444 T10485 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3854445 T10485 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3854445 T10485 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3854446 T10485 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3854447 T10485 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3854448 T10485 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3854448 T10485 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3854448 T10485 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3854449 T10485 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 3854450 T10486 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a29b992b[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 3854450 T10485 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3854451 T10489 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:42036 collection:collection1 shard:shard4 [junit4] 2> 3854452 T10435 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 3854453 T10435 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3854453 T10489 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard4/election [junit4] 2> 3854460 T10489 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard4 [junit4] 2> 3854462 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3854462 T10489 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3854463 T10489 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2592 name=collection1 org.apache.solr.core.SolrCore@8b902c02 url=https://127.0.0.1:42036/collection1 node=127.0.0.1:42036_ C2592_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:42036, core=collection1, node_name=127.0.0.1:42036_} [junit4] 2> 3854463 T10489 C2592 P42036 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:42036/collection1/ [junit4] 2> 3854464 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3854464 T10489 C2592 P42036 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3854464 T10489 C2592 P42036 oasc.SyncStrategy.syncToMe https://127.0.0.1:42036/collection1/ has no replicas [junit4] 2> 3854465 T10489 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:42036/collection1/ shard4 [junit4] 2> 3854465 T10489 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard4 [junit4] 2> 3854469 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3854572 T10470 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> 3854572 T10463 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> 3854573 T10484 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> 3854620 T10489 oasc.ZkController.register We are https://127.0.0.1:42036/collection1/ and leader is https://127.0.0.1:42036/collection1/ [junit4] 2> 3854621 T10489 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:42036 [junit4] 2> 3854621 T10489 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3854621 T10489 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3854621 T10489 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3854623 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3854623 T10489 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3854624 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3854625 T10460 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:42036", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:42036_", [junit4] 2> "shard":"shard4", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 3854728 T10470 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> 3854728 T10463 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> 3854729 T10484 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> 3854790 T10435 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 3854791 T10435 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3854793 T10435 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3854794 T10435 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:50734 [junit4] 2> 3854796 T10435 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3854796 T10435 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3854797 T10435 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004 [junit4] 2> 3854797 T10435 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/' [junit4] 2> 3854805 T10435 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/solr.xml [junit4] 2> 3854837 T10435 oasc.CoreContainer.<init> New CoreContainer -230409265 [junit4] 2> 3854838 T10435 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/] [junit4] 2> 3854839 T10435 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3854840 T10435 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3854840 T10435 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3854840 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3854841 T10435 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3854841 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3854842 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3854842 T10435 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3854843 T10435 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3854844 T10435 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3854846 T10435 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3854846 T10435 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3854847 T10435 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3854847 T10435 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49684/solr [junit4] 2> 3854848 T10435 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3854849 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3854850 T10500 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54701b53 name:ZooKeeperConnection Watcher:127.0.0.1:49684 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3854850 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3854852 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3854853 T10502 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d403f8be name:ZooKeeperConnection Watcher:127.0.0.1:49684/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3854853 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3854861 T10435 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3855865 T10435 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50734_ [junit4] 2> 3855867 T10435 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50734_ [junit4] 2> 3855881 T10504 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3855881 T10504 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3855883 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3855883 T10504 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3855885 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3855887 T10460 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:50734", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50734_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":null} [junit4] 2> 3855887 T10460 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4 [junit4] 2> 3855888 T10460 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 3855992 T10484 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> 3855992 T10463 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> 3855992 T10470 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> 3855992 T10503 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> 3856885 T10504 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3856885 T10504 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3856887 T10504 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3856887 T10504 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3856889 T10504 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3856889 T10504 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/' [junit4] 2> 3856890 T10504 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 3856891 T10504 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 3856892 T10504 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 3856930 T10504 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 3856964 T10504 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3856965 T10504 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 3856968 T10504 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 3856996 T10504 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3856997 T10504 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3856997 T10504 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 3856997 T10504 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/data/ [junit4] 2> 3856998 T10504 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 3856998 T10504 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/data [junit4] 2> 3856998 T10504 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/data/index/ [junit4] 2> 3856999 T10504 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3856999 T10504 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/data/index [junit4] 2> 3857000 T10504 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=34.9873046875, floorSegmentMB=0.2275390625, forceMergeDeletesPctAllowed=16.420750475281547, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3857007 T10504 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@355130c3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3857007 T10504 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3857008 T10504 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 3857009 T10504 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3857009 T10504 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3857010 T10504 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3857010 T10504 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3857011 T10504 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3857011 T10504 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3857012 T10504 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3857012 T10504 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3857012 T10504 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3857013 T10504 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3857013 T10504 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3857013 T10504 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3857014 T10504 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3857015 T10504 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3857016 T10504 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3857017 T10504 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3857018 T10504 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3857020 T10504 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3857021 T10504 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3857021 T10504 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=72.9794921875, floorSegmentMB=2.197265625, forceMergeDeletesPctAllowed=1.2536592992795692, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3857022 T10504 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@355130c3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3857022 T10504 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3857023 T10504 oass.SolrIndexSearcher.<init> Opening Searcher@c0c220d2[collection1] main [junit4] 2> 3857023 T10504 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3857024 T10504 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3857024 T10504 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3857025 T10504 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3857025 T10504 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3857025 T10504 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3857027 T10504 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3857027 T10504 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3857028 T10504 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3857028 T10504 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3857028 T10504 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 3857031 T10505 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c0c220d2[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 3857031 T10504 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3857033 T10508 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50734 collection:collection1 shard:shard2 [junit4] 2> 3857033 T10435 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 3857035 T10435 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3857034 T10508 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 3857042 T10508 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 3857044 T10508 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3857044 T10508 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 3857045 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> ASYNC NEW_CORE C2593 name=collection1 org.apache.solr.core.SolrCore@8491c442 url=https://127.0.0.1:50734/collection1 node=127.0.0.1:50734_ C2593_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:50734, core=collection1, node_name=127.0.0.1:50734_} [junit4] 2> 3857045 T10508 C2593 P50734 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50734/collection1/ [junit4] 2> 3857046 T10508 C2593 P50734 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3857046 T10508 C2593 P50734 oasc.SyncStrategy.syncToMe https://127.0.0.1:50734/collection1/ has no replicas [junit4] 2> 3857047 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3857047 T10508 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50734/collection1/ shard2 [junit4] 2> 3857047 T10508 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 3857051 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3857155 T10470 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> 3857155 T10463 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> 3857155 T10484 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> 3857155 T10503 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> 3857202 T10508 oasc.ZkController.register We are https://127.0.0.1:50734/collection1/ and leader is https://127.0.0.1:50734/collection1/ [junit4] 2> 3857203 T10508 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50734 [junit4] 2> 3857203 T10508 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3857203 T10508 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3857204 T10508 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3857205 T10463 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3857206 T10508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3857207 T10460 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 3857208 T10460 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:50734", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50734_", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node2"} [junit4] 2> 3857311 T10484 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> 3857311 T10470 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> 3857311 T10463 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> 3857311 T10503 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> 3857371 T10435 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 3857372 T10435 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3857374 T10435 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3857375 T10435 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:55488 [junit4] 2> 3857376 T10435 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3857377 T10435 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3857378 T10435 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-005 [junit4] 2> 3857378 T10435 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-005/' [junit4] 2> 3857385 T10435 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-005/solr.xml [junit4] 2> 3857425 T10435 oasc.CoreContainer.<init> New CoreContainer -1462437743 [junit4] 2> 3857426 T10435 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.schema.TestCloudSchemaless-2727D089E057FA9A-001/tempDir-005/] [junit4] 2> 3857427 T10435 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3857428 T10435 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3857428 T10435 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3857429 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3857430 T10435 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3857430 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3857431 T10435 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3857431 T10435 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3857432 T10435 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3857434 T10435 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3857435 T10435 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3857436 T10435 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3857436 T10435 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3857437 T10435 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49684/solr [junit4] 2> 3857438 T10435 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3857439 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3857440 T10519 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bef3b5c3 name:ZooKeeperConnection Watcher:127.0.0.1:49684 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3857440 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3857442 T10435 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3857443 T10521 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@539c7200 name:ZooKeeperConnection Watcher:127.0.0.1:49684/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3857444 T10435 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3857452 T10435 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3858456 T10435 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55488_ [junit4] 2> 3858458 T10435 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55488_ [junit4] 2> 3858473 T10523 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3858474 T10523 oasc.ZkController.publish numShards not found on descriptor - reading it from system p [...truncated too long message...] /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/tempDir-003/collection3/data/index;done=false>>] [junit4] 2> 9537071 T5432 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/tempDir-003/collection3/data/index [junit4] 2> 9537071 T5432 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/tempDir-003/collection3/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/tempDir-003/collection3/data;done=false>>] [junit4] 2> 9537072 T5432 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/tempDir-003/collection3/data [junit4] 2> 9537072 T5432 oas.SolrTestCaseJ4.tearDown ###Ending testRace [junit4] 2> 9537077 T5432 oas.SolrTestCaseJ4.setUp ###Starting testCreateSame [junit4] 2> 9537078 T5432 oas.SolrTestCaseJ4.tearDown ###Ending testCreateSame [junit4] 2> 9537082 T5432 oas.SolrTestCaseJ4.setUp ###Starting testLazySearch [junit4] 2> 9537083 T5432 oas.SolrTestCaseJ4.tearDown ###Ending testLazySearch [junit4] 2> 9537087 T5432 oas.SolrTestCaseJ4.setUp ###Starting testBadConfigsGenerateErrors [junit4] 2> 9537089 T5432 oas.SolrTestCaseJ4.tearDown ###Ending testBadConfigsGenerateErrors [junit4] 2> 9537093 T5432 oas.SolrTestCaseJ4.setUp ###Starting testCachingLimit [junit4] 2> 9537094 T5432 oas.SolrTestCaseJ4.tearDown ###Ending testCachingLimit [junit4] 2> 9537098 T5432 oas.SolrTestCaseJ4.setUp ###Starting testCreateTransientFromAdmin [junit4] 2> 9537100 T5432 oas.SolrTestCaseJ4.tearDown ###Ending testCreateTransientFromAdmin [junit4] 2> 9537102 T5432 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> 9537102 T5432 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-256857557 [junit4] 2> 9537103 T5432 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@dc3aed40 [junit4] 2> 9537103 T5432 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0} [junit4] 2> 9537103 T5432 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState [junit4] 2> 9537104 T5432 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 9537104 T5432 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser [junit4] 2> 9537105 T5432 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 9537105 T5432 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 9537105 T5432 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/init-core-data-001;done=false>>] [junit4] 2> 9537106 T5432 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/init-core-data-001 [junit4] 2> 9537106 T5432 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/init-core-data-001/index;done=false>>] [junit4] 2> 9537106 T5432 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001/init-core-data-001/index [junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./temp/solr.core.TestLazyCores-2727D089E057FA9A-001 [junit4] 2> 7220164 T5431 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=th_TH, timezone=Asia/Beirut [junit4] 2> NOTE: Linux 3.13.0-32-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=1,free=54463216,total=287047680 [junit4] 2> NOTE: All tests run in this JVM: [TestDynamicFieldCollectionResource, TestNoOpRegenerator, TestConfig, SolrCmdDistributorTest, TestRTGBase, SignatureUpdateProcessorFactoryTest, TestQuerySenderListener, MigrateRouteKeyTest, TestLMDirichletSimilarityFactory, CursorMarkTest, TestCloudManagedSchemaConcurrent, ExternalFileFieldSortTest, OverseerStatusTest, CoreAdminRequestStatusTest, TestWordDelimiterFilterFactory, ReturnFieldsTest, TestSurroundQueryParser, ParsingFieldUpdateProcessorsTest, SolrIndexSplitterTest, ReplicationFactorTest, PrimitiveFieldTypeTest, TestSolrJ, TestSchemaSimilarityResource, EchoParamsTest, TestCopyFieldCollectionResource, JSONWriterTest, TestStressReorder, TestIntervalFaceting, JsonLoaderTest, TestSolrIndexConfig, TestDocumentBuilder, TestFastOutputStream, TestBinaryResponseWriter, TestMissingGroups, SpellCheckCollatorTest, TestFieldTypeCollectionResource, TestCloudInspectUtil, TestCollationFieldDocValues, TestChildDocTransformer, TestAnalyzedSuggestions, MinimalSchemaTest, DistributedIntervalFacetingTest, XmlUpdateRequestHandlerTest, DirectSolrSpellCheckerTest, RecoveryZkTest, DocumentAnalysisRequestHandlerTest, UpdateParamsTest, CustomCollectionTest, TestStressRecovery, TestFastLRUCache, OverseerTest, QueryResultKeyTest, HighlighterTest, TestSolrDeletionPolicy1, TestReload, TestManagedResourceStorage, TestAddFieldRealTimeGet, PreAnalyzedUpdateProcessorTest, DOMUtilTest, HdfsBasicDistributedZkTest, TestReloadAndDeleteDocs, FastVectorHighlighterTest, TestSolrCoreProperties, RequiredFieldsTest, OpenExchangeRatesOrgProviderTest, DocValuesMultiTest, AnalysisErrorHandlingTest, TestPartialUpdateDeduplication, TestHashPartitioner, SuggesterTSTTest, TestSolrDeletionPolicy2, TestExceedMaxTermLength, PingRequestHandlerTest, PluginInfoTest, TestDocBasedVersionConstraints, ResponseHeaderTest, TestJmxIntegration, TestSchemaResource, ResponseLogComponentTest, TestOverriddenPrefixQueryForCustomFieldType, TestFastWriter, TestManagedResource, SystemInfoHandlerTest, TestSolr4Spatial, NoCacheHeaderTest, TestCoreDiscovery, TestRequestStatusCollectionAPI, TestSchemaVersionResource, TestManagedSchema, FieldMutatingUpdateProcessorTest, SpellingQueryConverterTest, TestAnalyzeInfixSuggestions, CoreMergeIndexesAdminHandlerTest, DeleteInactiveReplicaTest, HighlighterConfigTest, AddBlockUpdateTest, TestSolrXml, TestComponentsName, SyncSliceTest, TriLevelCompositeIdRoutingTest, AssignTest, TestSolrXmlPersistence, TestStressVersions, HdfsWriteToMultipleCollectionsTest, OpenCloseCoreStressTest, BasicZkTest, TestReplicationHandler, TestDistributedGrouping, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, DistributedTermsComponentTest, PeerSyncTest, BasicFunctionalityTest, TestLazyCores] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=2727D089E057FA9A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=th_TH -Dtests.timezone=Asia/Beirut -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J0 | TestLazyCores (suite) <<< [junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). [junit4] > at __randomizedtesting.SeedInfo.seed([2727D089E057FA9A]:0) [junit4] Completed on J0 in 7220.19s, 3 tests, 2 errors <<< FAILURES! [...truncated 12 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:474: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1298: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:922: There were test failures: 421 suites, 1756 tests, 1 suite-level error, 2 errors, 47 ignored (18 assumptions) Total time: 205 minutes 55 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;} Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org