Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1754/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.schema.TestCloudSchemaless.testDistribSearch Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:50726/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:50726/collection1 at __randomizedtesting.SeedInfo.seed([ACB6DAD6A7E73D20:2D5054CED0B85D1C]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:561) 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.GeneratedMethodAccessor38.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java: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:150) at java.net.SocketInputStream.read(SocketInputStream.java:121) at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) at sun.security.ssl.InputRecord.read(InputRecord.java:503) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:954) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:911) at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:449) ... 46 more Build Log: [...truncated 11176 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-ACB6DAD6A7E73D20-001/init-core-data-001 [junit4] 2> 2196466 T5463 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 2196467 T5463 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 2196475 T5463 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 2196476 T5463 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2196478 T5464 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 2196579 T5463 oasc.ZkTestServer.run start zk server on port:50715 [junit4] 2> 2196582 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2196588 T5470 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@69348b84 name:ZooKeeperConnection Watcher:127.0.0.1:50715 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2196590 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2196590 T5463 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 2196602 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2196620 T5472 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@20103ea7 name:ZooKeeperConnection Watcher:127.0.0.1:50715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2196621 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2196621 T5463 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 2196627 T5463 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 2196634 T5463 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 2196641 T5463 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 2196650 T5463 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> 2196650 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2196662 T5463 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> 2196663 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 2196673 T5463 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> 2196673 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2196682 T5463 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> 2196683 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 2196691 T5463 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> 2196692 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 2196699 T5463 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> 2196701 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 2196710 T5463 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> 2196710 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2196719 T5463 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> 2196720 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2196728 T5463 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> 2196729 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2196737 T5463 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> 2196738 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2196748 T5463 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> 2196749 T5463 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 2196761 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2196766 T5474 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@332219b2 name:ZooKeeperConnection Watcher:127.0.0.1:50715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2196767 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2198700 T5463 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2198709 T5463 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2198728 T5463 oejs.AbstractConnector.doStart Started [email protected]:50719 [junit4] 2> 2198733 T5463 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2198733 T5463 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2198734 T5463 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-ACB6DAD6A7E73D20-001/tempDir-002 [junit4] 2> 2198735 T5463 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/' [junit4] 2> 2198803 T5463 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-ACB6DAD6A7E73D20-001/tempDir-002/solr.xml [junit4] 2> 2198835 T5463 oasc.CoreContainer.<init> New CoreContainer 896101336 [junit4] 2> 2198836 T5463 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-ACB6DAD6A7E73D20-001/tempDir-002/] [junit4] 2> 2198838 T5463 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2198838 T5463 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2198838 T5463 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2198838 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2198839 T5463 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2198839 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2198839 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2198840 T5463 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2198840 T5463 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2198844 T5463 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2198846 T5463 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2198846 T5463 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2198847 T5463 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2198847 T5463 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50715/solr [junit4] 2> 2198848 T5463 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2198849 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2198855 T5485 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@239af761 name:ZooKeeperConnection Watcher:127.0.0.1:50715 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2198858 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2198864 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2198871 T5487 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ecac93c name:ZooKeeperConnection Watcher:127.0.0.1:50715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2198872 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2198879 T5463 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 2198888 T5463 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 2198899 T5463 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 2198911 T5463 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 2198921 T5463 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 2198935 T5463 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 2198940 T5463 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50719_ [junit4] 2> 2198944 T5463 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50719_ [junit4] 2> 2198956 T5463 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 2198961 T5463 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 2198971 T5463 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:50719_ [junit4] 2> 2198972 T5463 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 2198977 T5463 oasc.Overseer.start Overseer (id=92231335358824452-127.0.0.1:50719_-n_0000000000) starting [junit4] 2> 2198985 T5463 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 2199013 T5489 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 2199014 T5463 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 2199020 T5463 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 2199024 T5463 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2199033 T5488 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 2199040 T5490 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 2199040 T5490 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2199042 T5490 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2199043 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2199047 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2199049 T5488 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:50719";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50719_", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":null} [junit4] 2> 2199049 T5488 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 2199049 T5488 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 2199055 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2199057 T5491 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> 2200045 T5490 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2200046 T5490 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 2200047 T5490 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2200048 T5490 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 2200051 T5490 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2200051 T5490 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/collection1/' [junit4] 2> 2200053 T5490 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 2200053 T5490 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 2200054 T5490 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 2200099 T5490 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 2200106 T5490 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2200109 T5490 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> 2200112 T5490 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 2200115 T5490 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 2200125 T5490 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2200128 T5490 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema [junit4] 2> 2200133 T5490 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak [junit4] 2> 2200144 T5490 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> 2200144 T5490 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2200145 T5490 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 2200145 T5490 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-ACB6DAD6A7E73D20-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/collection1/data/ [junit4] 2> 2200145 T5490 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 2200146 T5490 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/collection1/data [junit4] 2> 2200147 T5490 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-ACB6DAD6A7E73D20-001/tempDir-002/collection1/data/index/ [junit4] 2> 2200148 T5490 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-ACB6DAD6A7E73D20-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2200148 T5490 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-002/collection1/data/index [junit4] 2> 2200148 T5490 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=34.16796875, floorSegmentMB=1.3173828125, forceMergeDeletesPctAllowed=24.861193504378168, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6822459123050819 [junit4] 2> 2200156 T5490 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-ACB6DAD6A7E73D20-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@226f398e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2200156 T5490 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2200158 T5490 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 2200161 T5490 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2200162 T5490 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2200163 T5490 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2200163 T5490 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2200163 T5490 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2200163 T5490 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2200164 T5490 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2200164 T5490 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2200164 T5490 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2200164 T5490 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2200165 T5490 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2200165 T5490 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2200173 T5490 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2200174 T5490 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2200176 T5490 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2200177 T5490 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2200179 T5490 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2200181 T5490 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2200181 T5490 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2200182 T5490 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=93.9990234375, floorSegmentMB=1.8115234375, forceMergeDeletesPctAllowed=14.888475819124274, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6231248174670746 [junit4] 2> 2200183 T5490 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-ACB6DAD6A7E73D20-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@226f398e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2200184 T5490 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2200184 T5490 oass.SolrIndexSearcher.<init> Opening Searcher@50266db5[collection1] main [junit4] 2> 2200184 T5490 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 2200187 T5490 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2200187 T5490 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2200189 T5490 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2200189 T5490 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2200189 T5490 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2200191 T5490 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 2200191 T5490 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2200193 T5490 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 2200200 T5490 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 2200200 T5490 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2200200 T5490 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2200200 T5490 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 2200203 T5490 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2200204 T5492 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50266db5[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 2200206 T5495 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50719 collection:control_collection shard:shard1 [junit4] 2> 2200207 T5463 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2200207 T5463 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2200208 T5495 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 2200214 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2200218 T5497 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@38222a1a name:ZooKeeperConnection Watcher:127.0.0.1:50715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2200219 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2200223 T5463 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2200223 T5495 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 2200228 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200229 T5463 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2200229 T5495 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2200230 T5495 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2251 name=collection1 org.apache.solr.core.SolrCore@7093603b url=https://127.0.0.1:50719/collection1 node=127.0.0.1:50719_ C2251_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:50719, core=collection1, node_name=127.0.0.1:50719_} [junit4] 2> 2200230 T5495 C2251 P50719 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50719/collection1/ [junit4] 2> 2200230 T5495 C2251 P50719 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2200231 T5495 C2251 P50719 oasc.SyncStrategy.syncToMe https://127.0.0.1:50719/collection1/ has no replicas [junit4] 2> 2200231 T5495 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50719/collection1/ shard1 [junit4] 2> 2200231 T5495 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2200234 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2200243 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200249 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200257 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200364 T5491 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> 2200366 T5498 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> 2200405 T5495 oasc.ZkController.register We are https://127.0.0.1:50719/collection1/ and leader is https://127.0.0.1:50719/collection1/ [junit4] 2> 2200406 T5495 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50719 [junit4] 2> 2200406 T5495 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2200406 T5495 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 2200406 T5495 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2200410 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200415 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200415 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200419 T5495 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2200424 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2200425 T5488 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:50719";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50719_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 2200432 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2200540 T5498 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> 2200541 T5499 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> 2202129 T5463 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 2202137 T5463 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2202153 T5463 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2202157 T5463 oejs.AbstractConnector.doStart Started [email protected]:50723 [junit4] 2> 2202160 T5463 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2202160 T5463 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2202161 T5463 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-ACB6DAD6A7E73D20-001/tempDir-003 [junit4] 2> 2202161 T5463 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/' [junit4] 2> 2202206 T5463 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-ACB6DAD6A7E73D20-001/tempDir-003/solr.xml [junit4] 2> 2202223 T5463 oasc.CoreContainer.<init> New CoreContainer 2096131068 [junit4] 2> 2202223 T5463 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-ACB6DAD6A7E73D20-001/tempDir-003/] [junit4] 2> 2202224 T5463 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2202224 T5463 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2202224 T5463 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2202225 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2202225 T5463 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2202225 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2202226 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2202226 T5463 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2202227 T5463 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2202230 T5463 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2202231 T5463 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2202232 T5463 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2202232 T5463 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2202232 T5463 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50715/solr [junit4] 2> 2202233 T5463 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2202234 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2202238 T5510 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e67bddd name:ZooKeeperConnection Watcher:127.0.0.1:50715 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2202239 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2202244 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2202247 T5512 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5926e72c name:ZooKeeperConnection Watcher:127.0.0.1:50715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2202248 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2202267 T5463 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2203277 T5463 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50723_ [junit4] 2> 2203279 T5463 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50723_ [junit4] 2> 2203302 T5514 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 2203302 T5514 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2203304 T5514 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2203304 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2203305 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2203306 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2203309 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2203310 T5488 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:50723";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50723_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":null} [junit4] 2> 2203310 T5488 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4] [junit4] 2> 2203311 T5488 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 2203315 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2203318 T5498 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> 2203320 T5515 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> 2203324 T5513 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> 2204306 T5514 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2204307 T5514 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 2204309 T5514 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2204309 T5514 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2204311 T5514 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2204311 T5514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/collection1/' [junit4] 2> 2204313 T5514 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 2204314 T5514 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 2204315 T5514 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 2204371 T5514 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 2204383 T5514 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2204388 T5514 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 2204392 T5514 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 2204403 T5514 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2204405 T5514 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2204406 T5514 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 2204407 T5514 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-ACB6DAD6A7E73D20-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/collection1/data/ [junit4] 2> 2204407 T5514 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 2204408 T5514 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/collection1/data [junit4] 2> 2204409 T5514 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-ACB6DAD6A7E73D20-001/tempDir-003/collection1/data/index/ [junit4] 2> 2204411 T5514 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-ACB6DAD6A7E73D20-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2204412 T5514 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-003/collection1/data/index [junit4] 2> 2204412 T5514 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=34.16796875, floorSegmentMB=1.3173828125, forceMergeDeletesPctAllowed=24.861193504378168, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6822459123050819 [junit4] 2> 2204418 T5514 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-ACB6DAD6A7E73D20-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bcaf984; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2204418 T5514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2204420 T5514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 2204421 T5514 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2204421 T5514 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2204421 T5514 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2204422 T5514 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2204422 T5514 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2204423 T5514 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2204423 T5514 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2204423 T5514 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2204424 T5514 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2204436 T5514 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2204436 T5514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2204436 T5514 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2204445 T5514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2204447 T5514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2204449 T5514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2204451 T5514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2204453 T5514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2204454 T5514 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2204455 T5514 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2204457 T5514 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=93.9990234375, floorSegmentMB=1.8115234375, forceMergeDeletesPctAllowed=14.888475819124274, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6231248174670746 [junit4] 2> 2204460 T5514 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-ACB6DAD6A7E73D20-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bcaf984; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2204461 T5514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2204461 T5514 oass.SolrIndexSearcher.<init> Opening Searcher@5e37642a[collection1] main [junit4] 2> 2204461 T5514 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2204466 T5514 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2204466 T5514 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2204468 T5514 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2204469 T5514 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2204469 T5514 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2204473 T5514 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 2204473 T5514 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2204473 T5514 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 2204474 T5514 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2204474 T5514 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 2204481 T5514 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2204481 T5516 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5e37642a[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 2204483 T5519 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50723 collection:collection1 shard:shard2 [junit4] 2> 2204484 T5463 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2204485 T5463 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2204486 T5519 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 2204507 T5519 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 2204514 T5519 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2204515 T5519 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2252 name=collection1 org.apache.solr.core.SolrCore@1a31ddcb url=https://127.0.0.1:50723/collection1 node=127.0.0.1:50723_ C2252_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:50723, core=collection1, node_name=127.0.0.1:50723_} [junit4] 2> 2204515 T5519 C2252 P50723 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50723/collection1/ [junit4] 2> 2204516 T5519 C2252 P50723 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2204517 T5519 C2252 P50723 oasc.SyncStrategy.syncToMe https://127.0.0.1:50723/collection1/ has no replicas [junit4] 2> 2204517 T5519 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50723/collection1/ shard2 [junit4] 2> 2204519 T5519 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 2204517 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2204534 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2204546 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2204567 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2204673 T5498 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> 2204674 T5515 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> 2204675 T5513 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> 2204709 T5519 oasc.ZkController.register We are https://127.0.0.1:50723/collection1/ and leader is https://127.0.0.1:50723/collection1/ [junit4] 2> 2204709 T5519 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50723 [junit4] 2> 2204710 T5519 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2204710 T5519 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 2204710 T5519 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2204722 T5519 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2204723 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2204724 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2204727 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2204732 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2204734 T5488 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:50723";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50723_", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 2204743 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2204850 T5499 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> 2204852 T5513 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> 2204853 T5498 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> 2206610 T5463 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 2206612 T5463 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2206621 T5463 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2206623 T5463 oejs.AbstractConnector.doStart Started [email protected]:50726 [junit4] 2> 2206627 T5463 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2206628 T5463 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2206628 T5463 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-ACB6DAD6A7E73D20-001/tempDir-004 [junit4] 2> 2206629 T5463 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/' [junit4] 2> 2206671 T5463 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/solr.xml [junit4] 2> 2206687 T5463 oasc.CoreContainer.<init> New CoreContainer 583264522 [junit4] 2> 2206688 T5463 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-ACB6DAD6A7E73D20-001/tempDir-004/] [junit4] 2> 2206688 T5463 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2206689 T5463 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2206689 T5463 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2206690 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2206690 T5463 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2206690 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2206691 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2206691 T5463 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2206692 T5463 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2206695 T5463 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2206697 T5463 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2206697 T5463 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2206698 T5463 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2206698 T5463 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50715/solr [junit4] 2> 2206699 T5463 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 2206702 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2206706 T5530 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f524789 name:ZooKeeperConnection Watcher:127.0.0.1:50715 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2206707 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2206714 T5463 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 2206717 T5532 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8d56f35 name:ZooKeeperConnection Watcher:127.0.0.1:50715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2206718 T5463 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 2206738 T5463 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 2207748 T5463 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50726_ [junit4] 2> 2207752 T5463 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50726_ [junit4] 2> 2207778 T5534 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 2207779 T5534 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2207781 T5534 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 2207781 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2207781 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2207781 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2207787 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2207789 T5488 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:50726";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50726_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":null} [junit4] 2> 2207789 T5488 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4 [junit4] 2> 2207790 T5488 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3 [junit4] 2> 2207796 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2207798 T5491 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> 2207799 T5513 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> 2207798 T5498 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> 2207798 T5533 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> 2208783 T5534 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 2208784 T5534 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 2208785 T5534 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 2208786 T5534 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2208789 T5534 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2208790 T5534 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/collection1/' [junit4] 2> 2208791 T5534 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 2208793 T5534 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 2208793 T5534 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 2208868 T5534 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0 [junit4] 2> 2208875 T5534 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2208879 T5534 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 2208883 T5534 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 2208917 T5534 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2208917 T5534 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2208917 T5534 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 2208918 T5534 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-ACB6DAD6A7E73D20-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/collection1/data/ [junit4] 2> 2208918 T5534 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 2208919 T5534 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/collection1/data [junit4] 2> 2208920 T5534 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-ACB6DAD6A7E73D20-001/tempDir-004/collection1/data/index/ [junit4] 2> 2208922 T5534 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-ACB6DAD6A7E73D20-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2208923 T5534 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-004/collection1/data/index [junit4] 2> 2208924 T5534 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=34.16796875, floorSegmentMB=1.3173828125, forceMergeDeletesPctAllowed=24.861193504378168, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6822459123050819 [junit4] 2> 2208930 T5534 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-ACB6DAD6A7E73D20-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ea2b3d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2208931 T5534 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2208934 T5534 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 2208935 T5534 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2208935 T5534 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2208936 T5534 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2208936 T5534 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2208936 T5534 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2208937 T5534 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2208938 T5534 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2208938 T5534 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2208938 T5534 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2208938 T5534 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2208938 T5534 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2208939 T5534 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2208945 T5534 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2208948 T5534 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2208949 T5534 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2208950 T5534 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2208951 T5534 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2208955 T5534 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2208955 T5534 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2208956 T5534 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=93.9990234375, floorSegmentMB=1.8115234375, forceMergeDeletesPctAllowed=14.888475819124274, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6231248174670746 [junit4] 2> 2208959 T5534 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-ACB6DAD6A7E73D20-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ea2b3d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 2208959 T5534 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2208959 T5534 oass.SolrIndexSearcher.<init> Opening Searcher@7cee7431[collection1] main [junit4] 2> 2208960 T5534 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 2208963 T5534 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2208963 T5534 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 2208964 T5534 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2208965 T5534 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 2208965 T5534 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2208968 T5534 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 2208968 T5534 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2208968 T5534 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 2208968 T5534 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2208968 T5534 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 2208972 T5534 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2208972 T5535 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7cee7431[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 2208975 T5538 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50726 collection:collection1 shard:shard3 [junit4] 2> 2208977 T5463 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2208977 T5463 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2208978 T5538 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 2208989 T5538 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 2208993 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2208998 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2208999 T5538 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 2208999 T5538 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2253 name=collection1 org.apache.solr.core.SolrCore@6e5c6456 url=https://127.0.0.1:50726/collection1 node=127.0.0.1:50726_ C2253_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:50726, core=collection1, node_name=127.0.0.1:50726_} [junit4] 2> 2208999 T5538 C2253 P50726 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50726/collection1/ [junit4] 2> 2208999 T5538 C2253 P50726 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 2209000 T5538 C2253 P50726 oasc.SyncStrategy.syncToMe https://127.0.0.1:50726/collection1/ has no replicas [junit4] 2> 2209000 T5538 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50726/collection1/ shard3 [junit4] 2> 2209000 T5538 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 2209019 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2209027 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2209037 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2209142 T5498 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> 2209145 T5533 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> 2209146 T5491 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> 2209148 T5513 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> 2209180 T5538 oasc.ZkController.register We are https://127.0.0.1:50726/collection1/ and leader is https://127.0.0.1:50726/collection1/ [junit4] 2> 2209181 T5538 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50726 [junit4] 2> 2209181 T5538 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 2209181 T5538 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 2209181 T5538 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 2209185 T5491 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2209185 T5515 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2209186 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2209187 T5538 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2209193 T5488 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 2209194 T5488 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:50726";, [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:50726_", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "core_node_name":"core_node2"} [junit4] 2> 2209198 T5499 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 2209305 T5533 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> 2209305 T5513 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> 2209307 T5499 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> 2209307 T5498 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> 2210850 T5463 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 2210851 T5463 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2210863 T5463 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2210863 T5463 oejs.AbstractConnector.doStart Started [email protected]:50729 [junit4] 2> 2210864 T5463 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2210865 T5463 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2210868 T5463 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-ACB6DAD6A7E73D20-001/tempDir-005 [junit4] 2> 2210869 T5463 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-ACB6DAD6A7E73D20-001/tempDir-005/' [junit4] 2> 2210922 T5463 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-ACB6DAD6A7E73D20-001/tempDir-005/solr.xml [junit4] 2> 2210940 T5463 oasc.CoreContainer.<init> New CoreContainer 137718792 [junit4] 2> 2210940 T5463 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-ACB6DAD6A7E73D20-001/tempDir-005/] [junit4] 2> 2210941 T5463 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2210941 T5463 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 2210941 T5463 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2210942 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2210942 T5463 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2210942 T5463 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2210943 T5463 oashc.HttpSha [...truncated too long message...] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCloudSchemaless -Dtests.method=testDistribSearch -Dtests.seed=ACB6DAD6A7E73D20 -Dtests.slow=true -Dtests.locale=ar_TN -Dtests.timezone=America/Marigot -Dtests.file.encoding=UTF-8 [junit4] ERROR 162s | TestCloudSchemaless.testDistribSearch <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:50726/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([ACB6DAD6A7E73D20:2D5054CED0B85D1C]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:561) [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:150) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:121) [junit4] > at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) [junit4] > at sun.security.ssl.InputRecord.read(InputRecord.java:503) [junit4] > at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:954) [junit4] > at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:911) [junit4] > at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) [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] > ... 46 more [junit4] 2> 2358899 T5463 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-ACB6DAD6A7E73D20-001 [junit4] 2> 162440 T5462 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=ar_TN, timezone=America/Marigot [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_11 (64-bit)/cpus=2,threads=1,free=148631104,total=262209536 [junit4] 2> NOTE: All tests run in this JVM: [TestRemoteStreaming, CSVRequestHandlerTest, DisMaxRequestHandlerTest, BlockDirectoryTest, TestSearcherReuse, TestBinaryField, ClusterStateTest, DistributedQueryComponentOptimizationTest, ExpressionTest, AliasIntegrationTest, DirectSolrSpellCheckerTest, BadComponentTest, DirectUpdateHandlerTest, URLClassifyProcessorTest, QueryResultKeyTest, StressHdfsTest, SuggestComponentTest, TriLevelCompositeIdRoutingTest, TestCharFilters, FieldMutatingUpdateProcessorTest, TestDynamicFieldResource, SolrIndexConfigTest, CoreAdminCreateDiscoverTest, LoggingHandlerTest, PolyFieldTest, LukeRequestHandlerTest, TestTolerantSearch, TestStressRecovery, TestPerFieldSimilarity, StatsComponentTest, TestSolrDeletionPolicy1, ZkSolrClientTest, ReplicationFactorTest, TestCollationFieldDocValues, ShardRoutingCustomTest, TestCollationField, TestSolrCoreProperties, MigrateRouteKeyTest, TestConfigSets, TestGroupingSearch, ParsingFieldUpdateProcessorsTest, MinimalSchemaTest, SuggesterTSTTest, TestSimpleQParserPlugin, TestDistributedGrouping, DistribDocExpirationUpdateProcessorTest, SampleTest, ScriptEngineTest, TestStandardQParsers, TestFieldTypeResource, HdfsUnloadDistributedZkTest, BadIndexSchemaTest, TestOmitPositions, TestReversedWildcardFilterFactory, NumericFieldsTest, TestAddFieldRealTimeGet, SOLR749Test, FullSolrCloudDistribCmdsTest, ResponseLogComponentTest, CoreAdminHandlerTest, TestBM25SimilarityFactory, TestFileDictionaryLookup, TestFieldSortValues, SolrIndexSplitterTest, CurrencyFieldXmlFileTest, TestSolrDeletionPolicy2, AnalyticsMergeStrategyTest, CustomCollectionTest, TestSchemaResource, TestMissingGroups, TestIndexSearcher, TestModifyConfFiles, UniqFieldsUpdateProcessorFactoryTest, TestBinaryResponseWriter, UpdateRequestProcessorFactoryTest, TestCursorMarkWithoutUniqueKey, TestMaxScoreQueryParser, TestElisionMultitermQuery, MultiTermTest, SolrInfoMBeanTest, TestFreeTextSuggestions, TestExtendedDismaxParser, SimplePostToolTest, TestSolrXMLSerializer, AlternateDirectoryTest, SuggesterTest, HardAutoCommitTest, StandardRequestHandlerTest, TestIBSimilarityFactory, TestRandomFaceting, TestCollectionAPI, UUIDFieldTest, PrimitiveFieldTypeTest, UnloadDistributedZkTest, CursorMarkTest, EnumFieldTest, InfoHandlerTest, TestPseudoReturnFields, TestQueryUtils, TestSolrXml, ConnectionManagerTest, StatelessScriptUpdateProcessorFactoryTest, IndexBasedSpellCheckerTest, TestCSVResponseWriter, TestReload, TestInfoStreamLogging, IndexSchemaTest, ReturnFieldsTest, TestPartialUpdateDeduplication, BasicDistributedZkTest, TestXIncludeConfig, DistributedIntervalFacetingTest, TestDefaultSearchFieldResource, TestBlendedInfixSuggestions, TestCoreContainer, IndexSchemaRuntimeFieldTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSort, SimpleFacetsTest, TestFunctionQuery, TestDocBasedVersionConstraints, TestAtomicUpdateErrorCases, SoftAutoCommitTest, DocumentAnalysisRequestHandlerTest, HdfsWriteToMultipleCollectionsTest, TestPHPSerializedResponseWriter, DOMUtilTest, ChaosMonkeyNothingIsSafeTest, TestStressVersions, DirectSolrConnectionTest, TestFastLRUCache, PreAnalyzedUpdateProcessorTest, JSONWriterTest, CollectionsAPIAsyncDistributedZkTest, AnalysisErrorHandlingTest, OutputWriterTest, BasicFunctionalityTest, TestCoreDiscovery, DeleteReplicaTest, TestIndexingPerformance, TestCloudManagedSchemaConcurrent, TestMergePolicyConfig, TestUniqueKeyFieldResource, RequiredFieldsTest, TestSolrIndexConfig, BlockCacheTest, TestReplicationHandlerBackup, OpenExchangeRatesOrgProviderTest, SpellPossibilityIteratorTest, TestCloudSchemaless] [junit4] Completed in 162.98s, 1 test, 1 error <<< FAILURES! [...truncated 835 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, 1757 tests, 1 error, 44 ignored (17 assumptions) Total time: 151 minutes 26 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
