Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/4133/ Java: 32bit/jdk1.7.0_65 -client -XX:+UseSerialGC
1 tests failed. REGRESSION: org.apache.solr.cloud.MultiThreadedOCPTest.testDistribSearch Error Message: Task 3002 did not complete, final state: failed Stack Trace: java.lang.AssertionError: Task 3002 did not complete, final state: failed at __randomizedtesting.SeedInfo.seed([A8F9350703B860C5:291FBB1F74E700F9]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.MultiThreadedOCPTest.testDeduplicationOfSubmittedTasks(MultiThreadedOCPTest.java:163) at org.apache.solr.cloud.MultiThreadedOCPTest.doTest(MultiThreadedOCPTest.java:72) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.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) Build Log: [...truncated 12094 lines...] [junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\init-core-data-001 [junit4] 2> 5693916 T13282 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 5693917 T13282 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 5693921 T13282 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 5693923 T13282 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 5693925 T13283 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 5694025 T13282 oasc.ZkTestServer.run start zk server on port:58985 [junit4] 2> 5694031 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5694040 T13289 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5574b name:ZooKeeperConnection Watcher:127.0.0.1:58985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5694042 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5694042 T13282 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 5694056 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5694059 T13291 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@efd8c7 name:ZooKeeperConnection Watcher:127.0.0.1:58985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5694060 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5694060 T13282 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 5694068 T13282 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 5694075 T13282 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 5694082 T13282 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 5694089 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 5694091 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 5694102 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4] 2> 5694103 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 5694110 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5694110 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5694118 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 5694118 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 5694126 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 5694126 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 5694138 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 5694138 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 5694149 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 5694149 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 5694158 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 5694158 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 5694168 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5694168 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5694176 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 5694177 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 5694183 T13282 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 5694183 T13282 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 5694194 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5694196 T13293 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b06c43 name:ZooKeeperConnection Watcher:127.0.0.1:58985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5694197 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5694200 T13284 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14794f3fb010002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 5697321 T13282 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5697339 T13282 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 5697349 T13282 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:58995 [junit4] 2> 5697354 T13282 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 5697355 T13282 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5697355 T13282 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-002 [junit4] 2> 5697356 T13282 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-002\' [junit4] 2> 5697399 T13282 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-002\solr.xml [junit4] 2> 5697475 T13282 oasc.CoreContainer.<init> New CoreContainer 25579210 [junit4] 2> 5697476 T13282 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-002\] [junit4] 2> 5697477 T13282 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5697478 T13282 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5697478 T13282 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5697479 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5697479 T13282 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5697480 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5697480 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5697480 T13282 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5697481 T13282 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5697485 T13282 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 5697489 T13282 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5697490 T13282 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5697490 T13282 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5697490 T13282 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58985/solr [junit4] 2> 5697491 T13282 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5697494 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5697496 T13304 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b48c96 name:ZooKeeperConnection Watcher:127.0.0.1:58985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5697496 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5697503 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5697505 T13306 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d96f0e name:ZooKeeperConnection Watcher:127.0.0.1:58985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5697506 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5697510 T13282 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 5697518 T13282 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 5697526 T13282 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 5697535 T13282 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 5697543 T13282 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 5697552 T13282 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 5697556 T13282 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58995_ [junit4] 2> 5697559 T13282 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58995_ [junit4] 2> 5697566 T13282 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 5697574 T13282 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 5697583 T13282 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:58995_ [junit4] 2> 5697583 T13282 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 5697589 T13282 oasc.Overseer.start Overseer (id=92206092993495044-127.0.0.1:58995_-n_0000000000) starting [junit4] 2> 5697596 T13282 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 5697624 T13308 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 5697626 T13282 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 5697635 T13282 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 5697639 T13282 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5697647 T13307 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 5697656 T13309 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 5697656 T13309 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5697657 T13309 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5697660 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5697663 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5697665 T13307 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:58995", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:58995_", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":null} [junit4] 2> 5697666 T13307 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 5697666 T13307 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5697672 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5697674 T13310 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> 5698768 T13309 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5698768 T13309 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 5698773 T13309 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5698774 T13309 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5698779 T13309 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5698780 T13309 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-002\collection1\' [junit4] 2> 5698785 T13309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 5698789 T13309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 5698789 T13309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 5698895 T13309 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10 [junit4] 2> 5698978 T13309 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5698982 T13309 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 5699001 T13309 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5699719 T13309 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 5699720 T13309 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 5699721 T13309 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5699736 T13309 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5699744 T13309 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5699783 T13309 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5699794 T13309 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5699798 T13309 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5699805 T13309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5699805 T13309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5699805 T13309 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5699811 T13309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5699811 T13309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5699811 T13309 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 5699811 T13309 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5699814 T13309 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-002\collection1\, dataDir=.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-001/control/data\ [junit4] 2> 5699814 T13309 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@d464fc [junit4] 2> 5699818 T13309 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-001/control/data\ [junit4] 2> 5699818 T13309 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-001/control/data\index/ [junit4] 2> 5699818 T13309 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-001\control\data\index' doesn't exist. Creating new index... [junit4] 2> 5699820 T13309 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-001/control/data\index [junit4] 2> 5699820 T13309 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=92.5244140625, floorSegmentMB=1.4677734375, forceMergeDeletesPctAllowed=12.937273499167981, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 5699821 T13309 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5a16e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ac014a),segFN=segments_1,generation=1} [junit4] 2> 5699821 T13309 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5699824 T13309 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5699825 T13309 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5699825 T13309 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5699825 T13309 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5699825 T13309 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5699825 T13309 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5699826 T13309 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5699826 T13309 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5699826 T13309 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5699830 T13309 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5699831 T13309 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 5699831 T13309 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 5699831 T13309 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 5699833 T13309 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 5699833 T13309 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 5699834 T13309 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5699851 T13309 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5699861 T13309 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5699864 T13309 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5699865 T13309 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=50.150390625, floorSegmentMB=2.08203125, forceMergeDeletesPctAllowed=18.759908536232018, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 5699866 T13309 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5a16e5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ac014a),segFN=segments_1,generation=1} [junit4] 2> 5699866 T13309 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5699869 T13309 oass.SolrIndexSearcher.<init> Opening Searcher@1500ff7[collection1] main [junit4] 2> 5699869 T13309 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5699876 T13309 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5699876 T13309 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5699877 T13309 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5699877 T13309 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5699877 T13309 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5699879 T13309 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5699879 T13309 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5699882 T13309 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 5699888 T13309 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 5699888 T13309 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5699888 T13309 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5699894 T13311 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1500ff7[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 5699921 T13309 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5699926 T13314 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:58995 collection:control_collection shard:shard1 [junit4] 2> 5699927 T13282 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5699928 T13282 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5699931 T13314 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 5699953 T13314 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 5699956 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5699960 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5699961 T13314 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5699961 T13316 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@966718 name:ZooKeeperConnection Watcher:127.0.0.1:58985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5699962 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5699962 T13314 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4257 name=collection1 org.apache.solr.core.SolrCore@e895f6 url=https://127.0.0.1:58995/collection1 node=127.0.0.1:58995_ C4257_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:58995, core=collection1, node_name=127.0.0.1:58995_} [junit4] 2> 5699963 T13314 C4257 P58995 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:58995/collection1/ [junit4] 2> 5699964 T13314 C4257 P58995 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5699964 T13314 C4257 P58995 oasc.SyncStrategy.syncToMe https://127.0.0.1:58995/collection1/ has no replicas [junit4] 2> 5699964 T13314 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:58995/collection1/ shard1 [junit4] 2> 5699965 T13314 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 5699966 T13282 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5699966 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5699974 T13282 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 5699978 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5699981 T13310 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> 5699985 T13317 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> 5699991 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5699998 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5700111 T13317 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> 5700112 T13310 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> 5700156 T13314 oasc.ZkController.register We are https://127.0.0.1:58995/collection1/ and leader is https://127.0.0.1:58995/collection1/ [junit4] 2> 5700156 T13314 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:58995 [junit4] 2> 5700156 T13314 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5700156 T13314 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 5700156 T13314 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5700166 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5700167 T13314 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5700168 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5700168 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5700170 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5700174 T13307 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:58995", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:58995_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"2", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 5700181 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5700297 T13318 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> 5700297 T13317 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> 5702908 T13282 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 5702911 T13282 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5702927 T13282 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 5702932 T13282 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:59007 [junit4] 2> 5702937 T13282 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 5702938 T13282 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5702938 T13282 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003 [junit4] 2> 5702939 T13282 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\' [junit4] 2> 5702990 T13282 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\solr.xml [junit4] 2> 5703077 T13282 oasc.CoreContainer.<init> New CoreContainer 11447171 [junit4] 2> 5703078 T13282 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\] [junit4] 2> 5703079 T13282 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5703080 T13282 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5703080 T13282 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5703080 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5703081 T13282 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5703081 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5703082 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5703082 T13282 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5703082 T13282 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5703087 T13282 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 5703091 T13282 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5703092 T13282 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5703093 T13282 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5703093 T13282 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58985/solr [junit4] 2> 5703094 T13282 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5703098 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5703103 T13330 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1765806 name:ZooKeeperConnection Watcher:127.0.0.1:58985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5703103 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5703111 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5703115 T13332 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10525de name:ZooKeeperConnection Watcher:127.0.0.1:58985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5703116 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5703140 T13282 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5704259 T13282 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59007_ [junit4] 2> 5704263 T13282 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59007_ [junit4] 2> 5704270 T13317 oascc.ZkStateReader$3.process Updating live nodes... (2) [junit4] 2> 5704270 T13318 oascc.ZkStateReader$3.process Updating live nodes... (2) [junit4] 2> 5704274 T13333 oascc.ZkStateReader$3.process Updating live nodes... (2) [junit4] 2> 5704297 T13334 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5704299 T13334 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5704301 T13334 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5704301 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5704301 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5704301 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5704309 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5704312 T13307 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:59007", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:59007_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "core_node_name":null} [junit4] 2> 5704313 T13307 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 5704313 T13307 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 5704320 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5704321 T13317 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> 5704322 T13333 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> 5704323 T13319 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> 5705412 T13334 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5705412 T13334 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5705414 T13334 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5705414 T13334 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5705416 T13334 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5705416 T13334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\collection1\' [junit4] 2> 5705419 T13334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 5705423 T13334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 5705427 T13334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 5705508 T13334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10 [junit4] 2> 5705570 T13334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5705574 T13334 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 5705589 T13334 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5706222 T13334 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 5706224 T13334 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 5706224 T13334 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5706243 T13334 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5706248 T13334 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5706277 T13334 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5706284 T13334 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5706293 T13334 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5706296 T13334 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5706296 T13334 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5706296 T13334 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5706300 T13334 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5706300 T13334 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5706300 T13334 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5706300 T13334 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5706302 T13334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\collection1\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\collection1\data\ [junit4] 2> 5706303 T13334 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@d464fc [junit4] 2> 5706304 T13334 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\collection1\data\ [junit4] 2> 5706304 T13334 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\collection1\data\index/ [junit4] 2> 5706304 T13334 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 5706305 T13334 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-003\collection1\data\index [junit4] 2> 5706305 T13334 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=92.5244140625, floorSegmentMB=1.4677734375, forceMergeDeletesPctAllowed=12.937273499167981, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 5706306 T13334 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1f4027e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc1117),segFN=segments_1,generation=1} [junit4] 2> 5706306 T13334 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5706311 T13334 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5706312 T13334 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5706312 T13334 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5706312 T13334 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5706313 T13334 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5706313 T13334 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5706315 T13334 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5706315 T13334 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5706315 T13334 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5706341 T13334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5706342 T13334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 5706342 T13334 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 5706343 T13334 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 5706344 T13334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 5706345 T13334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 5706346 T13334 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5706363 T13334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5706371 T13334 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5706372 T13334 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5706373 T13334 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=50.150390625, floorSegmentMB=2.08203125, forceMergeDeletesPctAllowed=18.759908536232018, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 5706374 T13334 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1f4027e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc1117),segFN=segments_1,generation=1} [junit4] 2> 5706374 T13334 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5706374 T13334 oass.SolrIndexSearcher.<init> Opening Searcher@3cab27[collection1] main [junit4] 2> 5706375 T13334 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5706377 T13334 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5706379 T13334 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5706381 T13334 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5706381 T13334 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5706381 T13334 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5706386 T13334 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 5706386 T13334 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5706386 T13334 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 5706386 T13334 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5706397 T13335 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3cab27[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 5706406 T13334 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5706409 T13338 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:59007 collection:collection1 shard:shard2 [junit4] 2> 5706410 T13282 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5706411 T13282 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5706413 T13338 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 5706431 T13338 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 5706434 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5706435 T13338 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5706435 T13338 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4258 name=collection1 org.apache.solr.core.SolrCore@1432b31 url=https://127.0.0.1:59007/collection1 node=127.0.0.1:59007_ C4258_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:59007, core=collection1, node_name=127.0.0.1:59007_} [junit4] 2> 5706435 T13338 C4258 P59007 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:59007/collection1/ [junit4] 2> 5706435 T13338 C4258 P59007 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5706435 T13338 C4258 P59007 oasc.SyncStrategy.syncToMe https://127.0.0.1:59007/collection1/ has no replicas [junit4] 2> 5706437 T13338 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:59007/collection1/ shard2 [junit4] 2> 5706437 T13338 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 5706440 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5706449 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5706452 T13333 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> 5706452 T13317 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> 5706453 T13319 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> 5706467 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5706473 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5706587 T13333 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> 5706588 T13317 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> 5706590 T13319 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> 5706630 T13338 oasc.ZkController.register We are https://127.0.0.1:59007/collection1/ and leader is https://127.0.0.1:59007/collection1/ [junit4] 2> 5706630 T13338 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:59007 [junit4] 2> 5706630 T13338 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5706631 T13338 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 5706631 T13338 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5706635 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5706636 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5706636 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5706638 T13338 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5706641 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5706643 T13307 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:59007", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:59007_", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 5706648 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5706763 T13318 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> 5706764 T13317 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> 5706764 T13333 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> 5709350 T13282 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 5709351 T13282 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5709364 T13282 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 5709366 T13282 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:59016 [junit4] 2> 5709372 T13282 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 5709373 T13282 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5709373 T13282 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004 [junit4] 2> 5709375 T13282 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\' [junit4] 2> 5709420 T13282 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\solr.xml [junit4] 2> 5709507 T13282 oasc.CoreContainer.<init> New CoreContainer 22587539 [junit4] 2> 5709507 T13282 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\] [junit4] 2> 5709509 T13282 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5709509 T13282 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5709511 T13282 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5709511 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5709511 T13282 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5709512 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5709512 T13282 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5709513 T13282 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5709513 T13282 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5709518 T13282 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 5709521 T13282 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5709522 T13282 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5709522 T13282 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5709524 T13282 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58985/solr [junit4] 2> 5709525 T13282 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5709527 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5709530 T13349 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32b65e name:ZooKeeperConnection Watcher:127.0.0.1:58985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5709530 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5709534 T13284 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14794f3fb010008, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 5709537 T13282 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5709540 T13351 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@527510 name:ZooKeeperConnection Watcher:127.0.0.1:58985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5709543 T13282 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5709563 T13282 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5710792 T13282 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59016_ [junit4] 2> 5710797 T13282 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59016_ [junit4] 2> 5710811 T13333 oascc.ZkStateReader$3.process Updating live nodes... (3) [junit4] 2> 5710813 T13318 oascc.ZkStateReader$3.process Updating live nodes... (3) [junit4] 2> 5710816 T13317 oascc.ZkStateReader$3.process Updating live nodes... (3) [junit4] 2> 5710819 T13352 oascc.ZkStateReader$3.process Updating live nodes... (3) [junit4] 2> 5710836 T13353 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5710836 T13353 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5710839 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5710839 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5710839 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5710839 T13353 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5710847 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5710850 T13307 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:59016", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:59016_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "core_node_name":null} [junit4] 2> 5710850 T13307 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 5710850 T13307 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5710858 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5710860 T13317 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> 5710861 T13318 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> 5710861 T13352 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> 5710861 T13333 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> 5712062 T13353 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5712062 T13353 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5712068 T13353 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5712068 T13353 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5712072 T13353 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5712072 T13353 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\collection1\' [junit4] 2> 5712075 T13353 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 5712078 T13353 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 5712078 T13353 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 5712166 T13353 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10 [junit4] 2> 5712231 T13353 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5712235 T13353 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 5712252 T13353 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5712880 T13353 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 5712880 T13353 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 5712882 T13353 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5712924 T13353 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5712929 T13353 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5712967 T13353 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5712975 T13353 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5712984 T13353 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5712986 T13353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5712986 T13353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5712986 T13353 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5712990 T13353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5712990 T13353 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5712991 T13353 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5712991 T13353 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5712992 T13353 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\collection1\, dataDir=.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\collection1\data\ [junit4] 2> 5712992 T13353 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@d464fc [junit4] 2> 5712993 T13353 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\collection1\data\ [junit4] 2> 5712993 T13353 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\collection1\data\index/ [junit4] 2> 5712993 T13353 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 5712994 T13353 oasc.CachingDirectoryFactory.get return new directory for .\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001\tempDir-004\collection1\data\index [junit4] 2> 5712996 T13353 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=92.5244140625, floorSegmentMB=1.4677734375, forceMergeDeletesPctAllowed=12.937273499167981, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 5712996 T13353 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@98065 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26d962),segFN=segments_1,generation=1} [junit4] 2> 5712996 T13353 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5713002 T13353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5713003 T13353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5713003 T13353 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5713003 T13353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5713004 T13353 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5713004 T13353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5713005 T13353 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5713005 T13353 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5713005 T13353 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5713008 T13353 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5713009 T13353 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 5713009 T13353 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 5713009 T13353 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 5713010 T13353 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 5713010 T13353 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 5713011 T13353 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5713023 T13353 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5713030 T13353 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5713030 T13353 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5713031 T13353 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=50.150390625, floorSegmentMB=2.08203125, forceMergeDeletesPctAllowed=18.759908536232018, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 5713031 T13353 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@98065 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26d962),segFN=segments_1,generation=1} [junit4] 2> 5713031 T13353 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5713033 T13353 oass.SolrIndexSearcher.<init> Opening Searcher@11b5700[collection1] main [junit4] 2> 5713033 T13353 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5713035 T13353 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5713035 T13353 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5713036 T13353 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5713036 T13353 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5713036 T13353 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5713040 T13353 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 5713040 T13353 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5713040 T13353 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 5713040 T13353 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5713047 T13354 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11b5700[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 5713052 T13353 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5713055 T13357 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:59016 collection:collection1 shard:shard1 [junit4] 2> 5713056 T13282 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5713056 T13282 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5713058 T13357 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 5713071 T13357 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 5713074 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5713077 T13357 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5713077 T13357 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4259 name=collection1 org.apache.solr.core.SolrCore@3aa45 url=https://127.0.0.1:59016/collection1 node=127.0.0.1:59016_ C4259_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:59016, core=collection1, node_name=127.0.0.1:59016_} [junit4] 2> 5713077 T13357 C4259 P59016 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:59016/collection1/ [junit4] 2> 5713077 T13357 C4259 P59016 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5713077 T13357 C4259 P59016 oasc.SyncStrategy.syncToMe https://127.0.0.1:59016/collection1/ has no replicas [junit4] 2> 5713077 T13357 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:59016/collection1/ shard1 [junit4] 2> 5713078 T13357 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 5713091 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5713098 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5713102 T13352 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> 5713102 T13333 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> 5713103 T13317 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> 5713108 T13318 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> 5713117 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5713123 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5713248 T13318 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> 5713250 T13317 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> 5713250 T13333 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> 5713251 T13352 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> 5713301 T13357 oasc.ZkController.register We are https://127.0.0.1:59016/collection1/ and leader is https://127.0.0.1:59016/collection1/ [junit4] 2> 5713301 T13357 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:59016 [junit4] 2> 5713301 T13357 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5713301 T13357 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 5713301 T13357 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5713304 T13319 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5713306 T13310 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5713306 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5713307 T13357 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5713310 T13307 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... [junit4] 2> 5713312 T13307 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "base_url":"https://127.0.0.1:59016", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:59016_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "core_node_name":"core_node2"} [junit4] 2> 5713316 T13318 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 5713442 T13333 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: [...truncated too long message...] va:72) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 5816583 T13282 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solr.cloud.MultiThreadedOCPTest-A8F9350703B860C5-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene49), sim=DefaultSimilarity, locale=es_SV, timezone=Atlantic/Bermuda [junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_65 (32-bit)/cpus=2,threads=1,free=183880584,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [DirectSolrConnectionTest, PreAnalyzedFieldTest, TestWordDelimiterFilterFactory, LegacyHTMLStripCharFilterTest, CoreAdminCreateDiscoverTest, SolrIndexSplitterTest, DirectUpdateHandlerOptimizeTest, FieldAnalysisRequestHandlerTest, TestConfigSets, TestQueryUtils, JSONWriterTest, TestLRUCache, ExternalFileFieldSortTest, DefaultValueUpdateProcessorTest, TestConfig, SoftAutoCommitTest, TestChildDocTransformer, EchoParamsTest, DistributedQueryComponentOptimizationTest, DocExpirationUpdateProcessorFactoryTest, TestFastLRUCache, TestCodecSupport, TestCollapseQParserPlugin, ZkSolrClientTest, TestSchemaNameResource, TestStandardQParsers, ResourceLoaderTest, TestCustomSort, TestManagedSchema, TestCloudInspectUtil, TestComponentsName, TestSchemaSimilarityResource, CoreAdminRequestStatusTest, TestDocBasedVersionConstraints, DistributedIntervalFacetingTest, TestJoin, TestRangeQuery, TestArbitraryIndexDir, TestCloudManagedSchema, StandardRequestHandlerTest, TestSolrCoreProperties, TestSolrJ, HdfsUnloadDistributedZkTest, SynonymTokenizerTest, XmlUpdateRequestHandlerTest, TestManagedResourceStorage, DocValuesMultiTest, TestInfoStreamLogging, ReplicationFactorTest, BasicDistributedZkTest, HighlighterConfigTest, TestStressVersions, HdfsWriteToMultipleCollectionsTest, TestReversedWildcardFilterFactory, TestSerializedLuceneMatchVersion, TestTolerantSearch, XsltUpdateRequestHandlerTest, AnalyticsMergeStrategyTest, CircularListTest, RequiredFieldsTest, TestSchemaVersionResource, FileBasedSpellCheckerTest, DistributedDebugComponentTest, TestLMJelinekMercerSimilarityFactory, TestStressReorder, NoCacheHeaderTest, TestBinaryField, TestSolr4Spatial, TestSurroundQueryParser, AtomicUpdatesTest, PluginInfoTest, SSLMigrationTest, TestRecovery, SOLR749Test, TestSolrXml, TestReloadAndDeleteDocs, BadCopyFieldTest, DeleteReplicaTest, TestFastWriter, TestPerFieldSimilarity, CoreMergeIndexesAdminHandlerTest, TestDefaultSearchFieldResource, TestSolrXMLSerializer, DistributedTermsComponentTest, CursorMarkTest, DocumentBuilderTest, TestRandomMergePolicy, TestManagedSchemaFieldResource, BlockCacheTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSweetSpotSimilarityFactory, ShardSplitTest, TestFunctionQuery, SolrCmdDistributorTest, CollectionsAPIAsyncDistributedZkTest, CurrencyFieldOpenExchangeTest, TestIndexSearcher, CSVRequestHandlerTest, HttpPartitionTest, SolrInfoMBeanTest, CustomCollectionTest, BasicZkTest, TestTrie, TestCopyFieldCollectionResource, BinaryUpdateRequestHandlerTest, SampleTest, DistributedSuggestComponentTest, TestWriterPerf, TestMaxScoreQueryParser, TestAtomicUpdateErrorCases, ScriptEngineTest, AlternateDirectoryTest, TestXIncludeConfig, DistributedQueryElevationComponentTest, TestFieldTypeResource, TermVectorComponentDistributedTest, PeerSyncTest, TestRandomDVFaceting, TestNoOpRegenerator, BlockDirectoryTest, TestSystemIdResolver, TestSort, DistributedQueryComponentCustomSortTest, TestRTGBase, PingRequestHandlerTest, TestAnalyzedSuggestions, TestBM25SimilarityFactory, DateMathParserTest, TestIBSimilarityFactory, TestDynamicFieldResource, SuggesterWFSTTest, DistanceFunctionTest, UUIDFieldTest, PathHierarchyTokenizerFactoryTest, TestDocumentBuilder, TestGroupingSearch, TestSolrQueryParserDefaultOperatorResource, ConvertedLegacyTest, SignatureUpdateProcessorFactoryTest, TestSolrQueryParserResource, JsonLoaderTest, TestZkChroot, PrimUtilsTest, SpellCheckComponentTest, TermVectorComponentTest, TestBinaryResponseWriter, PrimitiveFieldTypeTest, TestDistributedGrouping, TestRecoveryHdfs, UnloadDistributedZkTest, TestSimpleQParserPlugin, TestNonNRTOpen, TestHighlightDedupGrouping, TestFuzzyAnalyzedSuggestions, ClusterStateTest, DocValuesMissingTest, TestSchemaResource, UniqFieldsUpdateProcessorFactoryTest, TestDFRSimilarityFactory, TestCSVLoader, MergeStrategyTest, BasicDistributedZk2Test, SimplePostToolTest, LukeRequestHandlerTest, ShardRoutingCustomTest, SortByFunctionTest, HdfsRecoveryZkTest, TestCSVResponseWriter, TestPseudoReturnFields, OpenExchangeRatesOrgProviderTest, TestFaceting, IndexSchemaRuntimeFieldTest, TestReRankQParserPlugin, OverseerStatusTest, ShardRoutingTest, AnalyticsQueryTest, TestReplicationHandler, FieldMutatingUpdateProcessorTest, HardAutoCommitTest, TestIntervalFaceting, TestExceedMaxTermLength, WordBreakSolrSpellCheckerTest, HdfsBasicDistributedZk2Test, IndexBasedSpellCheckerTest, ChaosMonkeySafeLeaderTest, TestNonDefinedSimilarityFactory, TestClassNameShortening, RAMDirectoryFactoryTest, TestCollectionAPI, TestRemoteStreaming, TestMiniSolrCloudCluster, TestAddFieldRealTimeGet, TestSolrDeletionPolicy2, TestShortCircuitedRequests, TestRealTimeGet, TestUtils, ResponseHeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkCLITest, TestDistributedSearch, AutoCommitTest, TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, TestMultiCoreConfBootstrap, TestReload, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, StatsComponentTest, QueryElevationComponentTest, BadIndexSchemaTest, TestFiltering, BasicFunctionalityTest, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, OverseerCollectionProcessorTest, HighlighterTest, ShowFileRequestHandlerTest, CurrencyFieldXmlFileTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, SolrRequestParserTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, SpatialFilterTest, PolyFieldTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestUpdate, QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, DebugComponentTest, CacheHeaderTest, DisMaxRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions, TermsComponentTest, RequestHandlersTest, TestValueSourceCache, TestIndexingPerformance, MoreLikeThisHandlerTest, TestSolrQueryParser, FastVectorHighlighterTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, QueryParsingTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestLFUCache, TestQuerySenderListener, UpdateParamsTest, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestMergePolicyConfig, MultiTermTest, TestDocSet, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCharFilters, TestNumberUtils, TestLMDirichletSimilarityFactory, TestDefaultSimilarityFactory, TestJmxMonitoredMap, TimeZoneUtilsTest, TestFastOutputStream, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, TestSuggestSpellingConverter, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, FileUtilsTest, CursorPagingTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestCollationKeyRangeQueries, AliasIntegrationTest, AssignTest, AsyncMigrateRouteKeyTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, MigrateRouteKeyTest, MultiThreadedOCPTest] [junit4] Completed in 122.73s, 1 test, 1 failure <<< FAILURES! [...truncated 221 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:467: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:447: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:45: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:496: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1299: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:923: There were test failures: 411 suites, 1705 tests, 1 failure, 43 ignored (22 assumptions) Total time: 166 minutes 13 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.7.0_65 -client -XX:+UseSerialGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org