Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1741/ Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.schema.TestCloudSchemaless.testDistribSearch Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:50539/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:50539/collection1 at __randomizedtesting.SeedInfo.seed([11D8D6EC2C641251:903E58F45B3B726D]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558) 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:871) at sun.reflect.GeneratedMethodAccessor43.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) 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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51) 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:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:152) at java.net.SocketInputStream.read(SocketInputStream.java:122) at sun.security.ssl.InputRecord.readFully(InputRecord.java:442) at sun.security.ssl.InputRecord.read(InputRecord.java:480) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884) at sun.security.ssl.AppInputStream.read(AppInputStream.java:102) 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:448) ... 47 more Build Log: [...truncated 11190 lines...] [junit4] Suite: org.apache.solr.schema.TestCloudSchemaless [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/init-core-data-001 [junit4] 2> 2622468 T5592 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 2622469 T5592 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 2622473 T5592 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 2622474 T5592 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2622476 T5593 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 2622577 T5592 oasc.ZkTestServer.run start zk server on port:50515 [junit4] 2> 2622581 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2622592 T5599 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@64c7665c name:ZooKeeperConnection Watcher:127.0.0.1:50515 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2622592 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2622593 T5592 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 2622609 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2622614 T5601 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@331b2dff name:ZooKeeperConnection Watcher:127.0.0.1:50515/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2622615 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2622615 T5592 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 2622625 T5592 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 2622634 T5592 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 2622642 T5592 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 2622652 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-schemaless.xml to /configs/conf1/solrconfig.xml [junit4] 2> 2622652 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2622667 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-add-schema-fields-update-processor.xml to /configs/conf1/schema.xml [junit4] 2> 2622668 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 2622678 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2622679 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2622688 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 2622689 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 2622699 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 2622700 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 2622710 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 2622712 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 2622722 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 2622722 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2622731 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 2622732 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2622741 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2622741 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2622750 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 2622752 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2622760 T5592 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 2622761 T5592 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 2622772 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2622777 T5603 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@51f6249c name:ZooKeeperConnection Watcher:127.0.0.1:50515/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2622778 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2624870 T5592 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2624877 T5592 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2624879 T5592 oejs.AbstractConnector.doStart Started [email protected]:50519 [junit4] 2> 2624884 T5592 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2624885 T5592 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2624885 T5592 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002 [junit4] 2> 2624886 T5592 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/' [junit4] 2> 2624926 T5592 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/solr.xml [junit4] 2> 2624992 T5592 oasc.CoreContainer.<init> New CoreContainer 609801429 [junit4] 2> 2624993 T5592 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/] [junit4] 2> 2624994 T5592 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2624994 T5592 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2624994 T5592 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2624994 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2624995 T5592 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2624996 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2624996 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2624996 T5592 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2624996 T5592 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2624999 T5592 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2625001 T5592 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2625001 T5592 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2625001 T5592 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2625002 T5592 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50515/solr [junit4] 2> 2625002 T5592 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2625004 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2625008 T5614 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5962a658 name:ZooKeeperConnection Watcher:127.0.0.1:50515 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2625008 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2625031 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2625034 T5616 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@339bcb14 name:ZooKeeperConnection Watcher:127.0.0.1:50515/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2625034 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2625040 T5592 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 2625053 T5592 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 2625063 T5592 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 2625072 T5592 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 2625081 T5592 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 2625094 T5592 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 2625099 T5592 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50519_ [junit4] 2> 2625102 T5592 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50519_ [junit4] 2> 2625113 T5592 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 2625118 T5592 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 2625125 T5592 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 2625130 T5592 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:50519_ [junit4] 2> 2625130 T5592 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 2625138 T5592 oasc.Overseer.start Overseer (id=92303934987239428-127.0.0.1:50519_-n_0000000000) starting [junit4] 2> 2625148 T5592 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 2625181 T5592 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2625183 T5618 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 2625186 T5592 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 2625193 T5592 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 2625199 T5592 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2625208 T5617 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 2625558 T5620 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 2625558 T5620 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2625561 T5620 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2625561 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2625568 T5617 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "base_url":"https://127.0.0.1:50519";, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "node_name":"127.0.0.1:50519_", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "state":"down"} [junit4] 2> 2625568 T5617 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 2625568 T5617 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 2625578 T5621 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> 2626563 T5620 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2626563 T5620 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 2626565 T5620 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2626565 T5620 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 2626569 T5620 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2626569 T5620 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/' [junit4] 2> 2626571 T5620 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 2626572 T5620 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 2626572 T5620 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 2626642 T5620 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.0 [junit4] 2> 2626689 T5620 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2626692 T5620 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> 2626695 T5620 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 2626704 T5620 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 2626760 T5620 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2626763 T5620 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema [junit4] 2> 2626768 T5620 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak [junit4] 2> 2626781 T5620 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> 2626781 T5620 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2626781 T5620 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 2626782 T5620 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/data/ [junit4] 2> 2626782 T5620 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 2626783 T5620 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/data [junit4] 2> 2626783 T5620 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/data/index/ [junit4] 2> 2626784 T5620 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2626784 T5620 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/data/index [junit4] 2> 2626785 T5620 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=99.78125, floorSegmentMB=2.0732421875, forceMergeDeletesPctAllowed=15.79689044883887, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.16759332448233727 [junit4] 2> 2626790 T5620 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61fe4204; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2626790 T5620 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2626793 T5620 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 2627396 T5620 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2627397 T5620 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2627397 T5620 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2627398 T5620 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2627398 T5620 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2627399 T5620 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2627400 T5620 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2627400 T5620 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2627400 T5620 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2627401 T5620 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2627401 T5620 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2627402 T5620 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2627418 T5620 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2627421 T5620 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2627423 T5620 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2627424 T5620 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2627426 T5620 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2627431 T5620 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2627432 T5620 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2627433 T5620 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=53.939453125, floorSegmentMB=0.2705078125, forceMergeDeletesPctAllowed=23.592101098247106, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2627435 T5620 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61fe4204; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2627436 T5620 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2627436 T5620 oass.SolrIndexSearcher.<init> Opening Searcher@5d7ddb4[collection1] main [junit4] 2> 2627436 T5620 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 2627441 T5620 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2627441 T5620 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2627444 T5620 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2627444 T5620 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2627444 T5620 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2627446 T5620 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 2627446 T5620 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2627448 T5620 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 2627457 T5620 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 2627458 T5620 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2627458 T5620 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2627458 T5620 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 2627482 T5620 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2627483 T5622 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d7ddb4[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 2627486 T5625 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50519 collection:control_collection shard:shard1 [junit4] 2> 2627488 T5592 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2627489 T5592 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2627489 T5625 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 2627501 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2627507 T5627 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7ee6785b name:ZooKeeperConnection Watcher:127.0.0.1:50515/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2627507 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2627510 T5592 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2627511 T5625 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 2627519 T5592 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2627519 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2627524 T5625 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2627524 T5625 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2098 name=collection1 org.apache.solr.core.SolrCore@23ca1f00 url=https://127.0.0.1:50519/collection1 node=127.0.0.1:50519_ C2098_STATE=coll:control_collection core:collection1 props:{base_url=https://127.0.0.1:50519, core=collection1, node_name=127.0.0.1:50519_, state=down} [junit4] 2> 2627524 T5625 C2098 P50519 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50519/collection1/ [junit4] 2> 2627525 T5625 C2098 P50519 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2627525 T5625 C2098 P50519 oasc.SyncStrategy.syncToMe https://127.0.0.1:50519/collection1/ has no replicas [junit4] 2> 2627525 T5625 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50519/collection1/ shard1 [junit4] 2> 2627526 T5625 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2627564 T5621 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> 2627587 T5628 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> 2627589 T5629 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2627610 T5625 oasc.ZkController.register We are https://127.0.0.1:50519/collection1/ and leader is https://127.0.0.1:50519/collection1/ [junit4] 2> 2627610 T5625 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50519 [junit4] 2> 2627611 T5625 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2627611 T5625 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 2627611 T5625 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2627617 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2627626 T5617 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "base_url":"https://127.0.0.1:50519";, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"4", [junit4] 2> "node_name":"127.0.0.1:50519_", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "operation":"state", [junit4] 2> "state":"active"} [junit4] 2> 2627738 T5628 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> 2627738 T5621 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> 2629481 T5592 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 2629482 T5592 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2629492 T5592 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2629493 T5592 oejs.AbstractConnector.doStart Started [email protected]:50523 [junit4] 2> 2629498 T5592 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2629498 T5592 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2629499 T5592 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003 [junit4] 2> 2629499 T5592 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/' [junit4] 2> 2629561 T5592 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/solr.xml [junit4] 2> 2629655 T5592 oasc.CoreContainer.<init> New CoreContainer 748174211 [junit4] 2> 2629656 T5592 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/] [junit4] 2> 2629659 T5592 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2629660 T5592 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2629660 T5592 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2629661 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2629661 T5592 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2629662 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2629663 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2629663 T5592 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2629664 T5592 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2629667 T5592 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2629670 T5592 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2629671 T5592 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2629671 T5592 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2629671 T5592 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50515/solr [junit4] 2> 2629672 T5592 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2629673 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2629678 T5640 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fae0f59 name:ZooKeeperConnection Watcher:127.0.0.1:50515 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2629679 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2629684 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2629688 T5642 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19153ffe name:ZooKeeperConnection Watcher:127.0.0.1:50515/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2629688 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2629713 T5592 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2630724 T5592 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50523_ [junit4] 2> 2630725 T5592 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50523_ [junit4] 2> 2630738 T5592 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 2630754 T5644 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 2630754 T5644 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2630756 T5644 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2630756 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2630764 T5617 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "base_url":"https://127.0.0.1:50523";, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "node_name":"127.0.0.1:50523_", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "state":"down"} [junit4] 2> 2630764 T5617 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4] [junit4] 2> 2630764 T5617 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3 [junit4] 2> 2630772 T5621 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> 2630773 T5628 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> 2630773 T5643 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> 2631758 T5644 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2631759 T5644 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 2631763 T5644 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2631763 T5644 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2631768 T5644 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2631768 T5644 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/' [junit4] 2> 2631771 T5644 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 2631772 T5644 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 2631773 T5644 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 2631853 T5644 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.0 [junit4] 2> 2631904 T5644 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2631907 T5644 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 2631915 T5644 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 2631996 T5644 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2631997 T5644 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2631997 T5644 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 2631998 T5644 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/data/ [junit4] 2> 2631998 T5644 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 2631999 T5644 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/data [junit4] 2> 2632000 T5644 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/data/index/ [junit4] 2> 2632001 T5644 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2632002 T5644 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/data/index [junit4] 2> 2632002 T5644 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=99.78125, floorSegmentMB=2.0732421875, forceMergeDeletesPctAllowed=15.79689044883887, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.16759332448233727 [junit4] 2> 2632009 T5644 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2743c155; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2632009 T5644 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2632012 T5644 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 2632014 T5644 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2632014 T5644 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2632014 T5644 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2632015 T5644 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2632015 T5644 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2632015 T5644 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2632016 T5644 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2632016 T5644 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2632016 T5644 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2632017 T5644 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2632017 T5644 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2632017 T5644 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2632038 T5644 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2632043 T5644 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2632047 T5644 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2632050 T5644 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2632054 T5644 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2632065 T5644 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2632065 T5644 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2632067 T5644 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=53.939453125, floorSegmentMB=0.2705078125, forceMergeDeletesPctAllowed=23.592101098247106, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2632069 T5644 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2743c155; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2632070 T5644 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2632071 T5644 oass.SolrIndexSearcher.<init> Opening Searcher@59fc7412[collection1] main [junit4] 2> 2632071 T5644 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2632075 T5644 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2632075 T5644 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2632078 T5644 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2632078 T5644 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2632078 T5644 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2632083 T5644 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 2632084 T5644 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2632084 T5644 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 2632084 T5644 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2632084 T5644 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 2632088 T5644 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2632088 T5645 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59fc7412[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 2632092 T5648 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50523 collection:collection1 shard:shard3 [junit4] 2> 2632093 T5592 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2632094 T5592 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2632095 T5648 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 2632109 T5648 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 2632114 T5648 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2632114 T5648 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2099 name=collection1 org.apache.solr.core.SolrCore@55a59df4 url=https://127.0.0.1:50523/collection1 node=127.0.0.1:50523_ C2099_STATE=coll:collection1 core:collection1 props:{base_url=https://127.0.0.1:50523, core=collection1, node_name=127.0.0.1:50523_, state=down} [junit4] 2> 2632115 T5648 C2099 P50523 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50523/collection1/ [junit4] 2> 2632115 T5648 C2099 P50523 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2632115 T5648 C2099 P50523 oasc.SyncStrategy.syncToMe https://127.0.0.1:50523/collection1/ has no replicas [junit4] 2> 2632116 T5648 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50523/collection1/ shard3 [junit4] 2> 2632116 T5648 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 2632118 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2632150 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2632161 T5643 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> 2632161 T5621 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> 2632165 T5628 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> 2632184 T5648 oasc.ZkController.register We are https://127.0.0.1:50523/collection1/ and leader is https://127.0.0.1:50523/collection1/ [junit4] 2> 2632184 T5648 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50523 [junit4] 2> 2632185 T5648 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2632185 T5648 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 2632185 T5648 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2632192 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2632195 T5617 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "base_url":"https://127.0.0.1:50523";, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"4", [junit4] 2> "node_name":"127.0.0.1:50523_", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard3", [junit4] 2> "operation":"state", [junit4] 2> "state":"active"} [junit4] 2> 2632306 T5621 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> 2632311 T5643 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> 2632312 T5628 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> 2633990 T5592 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 2633991 T5592 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2633998 T5592 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2634000 T5592 oejs.AbstractConnector.doStart Started [email protected]:50526 [junit4] 2> 2634004 T5592 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2634005 T5592 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2634005 T5592 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004 [junit4] 2> 2634005 T5592 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/' [junit4] 2> 2634044 T5592 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/solr.xml [junit4] 2> 2634117 T5592 oasc.CoreContainer.<init> New CoreContainer 71441334 [junit4] 2> 2634117 T5592 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/] [junit4] 2> 2634120 T5592 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2634122 T5592 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2634122 T5592 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2634123 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2634124 T5592 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2634124 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2634124 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2634125 T5592 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2634127 T5592 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2634130 T5592 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2634134 T5592 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2634134 T5592 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2634135 T5592 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2634136 T5592 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50515/solr [junit4] 2> 2634136 T5592 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2634138 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2634144 T5659 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@137bf28e name:ZooKeeperConnection Watcher:127.0.0.1:50515 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2634145 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2634150 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2634152 T5661 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ac1cb20 name:ZooKeeperConnection Watcher:127.0.0.1:50515/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2634153 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2634175 T5592 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2635187 T5592 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50526_ [junit4] 2> 2635189 T5592 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50526_ [junit4] 2> 2635215 T5592 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 2635254 T5663 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 2635254 T5663 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2635257 T5663 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2635257 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2635265 T5617 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "base_url":"https://127.0.0.1:50526";, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "node_name":"127.0.0.1:50526_", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "state":"down"} [junit4] 2> 2635265 T5617 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4 [junit4] 2> 2635265 T5617 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 2635275 T5621 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> 2635277 T5628 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> 2635277 T5643 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> 2635277 T5662 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> 2636259 T5663 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2636260 T5663 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 2636262 T5663 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2636262 T5663 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2636265 T5663 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2636265 T5663 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/' [junit4] 2> 2636267 T5663 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 2636268 T5663 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 2636268 T5663 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 2636337 T5663 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.0 [junit4] 2> 2636384 T5663 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2636387 T5663 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 2636392 T5663 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 2636451 T5663 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2636453 T5663 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2636453 T5663 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 2636454 T5663 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/data/ [junit4] 2> 2636455 T5663 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 2636456 T5663 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/data [junit4] 2> 2636457 T5663 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/data/index/ [junit4] 2> 2636457 T5663 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2636459 T5663 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/data/index [junit4] 2> 2636459 T5663 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=99.78125, floorSegmentMB=2.0732421875, forceMergeDeletesPctAllowed=15.79689044883887, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.16759332448233727 [junit4] 2> 2636466 T5663 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15987bb9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2636466 T5663 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2636470 T5663 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 2636472 T5663 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2636472 T5663 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2636472 T5663 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2636473 T5663 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2636473 T5663 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2636473 T5663 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2636474 T5663 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2636474 T5663 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2636474 T5663 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2636475 T5663 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2636475 T5663 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2636475 T5663 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2636489 T5663 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2636491 T5663 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2636492 T5663 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2636494 T5663 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2636496 T5663 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2636502 T5663 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2636503 T5663 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2636504 T5663 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=53.939453125, floorSegmentMB=0.2705078125, forceMergeDeletesPctAllowed=23.592101098247106, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2636507 T5663 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15987bb9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2636507 T5663 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2636507 T5663 oass.SolrIndexSearcher.<init> Opening Searcher@273be71a[collection1] main [junit4] 2> 2636507 T5663 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2636511 T5663 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2636512 T5663 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2636513 T5663 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2636514 T5663 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2636514 T5663 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2636517 T5663 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 2636517 T5663 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2636517 T5663 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 2636518 T5663 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2636518 T5663 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 2636524 T5663 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2636524 T5664 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@273be71a[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 2636526 T5667 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50526 collection:collection1 shard:shard2 [junit4] 2> 2636527 T5592 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2636527 T5592 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2636528 T5667 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 2636540 T5667 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 2636545 T5667 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2636545 T5667 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2100 name=collection1 org.apache.solr.core.SolrCore@4e8d449a url=https://127.0.0.1:50526/collection1 node=127.0.0.1:50526_ C2100_STATE=coll:collection1 core:collection1 props:{base_url=https://127.0.0.1:50526, core=collection1, node_name=127.0.0.1:50526_, state=down} [junit4] 2> 2636545 T5667 C2100 P50526 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50526/collection1/ [junit4] 2> 2636545 T5667 C2100 P50526 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2636545 T5667 C2100 P50526 oasc.SyncStrategy.syncToMe https://127.0.0.1:50526/collection1/ has no replicas [junit4] 2> 2636546 T5667 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50526/collection1/ shard2 [junit4] 2> 2636546 T5667 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 2636559 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2636589 T5629 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> 2636589 T5662 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> 2636589 T5643 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> 2636600 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2636600 T5628 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> 2636602 T5643 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> 2636602 T5662 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> 2636603 T5621 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> 2636609 T5667 oasc.ZkController.register We are https://127.0.0.1:50526/collection1/ and leader is https://127.0.0.1:50526/collection1/ [junit4] 2> 2636610 T5667 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50526 [junit4] 2> 2636610 T5667 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2636611 T5667 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 2636611 T5667 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2636617 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2636624 T5617 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "base_url":"https://127.0.0.1:50526";, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "numShards":"4", [junit4] 2> "node_name":"127.0.0.1:50526_", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard2", [junit4] 2> "operation":"state", [junit4] 2> "state":"active"} [junit4] 2> 2636734 T5662 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> 2636739 T5643 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> 2636740 T5621 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> 2636740 T5628 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> 2638335 T5592 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 2638335 T5592 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2638345 T5592 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2638347 T5592 oejs.AbstractConnector.doStart Started [email protected]:50529 [junit4] 2> 2638352 T5592 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2638352 T5592 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2638352 T5592 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-005 [junit4] 2> 2638353 T5592 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-005/' [junit4] 2> 2638393 T5592 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-005/solr.xml [junit4] 2> 2638466 T5592 oasc.CoreContainer.<init> New CoreContainer 25742912 [junit4] 2> 2638466 T5592 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-005/] [junit4] 2> 2638469 T5592 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2638469 T5592 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2638469 T5592 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2638469 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2638470 T5592 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2638470 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2638470 T5592 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2638471 T5592 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2638472 T5592 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2638474 T5592 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2638476 T5592 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2638476 T5592 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2638476 T5592 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2638477 T5592 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50515/solr [junit4] 2> 2638478 T5592 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2638479 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2638484 T5678 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@df9c2a4 name:ZooKeeperConnection Watcher:127.0.0.1:50515 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2638485 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2638489 T5592 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2638492 T5680 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63d7b36b name:ZooKeeperConnection Watcher:127.0.0.1:50515/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2638493 T5592 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2638516 T5592 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2639527 T5592 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50529_ [junit4] 2> 2639529 T5592 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50529_ [junit4] 2> 2639544 T5592 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 2639561 T5682 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 2639561 T5682 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2639563 T5682 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2639563 T5621 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2639569 T5617 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "base_url":"https://127.0.0.1:50529";, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "node_name":"127.0.0.1:50529_", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "state":"down"} [junit4] 2> 2639570 T5617 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4 [junit4] 2> 2639570 T5617 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4 [junit4] 2> 2639579 T5628 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 2639580 T5681 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 2639580 T5643 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 2639580 T5662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 2639580 T5621 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 2640566 T5682 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2640566 T5682 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 2640567 T5682 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2640568 T5682 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2640571 T5682 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2640571 T5682 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-005/collection1/' [junit4] 2> 2640575 T5682 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001/tempDir-005/collection1/lib/.svn/' to classloader [junit4] 2> 2640575 T5682 oasc [...truncated too long message...] eer.java:89) [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:292) [junit4] 2> [junit4] 2> 2907180 T5592 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 2907181 T5592 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50515 50515 [junit4] 2> 2907209 T5593 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50515 50515 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCloudSchemaless -Dtests.method=testDistribSearch -Dtests.seed=11D8D6EC2C641251 -Dtests.slow=true -Dtests.locale=ga_IE -Dtests.timezone=Europe/Copenhagen -Dtests.file.encoding=UTF-8 [junit4] ERROR 285s | TestCloudSchemaless.testDistribSearch <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:50539/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([11D8D6EC2C641251:903E58F45B3B726D]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206) [junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124) [junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68) [junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54) [junit4] > at org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:152) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:122) [junit4] > at sun.security.ssl.InputRecord.readFully(InputRecord.java:442) [junit4] > at sun.security.ssl.InputRecord.read(InputRecord.java:480) [junit4] > at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927) [junit4] > at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884) [junit4] > at sun.security.ssl.AppInputStream.read(AppInputStream.java:102) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) [junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) [junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448) [junit4] > ... 47 more [junit4] 2> 2907508 T5592 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.schema.TestCloudSchemaless-11D8D6EC2C641251-001 [junit4] 2> 285046 T5591 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene45, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ga_IE, timezone=Europe/Copenhagen [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=2,threads=1,free=100476456,total=277426176 [junit4] 2> NOTE: All tests run in this JVM: [StatelessScriptUpdateProcessorFactoryTest, SolrPluginUtilsTest, TestRangeQuery, TestLMJelinekMercerSimilarityFactory, TestLRUCache, TestZkChroot, DeleteLastCustomShardedReplicaTest, SolrXmlInZkTest, DistributedQueryComponentOptimizationTest, TestReplicationHandler, TestHighFrequencyDictionaryFactory, TestFieldSortValues, TimeZoneUtilsTest, QueryParsingTest, TestDistributedMissingSort, FieldMutatingUpdateProcessorTest, TestFastLRUCache, TestLMDirichletSimilarityFactory, TestStandardQParsers, URLClassifyProcessorTest, TestDocSet, DefaultValueUpdateProcessorTest, JSONWriterTest, TestDistributedGrouping, TestOverriddenPrefixQueryForCustomFieldType, QueryElevationComponentTest, TestSuggestSpellingConverter, DistributedSpellCheckComponentTest, PluginInfoTest, TestCursorMarkWithoutUniqueKey, TestCSVLoader, TestChildDocTransformer, TestManagedSchema, TestSolrCoreProperties, TestIndexSearcher, HdfsLockFactoryTest, TestCollationField, MergeStrategyTest, QueryResultKeyTest, ZkSolrClientTest, OverseerRolesTest, TestSolrXmlPersistor, PrimUtilsTest, HighlighterConfigTest, ChaosMonkeyNothingIsSafeTest, SimpleFacetsTest, CoreAdminHandlerTest, BinaryUpdateRequestHandlerTest, TestManagedResourceStorage, DocValuesTest, OpenExchangeRatesOrgProviderTest, TestLuceneMatchVersion, TestBinaryField, LeaderElectionTest, OutputWriterTest, ExternalFileFieldSortTest, TriLevelCompositeIdRoutingTest, ChangedSchemaMergeTest, RequestHandlersTest, TestDistribDocBasedVersion, ClusterStateUpdateTest, TestStressUserVersions, PreAnalyzedUpdateProcessorTest, DistribDocExpirationUpdateProcessorTest, AssignTest, TestDefaultSearchFieldResource, TestXIncludeConfig, TestBM25SimilarityFactory, StandardRequestHandlerTest, SuggesterWFSTTest, TestIntervalFaceting, CSVRequestHandlerTest, TestComplexPhraseQParserPlugin, TestFieldTypeResource, HighlighterMaxOffsetTest, TestPartialUpdateDeduplication, HdfsWriteToMultipleCollectionsTest, WordBreakSolrSpellCheckerTest, DeleteReplicaTest, TestTolerantSearch, TestSchemaResource, TestDFRSimilarityFactory, SuggesterTest, DeleteShardTest, SolrIndexConfigTest, DistributedIntervalFacetingTest, TestStressReorder, DistributedFacetPivotSmallTest, ClusterStateTest, TestAnalyzedSuggestions, ReturnFieldsTest, TestCollationKeyRangeQueries, TestSortingResponseWriter, TestManagedSynonymFilterFactory, CurrencyFieldOpenExchangeTest, TestSolrJ, NumericFieldsTest, TestCloudInspectUtil, TestRandomFaceting, UUIDFieldTest, TestCopyFieldCollectionResource, SuggesterTSTTest, TestReversedWildcardFilterFactory, DistributedQueryElevationComponentTest, TestSimpleQParserPlugin, SharedFSAutoReplicaFailoverUtilsTest, TestConfig, TestRecovery, TestRemoteStreaming, TestDistributedSearch, HdfsRecoveryZkTest, TestValueSourceCache, RequiredFieldsTest, TestShardHandlerFactory, TermsComponentTest, IndexSchemaRuntimeFieldTest, TestStressLucene, HdfsDirectoryTest, TestRealTimeGet, TestWriterPerf, TestCloudManagedSchemaConcurrent, UniqFieldsUpdateProcessorFactoryTest, TestManagedSchemaFieldResource, DistributedExpandComponentTest, OpenCloseCoreStressTest, TestSystemIdResolver, TestCloudSchemaless] [junit4] Completed in 285.60s, 1 test, 1 error <<< FAILURES! [...truncated 895 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:474: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:454: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:45: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:189: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1351: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:966: There were test failures: 419 suites, 1732 tests, 1 error, 44 ignored (17 assumptions) Total time: 173 minutes 1 second Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseSerialGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
