Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1750/ 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:56511/n/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:56511/n/collection1 at __randomizedtesting.SeedInfo.seed([83AF82324C594018:2490C2A3B062024]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:560) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206) at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54) at org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871) at sun.reflect.GeneratedMethodAccessor47.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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java: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:448) ... 47 more Build Log: [...truncated 11610 lines...] [junit4] Suite: org.apache.solr.schema.TestCloudSchemaless [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/init-core-data-001 [junit4] 2> 3331237 T8931 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 3331238 T8931 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /n/ [junit4] 2> 3331242 T8931 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 3331242 T8931 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3331244 T8932 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3331345 T8931 oasc.ZkTestServer.run start zk server on port:56481 [junit4] 2> 3331347 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3331352 T8938 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5c151058 name:ZooKeeperConnection Watcher:127.0.0.1:56481 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3331352 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3331353 T8931 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3331365 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3331370 T8940 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b59867f name:ZooKeeperConnection Watcher:127.0.0.1:56481/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3331370 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3331370 T8931 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 3331378 T8931 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 3331384 T8931 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 3331390 T8931 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 3331396 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-schemaless.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3331398 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3331410 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-add-schema-fields-update-processor.xml to /configs/conf1/schema.xml [junit4] 2> 3331410 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 3331417 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3331418 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3331425 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3331426 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 3331434 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3331434 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 3331441 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 3331442 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 3331447 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3331448 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3331454 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3331455 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3331461 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3331462 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3331469 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3331470 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3331478 T8931 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3331479 T8931 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 3331489 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3331493 T8942 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@40d6d3f8 name:ZooKeeperConnection Watcher:127.0.0.1:56481/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3331493 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3333377 T8931 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3333384 T8931 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3333386 T8931 oejs.AbstractConnector.doStart Started [email protected]:56485 [junit4] 2> 3333390 T8931 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3333391 T8931 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3333391 T8931 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002 [junit4] 2> 3333392 T8931 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/' [junit4] 2> 3333452 T8931 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/solr.xml [junit4] 2> 3333492 T8931 oasc.CoreContainer.<init> New CoreContainer 1236540236 [junit4] 2> 3333492 T8931 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/] [junit4] 2> 3333493 T8931 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3333493 T8931 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3333493 T8931 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3333494 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3333494 T8931 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3333494 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3333495 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3333495 T8931 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3333495 T8931 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3333498 T8931 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3333501 T8931 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3333501 T8931 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3333502 T8931 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3333502 T8931 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56481/solr [junit4] 2> 3333503 T8931 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3333503 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3333506 T8953 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@39d0a4a3 name:ZooKeeperConnection Watcher:127.0.0.1:56481 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3333507 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3333511 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3333514 T8955 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6bd65bcc name:ZooKeeperConnection Watcher:127.0.0.1:56481/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3333515 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3333517 T8931 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 3333525 T8931 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 3333532 T8931 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 3333540 T8931 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 3333547 T8931 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 3333557 T8931 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 3333561 T8931 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56485_n [junit4] 2> 3333563 T8931 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56485_n [junit4] 2> 3333572 T8931 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 3333578 T8931 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 3333584 T8931 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3333590 T8931 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56485_n [junit4] 2> 3333590 T8931 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 3333597 T8931 oasc.Overseer.start Overseer (id=92328769183744004-127.0.0.1:56485_n-n_0000000000) starting [junit4] 2> 3333605 T8931 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 3333634 T8931 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3333636 T8956 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 3333638 T8957 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 3333640 T8931 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 3333646 T8931 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 3333651 T8931 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3333667 T8959 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 3333668 T8959 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3333670 T8959 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3333670 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3333677 T8956 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:56485/n";, [junit4] 2> "node_name":"127.0.0.1:56485_n", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3333677 T8956 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 3333678 T8956 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3333685 T8960 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> 3334671 T8959 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3334671 T8959 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 3334673 T8959 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3334673 T8959 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3334675 T8959 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3334676 T8959 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/' [junit4] 2> 3334677 T8959 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 3334678 T8959 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 3334679 T8959 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 3334725 T8959 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.11.0 [junit4] 2> 3334737 T8959 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3334741 T8959 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> 3334744 T8959 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3334748 T8959 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 3334760 T8959 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3334764 T8959 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema [junit4] 2> 3334768 T8959 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak [junit4] 2> 3334779 T8959 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> 3334779 T8959 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3334780 T8959 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 3334780 T8959 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/data/ [junit4] 2> 3334780 T8959 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 3334782 T8959 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/data [junit4] 2> 3334782 T8959 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/data/index/ [junit4] 2> 3334783 T8959 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3334783 T8959 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/data/index [junit4] 2> 3334784 T8959 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1921828908, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 3334791 T8959 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38e5d56d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3334791 T8959 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3334794 T8959 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 3334795 T8959 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3334796 T8959 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3334796 T8959 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3334796 T8959 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3334797 T8959 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3334797 T8959 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3334798 T8959 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3334798 T8959 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3334798 T8959 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3334799 T8959 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3334799 T8959 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3334800 T8959 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3334808 T8959 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3334810 T8959 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3334812 T8959 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3334815 T8959 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3334816 T8959 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3334819 T8959 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3334819 T8959 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3334820 T8959 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 3334824 T8959 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38e5d56d; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3334824 T8959 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3334825 T8959 oass.SolrIndexSearcher.<init> Opening Searcher@61107688[collection1] main [junit4] 2> 3334830 T8959 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3334832 T8959 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3334833 T8959 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3334835 T8959 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3334835 T8959 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3334835 T8959 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3334837 T8959 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3334837 T8959 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3334839 T8959 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 3334844 T8959 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 3334845 T8959 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3334846 T8959 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3334846 T8959 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 3334849 T8959 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3334849 T8961 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@61107688[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 3334851 T8964 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56485/n collection:control_collection shard:shard1 [junit4] 2> 3334853 T8931 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3334854 T8931 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3334858 T8964 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 3334866 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3334869 T8966 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7375d6a6 name:ZooKeeperConnection Watcher:127.0.0.1:56481/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3334870 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3334874 T8931 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3334876 T8964 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3334881 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3334882 T8931 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3334884 T8964 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3334884 T8964 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3092 name=collection1 org.apache.solr.core.SolrCore@674cd641 url=https://127.0.0.1:56485/n/collection1 node=127.0.0.1:56485_n C3092_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:56485/n, node_name=127.0.0.1:56485_n, state=down} [junit4] 2> 3334885 T8964 C3092 P56485 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56485/n/collection1/ [junit4] 2> 3334885 T8964 C3092 P56485 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3334885 T8964 C3092 P56485 oasc.SyncStrategy.syncToMe https://127.0.0.1:56485/n/collection1/ has no replicas [junit4] 2> 3334885 T8964 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56485/n/collection1/ shard1 [junit4] 2> 3334886 T8964 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3334933 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3334937 T8960 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> 3334938 T8967 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> 3334959 T8964 oasc.ZkController.register We are https://127.0.0.1:56485/n/collection1/ and leader is https://127.0.0.1:56485/n/collection1/ [junit4] 2> 3334959 T8964 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56485/n [junit4] 2> 3334960 T8964 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3334960 T8964 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 3334961 T8964 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3334966 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3334975 T8956 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:56485/n";, [junit4] 2> "node_name":"127.0.0.1:56485_n", [junit4] 2> "numShards":"4", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3335083 T8967 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> 3335085 T8960 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> 3336807 T8931 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 3336808 T8931 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3336814 T8931 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3336815 T8931 oejs.AbstractConnector.doStart Started [email protected]:56489 [junit4] 2> 3336818 T8931 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3336818 T8931 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3336818 T8931 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003 [junit4] 2> 3336819 T8931 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/' [junit4] 2> 3336859 T8931 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/solr.xml [junit4] 2> 3336894 T8931 oasc.CoreContainer.<init> New CoreContainer 228531779 [junit4] 2> 3336895 T8931 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/] [junit4] 2> 3336895 T8931 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3336896 T8931 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3336897 T8931 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3336897 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3336897 T8931 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3336898 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3336898 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3336898 T8931 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3336899 T8931 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3336902 T8931 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3336904 T8931 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3336905 T8931 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3336905 T8931 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3336905 T8931 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56481/solr [junit4] 2> 3336906 T8931 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3336908 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3336911 T8978 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a322867 name:ZooKeeperConnection Watcher:127.0.0.1:56481 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3336912 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3336916 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3336918 T8980 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10130cc7 name:ZooKeeperConnection Watcher:127.0.0.1:56481/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3336919 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3336936 T8931 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3337945 T8931 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56489_n [junit4] 2> 3337947 T8931 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56489_n [junit4] 2> 3337957 T8931 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3337967 T8982 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3337967 T8982 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3337969 T8982 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3337969 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3337975 T8956 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:56489/n";, [junit4] 2> "node_name":"127.0.0.1:56489_n", [junit4] 2> "numShards":"4", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3337975 T8956 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4] [junit4] 2> 3337976 T8956 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 3337984 T8981 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> 3337984 T8960 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> 3337984 T8967 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> 3338971 T8982 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3338972 T8982 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3338973 T8982 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3338973 T8982 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3338976 T8982 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3338976 T8982 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/' [junit4] 2> 3338978 T8982 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 3338978 T8982 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 3338980 T8982 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 3339054 T8982 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.11.0 [junit4] 2> 3339065 T8982 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3339068 T8982 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 3339072 T8982 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 3339084 T8982 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3339084 T8982 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3339084 T8982 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 3339085 T8982 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/data/ [junit4] 2> 3339085 T8982 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 3339087 T8982 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/data [junit4] 2> 3339089 T8982 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/data/index/ [junit4] 2> 3339090 T8982 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3339097 T8982 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/data/index [junit4] 2> 3339100 T8982 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1921828908, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 3339110 T8982 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78ac4d52; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3339111 T8982 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3339114 T8982 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 3339120 T8982 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3339120 T8982 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3339120 T8982 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3339121 T8982 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3339121 T8982 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3339121 T8982 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3339122 T8982 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3339122 T8982 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3339122 T8982 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3339122 T8982 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3339122 T8982 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3339122 T8982 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3339142 T8982 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3339145 T8982 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3339147 T8982 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3339148 T8982 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3339150 T8982 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3339153 T8982 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3339153 T8982 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3339155 T8982 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 3339157 T8982 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78ac4d52; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3339157 T8982 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3339158 T8982 oass.SolrIndexSearcher.<init> Opening Searcher@4f1c3ff4[collection1] main [junit4] 2> 3339158 T8982 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3339162 T8982 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3339163 T8982 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3339165 T8982 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3339165 T8982 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3339165 T8982 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3339169 T8982 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3339169 T8982 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3339169 T8982 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3339169 T8982 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3339169 T8982 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 3339175 T8982 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3339175 T8983 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4f1c3ff4[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 3339177 T8986 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56489/n collection:collection1 shard:shard2 [junit4] 2> 3339178 T8931 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3339178 T8931 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3339191 T8986 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 3339236 T8986 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 3339240 T8986 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3339240 T8986 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3093 name=collection1 org.apache.solr.core.SolrCore@4406a681 url=https://127.0.0.1:56489/n/collection1 node=127.0.0.1:56489_n C3093_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:56489/n, node_name=127.0.0.1:56489_n, state=down} [junit4] 2> 3339240 T8986 C3093 P56489 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56489/n/collection1/ [junit4] 2> 3339240 T8986 C3093 P56489 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3339241 T8986 C3093 P56489 oasc.SyncStrategy.syncToMe https://127.0.0.1:56489/n/collection1/ has no replicas [junit4] 2> 3339241 T8986 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56489/n/collection1/ shard2 [junit4] 2> 3339241 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3339241 T8986 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 3339262 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3339376 T8967 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> 3339379 T8981 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> 3339381 T8960 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> 3339421 T8986 oasc.ZkController.register We are https://127.0.0.1:56489/n/collection1/ and leader is https://127.0.0.1:56489/n/collection1/ [junit4] 2> 3339422 T8986 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56489/n [junit4] 2> 3339423 T8986 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3339424 T8986 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3339424 T8986 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3339429 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3339437 T8956 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:56489/n";, [junit4] 2> "node_name":"127.0.0.1:56489_n", [junit4] 2> "numShards":"4", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3339549 T8960 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> 3339550 T8967 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> 3339551 T8981 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> 3341299 T8931 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 3341300 T8931 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3341307 T8931 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3341308 T8931 oejs.AbstractConnector.doStart Started [email protected]:56492 [junit4] 2> 3341311 T8931 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3341312 T8931 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3341312 T8931 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004 [junit4] 2> 3341312 T8931 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/' [junit4] 2> 3341348 T8931 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/solr.xml [junit4] 2> 3341379 T8931 oasc.CoreContainer.<init> New CoreContainer 316144031 [junit4] 2> 3341379 T8931 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/] [junit4] 2> 3341380 T8931 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3341380 T8931 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3341381 T8931 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3341381 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3341381 T8931 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3341382 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3341382 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3341383 T8931 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3341383 T8931 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3341385 T8931 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3341386 T8931 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3341387 T8931 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3341387 T8931 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3341388 T8931 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56481/solr [junit4] 2> 3341388 T8931 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3341390 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3341392 T8997 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@473e427a name:ZooKeeperConnection Watcher:127.0.0.1:56481 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3341397 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3341401 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3341404 T8999 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2e49db92 name:ZooKeeperConnection Watcher:127.0.0.1:56481/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3341405 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3341428 T8931 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3342437 T8931 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56492_n [junit4] 2> 3342439 T8931 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56492_n [junit4] 2> 3342453 T8931 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3342466 T9001 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3342466 T9001 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3342468 T9001 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3342468 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3342473 T8956 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:56492/n";, [junit4] 2> "node_name":"127.0.0.1:56492_n", [junit4] 2> "numShards":"4", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3342473 T8956 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4 [junit4] 2> 3342473 T8956 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3 [junit4] 2> 3342480 T9000 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> 3342480 T8967 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> 3342480 T8981 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> 3342480 T8960 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> 3343469 T9001 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3343470 T9001 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3343471 T9001 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3343471 T9001 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3343475 T9001 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3343475 T9001 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/' [junit4] 2> 3343477 T9001 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 3343478 T9001 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 3343478 T9001 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 3343530 T9001 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.11.0 [junit4] 2> 3343538 T9001 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3343541 T9001 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema [junit4] 2> 3343543 T9001 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor [junit4] 2> 3343552 T9001 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3343553 T9001 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3343554 T9001 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory [junit4] 2> 3343554 T9001 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/data/ [junit4] 2> 3343554 T9001 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 3343555 T9001 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/data [junit4] 2> 3343556 T9001 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/data/index/ [junit4] 2> 3343556 T9001 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3343557 T9001 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/data/index [junit4] 2> 3343557 T9001 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1921828908, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 3343564 T9001 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e83c351; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3343564 T9001 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3343566 T9001 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema" [junit4] 2> 3343567 T9001 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3343568 T9001 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3343568 T9001 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3343568 T9001 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3343568 T9001 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3343568 T9001 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3343569 T9001 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3343569 T9001 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3343569 T9001 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3343569 T9001 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3343570 T9001 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3343570 T9001 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3343580 T9001 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3343583 T9001 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3343584 T9001 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3343585 T9001 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3343586 T9001 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3343589 T9001 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3343589 T9001 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3343590 T9001 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 3343591 T9001 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e83c351; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 3343592 T9001 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3343592 T9001 oass.SolrIndexSearcher.<init> Opening Searcher@747594c[collection1] main [junit4] 2> 3343593 T9001 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3343595 T9001 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3343595 T9001 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3343596 T9001 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3343596 T9001 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3343597 T9001 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3343601 T9001 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3343601 T9001 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3343602 T9001 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3343602 T9001 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3343602 T9001 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ... [junit4] 2> 3343604 T9001 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3343605 T9002 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@747594c[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 3343607 T9005 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56492/n collection:collection1 shard:shard3 [junit4] 2> 3343609 T8931 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 3343609 T8931 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3343627 T9005 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 3343656 T9005 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 3343664 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3343670 T9005 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3343670 T9005 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3094 name=collection1 org.apache.solr.core.SolrCore@5154fd36 url=https://127.0.0.1:56492/n/collection1 node=127.0.0.1:56492_n C3094_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:56492/n, node_name=127.0.0.1:56492_n, state=down} [junit4] 2> 3343671 T9005 C3094 P56492 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56492/n/collection1/ [junit4] 2> 3343672 T9005 C3094 P56492 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3343672 T9005 C3094 P56492 oasc.SyncStrategy.syncToMe https://127.0.0.1:56492/n/collection1/ has no replicas [junit4] 2> 3343672 T9005 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56492/n/collection1/ shard3 [junit4] 2> 3343672 T9005 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 3343699 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3343813 T8967 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> 3343813 T8981 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> 3343813 T8960 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> 3343813 T9000 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> 3343861 T9005 oasc.ZkController.register We are https://127.0.0.1:56492/n/collection1/ and leader is https://127.0.0.1:56492/n/collection1/ [junit4] 2> 3343862 T9005 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56492/n [junit4] 2> 3343864 T9005 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3343864 T9005 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3343864 T9005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3343871 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3343879 T8956 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:56492/n";, [junit4] 2> "node_name":"127.0.0.1:56492_n", [junit4] 2> "numShards":"4", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3344000 T8960 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> 3344003 T8981 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> 3344001 T9000 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> 3344003 T8967 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> 3345571 T8931 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 3345571 T8931 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3345578 T8931 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 3345580 T8931 oejs.AbstractConnector.doStart Started [email protected]:56495 [junit4] 2> 3345584 T8931 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3345585 T8931 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3345585 T8931 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-005 [junit4] 2> 3345586 T8931 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-005/' [junit4] 2> 3345640 T8931 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-005/solr.xml [junit4] 2> 3345655 T8931 oasc.CoreContainer.<init> New CoreContainer 1707688044 [junit4] 2> 3345655 T8931 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001/tempDir-005/] [junit4] 2> 3345656 T8931 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3345657 T8931 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3345657 T8931 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3345657 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3345658 T8931 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3345658 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3345658 T8931 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3345658 T8931 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3345658 T8931 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3345661 T8931 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3345664 T8931 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3345665 T8931 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3345666 T8931 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3345666 T8931 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56481/solr [junit4] 2> 3345666 T8931 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3345668 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3345672 T9016 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15ed8be2 name:ZooKeeperConnection Watcher:127.0.0.1:56481 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3345673 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3345683 T8931 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3345686 T9018 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e3072ae name:ZooKeeperConnection Watcher:127.0.0.1:56481/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3345687 T8931 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3345707 T8931 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3346733 T8931 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56495_n [junit4] 2> 3346736 T8931 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56495_n [junit4] 2> 3346747 T8931 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3346762 T9020 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3346763 T9020 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3346764 T8960 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3346764 T9020 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3346768 T8956 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:56495/n";, [junit4] 2> "node_name":"127.0.0.1:56495_n", [junit4] 2> "numShards":"4", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3346768 T8956 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4 [junit4] 2> 3346768 T8956 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4 [junit4] 2> 3346777 T9000 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 3346778 T8967 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 3346777 T8981 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 3346777 T9019 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 3346777 T8960 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 3347768 T9020 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3347768 T9020 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3347771 T9020 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3347772 T9020 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3347774 T9020 oascc.ZkStat [...truncated too long message...] :54) [junit4] > at org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java: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:448) [junit4] > ... 47 more [junit4] 2> 3489057 T8931 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-83AF82324C594018-001 [junit4] 2> NOTE: test params are: codec=Lucene410: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=fi, timezone=America/Campo_Grande [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_20 (64-bit)/cpus=2,threads=1,free=123398176,total=319094784 [junit4] 2> NOTE: All tests run in this JVM: [AtomicUpdatesTest, EchoParamsTest, BlockCacheTest, TestSort, AddSchemaFieldsUpdateProcessorFactoryTest, DocumentBuilderTest, AlternateDirectoryTest, ZkControllerTest, DistributedExpandComponentTest, SolrXmlInZkTest, TestFastLRUCache, TestRealTimeGet, SampleTest, TestHighlightDedupGrouping, TestCollationFieldDocValues, AnalysisErrorHandlingTest, TestFoldingMultitermQuery, SolrInfoMBeanTest, CurrencyFieldOpenExchangeTest, TestNRTOpen, LeaderElectionTest, TestReversedWildcardFilterFactory, DistributedIntervalFacetingTest, CollectionsAPIDistributedZkTest, TestExpandComponent, ZkNodePropsTest, DirectSolrConnectionTest, HdfsLockFactoryTest, DistributedFacetPivotSmallTest, SuggesterTSTTest, BJQParserTest, TestDefaultSimilarityFactory, SpellCheckComponentTest, SyncSliceTest, TestCSVLoader, TestMissingGroups, TestOmitPositions, DebugComponentTest, TestPivotHelperCode, TestCoreContainer, HdfsDirectoryTest, ZkCLITest, TestSuggestSpellingConverter, TestQueryUtils, MinimalSchemaTest, IndexSchemaTest, MergeStrategyTest, TriLevelCompositeIdRoutingTest, TestPHPSerializedResponseWriter, CoreContainerCoreInitFailuresTest, DistributedQueryElevationComponentTest, TestRandomDVFaceting, PluginInfoTest, TestLuceneMatchVersion, PrimitiveFieldTypeTest, TestSearcherReuse, OverseerRolesTest, CoreAdminRequestStatusTest, AnalyticsMergeStrategyTest, TestNoOpRegenerator, SOLR749Test, TestSolr4Spatial2, ChaosMonkeySafeLeaderTest, XsltUpdateRequestHandlerTest, TestAnalyzedSuggestions, TestStressUserVersions, TestTolerantSearch, BasicZkTest, TestReload, TestCursorMarkWithoutUniqueKey, HdfsUnloadDistributedZkTest, SchemaVersionSpecificBehaviorTest, TestSolrQueryParser, DistributedSpellCheckComponentTest, TestHighFrequencyDictionaryFactory, PrimUtilsTest, TestHashPartitioner, TestRemoteStreaming, SynonymTokenizerTest, BasicDistributedZkTest, TestFieldTypeCollectionResource, StatelessScriptUpdateProcessorFactoryTest, DefaultValueUpdateProcessorTest, TestSolrQueryParserResource, TestIntervalFaceting, TestRecoveryHdfs, TestCloudManagedSchema, TestStressLucene, TestChildDocTransformer, ZkSolrClientTest, TestConfigSets, CoreAdminHandlerTest, TestOverriddenPrefixQueryForCustomFieldType, SuggesterFSTTest, TestDocumentBuilder, SolrPluginUtilsTest, TestCharFilters, TestFieldTypeResource, FieldMutatingUpdateProcessorTest, TestDistributedGrouping, TestManagedResource, SpellPossibilityIteratorTest, SharedFSAutoReplicaFailoverUtilsTest, TestExtendedDismaxParser, MigrateRouteKeyTest, TestIBSimilarityFactory, TestRandomFaceting, TestCoreDiscovery, TestInitQParser, UpdateRequestProcessorFactoryTest, TestFastWriter, TestManagedStopFilterFactory, TestComponentsName, TestRequestStatusCollectionAPI, TestManagedSynonymFilterFactory, BasicFunctionalityTest, OverseerTest, HardAutoCommitTest, ShardRoutingCustomTest, TestDFRSimilarityFactory, RequestHandlersTest, TestDistribDocBasedVersion, PreAnalyzedFieldTest, DistributedQueryComponentOptimizationTest, TermVectorComponentDistributedTest, SuggesterTest, CursorPagingTest, HdfsBasicDistributedZkTest, SolrCoreCheckLockOnStartupTest, HttpPartitionTest, TestSolrJ, ClusterStateTest, TestQuerySenderNoQuery, TestRandomMergePolicy, HdfsWriteToMultipleCollectionsTest, TestSolrQueryParserDefaultOperatorResource, TestSimpleQParserPlugin, TestNonNRTOpen, BadIndexSchemaTest, TestSweetSpotSimilarityFactory, ReturnFieldsTest, TestSchemaResource, TestSchemaVersionResource, HighlighterTest, DistribCursorPagingTest, TestExceedMaxTermLength, ResponseHeaderTest, TestLMDirichletSimilarityFactory, OverseerStatusTest, FullSolrCloudDistribCmdsTest, HdfsRecoveryZkTest, TestCollationKeyRangeQueries, TestDistributedSearch, TestSolrXmlPersistor, SortByFunctionTest, FastVectorHighlighterTest, ClusterStateUpdateTest, TestJmxIntegration, TestCloudPivotFacet, NotRequiredUniqueKeyTest, DateMathParserTest, TestFiltering, AsyncMigrateRouteKeyTest, TestDocBasedVersionConstraints, TestDynamicFieldResource, TestJmxMonitoredMap, SpellingQueryConverterTest, DocValuesTest, EnumFieldTest, TestArbitraryIndexDir, TestMaxScoreQueryParser, PreAnalyzedUpdateProcessorTest, TestMultiCoreConfBootstrap, FileBasedSpellCheckerTest, TestCloudInspectUtil, ShowFileRequestHandlerTest, QueryParsingTest, DeleteReplicaTest, ExternalFileFieldSortTest, TestAnalyzeInfixSuggestions, BinaryUpdateRequestHandlerTest, MBeansHandlerTest, TestJoin, DeleteLastCustomShardedReplicaTest, BlockDirectoryTest, ResourceLoaderTest, SolrIndexSplitterTest, TestSolrCoreProperties, TestComplexPhraseQParserPlugin, SuggestComponentTest, DistributedSuggestComponentTest, TestClassNameShortening, TestSchemaSimilarityResource, DirectSolrSpellCheckerTest, CustomCollectionTest, DocumentAnalysisRequestHandlerTest, TestCopyFieldCollectionResource, SearchHandlerTest, MultiTermTest, AliasIntegrationTest, TestCloudSchemaless] [junit4] Completed in 157.87s, 1 test, 1 error <<< FAILURES! [...truncated 667 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:485: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:465: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:48: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:189: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:494: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1343: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:958: There were test failures: 419 suites, 1733 tests, 1 error, 44 ignored (17 assumptions) Total time: 153 minutes 52 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]
