Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/863/
3 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionTest.test
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([C9EA21E1D568013F]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.HttpPartitionTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([C9EA21E1D568013F]:0)
FAILED: org.apache.solr.cloud.hdfs.StressHdfsTest.test
Error Message:
No registered leader was found after waiting for 30000ms , collection:
delete_data_dir slice: shard1
Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found after
waiting for 30000ms , collection: delete_data_dir slice: shard1
at
__randomizedtesting.SeedInfo.seed([C9EA21E1D568013F:41BE1E3B7B946CC7]:0)
at
org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:637)
at
org.apache.solr.cloud.hdfs.StressHdfsTest.createAndDeleteCollection(StressHdfsTest.java:161)
at
org.apache.solr.cloud.hdfs.StressHdfsTest.test(StressHdfsTest.java:100)
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:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11233 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.StressHdfsTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/init-core-data-001
[junit4] 2> 4246623 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /bb/
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 4246719 WARN
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 4246723 WARN
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4246724 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 4246736 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_44057_hdfs____.uc6o78/webapp
[junit4] 2> 4246831 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log NO JSP
Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 4247069 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44057
[junit4] 2> 4247144 WARN
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4247145 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 4247157 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_54728_datanode____.lbvjob/webapp
[junit4] 2> 4247251 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log NO JSP
Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 4247532 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:54728
[junit4] 2> 4247579 WARN
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4247581 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 4247593 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_60970_datanode____ao6if3/webapp
[junit4] 2> 4247705 INFO (IPC Server handler 2 on 55501) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-0b5e16ab-4fd8-4695-821b-28c31ab21791 node DatanodeRegistration(127.0.0.1,
datanodeUuid=ee8af65c-0a32-4088-a674-f0b0a27c972a, infoPort=54728,
ipcPort=42850, storageInfo=lv=-56;cid=testClusterID;nsid=864581284;c=0),
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
[junit4] 2> 4247705 INFO (IPC Server handler 2 on 55501) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-39b96f7d-8fbf-41c2-93bb-f32a8d9bfea4 node DatanodeRegistration(127.0.0.1,
datanodeUuid=ee8af65c-0a32-4088-a674-f0b0a27c972a, infoPort=54728,
ipcPort=42850, storageInfo=lv=-56;cid=testClusterID;nsid=864581284;c=0),
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 4247712 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log NO JSP
Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 4247973 INFO
(SUITE-StressHdfsTest-seed#[C9EA21E1D568013F]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:60970
[junit4] 2> 4248070 INFO (IPC Server handler 6 on 55501) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-b5a2244b-890f-4dee-87d3-ab646fc52411 node DatanodeRegistration(127.0.0.1,
datanodeUuid=40999e10-e429-4d7f-9009-119a1245709c, infoPort=60970,
ipcPort=39633, storageInfo=lv=-56;cid=testClusterID;nsid=864581284;c=0),
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
[junit4] 2> 4248071 INFO (IPC Server handler 6 on 55501) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-fb7cde7c-a98c-488c-8055-c3a35496639f node DatanodeRegistration(127.0.0.1,
datanodeUuid=40999e10-e429-4d7f-9009-119a1245709c, infoPort=60970,
ipcPort=39633, storageInfo=lv=-56;cid=testClusterID;nsid=864581284;c=0),
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 4248158 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.ZkTestServer
STARTING ZK TEST SERVER
[junit4] 2> 4248158 INFO (Thread-75136) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4248158 INFO (Thread-75136) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 4248258 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.ZkTestServer
start zk server on port:60754
[junit4] 2> 4248258 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4248259 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4248262 INFO (zkCallback-3004-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@411b01dc
name:ZooKeeperConnection Watcher:127.0.0.1:60754 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4248262 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4248263 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4248263 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 4248266 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4248267 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4248268 INFO (zkCallback-3005-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@686cc267
name:ZooKeeperConnection Watcher:127.0.0.1:60754/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4248268 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4248268 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4248268 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 4248269 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 4248270 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 4248271 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 4248273 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4248273 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 4248275 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 4248275 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 4248277 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4248277 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4248278 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4248278 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 4248279 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4248280 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 4248281 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4248281 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 4248282 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4248282 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 4248284 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4248284 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 4248285 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4248285 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4248286 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4248287 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 4248288 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4248288 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 4248581 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.SolrTestCaseJ4
Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores/collection1
[junit4] 2> 4248582 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.e.j.s.Server
jetty-9.2.13.v20150730
[junit4] 2> 4248584 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@17db29ba{/bb,null,AVAILABLE}
[junit4] 2> 4248584 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@7be3a81b{HTTP/1.1}{127.0.0.1:40426}
[junit4] 2> 4248584 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.e.j.s.Server
Started @4251474ms
[junit4] 2> 4248584 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:55501/hdfs__localhost_55501__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001_tempDir-002_control_data,
hostContext=/bb, hostPort=40426,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores}
[junit4] 2> 4248585 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 4248585 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001'
[junit4] 2> 4248585 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4248585 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 4248585 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4248586 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4248587 INFO (zkCallback-3006-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@45efa888
name:ZooKeeperConnection Watcher:127.0.0.1:60754/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4248587 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4248587 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4248588 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4248588 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.SolrXmlConfig
Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/solr.xml
[junit4] 2> 4248593 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoresLocator
Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores
[junit4] 2> 4248593 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoreContainer
New CoreContainer 1498185142
[junit4] 2> 4248593 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoreContainer
Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001]
[junit4] 2> 4248593 WARN
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoreContainer
Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/lib
[junit4] 2> 4248593 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 4248594 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4248595 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.l.LogWatcher
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4248595 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.l.LogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4248595 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:60754/solr
[junit4] 2> 4248595 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.ZkController
zkHost includes chroot
[junit4] 2> 4248595 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4248595 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4248596 INFO (zkCallback-3008-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7ceb1269
name:ZooKeeperConnection Watcher:127.0.0.1:60754 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4248596 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4248596 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4248598 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4248598 INFO
(zkCallback-3009-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@39905816
name:ZooKeeperConnection Watcher:127.0.0.1:60754/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4248598 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4248599 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 4248601 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 4248603 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 4248604 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 4248606 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 4248608 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 4248609 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 4248610 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 4248611 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 4248612 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40426_bb
[junit4] 2> 4248612 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40426_bb
[junit4] 2> 4248614 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 4248615 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 4248616 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4248616 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.LeaderElector Joined leadership election with path:
/overseer_elect/election/94925877019148292-127.0.0.1:40426_bb-n_0000000000
[junit4] 2> 4248616 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40426_bb
[junit4] 2> 4248616 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 4248617 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.Overseer Overseer
(id=94925877019148292-127.0.0.1:40426_bb-n_0000000000) starting
[junit4] 2> 4248619 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 4248623 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 4248624 INFO
(OverseerCollectionConfigSetProcessor-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 4248624 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 4248624 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 4248639 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 4248639 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 4248639 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.CoresLocator Looking for core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores
[junit4] 2> 4248640 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1,
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true,
configSetProperties=configsetprops.json,
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores/collection1,
collection=control_collection,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 4248640 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores/collection1/
[junit4] 2> 4248640 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 4248641 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 4248641 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 4248641 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 4248641 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:40426_bb ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 4248643 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40426/bb";,
[junit4] 2> "node_name":"127.0.0.1:40426_bb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 4248643 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 4248643 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40426/bb";,
[junit4] 2> "node_name":"127.0.0.1:40426_bb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 4248644 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 4248644 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 4248645 INFO
(zkCallback-3009-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json,
has occurred - updating... (live nodes size: 1)
[junit4] 2> 4249644 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 4249644 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 4249644 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 4249644 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config
from:/collections/control_collection
[junit4] 2> 4249645 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1
specified config exists in ZooKeeper
[junit4] 2> 4249645 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores/collection1'
[junit4] 2> 4249645 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4249645 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 4249647 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1] o.a.s.c.Config
loaded config solrconfig.xml with version 0
[junit4] 2> 4249651 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 4249655 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4249662 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1] o.a.s.c.Config
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4249671 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 4249781 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4249791 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 4249792 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 4249800 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 4249803 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 4249805 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 4249806 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 4249806 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 4249806 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 4249807 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 4249807 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 4249808 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/configs/conf1',
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 4249808 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 4249808 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
[junit4] 2> 4249808 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:55501/solr_hdfs_home
[junit4] 2> 4249808 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 4249808 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/control-001/cores/collection1],
dataDir=[null]
[junit4] 2> 4249808 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@18e69b8d
[junit4] 2> 4249809 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55501/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 4249817 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds
and try again.
[junit4] 2> 4254818 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds
and try again.
[junit4] 2> 4259824 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
hdfs://localhost:55501/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 4259825 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=hdfs://localhost:55501/solr_hdfs_home/control_collection/core_node1/data/index/
[junit4] 2> 4259834 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'hdfs:/localhost:55501/solr_hdfs_home/control_collection/core_node1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 4259834 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55501/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 4259841 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 4259841 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 4259841 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 4259852 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 4259853 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
hdfs://localhost:55501/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 4259853 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=43, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=75.5009765625,
floorSegmentMB=0.970703125, forceMergeDeletesPctAllowed=29.193578687390183,
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2875609045286608
[junit4] 2> 4259882 INFO (IPC Server handler 3 on 55501) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:33756 is
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION,
primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-0b5e16ab-4fd8-4695-821b-28c31ab21791:NORMAL:127.0.0.1:57723|RBW],
ReplicaUnderConstruction[[DISK]DS-b5a2244b-890f-4dee-87d3-ab646fc52411:NORMAL:127.0.0.1:33756|RBW]]}
size 0
[junit4] 2> 4259886 INFO (IPC Server handler 1 on 55501) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57723 is
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION,
primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-0b5e16ab-4fd8-4695-821b-28c31ab21791:NORMAL:127.0.0.1:57723|RBW],
ReplicaUnderConstruction[[DISK]DS-b5a2244b-890f-4dee-87d3-ab646fc52411:NORMAL:127.0.0.1:33756|RBW]]}
size 0
[junit4] 2> 4259890 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=NRTCachingDirectory(BlockDirectory(HdfsDirectory@b89be12f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2be7210b);
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
[junit4] 2> 4259890 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 4259891 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
Looking for old index directories to cleanup for core collection1 in
hdfs://localhost:55501/solr_hdfs_home/control_collection/core_node1/data/
[junit4] 2> 4259894 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 4259894 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 4259895 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 4259895 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 4259895 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 4259895 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 4259895 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 4259895 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 4259895 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating
implicit default
[junit4] 2> 4259895 WARN
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 4259897 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 4259898 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 4259899 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 4259900 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 4259903 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 4259904 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4259904 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 4259904 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2
[junit4] 2> 4259913 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 4259913 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 4259928 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.9365234375, noCFSRatio=0.0]
[junit4] 2> 4259935 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=NRTCachingDirectory(BlockDirectory(HdfsDirectory@b89be12f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2be7210b);
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
[junit4] 2> 4259935 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 4259935 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@2f1cf4ba[collection1] main
[junit4] 2> 4259935 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.ZkStateReader Load collection config
from:/collections/control_collection
[junit4] 2> 4259936 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1
specified config exists in ZooKeeper
[junit4] 2> 4259936 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the
RestManager with znodeBase: /configs/conf1
[junit4] 2> 4259936 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4259936 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 4259936 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4259937 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage No data found for znode
/configs/conf1/_rest_managed.json
[junit4] 2> 4259937 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4259937 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 4259937 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 4259939 INFO
(searcherExecutor-11467-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@2f1cf4ba[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4259940 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 4259940 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version
buckets from index
[junit4] 2> 4259940 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket
highest value from index
[junit4] 2> 4259940 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1518814044659646464
[junit4] 2> 4259940 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 0.0ms to seed version buckets with highest version
1518814044659646464
[junit4] 2> 4259940 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 4259941 INFO
(coreLoadExecutor-11466-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 4259941 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
Register replica - core:collection1 address:http://127.0.0.1:40426/bb
collection:control_collection shard:shard1
[junit4] 2> 4259942 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 4259945 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector
Joined leadership election with path:
/collections/control_collection/leader_elect/shard1/election/94925877019148292-core_node1-n_0000000000
[junit4] 2> 4259945 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
[junit4] 2> 4259947 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4259947 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4259947 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:40426/bb/collection1/
[junit4] 2> 4259947 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 4259947 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 4259947 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:40426/bb/collection1/ has no replicas
[junit4] 2> 4259948 INFO
(zkCallback-3009-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json,
has occurred - updating... (live nodes size: 1)
[junit4] 2> 4259948 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 4259950 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 4259952 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:40426/bb/collection1/ shard1
[junit4] 2> 4259953 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:40426/bb";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 4260055 INFO
(zkCallback-3009-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json,
has occurred - updating... (live nodes size: 1)
[junit4] 2> 4260103 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are http://127.0.0.1:40426/bb/collection1/ and leader is
http://127.0.0.1:40426/bb/collection1/
[junit4] 2> 4260103 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:40426/bb
[junit4] 2> 4260103 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 4260104 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 4260104 INFO
(coreZkRegister-11460-thread-1-processing-n:127.0.0.1:40426_bb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40426_bb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 4260107 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40426/bb";,
[junit4] 2> "node_name":"127.0.0.1:40426_bb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 4260107 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40426/bb";,
[junit4] 2> "node_name":"127.0.0.1:40426_bb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 4260146 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4260146 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4260147 INFO (zkCallback-3011-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4e07d82e
name:ZooKeeperConnection Watcher:127.0.0.1:60754/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4260148 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4260148 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4260148 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 4260150 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.ChaosMonkey
monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 4260151 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 4260151 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4260151 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4260152 INFO (zkCallback-3012-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@442c0e80
name:ZooKeeperConnection Watcher:127.0.0.1:60754/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4260152 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4260153 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4260155 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 4260155 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ClusterStateMutator building a new cName:
collection1
[junit4] 2> 4260155 INFO (zkCallback-3011-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 1)
[junit4] 2> 4260155 INFO
(zkCallback-3009-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json,
has occurred - updating... (live nodes size: 1)
[junit4] 2> 4260259 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/collection1/state.json
[junit4] 2> 4260500 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.SolrTestCaseJ4
Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/cores/collection1
[junit4] 2> 4260501 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001
[junit4] 2> 4260502 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.e.j.s.Server
jetty-9.2.13.v20150730
[junit4] 2> 4260504 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@45b2a67a{/bb,null,AVAILABLE}
[junit4] 2> 4260504 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@8b60120{HTTP/1.1}{127.0.0.1:37100}
[junit4] 2> 4260504 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.e.j.s.Server
Started @4263393ms
[junit4] 2> 4260504 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:55501/hdfs__localhost_55501__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001_tempDir-002_jetty1,
solrconfig=solrconfig.xml, hostContext=/bb, hostPort=37100,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/cores}
[junit4] 2> 4260504 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 4260504 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001'
[junit4] 2> 4260505 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4260505 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 4260505 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4260506 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4260507 INFO (zkCallback-3013-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@254cfd18
name:ZooKeeperConnection Watcher:127.0.0.1:60754/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4260507 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4260507 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4260509 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4260509 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.SolrXmlConfig
Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/solr.xml
[junit4] 2> 4260516 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoresLocator
Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/cores
[junit4] 2> 4260516 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoreContainer
New CoreContainer 1991130169
[junit4] 2> 4260516 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoreContainer
Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001]
[junit4] 2> 4260516 WARN
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.CoreContainer
Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/lib
[junit4] 2> 4260516 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 4260518 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4260518 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.l.LogWatcher
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4260518 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.l.LogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4260518 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:60754/solr
[junit4] 2> 4260518 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ] o.a.s.c.ZkController
zkHost includes chroot
[junit4] 2> 4260519 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 4260519 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4260520 INFO (zkCallback-3015-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@54b8461d
name:ZooKeeperConnection Watcher:127.0.0.1:60754 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4260520 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4260521 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 4260522 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4260523 INFO
(zkCallback-3016-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2cf2f8fe
name:ZooKeeperConnection Watcher:127.0.0.1:60754/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4260523 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4260528 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 4261534 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37100_bb
[junit4] 2> 4261534 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37100_bb
[junit4] 2> 4261535 INFO
(zkCallback-3009-thread-1-processing-n:127.0.0.1:40426_bb)
[n:127.0.0.1:40426_bb ] o.a.s.c.c.ZkStateReader A live node change:
WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has
occurred - updating... (live nodes size: 1)
[junit4] 2> 4261535 INFO (zkCallback-3011-thread-1) [ ]
o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live
nodes size: 1)
[junit4] 2> 4261535 INFO
(zkCallback-3016-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb ] o.a.s.c.c.ZkStateReader A live node change:
WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has
occurred - updating... (live nodes size: 1)
[junit4] 2> 4261539 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4261539 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.LeaderElector Joined leadership election with path:
/overseer_elect/election/94925877019148297-127.0.0.1:37100_bb-n_0000000001
[junit4] 2> 4261540 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.LeaderElector Watching path
/overseer_elect/election/94925877019148292-127.0.0.1:40426_bb-n_0000000000 to
know if I could be the leader
[junit4] 2> 4261561 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 4261561 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 4261562 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.CoresLocator Looking for core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/cores
[junit4] 2> 4261562 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1,
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true,
configSetProperties=configsetprops.json,
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/cores/collection1,
collection=collection1,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 4261563 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_C9EA21E1D568013F-001/shard-1-001/cores/collection1/
[junit4] 2> 4261563 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 4261564 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 4261564 INFO
(TEST-StressHdfsTest.test-seed#[C9EA21E1D568013F]) [n:127.0.0.1:37100_bb ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 4261564 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 4261565 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 4261566 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.ZkController
Registering watch for collection collection1
[junit4] 2> 4261566 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
addZkWatch collection1
[junit4] 2> 4261566 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:37100/bb";,
[junit4] 2> "node_name":"127.0.0.1:37100_bb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 4261567 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
Add data for collection1 ver 0
[junit4] 2> 4261568 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.ZkController look
for our core node name
[junit4] 2> 4261568 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:37100/bb";,
[junit4] 2> "node_name":"127.0.0.1:37100_bb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4261569 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ReplicaMutator Collection already exists
with numShards=1
[junit4] 2> 4261569 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 4261671 INFO
(OverseerStateUpdate-94925877019148292-127.0.0.1:40426_bb-n_0000000000)
[n:127.0.0.1:40426_bb ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/collection1/state.json version: 0
[junit4] 2> 4261672 INFO
(zkCallback-3016-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json for collection collection1 has
occurred - updating... (live nodes size: 2)
[junit4] 2> 4261672 INFO
(zkCallback-3016-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb ] o.a.s.c.c.ZkStateReader Updating data for
collection1 from 0 to 1
[junit4] 2> 4262569 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 4262569 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c:collection1 x:collection1] o.a.s.c.ZkController Check
for collection zkNode:collection1
[junit4] 2> 4262569 INFO
(coreLoadExecutor-11477-thread-1-processing-n:127.0.0.1:37100_bb)
[n:127.0.0.1:37100_bb c
[...truncated too long message...]
127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 26) Thread[id=6170, name=Scheduler-1631776602,
state=WAITING, group=TGRP-HttpPartitionTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 27) Thread[id=6520,
name=zkCallback-569-thread-6-processing-n:127.0.0.1:60385_yv%2Fx-SendThread(127.0.0.1:53440),
state=TIMED_WAITING, group=TGRP-HttpPartitionTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
[junit4] 2> 28) Thread[id=6124,
name=qtp1621275060-6124-selector-ServerConnectorManager@2359a388/1,
state=RUNNABLE, group=TGRP-HttpPartitionTest]
[junit4] 2> at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] 2> at
sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:90)
[junit4] 2> at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.select(SelectorManager.java:600)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.run(SelectorManager.java:549)
[junit4] 2> at
org.eclipse.jetty.util.thread.NonBlockingThread.run(NonBlockingThread.java:52)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 29) Thread[id=6173, name=Scheduler-103267164, state=WAITING,
group=TGRP-HttpPartitionTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 30) Thread[id=6065,
name=qtp1394515894-6065-selector-ServerConnectorManager@1c762ec9/0,
state=RUNNABLE, group=TGRP-HttpPartitionTest]
[junit4] 2> at sun.nio.ch.EPollArrayWrapper.interrupt(Native
Method)
[junit4] 2> at
sun.nio.ch.EPollArrayWrapper.interrupt(EPollArrayWrapper.java:317)
[junit4] 2> at
sun.nio.ch.EPollSelectorImpl.wakeup(EPollSelectorImpl.java:193)
[junit4] 2> at
java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:213)
[junit4] 2> at
java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:219)
[junit4] 2> at
sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:78)
[junit4] 2> at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.select(SelectorManager.java:600)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.run(SelectorManager.java:549)
[junit4] 2> at
org.eclipse.jetty.util.thread.NonBlockingThread.run(NonBlockingThread.java:52)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60):
{range_facet_l_dv=BlockTreeOrds(blocksize=128),
_version_=PostingsFormat(name=LuceneFixedGap),
multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval),
a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval),
intDefault=PostingsFormat(name=LuceneFixedGap),
id=BlockTreeOrds(blocksize=128),
range_facet_i_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval),
text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
range_facet_l=PostingsFormat(name=LuceneVarGapDocFreqInterval),
timestamp=PostingsFormat(name=LuceneVarGapDocFreqInterval)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Direct),
range_facet_i_dv=DocValuesFormat(name=Memory),
timestamp=DocValuesFormat(name=Memory)}, sim=ClassicSimilarity, locale=de_CH,
timezone=CET
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_66 (64-bit)/cpus=4,threads=51,free=207668696,total=513802240
[junit4] 2> NOTE: All tests run in this JVM: [TestImplicitCoreProperties,
TestStandardQParsers, SaslZkACLProviderTest,
TestLMJelinekMercerSimilarityFactory, TestHdfsUpdateLog,
TestManagedSchemaFieldResource, BasicFunctionalityTest, TestRTimerTree,
TermVectorComponentTest, ZkControllerTest, DistributedTermsComponentTest,
TestHashPartitioner, NumericFieldsTest, SolrInfoMBeanTest,
CoreMergeIndexesAdminHandlerTest, OpenExchangeRatesOrgProviderTest,
TestWordDelimiterFilterFactory, TestCollectionAPI, TestReplicaProperties,
TestDFRSimilarityFactory, WordBreakSolrSpellCheckerTest, TestDocumentBuilder,
TestRawResponseWriter, LoggingHandlerTest, SimplePostToolTest,
CloneFieldUpdateProcessorFactoryTest, HdfsRecoverLeaseTest,
DirectSolrSpellCheckerTest, TestChildDocTransformer, SpellCheckComponentTest,
MinimalSchemaTest, DistribJoinFromCollectionTest, TestShortCircuitedRequests,
TestSurroundQueryParser, TestDistributedGrouping, TestCloudManagedSchema,
IndexBasedSpellCheckerTest, TestCustomSort, TestTrackingShardHandlerFactory,
TestSystemIdResolver, TestPartialUpdateDeduplication,
ChaosMonkeyNothingIsSafeTest, DistributedMLTComponentTest, TestInitParams,
TestSimpleQParserPlugin, TestSolrDeletionPolicy2, TestFiltering,
DocumentBuilderTest, ClusterStateUpdateTest, TestBlobHandler,
TestDynamicLoading, CollectionsAPIAsyncDistributedZkTest, RulesTest,
HttpPartitionTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionTest
-Dtests.seed=C9EA21E1D568013F -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=de_CH -Dtests.timezone=CET -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | HttpPartitionTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([C9EA21E1D568013F]:0)
[junit4] Completed [523/562] on J1 in 7223.70s, 1 test, 2 errors <<<
FAILURES!
[...truncated 127 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987:
There were test failures: 562 suites (8 ignored), 2228 tests, 1 suite-level
error, 2 errors, 208 ignored (17 assumptions) [seed: C9EA21E1D568013F]
Total time: 300 minutes 43 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
