Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1752/ Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed. REGRESSION: org.apache.solr.schema.TestCloudSchemaless.testDistribSearch Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:50158/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:50158/collection1 at __randomizedtesting.SeedInfo.seed([97A77AFBB80402FB:1641F4E3CF5B62C7]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206) at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54) at org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) 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.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:449) ... 47 more Build Log: [...truncated 11110 lines...] [junit4] Suite: org.apache.solr.schema.TestCloudSchemaless [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/init-core-data-001 [junit4] 2> 1672788 T3945 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 1672788 T3945 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 1672793 T3945 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 1672794 T3945 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1672795 T3946 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 1672897 T3945 oasc.ZkTestServer.run start zk server on port:50141 [junit4] 2> 1672899 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1672903 T3952 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54acfeb7 name:ZooKeeperConnection Watcher:127.0.0.1:50141 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1672903 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1672904 T3945 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 1672913 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1672916 T3954 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63c2d999 name:ZooKeeperConnection Watcher:127.0.0.1:50141/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1672917 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1672917 T3945 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 1672923 T3945 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 1672928 T3945 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 1672931 T3945 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 1672938 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-schemaless.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1672938 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1672944 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-add-schema-fields-update-processor.xml to /configs/conf1/schema.xml [junit4] 2> 1672945 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 1672950 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1672951 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1672955 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1672956 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 1672962 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1672962 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 1672970 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1672970 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 1672978 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1672979 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1672986 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1672987 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1672995 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1672996 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1673005 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1673006 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1673014 T3945 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1673014 T3945 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 1673024 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1673026 T3956 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f4c3e90 name:ZooKeeperConnection Watcher:127.0.0.1:50141/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1673026 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1674874 T3945 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1674882 T3945 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1674906 T3945 oejs.AbstractConnector.doStart Started [email protected]:50145 [junit4] 2> 1674910 T3945 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1674911 T3945 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1674912 T3945 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002 [junit4] 2> 1674912 T3945 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/' [junit4] 2> 1674965 T3945 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/solr.xml [junit4] 2> 1675027 T3945 oasc.CoreContainer.<init> New CoreContainer 1104573279 [junit4] 2> 1675028 T3945 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/] [junit4] 2> 1675029 T3945 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1675030 T3945 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1675030 T3945 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1675030 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1675031 T3945 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1675031 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1675032 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1675033 T3945 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1675033 T3945 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1675036 T3945 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1675038 T3945 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1675039 T3945 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1675039 T3945 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1675040 T3945 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50141/solr [junit4] 2> 1675041 T3945 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1675042 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1675046 T3967 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2fa1ff04 name:ZooKeeperConnection Watcher:127.0.0.1:50141 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1675048 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1675054 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1675057 T3969 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3e5ed083 name:ZooKeeperConnection Watcher:127.0.0.1:50141/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1675057 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1675061 T3945 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 1675070 T3945 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 1675079 T3945 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 1675088 T3945 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 1675096 T3945 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 1675109 T3945 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 1675114 T3945 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50145_ [junit4] 2> 1675116 T3945 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50145_ [junit4] 2> 1675124 T3945 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 1675130 T3945 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 1675139 T3945 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:50145_ [junit4] 2> 1675139 T3945 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 1675145 T3945 oasc.Overseer.start Overseer (id=92225153164443652-127.0.0.1:50145_-n_0000000000) starting [junit4] 2> 1675152 T3945 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 1675180 T3971 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 1675181 T3945 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 1675187 T3945 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 1675192 T3945 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1675200 T3970 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 1675212 T3972 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 1675212 T3972 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1675214 T3972 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1675214 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1675218 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1675220 T3970 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:50145";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50145_", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":null} [junit4] 2> 1675220 T3970 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 1675220 T3970 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 1675226 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1675228 T3973 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> 1676216 T3972 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1676216 T3972 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 1676218 T3972 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1676218 T3972 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1676221 T3972 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1676221 T3972 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/' [junit4] 2> 1676223 T3972 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 1676223 T3972 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 1676224 T3972 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 1676283 T3972 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 1676335 T3972 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1676337 T3972 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> 1676340 T3972 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 1676345 T3972 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 1676381 T3972 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1676384 T3972 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema [junit4] 2> 1676388 T3972 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak [junit4] 2> 1676397 T3972 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> 1676397 T3972 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1676397 T3972 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 1676398 T3972 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/data/ [junit4] 2> 1676398 T3972 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 1676399 T3972 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/data [junit4] 2> 1676400 T3972 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/data/index/ [junit4] 2> 1676401 T3972 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1676402 T3972 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/data/index [junit4] 2> 1676402 T3972 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5664413813820348] [junit4] 2> 1676411 T3972 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9529cea; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 1676411 T3972 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1676414 T3972 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 1676496 T3972 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1676496 T3972 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1676497 T3972 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1676497 T3972 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1676497 T3972 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1676498 T3972 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1676499 T3972 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 1676499 T3972 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1676499 T3972 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1676499 T3972 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 1676499 T3972 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1676500 T3972 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 1676512 T3972 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1676514 T3972 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1676516 T3972 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1676517 T3972 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1676519 T3972 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1676527 T3972 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1676527 T3972 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1676528 T3972 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=141874533, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1676530 T3972 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9529cea; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 1676531 T3972 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1676531 T3972 oass.SolrIndexSearcher.<init> Opening Searcher@27dbe2ac[collection1] main [junit4] 2> 1676531 T3972 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1676535 T3972 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1676535 T3972 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1676537 T3972 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1676537 T3972 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1676537 T3972 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1676539 T3972 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1676539 T3972 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1676541 T3972 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 1676547 T3972 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 1676547 T3972 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1676547 T3972 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1676548 T3972 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 1676551 T3972 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1676551 T3974 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@27dbe2ac[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 1676553 T3977 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50145 collection:control_collection shard:shard1 [junit4] 2> 1676554 T3945 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1676554 T3945 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1676555 T3977 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 1676562 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1676566 T3979 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bfd8037 name:ZooKeeperConnection Watcher:127.0.0.1:50141/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1676566 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1676569 T3945 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1676571 T3977 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 1676575 T3945 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1676577 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676580 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1676584 T3977 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1676584 T3977 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C425 name=collection1 org.apache.solr.core.SolrCore@64b5deaf url=https://127.0.0.1:50145/collection1 node=127.0.0.1:50145_ C425_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:50145, core=collection1, node_name=127.0.0.1:50145_} [junit4] 2> 1676584 T3977 C425 P50145 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50145/collection1/ [junit4] 2> 1676585 T3977 C425 P50145 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1676586 T3977 C425 P50145 oasc.SyncStrategy.syncToMe https://127.0.0.1:50145/collection1/ has no replicas [junit4] 2> 1676586 T3977 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50145/collection1/ shard1 [junit4] 2> 1676587 T3977 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1676591 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676606 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676619 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676726 T3973 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> 1676727 T3980 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> 1676767 T3977 oasc.ZkController.register We are https://127.0.0.1:50145/collection1/ and leader is https://127.0.0.1:50145/collection1/ [junit4] 2> 1676767 T3977 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50145 [junit4] 2> 1676767 T3977 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1676767 T3977 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 1676767 T3977 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1676770 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676771 T3981 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676772 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676774 T3977 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1676778 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1676779 T3970 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:50145";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50145_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 1676784 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1676890 T3973 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> 1676892 T3980 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> 1678172 T3945 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 1678174 T3945 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1678180 T3945 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1678182 T3945 oejs.AbstractConnector.doStart Started [email protected]:50149 [junit4] 2> 1678186 T3945 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1678186 T3945 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1678186 T3945 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003 [junit4] 2> 1678186 T3945 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/' [junit4] 2> 1678225 T3945 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/solr.xml [junit4] 2> 1678286 T3945 oasc.CoreContainer.<init> New CoreContainer 993669953 [junit4] 2> 1678286 T3945 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/] [junit4] 2> 1678287 T3945 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1678287 T3945 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1678288 T3945 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1678288 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1678288 T3945 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1678289 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1678289 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1678289 T3945 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1678289 T3945 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1678292 T3945 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1678295 T3945 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1678295 T3945 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1678296 T3945 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1678296 T3945 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50141/solr [junit4] 2> 1678296 T3945 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1678298 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1678301 T3992 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@61306a6d name:ZooKeeperConnection Watcher:127.0.0.1:50141 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1678302 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1678308 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1678310 T3994 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@38b20ad5 name:ZooKeeperConnection Watcher:127.0.0.1:50141/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1678311 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1678331 T3945 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1679341 T3945 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50149_ [junit4] 2> 1679345 T3945 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50149_ [junit4] 2> 1679377 T3996 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 1679378 T3996 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1679381 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1679381 T3981 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1679382 T3996 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1679383 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1679388 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1679391 T3970 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:50149";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50149_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":null} [junit4] 2> 1679391 T3970 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4] [junit4] 2> 1679392 T3970 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3 [junit4] 2> 1679403 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1679405 T3997 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> 1679405 T3995 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> 1679405 T3980 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> 1680386 T3996 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1680386 T3996 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 1680388 T3996 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1680388 T3996 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1680391 T3996 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1680391 T3996 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/' [junit4] 2> 1680393 T3996 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 1680393 T3996 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 1680394 T3996 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 1680456 T3996 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 1680496 T3996 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1680499 T3996 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 1680506 T3996 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 1680544 T3996 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1680545 T3996 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1680545 T3996 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 1680546 T3996 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/data/ [junit4] 2> 1680546 T3996 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 1680546 T3996 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/data [junit4] 2> 1680547 T3996 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/data/index/ [junit4] 2> 1680548 T3996 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1680549 T3996 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/data/index [junit4] 2> 1680550 T3996 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5664413813820348] [junit4] 2> 1680556 T3996 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3fbd06; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 1680557 T3996 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1680559 T3996 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 1680561 T3996 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1680561 T3996 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1680561 T3996 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1680561 T3996 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1680562 T3996 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1680562 T3996 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1680563 T3996 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 1680563 T3996 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1680564 T3996 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1680564 T3996 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 1680564 T3996 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1680564 T3996 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 1680576 T3996 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1680578 T3996 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1680581 T3996 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1680583 T3996 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1680584 T3996 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1680592 T3996 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1680592 T3996 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1680595 T3996 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=141874533, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1680598 T3996 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3fbd06; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 1680599 T3996 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1680600 T3996 oass.SolrIndexSearcher.<init> Opening Searcher@186fbd10[collection1] main [junit4] 2> 1680600 T3996 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1680603 T3996 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1680604 T3996 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1680605 T3996 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1680606 T3996 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1680606 T3996 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1680608 T3996 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 1680608 T3996 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1680608 T3996 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 1680608 T3996 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1680609 T3996 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 1680615 T3996 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1680615 T3998 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@186fbd10[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 1680617 T4001 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50149 collection:collection1 shard:shard3 [junit4] 2> 1680617 T3945 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1680618 T3945 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1680619 T4001 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 1680632 T4001 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 1680635 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680641 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1680643 T4001 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1680643 T4001 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C426 name=collection1 org.apache.solr.core.SolrCore@4215bf06 url=https://127.0.0.1:50149/collection1 node=127.0.0.1:50149_ C426_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:50149, core=collection1, node_name=127.0.0.1:50149_} [junit4] 2> 1680644 T4001 C426 P50149 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50149/collection1/ [junit4] 2> 1680644 T4001 C426 P50149 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1680644 T4001 C426 P50149 oasc.SyncStrategy.syncToMe https://127.0.0.1:50149/collection1/ has no replicas [junit4] 2> 1680645 T4001 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50149/collection1/ shard3 [junit4] 2> 1680645 T4001 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 1680665 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680673 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680687 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680793 T3980 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> 1680793 T3997 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> 1680794 T3995 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> 1680834 T4001 oasc.ZkController.register We are https://127.0.0.1:50149/collection1/ and leader is https://127.0.0.1:50149/collection1/ [junit4] 2> 1680835 T4001 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50149 [junit4] 2> 1680835 T4001 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1680836 T4001 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 1680836 T4001 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1680840 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680840 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680843 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1680840 T3981 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680844 T4001 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1680845 T3970 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:50149";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50149_", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 1680850 T3981 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1680957 T3981 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> 1680957 T3980 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> 1680958 T3995 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> 1682252 T3945 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 1682253 T3945 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1682259 T3945 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1682261 T3945 oejs.AbstractConnector.doStart Started [email protected]:50152 [junit4] 2> 1682266 T3945 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1682266 T3945 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1682267 T3945 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004 [junit4] 2> 1682267 T3945 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/' [junit4] 2> 1682303 T3945 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/solr.xml [junit4] 2> 1682367 T3945 oasc.CoreContainer.<init> New CoreContainer 2075884401 [junit4] 2> 1682368 T3945 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/] [junit4] 2> 1682369 T3945 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1682369 T3945 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1682370 T3945 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1682371 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1682371 T3945 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1682371 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1682371 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1682371 T3945 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1682372 T3945 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1682375 T3945 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1682377 T3945 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1682378 T3945 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1682378 T3945 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1682379 T3945 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50141/solr [junit4] 2> 1682379 T3945 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1682380 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1682383 T4012 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@40ff1f1f name:ZooKeeperConnection Watcher:127.0.0.1:50141 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1682384 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1682389 T3945 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1682392 T4014 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a0fbb5a name:ZooKeeperConnection Watcher:127.0.0.1:50141/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1682392 T3945 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1682411 T3945 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1683421 T3945 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50152_ [junit4] 2> 1683423 T3945 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50152_ [junit4] 2> 1683448 T4016 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 1683449 T4016 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1683451 T3981 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1683451 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1683451 T4016 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1683451 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1683456 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1683458 T3970 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:50152";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50152_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":null} [junit4] 2> 1683458 T3970 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4 [junit4] 2> 1683458 T3970 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 1683463 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1683465 T4015 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> 1683465 T3997 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> 1683465 T3980 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> 1683465 T3995 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> 1684454 T4016 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1684454 T4016 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 1684456 T4016 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1684457 T4016 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1684459 T4016 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1684459 T4016 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/' [junit4] 2> 1684461 T4016 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 1684461 T4016 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 1684461 T4016 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 1684524 T4016 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 1684564 T4016 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1684567 T4016 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 1684573 T4016 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 1684622 T4016 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1684623 T4016 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1684624 T4016 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 1684624 T4016 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/data/ [junit4] 2> 1684625 T4016 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 1684626 T4016 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/data [junit4] 2> 1684627 T4016 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/data/index/ [junit4] 2> 1684629 T4016 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1684630 T4016 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/data/index [junit4] 2> 1684630 T4016 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5664413813820348] [junit4] 2> 1684636 T4016 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46cb2896; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 1684636 T4016 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1684642 T4016 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 1684644 T4016 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1684645 T4016 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1684645 T4016 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1684646 T4016 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1684646 T4016 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1684646 T4016 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1684647 T4016 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 1684647 T4016 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1684648 T4016 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1684648 T4016 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 1684649 T4016 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1684649 T4016 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 1684668 T4016 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1684672 T4016 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1684676 T4016 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1684679 T4016 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1684682 T4016 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1684692 T4016 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1684692 T4016 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1684693 T4016 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=141874533, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1684696 T4016 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46cb2896; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 1684696 T4016 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1684696 T4016 oass.SolrIndexSearcher.<init> Opening Searcher@79a5bd29[collection1] main [junit4] 2> 1684697 T4016 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1684700 T4016 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1684701 T4016 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1684703 T4016 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1684703 T4016 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1684703 T4016 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1684706 T4016 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 1684706 T4016 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1684707 T4016 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 1684707 T4016 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1684707 T4016 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 1684712 T4016 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1684712 T4017 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@79a5bd29[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 1684714 T4020 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50152 collection:collection1 shard:shard1 [junit4] 2> 1684714 T3945 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1684715 T3945 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1684716 T4020 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 1684728 T4020 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 1684735 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1684739 T4020 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1684739 T4020 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C427 name=collection1 org.apache.solr.core.SolrCore@6a3257d4 url=https://127.0.0.1:50152/collection1 node=127.0.0.1:50152_ C427_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:50152, core=collection1, node_name=127.0.0.1:50152_} [junit4] 2> 1684739 T4020 C427 P50152 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50152/collection1/ [junit4] 2> 1684740 T4020 C427 P50152 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1684740 T4020 C427 P50152 oasc.SyncStrategy.syncToMe https://127.0.0.1:50152/collection1/ has no replicas [junit4] 2> 1684740 T4020 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50152/collection1/ shard1 [junit4] 2> 1684740 T4020 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 1684742 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1684749 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1684760 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1684769 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1684875 T4015 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> 1684875 T3980 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> 1684877 T3997 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> 1684878 T3995 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> 1684918 T4020 oasc.ZkController.register We are https://127.0.0.1:50152/collection1/ and leader is https://127.0.0.1:50152/collection1/ [junit4] 2> 1684918 T4020 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50152 [junit4] 2> 1684919 T4020 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1684919 T4020 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 1684919 T4020 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1684922 T3997 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1684923 T3973 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1684924 T3981 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1684928 T4020 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1684931 T3970 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 1684932 T3970 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:50152";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50152_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node2"} [junit4] 2> 1684939 T3981 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1685044 T3995 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> 1685044 T3980 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> 1685045 T3981 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> 1685045 T4015 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> 1686298 T3945 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 1686298 T3945 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1686307 T3945 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1686309 T3945 oejs.AbstractConnector.doStart Started [email protected]:50155 [junit4] 2> 1686312 T3945 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1686313 T3945 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1686313 T3945 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-005 [junit4] 2> 1686313 T3945 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-005/' [junit4] 2> 1686352 T3945 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-005/solr.xml [junit4] 2> 1686417 T3945 oasc.CoreContainer.<init> New CoreContainer 1896558539 [junit4] 2> 1686418 T3945 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001/tempDir-005/] [junit4] 2> 1686419 T3945 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1686420 T3945 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1686420 T3945 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1686420 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1686420 T3945 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1686420 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1686421 T3945 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] [...truncated too long message...] it4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:302) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:299) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:299) [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317) [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85) [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289) [junit4] 2> [junit4] 2> 1819078 T3945 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> 1824067 T3945 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 1824069 T3945 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50141 50141 [junit4] 2> 1824080 T3946 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50141 50141 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCloudSchemaless -Dtests.method=testDistribSearch -Dtests.seed=97A77AFBB80402FB -Dtests.slow=true -Dtests.locale=en_GB -Dtests.timezone=MST7MDT -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 152s | TestCloudSchemaless.testDistribSearch <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:50158/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([97A77AFBB80402FB:1641F4E3CF5B62C7]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559) [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:865) [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:449) [junit4] > ... 47 more [junit4] 2> 1824332 T3945 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-97A77AFBB80402FB-001 [junit4] 2> 151549 T3944 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=en_GB, timezone=MST7MDT [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=2,threads=1,free=65236488,total=277872640 [junit4] 2> NOTE: All tests run in this JVM: [TestAnalyzeInfixSuggestions, NoCacheHeaderTest, TestBinaryField, SpellCheckCollatorTest, QueryEqualityTest, SuggesterFSTTest, SpellingQueryConverterTest, CurrencyFieldOpenExchangeTest, TestFileDictionaryLookup, TestCloudManagedSchema, TestPartialUpdateDeduplication, TestQuerySenderNoQuery, TestCloudInspectUtil, MBeansHandlerTest, TestSolrXmlPersistence, DistributedTermsComponentTest, SearchHandlerTest, ZkControllerTest, BasicDistributedZk2Test, ShardRoutingTest, ConnectionManagerTest, HdfsDirectoryTest, SolrInfoMBeanTest, HdfsUnloadDistributedZkTest, TestBinaryResponseWriter, TestShardHandlerFactory, QueryFacetTest, RequiredFieldsTest, ScriptEngineTest, TestWordDelimiterFilterFactory, TestLMJelinekMercerSimilarityFactory, TestIntervalFaceting, DeleteShardTest, FieldFacetTest, CacheHeaderTest, ChangedSchemaMergeTest, TestSolrQueryParser, TestFastOutputStream, TestCollapseQParserPlugin, CursorPagingTest, TestClassNameShortening, TestCodecSupport, NotRequiredUniqueKeyTest, DateRangeFieldTest, TestSystemIdResolver, HttpPartitionTest, SpellCheckComponentTest, TestSolrDeletionPolicy1, TestRandomDVFaceting, TestSimpleQParserPlugin, FunctionTest, AddSchemaFieldsUpdateProcessorFactoryTest, RollingRestartTest, TestDistributedGrouping, SpellPossibilityIteratorTest, SpatialFilterTest, XsltUpdateRequestHandlerTest, TestRecoveryHdfs, TestManagedSchema, WordBreakSolrSpellCheckerTest, DistributedIntervalFacetingTest, BasicZkTest, TestOrdValues, OutputWriterTest, DirectSolrSpellCheckerTest, SSLMigrationTest, CopyFieldTest, StressHdfsTest, TestConfigSets, RangeFacetTest, TestFreeTextSuggestions, TestAnalyzedSuggestions, TestSweetSpotSimilarityFactory, OpenCloseCoreStressTest, ResponseLogComponentTest, CSVRequestHandlerTest, DeleteReplicaTest, TestExpandComponent, ModifyConfFileTest, CurrencyFieldXmlFileTest, TestWriterPerf, SampleTest, FieldAnalysisRequestHandlerTest, RemoteQueryErrorTest, LeaderElectionIntegrationTest, UUIDFieldTest, AnalysisAfterCoreReloadTest, TestMiniSolrCloudCluster, TestCSVResponseWriter, SolrPluginUtilsTest, HdfsCollectionsAPIDistributedZkTest, TimeZoneUtilsTest, SolrIndexConfigTest, TestCloudSchemaless] [junit4] Completed in 151.84s, 1 test, 1 error <<< FAILURES! [...truncated 1009 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1298: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:922: There were test failures: 422 suites, 1756 tests, 1 error, 44 ignored (17 assumptions) Total time: 165 minutes 33 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseParallelGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
