Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1987/ Java: 64bit/jdk-9-ea+140 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.update.processor.TestNamedUpdateProcessors.test Error Message: Error from server at https://127.0.0.1:40490/ail/collection1: error instantiating class :org.apache.solr.update.processor.RuntimeUrp Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40490/ail/collection1: error instantiating class :org.apache.solr.update.processor.RuntimeUrp at __randomizedtesting.SeedInfo.seed([D07F08A45427B5FE:582B377EFADBD806]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:593) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.update.processor.TestNamedUpdateProcessors.test(TestNamedUpdateProcessors.java:125) at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method) at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62) at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535) 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:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) 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:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) 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:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) 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(java.base@9-ea/Thread.java:843) Build Log: [...truncated 11071 lines...] [junit4] Suite: org.apache.solr.update.processor.TestNamedUpdateProcessors [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/init-core-data-001 [junit4] 2> 320150 INFO (SUITE-TestNamedUpdateProcessors-seed#[D07F08A45427B5FE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 320151 INFO (SUITE-TestNamedUpdateProcessors-seed#[D07F08A45427B5FE]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ail/ [junit4] 2> 320153 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 320153 INFO (Thread-570) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 320153 INFO (Thread-570) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 320253 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkTestServer start zk server on port:44379 [junit4] 2> 320261 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 320263 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 320265 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 320267 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 320269 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 320270 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 320272 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 320275 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 320277 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 320280 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 320282 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 320766 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/control-001/cores/collection1 [junit4] 2> 320769 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 320770 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ac6f4a7{/ail,null,AVAILABLE} [junit4] 2> 320771 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3e3fdea0{SSL,[ssl, http/1.1]}{127.0.0.1:37526} [junit4] 2> 320771 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server Started @322622ms [junit4] 2> 320771 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ail, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/tempDir-001/control/data, hostPort=37526, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/control-001/cores} [junit4] 2> 320771 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 320771 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0 [junit4] 2> 320772 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 320772 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 320772 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-19T14:06:58.973046Z [junit4] 2> 320774 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 320774 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/control-001/solr.xml [junit4] 2> 320780 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 320781 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44379/solr [junit4] 2> 320791 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37526_ail [junit4] 2> 320792 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.Overseer Overseer (id=96789202098978821-127.0.0.1:37526_ail-n_0000000000) starting [junit4] 2> 320794 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37526_ail [junit4] 2> 320795 INFO (OverseerStateUpdate-96789202098978821-127.0.0.1:37526_ail-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 320806 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/control-001/cores [junit4] 2> 320806 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 320808 INFO (OverseerStateUpdate-96789202098978821-127.0.0.1:37526_ail-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 321819 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 321837 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 321923 WARN (coreLoadExecutor-1416-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 321926 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 321934 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 321935 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/control-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/control-001/cores/collection1/data/] [junit4] 2> 321935 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5140b497 [junit4] 2> 321936 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=11394835, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 321938 WARN (coreLoadExecutor-1416-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 321943 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 321943 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 321945 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 321945 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 321945 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=28.103515625, floorSegmentMB=0.9404296875, forceMergeDeletesPctAllowed=23.104530208935095, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7008964594661039 [junit4] 2> 321946 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@183ac5a3[collection1] main] [junit4] 2> 321946 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 321946 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 321946 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 321947 INFO (searcherExecutor-1417-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@183ac5a3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 321947 INFO (coreLoadExecutor-1416-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548627235462709248 [junit4] 2> 321952 INFO (coreZkRegister-1409-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 321952 INFO (coreZkRegister-1409-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 321952 INFO (coreZkRegister-1409-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37526/ail/collection1/ [junit4] 2> 321952 INFO (coreZkRegister-1409-thread-1) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 321952 INFO (coreZkRegister-1409-thread-1) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:37526/ail/collection1/ has no replicas [junit4] 2> 321955 INFO (coreZkRegister-1409-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:37526/ail/collection1/ shard1 [junit4] 2> 322105 INFO (coreZkRegister-1409-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 322313 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 322314 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 322314 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 322839 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-1-001/cores/collection1 [junit4] 2> 322839 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-1-001 [junit4] 2> 322843 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 322849 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ac3f2e8{/ail,null,AVAILABLE} [junit4] 2> 322849 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@696c4cc2{SSL,[ssl, http/1.1]}{127.0.0.1:38535} [junit4] 2> 322849 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server Started @324700ms [junit4] 2> 322849 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ail, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/tempDir-001/jetty1, hostPort=38535, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-1-001/cores} [junit4] 2> 322850 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 322850 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0 [junit4] 2> 322850 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 322850 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 322850 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-19T14:07:01.051385Z [junit4] 2> 322854 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 322854 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-1-001/solr.xml [junit4] 2> 322859 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 322860 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44379/solr [junit4] 2> 322867 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 322869 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38535_ail [junit4] 2> 322872 INFO (zkCallback-648-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 322872 INFO (zkCallback-638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 322873 INFO (zkCallback-642-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 322884 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-1-001/cores [junit4] 2> 322884 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 322887 INFO (OverseerStateUpdate-96789202098978821-127.0.0.1:37526_ail-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 322989 INFO (zkCallback-648-thread-1) [ ] 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> 323898 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 323915 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 323999 WARN (coreLoadExecutor-1427-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 324001 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 324007 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 324007 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-1-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-1-001/cores/collection1/data/] [junit4] 2> 324007 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5140b497 [junit4] 2> 324008 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=11394835, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 324011 WARN (coreLoadExecutor-1427-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 324017 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 324017 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 324019 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 324019 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 324020 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=28.103515625, floorSegmentMB=0.9404296875, forceMergeDeletesPctAllowed=23.104530208935095, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7008964594661039 [junit4] 2> 324020 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6cda813c[collection1] main] [junit4] 2> 324020 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 324021 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 324021 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 324021 INFO (searcherExecutor-1428-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6cda813c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 324022 INFO (coreLoadExecutor-1427-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548627237638504448 [junit4] 2> 324026 INFO (coreZkRegister-1422-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 324026 INFO (coreZkRegister-1422-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 324026 INFO (coreZkRegister-1422-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38535/ail/collection1/ [junit4] 2> 324026 INFO (coreZkRegister-1422-thread-1) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 324026 INFO (coreZkRegister-1422-thread-1) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:38535/ail/collection1/ has no replicas [junit4] 2> 324029 INFO (coreZkRegister-1422-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38535/ail/collection1/ shard2 [junit4] 2> 324131 INFO (zkCallback-648-thread-1) [ ] 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> 324180 INFO (coreZkRegister-1422-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 324282 INFO (zkCallback-648-thread-1) [ ] 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> 324872 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-2-001/cores/collection1 [junit4] 2> 324873 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-2-001 [junit4] 2> 324875 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 324876 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@487f9190{/ail,null,AVAILABLE} [junit4] 2> 324877 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5eba82d1{SSL,[ssl, http/1.1]}{127.0.0.1:41602} [junit4] 2> 324877 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server Started @326728ms [junit4] 2> 324877 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ail, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/tempDir-001/jetty2, hostPort=41602, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-2-001/cores} [junit4] 2> 324877 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 324878 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0 [junit4] 2> 324878 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 324878 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 324878 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-19T14:07:03.079187Z [junit4] 2> 324880 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 324880 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-2-001/solr.xml [junit4] 2> 324898 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 324900 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44379/solr [junit4] 2> 324906 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 324908 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41602_ail [junit4] 2> 324908 INFO (zkCallback-642-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 324908 INFO (zkCallback-654-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 324909 INFO (zkCallback-638-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 324908 INFO (zkCallback-648-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 324914 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-2-001/cores [junit4] 2> 324914 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 324916 INFO (OverseerStateUpdate-96789202098978821-127.0.0.1:37526_ail-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 325018 INFO (zkCallback-648-thread-1) [ ] 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: [3]) [junit4] 2> 325018 INFO (zkCallback-654-thread-1) [ ] 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: [3]) [junit4] 2> 325923 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 325935 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 326002 WARN (coreLoadExecutor-1438-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 326004 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 326011 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 326011 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-2-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-2-001/cores/collection1/data/] [junit4] 2> 326011 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5140b497 [junit4] 2> 326012 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=11394835, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 326015 WARN (coreLoadExecutor-1438-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 326021 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 326021 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 326023 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 326023 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 326024 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=28.103515625, floorSegmentMB=0.9404296875, forceMergeDeletesPctAllowed=23.104530208935095, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7008964594661039 [junit4] 2> 326024 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@134d2a97[collection1] main] [junit4] 2> 326025 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 326025 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 326025 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 326026 INFO (searcherExecutor-1439-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@134d2a97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 326026 INFO (coreLoadExecutor-1438-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548627239739850752 [junit4] 2> 326030 INFO (coreZkRegister-1433-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 326030 INFO (coreZkRegister-1433-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 326030 INFO (coreZkRegister-1433-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:41602/ail/collection1/ [junit4] 2> 326030 INFO (coreZkRegister-1433-thread-1) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 326030 INFO (coreZkRegister-1433-thread-1) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:41602/ail/collection1/ has no replicas [junit4] 2> 326032 INFO (coreZkRegister-1433-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:41602/ail/collection1/ shard1 [junit4] 2> 326134 INFO (zkCallback-654-thread-1) [ ] 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: [3]) [junit4] 2> 326134 INFO (zkCallback-648-thread-1) [ ] 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: [3]) [junit4] 2> 326182 INFO (coreZkRegister-1433-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 326285 INFO (zkCallback-654-thread-1) [ ] 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: [3]) [junit4] 2> 326285 INFO (zkCallback-648-thread-1) [ ] 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: [3]) [junit4] 2> 326929 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/cores/collection1 [junit4] 2> 326930 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001 [junit4] 2> 326932 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 326933 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8ba1919{/ail,null,AVAILABLE} [junit4] 2> 326933 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4ae513af{SSL,[ssl, http/1.1]}{127.0.0.1:42752} [junit4] 2> 326934 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server Started @328785ms [junit4] 2> 326934 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ail, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/tempDir-001/jetty3, hostPort=42752, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/cores} [junit4] 2> 326934 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 326934 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0 [junit4] 2> 326934 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 326934 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 326934 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-19T14:07:05.135718Z [junit4] 2> 326936 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 326936 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/solr.xml [junit4] 2> 326941 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 326943 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44379/solr [junit4] 2> 326948 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 326950 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42752_ail [junit4] 2> 326950 INFO (zkCallback-648-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 326950 INFO (zkCallback-654-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 326951 INFO (zkCallback-660-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 326950 INFO (zkCallback-642-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 326950 INFO (zkCallback-638-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 326957 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/cores [junit4] 2> 326957 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 326959 INFO (OverseerStateUpdate-96789202098978821-127.0.0.1:37526_ail-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 327060 INFO (zkCallback-654-thread-1) [ ] 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: [4]) [junit4] 2> 327060 INFO (zkCallback-648-thread-1) [ ] 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: [4]) [junit4] 2> 327060 INFO (zkCallback-660-thread-1) [ ] 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: [4]) [junit4] 2> 327967 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 327981 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 328076 WARN (coreLoadExecutor-1449-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 328077 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 328083 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 328083 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/cores/collection1/data/] [junit4] 2> 328083 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5140b497 [junit4] 2> 328085 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=11394835, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 328088 WARN (coreLoadExecutor-1449-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 328094 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 328094 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 328096 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 328096 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 328097 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=28.103515625, floorSegmentMB=0.9404296875, forceMergeDeletesPctAllowed=23.104530208935095, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7008964594661039 [junit4] 2> 328097 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6b7e0cf1[collection1] main] [junit4] 2> 328098 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 328098 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 328098 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 328099 INFO (searcherExecutor-1450-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6b7e0cf1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 328099 INFO (coreLoadExecutor-1449-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548627241913548800 [junit4] 2> 328102 INFO (coreZkRegister-1444-thread-1) [ ] o.a.s.c.ZkController Core needs to recover:collection1 [junit4] 2> 328102 INFO (updateExecutor-657-thread-1) [ ] o.a.s.u.DefaultSolrCoreState Running recovery [junit4] 2> 328102 INFO (recoveryExecutor-658-thread-1) [ ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true [junit4] 2> 328103 INFO (recoveryExecutor-658-thread-1) [ ] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]] [junit4] 2> 328103 INFO (recoveryExecutor-658-thread-1) [ ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1] [junit4] 2> 328103 INFO (recoveryExecutor-658-thread-1) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 328103 INFO (recoveryExecutor-658-thread-1) [ ] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [https://127.0.0.1:38535/ail/collection1/] and I am [https://127.0.0.1:42752/ail/collection1/] [junit4] 2> 328105 INFO (recoveryExecutor-658-thread-1) [ ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [https://127.0.0.1:38535/ail]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:42752_ail&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true] [junit4] 2> 328156 INFO (qtp440519233-3171) [ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true [junit4] 2> 328156 INFO (qtp440519233-3171) [ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard2 of collection1) have state: recovering [junit4] 2> 328157 INFO (qtp440519233-3171) [ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:42752_ail, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"https://127.0.0.1:42752/ail","node_name":"127.0.0.1:42752_ail","state":"down"} [junit4] 2> 328205 INFO (zkCallback-654-thread-1) [ ] 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: [4]) [junit4] 2> 328205 INFO (zkCallback-660-thread-1) [ ] 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: [4]) [junit4] 2> 328205 INFO (zkCallback-648-thread-1) [ ] 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: [4]) [junit4] 2> 328932 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/cores/collection1 [junit4] 2> 328933 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001 [junit4] 2> 328935 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 328936 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@32d82a59{/ail,null,AVAILABLE} [junit4] 2> 328937 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@382cf77d{SSL,[ssl, http/1.1]}{127.0.0.1:40490} [junit4] 2> 328937 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.Server Started @330788ms [junit4] 2> 328937 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ail, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/tempDir-001/jetty4, hostPort=40490, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/cores} [junit4] 2> 328938 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 328938 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0 [junit4] 2> 328938 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 328938 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 328938 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-19T14:07:07.139396Z [junit4] 2> 328940 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 328940 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/solr.xml [junit4] 2> 328945 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 328946 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44379/solr [junit4] 2> 328952 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 328954 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40490_ail [junit4] 2> 328955 INFO (zkCallback-642-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 328955 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 328955 INFO (zkCallback-638-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 328955 INFO (zkCallback-648-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 328955 INFO (zkCallback-654-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 328955 INFO (zkCallback-660-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 328965 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/cores [junit4] 2> 328965 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 328966 INFO (OverseerStateUpdate-96789202098978821-127.0.0.1:37526_ail-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 329068 INFO (zkCallback-667-thread-1) [ ] 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: [5]) [junit4] 2> 329068 INFO (zkCallback-654-thread-1) [ ] 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: [5]) [junit4] 2> 329068 INFO (zkCallback-648-thread-1) [ ] 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: [5]) [junit4] 2> 329068 INFO (zkCallback-660-thread-1) [ ] 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: [5]) [junit4] 2> 329157 INFO (qtp440519233-3171) [ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:42752_ail, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"https://127.0.0.1:42752/ail","node_name":"127.0.0.1:42752_ail","state":"recovering"} [junit4] 2> 329157 INFO (qtp440519233-3171) [ ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds. [junit4] 2> 329157 INFO (qtp440519233-3171) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:42752_ail&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=1001 [junit4] 2> 329974 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 329986 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 330062 WARN (coreLoadExecutor-1460-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 330065 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 330071 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 330071 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/cores/collection1/data/] [junit4] 2> 330071 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5140b497 [junit4] 2> 330072 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=11394835, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330075 WARN (coreLoadExecutor-1460-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 330083 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 330083 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 330085 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 330085 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 330085 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=28.103515625, floorSegmentMB=0.9404296875, forceMergeDeletesPctAllowed=23.104530208935095, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7008964594661039 [junit4] 2> 330086 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6715adc0[collection1] main] [junit4] 2> 330086 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 330086 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 330086 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 330087 INFO (searcherExecutor-1461-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6715adc0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 330087 INFO (coreLoadExecutor-1460-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548627243998117888 [junit4] 2> 330126 INFO (coreZkRegister-1455-thread-1) [ ] o.a.s.c.ZkController Core needs to recover:collection1 [junit4] 2> 330126 INFO (updateExecutor-664-thread-1) [ ] o.a.s.u.DefaultSolrCoreState Running recovery [junit4] 2> 330126 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true [junit4] 2> 330127 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]] [junit4] 2> 330127 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1] [junit4] 2> 330127 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 330127 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [https://127.0.0.1:41602/ail/collection1/] and I am [https://127.0.0.1:40490/ail/collection1/] [junit4] 2> 330129 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [https://127.0.0.1:41602/ail]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:40490_ail&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true] [junit4] 2> 330179 INFO (qtp400367745-3201) [ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true [junit4] 2> 330179 INFO (qtp400367745-3201) [ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering [junit4] 2> 330179 INFO (qtp400367745-3201) [ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:40490_ail, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{"core":"collection1","base_url":"https://127.0.0.1:40490/ail","node_name":"127.0.0.1:40490_ail","state":"down"} [junit4] 2> 330229 INFO (zkCallback-648-thread-1) [ ] 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: [5]) [junit4] 2> 330229 INFO (zkCallback-660-thread-1) [ ] 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: [5]) [junit4] 2> 330229 INFO (zkCallback-667-thread-1) [ ] 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: [5]) [junit4] 2> 330229 INFO (zkCallback-654-thread-1) [ ] 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: [5]) [junit4] 2> 330468 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 330514 INFO (qtp1321888958-3263) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&name=.system&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 330520 INFO (OverseerThreadFactory-1414-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection .system [junit4] 2> 330727 INFO (qtp1321888958-3264) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=.system&newCollection=true&name=.system_shard1_replica2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2 [junit4] 2> 330771 INFO (qtp1067003310-3229) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=.system&newCollection=true&name=.system_shard1_replica1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2 [junit4] 2> 330875 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 330875 INFO (zkCallback-660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 331179 INFO (qtp400367745-3201) [ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:40490_ail, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{"core":"collection1","base_url":"https://127.0.0.1:40490/ail","node_name":"127.0.0.1:40490_ail","state":"recovering"} [junit4] 2> 331179 INFO (qtp400367745-3201) [ ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node4, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds. [junit4] 2> 331179 INFO (qtp400367745-3201) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:40490_ail&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node4&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=1000 [junit4] 2> 331734 INFO (qtp1321888958-3264) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 331739 INFO (qtp1321888958-3264) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead [junit4] 2> 331741 INFO (qtp1321888958-3264) [ ] o.a.s.s.IndexSchema [.system_shard1_replica2] Schema name=_system collection or core [junit4] 2> 331743 INFO (qtp1321888958-3264) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 331744 INFO (qtp1321888958-3264) [ ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema [junit4] 2> 331746 INFO (qtp1321888958-3264) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak [junit4] 2> 331746 INFO (qtp1321888958-3264) [ ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica2' using configuration from collection .system [junit4] 2> 331746 INFO (qtp1321888958-3264) [ ] o.a.s.c.SolrCore [[.system_shard1_replica2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/cores/.system_shard1_replica2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-4-001/cores/.system_shard1_replica2/data/] [junit4] 2> 331752 INFO (qtp1321888958-3264) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 331752 INFO (qtp1321888958-3264) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 331758 INFO (qtp1321888958-3264) [ ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs [junit4] 2> 331758 INFO (qtp1321888958-3264) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 331758 INFO (qtp1321888958-3264) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@134ed5b7[.system_shard1_replica2] main] [junit4] 2> 331759 INFO (qtp1321888958-3264) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system [junit4] 2> 331759 INFO (qtp1321888958-3264) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system [junit4] 2> 331759 INFO (qtp1321888958-3264) [ ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema [junit4] 2> 331759 INFO (qtp1321888958-3264) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 331759 INFO (searcherExecutor-1466-thread-1) [ ] o.a.s.c.SolrCore [.system_shard1_replica2] Registered new searcher Searcher@134ed5b7[.system_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 331759 INFO (qtp1321888958-3264) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548627245751336960 [junit4] 2> 331763 INFO (qtp1321888958-3264) [ ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 331778 INFO (qtp1067003310-3229) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 331802 INFO (qtp1067003310-3229) [ ] o.a.s.s.IndexSchema [.system_shard1_replica1] Schema name=_system collection or core [junit4] 2> 331804 INFO (qtp1067003310-3229) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 331805 INFO (qtp1067003310-3229) [ ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica1' using configuration from collection .system [junit4] 2> 331806 INFO (qtp1067003310-3229) [ ] o.a.s.c.SolrCore [[.system_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/cores/.system_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001/shard-3-001/cores/.system_shard1_replica1/data/] [junit4] 2> 331812 INFO (qtp1067003310-3229) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 331812 INFO (qtp1067003310-3229) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 331815 INFO (qtp1067003310-3229) [ ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs [junit4] 2> 331815 INFO (qtp1067003310-3229) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 331815 INFO (qtp1067003310-3229) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@76dd53db[.system_shard1_replica1] main] [junit4] 2> 331816 INFO (qtp1067003310-3229) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system [junit4] 2> 331816 INFO (qtp1067003310-3229) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system [junit4] 2> 331816 INFO (qtp1067003310-3229) [ ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema [junit4] 2> 331816 INFO (qtp1067003310-3229) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 331817 INFO (searcherExecutor-1471-thread-1) [ ] o.a.s.c.SolrCore [.system_shard1_replica1] Registered new searcher Searcher@76dd53db[.system_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 331817 INFO (qtp1067003310-3229) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548627245812154368 [junit4] 2> 331865 INFO (zkCallback-660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 331865 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 332264 INFO (qtp1321888958-3264) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 332264 INFO (qtp1321888958-3264) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 332264 INFO (qtp1321888958-3264) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40490/ail/.system_shard1_replica2/ [junit4] 2> 332264 INFO (qtp1321888958-3264) [ ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica2 url=https://127.0.0.1:40490/ail START replicas=[https://127.0.0.1:42752/ail/.system_shard1_replica1/] nUpdates=100 [junit4] 2> 332264 INFO (qtp1321888958-3264) [ ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica2 url=https://127.0.0.1:40490/ail DONE. We have no versions. sync failed. [junit4] 2> 332269 INFO (qtp1067003310-3230) [ ] o.a.s.c.S.Request [.system_shard1_replica1] webapp=/ail path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 332269 INFO (qtp1321888958-3264) [ ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 332269 INFO (qtp1321888958-3264) [ ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 332271 INFO (qtp1321888958-3264) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40490/ail/.system_shard1_replica2/ shard1 [junit4] 2> 332372 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 332372 INFO (zkCallback-660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 332421 INFO (qtp1321888958-3264) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 332423 INFO (qtp1321888958-3264) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=.system&newCollection=true&name=.system_shard1_replica2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2} status=0 QTime=1696 [junit4] 2> 332523 INFO (zkCallback-660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 332524 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 332822 INFO (qtp1067003310-3229) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=.system&newCollection=true&name=.system_shard1_replica1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2} status=0 QTime=2051 [junit4] 2> 332824 INFO (qtp1321888958-3263) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 332922 INFO (zkCallback-660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 332922 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [5]) [junit4] 2> 333824 INFO (qtp1321888958-3263) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&name=.system&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=3309 [junit4] 2> 333824 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: .system failOnTimeout:true timeout (sec):330 [junit4] 1> - [junit4] 1> replica:core_node1 rstate:active live:true [junit4] 1> replica:core_node2 rstate:active live:true [junit4] 1> no one is recoverying [junit4] 2> 333825 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: .system [junit4] 2> 333834 INFO (qtp1321888958-3265) [ ] o.a.s.h.BlobHandler inserting new blob colltest/1 ,size 1223, md5 e00333bbc351d96f3b026581bf56dc81 [junit4] 2> 333835 INFO (qtp1321888958-3265) [ ] o.a.s.h.BlobHandler Adding doc: {id=colltest/1, md5=e00333bbc351d96f3b026581bf56dc81, blobName=colltest, version=1, timestamp=Wed Oct 19 17:07:12 EEST 2016, size=1223, blob=java.nio.HeapByteBuffer[pos=0 lim=1223 cap=2048]} [junit4] 2> 333836 INFO (qtp1321888958-3265) [ ] o.a.s.h.BlobHandler committing doc: {id=colltest/1, md5=e00333bbc351d96f3b026581bf56dc81, blobName=colltest, version=1, timestamp=Wed Oct 19 17:07:12 EEST 2016, size=1223, blob=java.nio.HeapByteBuffer[pos=0 lim=1223 cap=2048]} [junit4] 2> 333840 INFO (qtp1067003310-3235) [ ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica1] webapp=/ail path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:40490/ail/.system_shard1_replica2/&wt=javabin&version=2}{add=[colltest/1 (1548627247928180736)]} 0 1 [junit4] 2> 333842 INFO (qtp1321888958-3266) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 333842 INFO (qtp1321888958-3266) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a8d053b [junit4] 2> 333843 INFO (qtp1067003310-3230) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 333843 INFO (qtp1067003310-3230) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@c57fc45 [junit4] 2> 333848 INFO (qtp1321888958-3266) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ed2b146[.system_shard1_replica2] main] [junit4] 2> 333848 INFO (qtp1067003310-3230) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@33e46e91[.system_shard1_replica1] main] [junit4] 2> 333848 INFO (searcherExecutor-1471-thread-1) [ ] o.a.s.c.SolrCore [.system_shard1_replica1] Registered new searcher Se [...truncated too long message...] ut we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 339151 INFO (zkCallback-660-thread-2) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@79981799 [junit4] 2> 339664 WARN (zkCallback-660-thread-3) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 339665 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@4ae513af{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 339666 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@8ba1919{/ail,null,UNAVAILABLE} [junit4] 2> 339666 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 40490 [junit4] 2> 339666 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1019295375 [junit4] 2> 339666 WARN (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[collection1] coreNodeName=[core_node4] [junit4] 2> 339667 INFO (coreCloseExecutor-1542-thread-2) [ ] o.a.s.c.SolrCore [.system_shard1_replica2] CLOSING SolrCore org.apache.solr.core.SolrCore@d7be7b4 [junit4] 2> 339669 WARN (updateExecutor-664-thread-2) [ ] o.a.s.u.DefaultSolrCoreState Skipping recovery because Solr is shutdown [junit4] 2> 339669 INFO (zkCallback-667-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1]) [junit4] 2> 339771 INFO (zkCallback-667-thread-2) [ ] 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: [1]) [junit4] 2> 341663 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@667a65ff [junit4] 2> 341663 INFO (zkCallback-667-thread-2) [ ] 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: [1]) [junit4] 2> 343181 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy RecoveryStrategy has been closed [junit4] 2> 343181 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy Finished recovery process, successful=[false] [junit4] 2> 343181 INFO (recoveryExecutor-665-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3268adc5 [junit4] 2> 343182 WARN (recoveryExecutor-665-thread-1) [ ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[collection1] coreNodeName=[core_node4] [junit4] 2> 343183 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.Overseer Overseer (id=96789202098978835-127.0.0.1:40490_ail-n_0000000004) closing [junit4] 2> 343183 INFO (OverseerStateUpdate-96789202098978835-127.0.0.1:40490_ail-n_0000000004) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:40490_ail [junit4] 2> 343184 WARN (zkCallback-667-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 343185 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@382cf77d{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 343185 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@32d82a59{/ail,null,UNAVAILABLE} [junit4] 2> 343186 INFO (TEST-TestNamedUpdateProcessors.test-seed#[D07F08A45427B5FE]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44379 44379 [junit4] 2> 343228 INFO (Thread-570) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44379 44379 [junit4] 2> 343228 WARN (Thread-570) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 15 /solr/configs/conf1 [junit4] 2> 6 /solr/aliases.json [junit4] 2> 5 /solr/security.json [junit4] 2> 2 /solr/configs/.system [junit4] 2> 2 /solr/configs/.system/managed-schema [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> 2 /solr/collections/.system/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 33 /solr/overseer/queue [junit4] 2> 19 /solr/overseer/collection-queue-work [junit4] 2> 16 /solr/overseer/queue-work [junit4] 2> 6 /solr/live_nodes [junit4] 2> 6 /solr/collections [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNamedUpdateProcessors -Dtests.method=test -Dtests.seed=D07F08A45427B5FE -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=vo-001 -Dtests.timezone=Europe/Tiraspol -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 23.1s J2 | TestNamedUpdateProcessors.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40490/ail/collection1: error instantiating class :org.apache.solr.update.processor.RuntimeUrp [junit4] > at __randomizedtesting.SeedInfo.seed([D07F08A45427B5FE:582B377EFADBD806]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:593) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.update.processor.TestNamedUpdateProcessors.test(TestNamedUpdateProcessors.java:125) [junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method) [junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62) [junit4] > at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) [junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843) [junit4] 2> 343231 INFO (SUITE-TestNamedUpdateProcessors-seed#[D07F08A45427B5FE]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_D07F08A45427B5FE-001 [junit4] 2> Oct 19, 2016 2:07:21 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {blobName=PostingsFormat(name=Memory doPackFST= true), range_facet_l_dv=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=Memory doPackFST= true), intDefault=FSTOrd50, version=PostingsFormat(name=Memory doPackFST= true), mul_s=PostingsFormat(name=Memory doPackFST= true), range_facet_l=FSTOrd50, test_s=FSTOrd50, size=PostingsFormat(name=LuceneFixedGap), _version_=FSTOrd50, id=PostingsFormat(name=Asserting), range_facet_i_dv=FSTOrd50, md5=FSTOrd50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=905, maxMBSortInHeap=5.161555832185528, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=vo-001, timezone=Europe/Tiraspol [junit4] 2> NOTE: Linux 4.4.0-42-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=271762888,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [TestIndexingPerformance, CleanupOldIndexTest, TestLFUCache, TestBinaryField, TestTolerantSearch, TestSolrQueryParserDefaultOperatorResource, TestReplicaProperties, TestFieldTypeResource, SuggesterTSTTest, TestSortByMinMaxFunction, DocValuesMissingTest, TestSmileRequest, DistribCursorPagingTest, TestSolrCoreSnapshots, HdfsDirectoryFactoryTest, TestXmlQParser, TestCryptoKeys, HdfsRecoverLeaseTest, DistanceUnitsTest, TestIndexSearcher, UpdateParamsTest, TestClusterStateMutator, TestCustomSort, QueryParsingTest, BooleanFieldTest, ConnectionManagerTest, SuggesterWFSTTest, OverseerTest, HdfsSyncSliceTest, OpenExchangeRatesOrgProviderTest, TestDistribIDF, BadComponentTest, StatsReloadRaceTest, HdfsUnloadDistributedZkTest, MultiThreadedOCPTest, TestBulkSchemaAPI, CurrencyFieldXmlFileTest, CheckHdfsIndexTest, SOLR749Test, TestBinaryResponseWriter, TestNamedUpdateProcessors] [junit4] Completed [131/641 (1!)] on J2 in 23.86s, 1 test, 1 error <<< FAILURES! [...truncated 51560 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
