You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/03/27 09:22:18 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_60-ea-b10) -
Build # 3899 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3899/
Java: 32bit/jdk1.7.0_60-ea-b10 -client -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.impl.CloudSolrServerTest.testDistribSearch
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([C3C92C4C0BD03104:422FA2547C8F5138]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:256)
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:1617)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
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:70)
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:359)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
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 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:70)
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:359)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 20656 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
[junit4] 2> 99546 T346 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 99547 T346 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /q_ea/e
[junit4] 2> 99557 T346 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test?
[junit4] 2> 99563 T346 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-CloudSolrServerTest-1395908370077
[junit4] 2> 99586 T346 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 99600 T347 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 99816 T346 oasc.ZkTestServer.run start zk server on port:55580
[junit4] 2> 99983 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 100017 T348 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit4] 2> 102155 T353 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12a17c5 name:ZooKeeperConnection Watcher:127.0.0.1:55580 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 102155 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 102159 T346 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 102249 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 102253 T355 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c9f75a name:ZooKeeperConnection Watcher:127.0.0.1:55580/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 102253 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 102267 T346 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 102276 T346 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 102284 T346 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 102288 T346 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 102295 T346 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 102296 T346 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 102312 T346 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 102312 T346 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 102319 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml because it doesn't exist
[junit4] 2> 102319 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\stopwords.txt because it doesn't exist
[junit4] 2> 102319 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\protwords.txt because it doesn't exist
[junit4] 2> 102319 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\currency.xml because it doesn't exist
[junit4] 2> 102319 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\enumsConfig.xml because it doesn't exist
[junit4] 2> 102321 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\open-exchange-rates.json because it doesn't exist
[junit4] 2> 102321 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4] 2> 102321 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\old_synonyms.txt because it doesn't exist
[junit4] 2> 102321 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\synonyms.txt because it doesn't exist
[junit4] 2> 102351 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 102355 T357 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35b36e name:ZooKeeperConnection Watcher:127.0.0.1:55580/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 102355 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 102954 T346 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 102970 T346 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 103001 T346 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:55591
[junit4] 2> 103006 T346 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 103006 T346 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 103006 T346 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1395908372872
[junit4] 2> 103006 T346 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1395908372872\'
[junit4] 2> 103060 T346 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1395908372872\solr.xml
[junit4] 2> 103176 T346 oasc.CoreContainer.<init> New CoreContainer 19661446
[junit4] 2> 103176 T346 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1395908372872\]
[junit4] 2> 103178 T346 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 103179 T346 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 103181 T346 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 103181 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 103182 T346 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 103182 T346 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 103182 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 103183 T346 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 103183 T346 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 103191 T346 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 103191 T346 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 103192 T346 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 103192 T346 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55580/solr
[junit4] 2> 103235 T346 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 103237 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 103244 T368 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@660c0d name:ZooKeeperConnection Watcher:127.0.0.1:55580 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 103245 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 103266 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 103269 T370 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f43ca9 name:ZooKeeperConnection Watcher:127.0.0.1:55580/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 103269 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 103293 T346 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 103313 T346 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 103336 T346 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 103347 T346 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 103358 T346 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 103387 T346 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 103391 T346 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55591_q_ea%2Fe
[junit4] 2> 103400 T346 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55591_q_ea%2Fe
[junit4] 2> 103410 T346 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 103416 T346 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 103442 T346 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:55591_q_ea%2Fe
[junit4] 2> 103444 T346 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 103449 T346 oasc.Overseer.start Overseer (id=91482250957488132-127.0.0.1:55591_q_ea%2Fe-n_0000000000) starting
[junit4] 2> 103484 T346 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 103530 T372 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 103533 T346 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 103538 T372 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 103541 T346 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 103545 T346 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 103571 T371 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 103596 T373 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 103596 T373 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 103604 T373 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 103604 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 103614 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 103616 T371 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:55591/q_ea/e",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55591_q_ea%2Fe",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 103623 T371 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 103659 T371 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 103671 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 103681 T370 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> 104792 T373 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 104792 T373 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 104794 T373 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 104794 T373 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 104796 T373 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 104797 T373 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1395908372872\collection1\'
[junit4] 2> 104915 T373 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 105006 T373 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 105014 T373 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 105030 T373 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 105781 T373 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 105784 T373 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 105786 T373 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 105786 T373 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 105789 T373 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 105789 T373 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 105789 T373 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1395908372872\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/control/data\
[junit4] 2> 105789 T373 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 105790 T373 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/control/data\
[junit4] 2> 105790 T373 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/control/data\index/
[junit4] 2> 105790 T373 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\control\data\index' doesn't exist. Creating new index...
[junit4] 2> 105790 T373 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/control/data\index
[junit4] 2> 105796 T373 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@ec70a1 lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\control\data\index),segFN=segments_1,generation=1}
[junit4] 2> 105797 T373 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 105799 T373 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 105800 T373 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 105800 T373 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 105800 T373 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 105800 T373 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 105801 T373 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 105801 T373 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 105801 T373 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 105815 T373 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 105832 T373 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 105832 T373 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 105834 T373 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@ec70a1 lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\control\data\index),segFN=segments_1,generation=1}
[junit4] 2> 105834 T373 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 105835 T373 oass.SolrIndexSearcher.<init> Opening Searcher@1653852[collection1] main
[junit4] 2> 105835 T373 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 105838 T373 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 105838 T373 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 105850 T373 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 105850 T373 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 105850 T373 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 105852 T373 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 105852 T373 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 105852 T373 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 105852 T373 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 105856 T374 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1653852[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 105856 T373 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 105862 T377 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:55591/q_ea/e collection:control_collection shard:shard1
[junit4] 2> 105864 T346 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 105864 T346 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 105894 T377 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 105895 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 105899 T379 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2942b3 name:ZooKeeperConnection Watcher:127.0.0.1:55580/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 105899 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 105903 T346 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 105915 T346 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 105917 T377 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 105924 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 105926 T377 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 105926 T377 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 105933 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> ASYNC NEW_CORE C182 name=collection1 org.apache.solr.core.SolrCore@cd3bc4 url=https://127.0.0.1:55591/q_ea/e/collection1 node=127.0.0.1:55591_q_ea%2Fe C182_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:55591/q_ea/e, core=collection1, node_name=127.0.0.1:55591_q_ea%2Fe}
[junit4] 2> 105935 T377 C182 P55591 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:55591/q_ea/e/collection1/
[junit4] 2> 105936 T377 C182 P55591 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 105936 T377 C182 P55591 oasc.SyncStrategy.syncToMe https://127.0.0.1:55591/q_ea/e/collection1/ has no replicas
[junit4] 2> 105936 T377 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:55591/q_ea/e/collection1/ shard1
[junit4] 2> 105942 T377 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 105955 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 105959 T379 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> 105960 T370 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> 105977 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 105987 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 106114 T379 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> 106114 T370 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> 106156 T377 oasc.ZkController.register We are https://127.0.0.1:55591/q_ea/e/collection1/ and leader is https://127.0.0.1:55591/q_ea/e/collection1/
[junit4] 2> 106157 T377 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:55591/q_ea/e
[junit4] 2> 106157 T377 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 106157 T377 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 106157 T377 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 106160 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 106160 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 106160 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 106162 T377 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 106165 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 106166 T371 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:55591/q_ea/e",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55591_q_ea%2Fe",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 106175 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 106301 T370 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> 106301 T379 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> 106385 T346 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 106386 T346 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 106399 T346 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 106401 T346 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:55603
[junit4] 2> 106406 T346 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 106406 T346 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 106406 T346 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1395908376427
[junit4] 2> 106407 T346 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1395908376427\'
[junit4] 2> 106459 T346 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1395908376427\solr.xml
[junit4] 2> 106581 T346 oasc.CoreContainer.<init> New CoreContainer 24350262
[junit4] 2> 106581 T346 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1395908376427\]
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 106582 T346 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 106585 T346 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 106591 T346 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 106591 T346 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 106591 T346 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 106591 T346 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55580/solr
[junit4] 2> 106591 T346 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 106597 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 106600 T390 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@229ed9 name:ZooKeeperConnection Watcher:127.0.0.1:55580 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 106600 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 106607 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 106611 T348 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x145029f5f930006, 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:744)
[junit4] 2>
[junit4] 2> 106617 T392 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c5286 name:ZooKeeperConnection Watcher:127.0.0.1:55580/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 106617 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 106634 T346 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 107827 T346 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55603_q_ea%2Fe
[junit4] 2> 107832 T346 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55603_q_ea%2Fe
[junit4] 2> 107841 T379 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 107841 T370 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 107841 T392 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 107870 T393 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 107870 T393 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 107872 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 107872 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 107872 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 107872 T393 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 107877 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 107879 T371 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:55603/q_ea/e",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55603_q_ea%2Fe",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 107879 T371 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 107879 T371 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 107887 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 108015 T392 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> 108015 T379 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> 108017 T370 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> 109059 T393 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 109059 T393 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 109063 T393 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 109063 T393 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 109068 T393 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 109068 T393 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1395908376427\collection1\'
[junit4] 2> 109162 T393 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 109249 T393 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 109254 T393 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 109271 T393 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 110004 T393 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 110008 T393 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 110010 T393 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 110011 T393 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 110011 T393 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 110011 T393 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 110015 T393 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1395908376427\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty1\
[junit4] 2> 110015 T393 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 110016 T393 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty1\
[junit4] 2> 110016 T393 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty1\index/
[junit4] 2> 110016 T393 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty1\index' doesn't exist. Creating new index...
[junit4] 2> 110017 T393 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty1\index
[junit4] 2> 110025 T393 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@179e3c1 lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty1\index),segFN=segments_1,generation=1}
[junit4] 2> 110025 T393 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 110035 T393 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 110035 T393 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 110035 T393 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 110035 T393 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 110037 T393 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 110038 T393 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 110038 T393 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 110038 T393 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 110050 T393 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 110059 T393 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 110059 T393 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 110062 T393 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@179e3c1 lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty1\index),segFN=segments_1,generation=1}
[junit4] 2> 110062 T393 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 110064 T393 oass.SolrIndexSearcher.<init> Opening Searcher@394f2c[collection1] main
[junit4] 2> 110064 T393 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 110068 T393 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 110068 T393 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 110070 T393 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 110070 T393 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 110070 T393 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 110071 T393 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 110072 T393 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 110072 T393 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 110072 T393 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 110079 T393 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 110079 T394 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@394f2c[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 110081 T397 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:55603/q_ea/e collection:collection1 shard:shard2
[junit4] 2> 110082 T346 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 110082 T346 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 110083 T397 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 110106 T397 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 110113 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110113 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110113 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110115 T397 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 110115 T397 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C183 name=collection1 org.apache.solr.core.SolrCore@12c6226 url=https://127.0.0.1:55603/q_ea/e/collection1 node=127.0.0.1:55603_q_ea%2Fe C183_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:55603/q_ea/e, core=collection1, node_name=127.0.0.1:55603_q_ea%2Fe}
[junit4] 2> 110115 T397 C183 P55603 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:55603/q_ea/e/collection1/
[junit4] 2> 110115 T397 C183 P55603 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 110115 T397 C183 P55603 oasc.SyncStrategy.syncToMe https://127.0.0.1:55603/q_ea/e/collection1/ has no replicas
[junit4] 2> 110115 T397 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:55603/q_ea/e/collection1/ shard2
[junit4] 2> 110118 T397 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 110122 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 110135 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110140 T370 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> 110140 T379 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> 110142 T392 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> 110158 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 110166 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110293 T392 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> 110294 T370 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> 110294 T379 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> 110330 T397 oasc.ZkController.register We are https://127.0.0.1:55603/q_ea/e/collection1/ and leader is https://127.0.0.1:55603/q_ea/e/collection1/
[junit4] 2> 110330 T397 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:55603/q_ea/e
[junit4] 2> 110330 T397 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 110330 T397 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 110330 T397 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 110332 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110332 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110332 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110335 T397 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 110339 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 110341 T371 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:55603/q_ea/e",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55603_q_ea%2Fe",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 110349 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 110472 T392 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> 110475 T379 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> 110478 T370 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> 110581 T346 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 110582 T346 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 110598 T346 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 110601 T346 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:55612
[junit4] 2> 110605 T346 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 110605 T346 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 110606 T346 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1395908380602
[junit4] 2> 110606 T346 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1395908380602\'
[junit4] 2> 110656 T346 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1395908380602\solr.xml
[junit4] 2> 110781 T346 oasc.CoreContainer.<init> New CoreContainer 30643732
[junit4] 2> 110782 T346 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1395908380602\]
[junit4] 2> 110784 T346 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 110784 T346 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 110785 T346 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 110785 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 110785 T346 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 110785 T346 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 110786 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 110786 T346 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 110786 T346 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 110794 T346 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 110794 T346 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 110794 T346 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 110795 T346 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55580/solr
[junit4] 2> 110795 T346 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 110799 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 110802 T408 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1097d73 name:ZooKeeperConnection Watcher:127.0.0.1:55580 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 110803 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 110809 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 110811 T410 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4659a0 name:ZooKeeperConnection Watcher:127.0.0.1:55580/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 110811 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 110836 T346 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 112027 T346 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55612_q_ea%2Fe
[junit4] 2> 112030 T346 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55612_q_ea%2Fe
[junit4] 2> 112038 T370 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 112038 T379 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 112039 T410 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 112046 T392 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 112064 T411 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 112066 T411 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 112068 T411 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 112068 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 112068 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 112069 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 112073 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 112074 T371 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:55612/q_ea/e",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55612_q_ea%2Fe",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 112075 T371 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 112075 T371 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 112082 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 112205 T379 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> 112205 T410 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> 112206 T370 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> 112206 T392 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> 113254 T411 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 113254 T411 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 113256 T411 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 113256 T411 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 113262 T411 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 113262 T411 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1395908380602\collection1\'
[junit4] 2> 113368 T411 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 113481 T411 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 113485 T411 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 113506 T411 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 114294 T411 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 114297 T411 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 114300 T411 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 114300 T411 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 114302 T411 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 114302 T411 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 114302 T411 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1395908380602\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty2\
[junit4] 2> 114302 T411 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 114303 T411 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty2\
[junit4] 2> 114303 T411 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty2\index/
[junit4] 2> 114303 T411 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty2\index' doesn't exist. Creating new index...
[junit4] 2> 114303 T411 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty2\index
[junit4] 2> 114318 T411 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@ccd638 lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty2\index),segFN=segments_1,generation=1}
[junit4] 2> 114318 T411 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 114323 T411 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 114324 T411 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 114324 T411 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 114324 T411 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 114324 T411 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 114325 T411 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 114325 T411 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 114325 T411 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 114338 T411 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 114346 T411 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 114346 T411 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 114348 T411 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@ccd638 lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty2\index),segFN=segments_1,generation=1}
[junit4] 2> 114348 T411 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 114348 T411 oass.SolrIndexSearcher.<init> Opening Searcher@10d3fe2[collection1] main
[junit4] 2> 114348 T411 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 114352 T411 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 114352 T411 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 114353 T411 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 114353 T411 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 114353 T411 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 114354 T411 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 114354 T411 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 114354 T411 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 114354 T411 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 114357 T412 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10d3fe2[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 114357 T411 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 114360 T415 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:55612/q_ea/e collection:collection1 shard:shard1
[junit4] 2> 114361 T346 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 114361 T346 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 114363 T415 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 114378 T415 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 114384 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114384 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114384 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114386 T415 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 114386 T415 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C184 name=collection1 org.apache.solr.core.SolrCore@19bdc8a url=https://127.0.0.1:55612/q_ea/e/collection1 node=127.0.0.1:55612_q_ea%2Fe C184_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:55612/q_ea/e, core=collection1, node_name=127.0.0.1:55612_q_ea%2Fe}
[junit4] 2> 114386 T415 C184 P55612 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:55612/q_ea/e/collection1/
[junit4] 2> 114386 T415 C184 P55612 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 114386 T415 C184 P55612 oasc.SyncStrategy.syncToMe https://127.0.0.1:55612/q_ea/e/collection1/ has no replicas
[junit4] 2> 114386 T415 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:55612/q_ea/e/collection1/ shard1
[junit4] 2> 114391 T415 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 114395 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 114408 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114412 T370 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> 114413 T410 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> 114413 T379 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> 114414 T392 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> 114428 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 114436 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114561 T392 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> 114562 T370 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> 114562 T410 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> 114564 T379 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> 114603 T415 oasc.ZkController.register We are https://127.0.0.1:55612/q_ea/e/collection1/ and leader is https://127.0.0.1:55612/q_ea/e/collection1/
[junit4] 2> 114603 T415 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:55612/q_ea/e
[junit4] 2> 114603 T415 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 114603 T415 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 114603 T415 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 114606 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114606 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114606 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114609 T415 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 114613 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 114616 T371 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:55612/q_ea/e",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55612_q_ea%2Fe",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 114623 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 114739 T392 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> 114742 T370 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> 114742 T379 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> 114743 T410 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> 114861 T346 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 114862 T346 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 114881 T346 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 114883 T346 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:55621
[junit4] 2> 114888 T346 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 114888 T346 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 114889 T346 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1395908384886
[junit4] 2> 114889 T346 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1395908384886\'
[junit4] 2> 114937 T346 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1395908384886\solr.xml
[junit4] 2> 115046 T346 oasc.CoreContainer.<init> New CoreContainer 13192403
[junit4] 2> 115047 T346 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1395908384886\]
[junit4] 2> 115048 T346 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 115049 T346 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 115049 T346 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 115049 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 115050 T346 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 115050 T346 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 115051 T346 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 115051 T346 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 115051 T346 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 115059 T346 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 115059 T346 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 115060 T346 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 115060 T346 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55580/solr
[junit4] 2> 115060 T346 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 115063 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 115066 T426 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8062f9 name:ZooKeeperConnection Watcher:127.0.0.1:55580 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 115067 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 115070 T348 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x145029f5f93000a, 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:744)
[junit4] 2>
[junit4] 2> 115072 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 115074 T428 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1085227 name:ZooKeeperConnection Watcher:127.0.0.1:55580/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 115075 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 115097 T346 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 116172 T346 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55621_q_ea%2Fe
[junit4] 2> 116177 T346 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55621_q_ea%2Fe
[junit4] 2> 116191 T370 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 116192 T410 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 116193 T379 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 116194 T392 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 116194 T428 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 116230 T429 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 116230 T429 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 116234 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 116234 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 116236 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 116234 T429 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 116243 T371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 116245 T371 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:55621/q_ea/e",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55621_q_ea%2Fe",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 116246 T371 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 116246 T371 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 116257 T370 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 116376 T392 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 116377 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 116378 T370 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 116379 T410 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 116379 T379 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 117306 T429 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 117306 T429 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 117310 T429 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 117310 T429 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 117315 T429 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 117315 T429 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1395908384886\collection1\'
[junit4] 2> 117419 T429 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 117495 T429 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 117498 T429 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 117514 T429 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 118178 T429 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 118181 T429 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 118188 T429 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 118190 T429 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 118191 T429 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 118191 T429 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 118191 T429 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1395908384886\collection1\, dataDir=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty3\
[junit4] 2> 118192 T429 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 118192 T429 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty3\
[junit4] 2> 118192 T429 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty3\index/
[junit4] 2> 118192 T429 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty3\index' doesn't exist. Creating new index...
[junit4] 2> 118193 T429 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075/jetty3\index
[junit4] 2> 118197 T429 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@905da lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty3\index),segFN=segments_1,generation=1}
[junit4] 2> 118197 T429 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 118200 T429 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 118201 T429 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 118201 T429 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 118201 T429 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 118201 T429 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 118202 T429 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 118202 T429 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 118202 T429 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 118214 T429 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 118222 T429 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 118222 T429 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 118225 T429 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=BaseDirectoryWrapper(RAMDirectory@905da lockFactory=NativeFSLockFactory@.\org.apache.solr.client.solrj.impl.CloudSolrServerTest-1395908370075\jetty3\index),segFN=segments_1,generation=1}
[junit4] 2> 118225 T429 oasc.SolrDeletionPolicy.u
[...truncated too long message...]
\solrtest-CloudSolrServerTest-1395908399695
[junit4] 2> 129184 T346 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 129186 T449 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 129282 T346 oasc.ZkTestServer.run start zk server on port:55687
[junit4] 2> 129286 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 129298 T455 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bc5b2e name:ZooKeeperConnection Watcher:127.0.0.1:55687 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 129299 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 129300 T346 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 129314 T450 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x145029fd2560000, 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:744)
[junit4] 2>
[junit4] 2> 129319 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 129325 T457 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12c676e name:ZooKeeperConnection Watcher:127.0.0.1:55687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 129326 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 129327 T346 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 129340 T346 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 129351 T346 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 129359 T346 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 129367 T346 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 129367 T346 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 129385 T346 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 129387 T346 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 129408 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml because it doesn't exist
[junit4] 2> 129409 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\stopwords.txt because it doesn't exist
[junit4] 2> 129409 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\protwords.txt because it doesn't exist
[junit4] 2> 129410 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\currency.xml because it doesn't exist
[junit4] 2> 129410 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\enumsConfig.xml because it doesn't exist
[junit4] 2> 129411 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\open-exchange-rates.json because it doesn't exist
[junit4] 2> 129411 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\mapping-ISOLatin1Accent.txt because it doesn't exist
[junit4] 2> 129411 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\old_synonyms.txt because it doesn't exist
[junit4] 2> 129411 T346 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\synonyms.txt because it doesn't exist
[junit4] 2> 129416 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 129416 T450 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x145029fd2560001, 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:744)
[junit4] 2>
[junit4] 2> 129420 T459 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1022ddf name:ZooKeeperConnection Watcher:127.0.0.1:55687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 129421 T346 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 129424 T450 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x145029fd2560002, 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:744)
[junit4] 2>
[junit4] 2> 129431 T346 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 129432 T460 oaz.ClientCnxn$SendThread.run WARN Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect java.net.BindException: Cannot assign requested address: no further information
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2>
[junit4] 2> 130587 T346 oas.SolrTestCaseJ4.tearDown ###Ending testShutdown
[junit4] 2> 130590 T346 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:55687 55687
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 132757 T346 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=115), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=115)), sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ar_TN, timezone=America/Yellowknife
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_60-ea (32-bit)/cpus=2,threads=1,free=43859928,total=197844992
[junit4] 2> NOTE: All tests run in this JVM: [TestRetryUtil, MultiCoreEmbeddedTest, DocumentAnalysisResponseTest, LargeVolumeEmbeddedTest, NamedListTest, TestXMLEscaping, NoOpResponseParserTest, AnlysisResponseBaseTest, TestEmbeddedSolrServer, TestBatchUpdate, TestNamedListCodec, TestSolrProperties, SolrPingTest, TestJavaBinCodec, ClientUtilsTest, TestCoreAdmin, TermsResponseTest, SolrExampleJettyTest, QueryResponseTest, TestUpdateRequestCodec, TestLBHttpSolrServer, FacetFieldTest, LargeVolumeJettyTest, ModifiableSolrParamsTest, CloudSolrServerTest]
[junit4] Completed in 33.31s, 2 tests, 1 failure <<< FAILURES!
[...truncated 78 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:202: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:490: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1275: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:907: There were test failures: 49 suites, 284 tests, 1 failure
Total time: 99 minutes 56 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_60-ea-b10 -client -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_51) - Build #
3901 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3901/
Java: 32bit/jdk1.7.0_51 -client -XX:+UseG1GC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.common.util.ContentStreamTest
Error Message:
!!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-ContentStreamTest-1395962448800 FAILED !!!!!
Stack Trace:
java.lang.AssertionError: !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-ContentStreamTest-1395962448800 FAILED !!!!!
at __randomizedtesting.SeedInfo.seed([32A8B44CEE4187EC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:217)
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:1617)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:789)
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 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:70)
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:359)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 11572 lines...]
[junit4] Suite: org.apache.solr.common.util.ContentStreamTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-ContentStreamTest-1395962448800
[junit4] 2> 68449 T207 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 68452 T207 oas.SolrTestCaseJ4.setUp ###Starting testURLStream
[junit4] 2> 68453 T207 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 68453 T207 oasc.SolrResourceLoader.locateSolrHome solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 68453 T207 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'solr/'
[junit4] 2> 68554 T207 oas.SolrTestCaseJ4.tearDown ###Ending testURLStream
[junit4] 2> 68565 T207 oas.SolrTestCaseJ4.setUp ###Starting testFileStream
[junit4] 2> 68566 T207 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 68566 T207 oasc.SolrResourceLoader.locateSolrHome solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 68567 T207 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'solr/'
[junit4] 2> 68615 T207 oas.SolrTestCaseJ4.tearDown ###Ending testFileStream
[junit4] 2> 68622 T207 oas.SolrTestCaseJ4.setUp ###Starting testStringStream
[junit4] 2> 68623 T207 oas.SolrTestCaseJ4.tearDown ###Ending testStringStream
[junit4] 2> 68624 T207 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-ContentStreamTest-1395962448800\README FAILED !!!!!
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=271), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=271)), sim=DefaultSimilarity, locale=en_IE, timezone=America/Vancouver
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_51 (32-bit)/cpus=2,threads=1,free=17354576,total=84934656
[junit4] 2> NOTE: All tests run in this JVM: [TestXMLEscaping, JettyWebappTest, CloudSolrServerTest, LargeVolumeJettyTest, TestHash, SolrExampleStreamingTest, IteratorChainTest, TestFastInputStream, ContentStreamTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ContentStreamTest -Dtests.seed=32A8B44CEE4187EC -Dtests.slow=true -Dtests.locale=en_IE -Dtests.timezone=America/Vancouver -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s | ContentStreamTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: !!!! WARNING: best effort to remove C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-ContentStreamTest-1395962448800 FAILED !!!!!
[junit4] > at __randomizedtesting.SeedInfo.seed([32A8B44CEE4187EC]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:217)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] Completed in 0.41s, 3 tests, 1 failure <<< FAILURES!
[...truncated 126 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:202: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:490: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1275: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:907: There were test failures: 49 suites, 284 tests, 1 suite-level error
Total time: 111 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_51 -client -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0) - Build # 3900
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3900/
Java: 32bit/jdk1.8.0 -client -XX:+UseParallelGC
2 tests failed.
REGRESSION: org.apache.lucene.search.TestControlledRealTimeReopenThread.testCRTReopen
Error Message:
waited too long for generation 1204287
Stack Trace:
java.lang.AssertionError: waited too long for generation 1204287
at __randomizedtesting.SeedInfo.seed([801B7377FFF2F131:D23BEE432B354948]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.lucene.search.TestControlledRealTimeReopenThread.testCRTReopen(TestControlledRealTimeReopenThread.java:520)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
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:70)
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:359)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
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 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:70)
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:359)
at java.lang.Thread.run(Thread.java:744)
FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestControlledRealTimeReopenThread
Error Message:
1 thread leaked from SUITE scope at org.apache.lucene.search.TestControlledRealTimeReopenThread: 1) Thread[id=2963, name=Thread-2082, state=TIMED_WAITING, group=TGRP-TestControlledRealTimeReopenThread] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.apache.lucene.search.ControlledRealTimeReopenThread.run(ControlledRealTimeReopenThread.java:223)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.lucene.search.TestControlledRealTimeReopenThread:
1) Thread[id=2963, name=Thread-2082, state=TIMED_WAITING, group=TGRP-TestControlledRealTimeReopenThread]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.apache.lucene.search.ControlledRealTimeReopenThread.run(ControlledRealTimeReopenThread.java:223)
at __randomizedtesting.SeedInfo.seed([801B7377FFF2F131]:0)
Build Log:
[...truncated 1490 lines...]
[junit4] Suite: org.apache.lucene.search.TestControlledRealTimeReopenThread
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestControlledRealTimeReopenThread -Dtests.method=testCRTReopen -Dtests.seed=801B7377FFF2F131 -Dtests.slow=true -Dtests.locale=fi_FI -Dtests.timezone=Asia/Magadan -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 1209s | TestControlledRealTimeReopenThread.testCRTReopen <<<
[junit4] > Throwable #1: java.lang.AssertionError: waited too long for generation 1204287
[junit4] > at __randomizedtesting.SeedInfo.seed([801B7377FFF2F131:D23BEE432B354948]:0)
[junit4] > at org.apache.lucene.search.TestControlledRealTimeReopenThread.testCRTReopen(TestControlledRealTimeReopenThread.java:520)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=DefaultSimilarity, locale=fi_FI, timezone=Asia/Magadan
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0 (32-bit)/cpus=2,threads=1,free=131651536,total=251658240
[junit4] 2> NOTE: All tests run in this JVM: [Nested1, TestTryDelete, TestDocumentsWriterDeleteQueue, TestFileSwitchDirectory, TestBroadWord, TestTopScoreDocCollector, TestUniqueTermCount, TestWorstCaseTestBehavior, TestMultiTermConstantScore, TestCharsRef, TestOfflineSorter, TestBinaryDocument, Nested3, Nested1, Nested2, TestPrefixQuery, TestNearSpansOrdered, TestDocument, TestDocValuesScoring, TestFieldCache, TestDoubleBarrelLRUCache, TestWildcardRandom, TestDocumentWriter, TestFilterDirectory, TestMultiLevelSkipList, Test2BPostings, TestIntroSorter, TestTwoPhaseCommitTool, TestFieldsReader, TestTermsEnum, TestHugeRamFile, TestIndexWriterCommit, TestParallelTermEnum, TestMergeSchedulerExternal, TestBooleanScorer, TestDeterminizeLexicon, TestShardSearching, TestPerFieldDocValuesFormat, TestFlex, Nested1, TestAssertions, Nested1, TestNeverDelete, ThrowInUncaught, TestPerFieldPostingsFormat, NestedSetupChain, NestedTeardownChain, TestCrash, TestNamedSPILoader, TestSearchAfter, TestIndexWriterWithThreads, TestTieredMergePolicy, TestAutomatonQuery, TestLucene42DocValuesFormat, TestDateSort, TestTermVectorsFormat, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, Nested, TestExternalCodecs, TestToken, TestIndexWriterForceMerge, TestBasicOperations, TestSimpleAttributeImpl, TestBagOfPostings, TestBooleanOr, TestLucene40DocValuesFormat, TestPForDeltaDocIdSet, TestByteSlices, TestSpecialOperations, TestPayloadExplanations, TestTimSorter, TestSegmentTermEnum, TestNumericRangeQuery32, TestRollingUpdates, TestPhrasePrefixQuery, TestDirectoryReaderReopen, TestPagedBytes, TestLookaheadTokenFilter, TestIntBlockPool, TestStressIndexing2, TestDocValuesIndexing, TestIndexReaderClose, TestRamUsageEstimatorOnWildAnimals, TestPerSegmentDeletes, TestDeterminism, TestDirectoryReader, TestNGramPhraseQuery, TestSmallFloat, TestInPlaceMergeSorter, TestTermScorer, TestSpanExplanations, TestLazyProxSkipping, TestEarlyTermination, TestFastCompressionMode, TestFieldCacheRewriteMethod, TestBlockPostingsFormat, Nested1, TestBooleanQuery, TestScorerPerf, TestMockDirectoryWrapper, TestSimilarityProvider, TestByteBlockPool, TestIndexableField, TestDemo, TestTermRangeQuery, TestSortRandom, TestTotalHitCountCollector, TestDocTermOrdsRangeFilter, TestDoc, TestNRTReaderWithThreads, TestMultiMMap, TestSimpleExplanations, TestDateFilter, TestIntsRef, TestMixedDocValuesUpdates, TestPrefixRandom, TestFieldInfos, TestPriorityQueue, TestMultiDocValues, TestSloppyMath, TestSpanExplanationsOfNonMatches, TestUnicodeUtil, TestTransactions, TestDocTermOrds, TestNumericTokenStream, TestFlushByRamOrCountsPolicy, TestIndexWriterOutOfFileDescriptors, TestBooleanMinShouldMatch, TestScoreCachingWrappingScorer, TestNumericRangeQuery64, TestTermsEnum2, InBeforeClass, InAfterClass, InTestMethod, NonStringProperties, TestStoredFieldsFormat, TestSimpleSearchEquivalence, TestIndexWriterDelete, TestBytesRefHash, TestAttributeSource, TestLockFactory, TestRamUsageEstimator, TestMatchAllDocsQuery, TestDuelingCodecs, TestIndexWriterLockRelease, Test4GBStoredFields, TestBytesRef, TestForUtil, TestMockCharFilter, TestSpanSearchEquivalence, TestEliasFanoDocIdSet, TestIndexWriterOnJRECrash, TestVirtualMethod, TestEliasFanoSequence, Test2BPositions, TestMinShouldMatch2, TestSearch, Test2BDocs, TestMultiThreadTermVectors, TestIndexWriter, TestSegmentReader, TestCloseableThreadLocal, TestPayloadTermQuery, TestIndexFileDeleter, TestMixedCodecs, TestIndexWriterMergePolicy, TestByteArrayDataInput, TestReaderClosed, TestWeakIdentityMap, TestSpans, TestUTF32ToUTF8, TestTermVectors, TestIndexCommit, TestIndexWriterUnicode, TestBlockPostingsFormat3, Test2BTerms, TestSpansAdvanced2, TestAutomatonQueryUnicode, TestWildcard, TestLucene40TermVectorsFormat, TestNot, TestReusableStringReader, TestFieldMaskingSpanQuery, TestLock, MultiCollectorTest, TestPositiveScoresOnlyCollector, TestOmitTf, TestConsistentFieldNumbers, TestAtomicUpdate, TestFilterAtomicReader, TestStressAdvance, Nested, TestRAMDirectory, TestSearcherManager, Nested, Nested, TestTermdocPerf, TestCompressingTermVectorsFormat, TestIndexWriterConfig, TestDocValuesFormat, TestIndexWriterReader, TestIndexWriterMerging, TestFSTs, TestBytesStore, TestPackedInts, TestBackwardsCompatibility, TestGraphTokenizers, TestAddIndexes, TestIndexWriterExceptions, TestDeletionPolicy, TestBasics, TestSloppyPhraseQuery, TestNRTThreads, TestBoolean2, TestPersistentSnapshotDeletionPolicy, TestNorms, TestTimeLimitingCollector, TestSearchWithThreads, TestConcurrentMergeScheduler, TestHighCompressionMode, TestOpenBitSet, TestDocumentsWriterStallControl, TestTopDocsMerge, TestCompoundFile, TestLongPostings, TestStressIndexing, TestMinimize, TestBlockPostingsFormat2, TestDocTermOrdsRewriteMethod, TestSnapshotDeletionPolicy, TestLucene40PostingsReader, TestArrayUtil, TestLevenshteinAutomata, TestBufferedIndexInput, TestCompressingStoredFieldsFormat, TestSegmentMerger, TestPhraseQuery, TestCollectionUtil, TestBagOfPositions, TestOmitNorms, TestFixedBitSet, TestComplexExplanations, TestMockAnalyzer, TestRegexpRandom2, TestNRTCachingDirectory, TestFastDecompressionMode, TestParallelCompositeReader, TestIndexWriterNRTIsCurrent, TestSegmentTermDocs, TestPerFieldPostingsFormat2, TestSort, TestBitVector, TestReuseDocsEnum, TestFieldCacheSanityChecker, TestCustomSearcherSort, TestPostingsFormat, TestSimilarityBase, TestNumericUtils, TestIndexWriterOnDiskFull, TestCustomNorms, TestPostingsOffsets, TestPayloadSpans, TestTransactionRollback, TestLucene40PostingsFormat, TestSortDocValues, Nested, TestMultiPhraseQuery, TestDocsAndPositions, TestFieldCacheRangeFilter, TestTermVectorsWriter, TestCodecs, TestCharTermAttributeImpl, TestOmitPositions, TestParallelAtomicReader, TestThreadedForceMerge, TestStressNRT, TestIndexInput, TestCopyBytes, TestFilteredQuery, TestSloppyPhraseQuery2, TestDisjunctionMaxQuery, TestForceMergeForever, TestSameScoresWithThreads, TestQueryWrapperFilter, TestMultiFields, TestPayloadNearQuery, TestCrashCausesCorruptIndex, TestLiveFieldValues, TestForTooMuchCloning, TestFuzzyQuery, TestPayloads, TestSimilarity2, TestTermRangeFilter, TestNoDeletionPolicy, TestSimpleExplanationsOfNonMatches, TestRegexpQuery, TestTermVectorsReader, TestMultiValuedNumericRangeQuery, TestPrefixInBooleanQuery, TestTopDocsCollector, TestComplexExplanationsOfNonMatches, TestPrefixCodedTerms, TestAllFilesHaveCodecHeader, TestParallelReaderEmptyIndex, TestMaxTermFrequency, TestTopFieldCollector, TestSumDocFreq, TestSentinelIntSet, TestWindowsMMap, TestDirectory, TestRegexpRandom, TestCheckIndex, TestPayloadsOnVectors, TestCachingWrapperFilter, TestFieldValueFilter, TestSpanMultiTermQueryWrapper, TestBooleanQueryVisitSubscorers, TestSearchForDuplicates, TestSizeBoundedForceMerge, TestCompiledAutomaton, TestConstantScoreQuery, TestDocValuesWithThreads, Before3, Before3, TestDocIdSet, TestElevationComparator, TestFilteredSearch, TestBinaryTerms, TestSubScorerFreqs, TestRecyclingIntBlockAllocator, TestConjunctions, TestMultiTermQueryRewrites, TestPositionIncrement, TestField, TestSimilarity, TestIdentityHashSet, TestRollingBuffer, TestSetOnce, TestCachingTokenFilter, TestSpansAdvanced, TestFilterIterator, TestDateTools, TestSameTokenSamePosition, TestDocCount, TestCachingCollector, TestFieldCacheTermsFilter, Nested1, TestRecyclingByteBlockAllocator, TestSpanFirstQuery, Nested, TestIsCurrent, TestNoMergePolicy, TestNoMergeScheduler, TestCharFilter, TestRollback, TestExplanations, TestVersionComparator, TestPrefixFilter, TestVersion, TestCodecHoldsOpenFiles, TestDocBoost, TestTerm, Test2BPostingsBytes, TestIOUtils, TestNewestSegment, TestLucene40StoredFieldsFormat, TestLucene41StoredFieldsFormat, TestLucene45DocValuesFormat, TestBinaryDocValuesUpdates, TestDocInverterPerFieldErrorInfo, TestExceedMaxTermLength, TestLogMergePolicy, TestNumericDocValuesUpdates, FuzzyTermOnShortTermsTest, TestControlledRealTimeReopenThread]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestControlledRealTimeReopenThread -Dtests.seed=801B7377FFF2F131 -Dtests.slow=true -Dtests.locale=fi_FI -Dtests.timezone=Asia/Magadan -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s | TestControlledRealTimeReopenThread (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.lucene.search.TestControlledRealTimeReopenThread:
[junit4] > 1) Thread[id=2963, name=Thread-2082, state=TIMED_WAITING, group=TGRP-TestControlledRealTimeReopenThread]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at org.apache.lucene.search.ControlledRealTimeReopenThread.run(ControlledRealTimeReopenThread.java:223)
[junit4] > at __randomizedtesting.SeedInfo.seed([801B7377FFF2F131]:0)
[junit4] Completed in 1231.10s, 5 tests, 1 failure, 1 error <<< FAILURES!
[...truncated 57 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build.xml:49: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1275: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:907: There were test failures: 385 suites, 2796 tests, 1 suite-level error, 1 failure, 82 ignored (69 assumptions)
Total time: 34 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0 -client -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure