This is LUCENE-8012 I think, digging now. > On 2 Jan 2018, at 13:20, Policeman Jenkins Server <jenk...@thetaphi.de> wrote: > > Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4360/ > Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC > > 2 tests failed. > FAILED: > org.apache.solr.ltr.TestLTRQParserExplain.testRerankedExplainSameBetweenDifferentDocsWithSameFeatures > > Error Message: > mismatch: ' 3.5116758 = > LinearModel(name=6029760550880411648,featureWeights=[title=0.0,description=0.1,keywords=0.2,popularity=0.3,text=0.4,queryIntentPerson=0.1231231,queryIntentCompany=0.12121211]) > model applied to features, sum of: 0.0 = prod of: 0.0 = weight on > feature 1.0 = ValueFeature [name=title, params={value=1}] 0.2 = prod > of: 0.1 = weight on feature 2.0 = ValueFeature [name=description, > params={value=2}] 0.4 = prod of: 0.2 = weight on feature 2.0 = > ValueFeature [name=keywords, params={value=2}] 0.09 = prod of: 0.3 = > weight on feature 0.3 = normalized using > MinMaxNormalizer(min=0.0,max=10.0) 3.0 = ValueFeature [name=popularity, > params={value=3}] 1.6 = prod of: 0.4 = weight on feature 4.0 = > ValueFeature [name=text, params={value=4}] 0.6156155 = prod of: > 0.1231231 = weight on feature 5.0 = ValueFeature [name=queryIntentPerson, > params={value=5}] 0.60606056 = prod of: 0.12121211 = weight on feature > 5.0 = ValueFeature [name=queryIntentCompany, params={value=5}] '!=' > 3.5116758 = > LinearModel(name=6029760550880411648,featureWeights=[title=0.0,description=0.1,keywords=0.2,popularity=0.3,text=0.4,queryIntentPerson=0.1231231,queryIntentCompany=0.12121211]) > model applied to features, sum of: 0.0 = prod of: 0.0 = weight on > feature 1.0 = ValueFeature [name=title, params={value=1}] > 0.20000000298023224 = prod of: 0.1 = weight on feature 2.0 = > ValueFeature [name=description, params={value=2}] 0.4000000059604645 = prod > of: 0.2 = weight on feature 2.0 = ValueFeature [name=keywords, > params={value=2}] 0.09000000715255752 = prod of: 0.3 = weight on > feature 0.3 = normalized using MinMaxNormalizer(min=0.0,max=10.0) > 3.0 = ValueFeature [name=popularity, params={value=3}] 1.600000023841858 = > prod of: 0.4 = weight on feature 4.0 = ValueFeature [name=text, > params={value=4}] 0.6156155094504356 = prod of: 0.1231231 = weight on > feature 5.0 = ValueFeature [name=queryIntentPerson, params={value=5}] > 0.6060605496168137 = prod of: 0.12121211 = weight on feature 5.0 = > ValueFeature [name=queryIntentCompany, params={value=5}] ' @ debug/explain/7 > > Stack Trace: > java.lang.RuntimeException: mismatch: ' > 3.5116758 = > LinearModel(name=6029760550880411648,featureWeights=[title=0.0,description=0.1,keywords=0.2,popularity=0.3,text=0.4,queryIntentPerson=0.1231231,queryIntentCompany=0.12121211]) > model applied to features, sum of: > 0.0 = prod of: > 0.0 = weight on feature > 1.0 = ValueFeature [name=title, params={value=1}] > 0.2 = prod of: > 0.1 = weight on feature > 2.0 = ValueFeature [name=description, params={value=2}] > 0.4 = prod of: > 0.2 = weight on feature > 2.0 = ValueFeature [name=keywords, params={value=2}] > 0.09 = prod of: > 0.3 = weight on feature > 0.3 = normalized using MinMaxNormalizer(min=0.0,max=10.0) > 3.0 = ValueFeature [name=popularity, params={value=3}] > 1.6 = prod of: > 0.4 = weight on feature > 4.0 = ValueFeature [name=text, params={value=4}] > 0.6156155 = prod of: > 0.1231231 = weight on feature > 5.0 = ValueFeature [name=queryIntentPerson, params={value=5}] > 0.60606056 = prod of: > 0.12121211 = weight on feature > 5.0 = ValueFeature [name=queryIntentCompany, params={value=5}] > '!=' > 3.5116758 = > LinearModel(name=6029760550880411648,featureWeights=[title=0.0,description=0.1,keywords=0.2,popularity=0.3,text=0.4,queryIntentPerson=0.1231231,queryIntentCompany=0.12121211]) > model applied to features, sum of: > 0.0 = prod of: > 0.0 = weight on feature > 1.0 = ValueFeature [name=title, params={value=1}] > 0.20000000298023224 = prod of: > 0.1 = weight on feature > 2.0 = ValueFeature [name=description, params={value=2}] > 0.4000000059604645 = prod of: > 0.2 = weight on feature > 2.0 = ValueFeature [name=keywords, params={value=2}] > 0.09000000715255752 = prod of: > 0.3 = weight on feature > 0.3 = normalized using MinMaxNormalizer(min=0.0,max=10.0) > 3.0 = ValueFeature [name=popularity, params={value=3}] > 1.600000023841858 = prod of: > 0.4 = weight on feature > 4.0 = ValueFeature [name=text, params={value=4}] > 0.6156155094504356 = prod of: > 0.1231231 = weight on feature > 5.0 = ValueFeature [name=queryIntentPerson, params={value=5}] > 0.6060605496168137 = prod of: > 0.12121211 = weight on feature > 5.0 = ValueFeature [name=queryIntentCompany, params={value=5}] > ' @ debug/explain/7 > at > __randomizedtesting.SeedInfo.seed([BF50F24C695B12A1:1B79AC8849EBA0FF]:0) > at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248) > at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192) > at > org.apache.solr.ltr.TestLTRQParserExplain.testRerankedExplainSameBetweenDifferentDocsWithSameFeatures(TestLTRQParserExplain.java:80) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:498) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) > 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:368) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) > 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:368) > at java.lang.Thread.run(Thread.java:748) > > > FAILED: > org.apache.solr.cloud.autoscaling.sim.TestExecutePlanAction.testIntegration > > Error Message: > > > Stack Trace: > java.util.ConcurrentModificationException > at > __randomizedtesting.SeedInfo.seed([DB943222460F3A98:6BF53C0E63309BBD]:0) > at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901) > at java.util.ArrayList$Itr.next(ArrayList.java:851) > at > org.apache.solr.cloud.autoscaling.sim.SimSolrCloudTestCase.tearDown(SimSolrCloudTestCase.java:141) > at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:498) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) > 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:368) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) > 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:368) > at java.lang.Thread.run(Thread.java:748) > > > > > Build Log: > [...truncated 12169 lines...] > [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestExecutePlanAction > [junit4] 2> Creating dataDir: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.sim.TestExecutePlanAction_DB943222460F3A98-001/init-core-data-001 > [junit4] 2> 673723 WARN > (SUITE-TestExecutePlanAction-seed#[DB943222460F3A98]-worker) [ ] > o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4 > [junit4] 2> 673723 INFO > (SUITE-TestExecutePlanAction-seed#[DB943222460F3A98]-worker) [ ] > o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) > w/NUMERIC_DOCVALUES_SYSPROP=true > [junit4] 2> 673726 INFO > (SUITE-TestExecutePlanAction-seed#[DB943222460F3A98]-worker) [ ] > o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: > @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, > clientAuth=NaN) w/ MAC_OS_X supressed clientAuth > [junit4] 2> 673726 INFO > (SUITE-TestExecutePlanAction-seed#[DB943222460F3A98]-worker) [ ] > o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: > test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom > [junit4] 2> 673745 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger > [junit4] 2> 673745 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 0 > [junit4] 2> 673745 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr] > [junit4] 2> 673745 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1 > [junit4] 2> 673745 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 0 > [junit4] 2> 673747 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.SolrTestCaseJ4 ###Starting testExecute > [junit4] 2> 673747 DEBUG > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 1 > [junit4] 2> 673748 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing > object cache... > [junit4] 2> 673749 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers > [junit4] 2> 673749 DEBUG > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed > explicitly > [junit4] 2> 673749 WARN (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Interrupted > [junit4] 2> java.lang.InterruptedException > [junit4] 2> at > java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) > [junit4] 2> at > java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335) > [junit4] 2> at > org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:154) > [junit4] 2> at java.lang.Thread.run(Thread.java:748) > [junit4] 2> 673750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger > [junit4] 2> 673750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 2 > [junit4] 2> 673750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr, > 127.0.0.1:10000_solr] > [junit4] 2> 673750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1 > [junit4] 2> 673750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 2 > [junit4] 2> 673750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers > [junit4] 2> 673750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 > [junit4] 2> 673751 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673772 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673796 DEBUG (ScheduledTrigger-2534-thread-2) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673816 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673837 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673860 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673863 DEBUG (simCloudManagerPool-2533-thread-1) [ ] > o.a.s.c.o.ClusterStateMutator building a new cName: testExecute > [junit4] 2> 673876 DEBUG (simCloudManagerPool-2533-thread-1) [ ] > o.a.s.c.Assign Identify nodes using default > [junit4] 2> 673890 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673909 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction Collection ready after 1372ms > [junit4] 2> 673913 DEBUG > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.ExecutePlanAction -- processing event: { > [junit4] 2> "id":"1505fce86a798140T33nqmts7exph1isszkrommojw", > [junit4] 2> "source":"mock_trigger_name", > [junit4] 2> "eventTime":1514894924829000000, > [junit4] 2> "eventType":"NODELOST", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[1514894924829000000], > [junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}} with context > properties: > {operations=[org.apache.solr.client.solrj.request.CollectionAdminRequest$MoveReplica@61774019, > org.apache.solr.cloud.autoscaling.sim.TestExecutePlanAction$1@287f10fb]} > [junit4] 2> 673913 DEBUG > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.ExecutePlanAction Executing operation: > action=MOVEREPLICA&collection=testExecute&targetNode=127.0.0.1:10000_solr&inPlaceMove=true&replica=core_node1 > [junit4] 2> 673915 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673945 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 673975 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674006 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674017 DEBUG (simCloudManagerPool-2533-thread-4) [ ] > o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node4":{ > [junit4] 2> "core":"testExecute_shard1_replica_n3", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"testExecute", > [junit4] 2> "node_name":"127.0.0.1:10000_solr", > [junit4] 2> "type":"NRT"}} > [junit4] 2> 674036 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674066 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674094 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674117 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674138 DEBUG (ScheduledTrigger-2534-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674141 DEBUG (simCloudManagerPool-2533-thread-5) [ ] > o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testExecute / > shard1: {"core_node2":{ > [junit4] 2> "node_name":"127.0.0.1:10000_solr", > [junit4] 2> "core":"testExecute_shard1_replica_n2", > [junit4] 2> "leader":"true", > [junit4] 2> "state":"active", > [junit4] 2> "type":"NRT", > [junit4] 2> "INDEX.sizeInBytes":123450000, > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"testExecute"}} > [junit4] 2> 674141 DEBUG > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.ExecutePlanAction Executing operation: action=OVERSEERSTATUS > [junit4] 2> 674148 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction Collection ready after 6ms > [junit4] 2> 674148 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction -------------_ FINAL STATE -------------- > [junit4] 2> 674148 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction * Node values: { > [junit4] 2> "127.0.0.1:10001_solr":{ > [junit4] 2> "sysprop.java.vendor":"Oracle Corporation", > [junit4] 2> "cores":0, > [junit4] 2> "host":"127.0.0.1", > [junit4] 2> "freedisk":1000, > [junit4] 2> "sysprop.java.version":"1.8.0_144", > [junit4] 2> "ip_1":"127", > [junit4] 2> "ip_3":"0", > [junit4] 2> "ip_2":"0", > [junit4] 2> "ip_4":"1", > [junit4] 2> "node":"127.0.0.1:10001_solr", > [junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0, > [junit4] 2> "port":10001, > [junit4] 2> "heapUsage":123450000, > [junit4] 2> "sysLoadAvg":1.0, > [junit4] 2> > "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0}, > [junit4] 2> "127.0.0.1:10000_solr":{ > [junit4] 2> "sysprop.java.vendor":"Oracle Corporation", > [junit4] 2> "cores":2, > [junit4] 2> "host":"127.0.0.1", > [junit4] 2> "freedisk":980, > [junit4] 2> "sysprop.java.version":"1.8.0_144", > [junit4] 2> "ip_1":"127", > [junit4] 2> "ip_3":"0", > [junit4] 2> "ip_2":"0", > [junit4] 2> "ip_4":"1", > [junit4] 2> "node":"127.0.0.1:10000_solr", > [junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0, > [junit4] 2> "port":10000, > [junit4] 2> "heapUsage":123450000, > [junit4] 2> "sysLoadAvg":1.0, > [junit4] 2> > "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0}} > [junit4] 2> 674148 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction * Live nodes: [127.0.0.1:10001_solr, > 127.0.0.1:10000_solr] > [junit4] 2> 674149 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction * Collection testExecute state: > DocCollection(testExecute//clusterstate.json/3)={ > [junit4] 2> "replicationFactor":"2", > [junit4] 2> "pullReplicas":"0", > [junit4] 2> "router":{"name":"compositeId"}, > [junit4] 2> "maxShardsPerNode":"1", > [junit4] 2> "autoAddReplicas":"false", > [junit4] 2> "nrtReplicas":"2", > [junit4] 2> "tlogReplicas":"0", > [junit4] 2> "autoCreated":"true", > [junit4] 2> "shards":{"shard1":{ > [junit4] 2> "replicas":{ > [junit4] 2> "core_node2":{ > [junit4] 2> "core":"testExecute_shard1_replica_n2", > [junit4] 2> "leader":"true", > [junit4] 2> "INDEX.sizeInBytes":123450000, > [junit4] 2> "node_name":"127.0.0.1:10000_solr", > [junit4] 2> "state":"active", > [junit4] 2> "type":"NRT"}, > [junit4] 2> "core_node4":{ > [junit4] 2> "node_name":"127.0.0.1:10000_solr", > [junit4] 2> "core":"testExecute_shard1_replica_n3", > [junit4] 2> "state":"active", > [junit4] 2> "INDEX.sizeInBytes":123450000, > [junit4] 2> "type":"NRT"}}, > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active"}}} > [junit4] 2> 674149 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.SolrTestCaseJ4 ###Ending testExecute > [junit4] 2> 674149 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase > [junit4] 2> > [junit4] 2> 674149 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ############################################# > [junit4] 2> 674149 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############ > [junit4] 2> 674149 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ############################################# > [junit4] 2> > [junit4] 2> 674149 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 2 > [junit4] 2> 674150 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 1 > [junit4] 2> 674150 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0 > [junit4] 2> 674150 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [testExecute] > [junit4] 2> 674150 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 2 > [junit4] 2> 674151 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0 > [junit4] 2> 674151 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 2 > [junit4] 2> 674151 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## * testExecute 2 > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - active 2 > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ########## > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1 > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - DELETESTATUS 2 > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - MOVEREPLICA 1 > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - OVERSEERSTATUS 1 > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - REQUESTSTATUS 2 > [junit4] 2> 674152 INFO > (TEST-TestExecutePlanAction.testExecute-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts > ########### > [junit4] 2> 674156 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.SolrTestCaseJ4 ###Starting testIntegration > [junit4] 2> 674156 DEBUG > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 3 > [junit4] 2> 674156 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 3 > [junit4] 2> 674156 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing > object cache... > [junit4] 2> 674156 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers > [junit4] 2> 674157 DEBUG > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed > explicitly > [junit4] 2> 674157 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers > [junit4] 2> 674157 WARN (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Interrupted > [junit4] 2> java.lang.InterruptedException > [junit4] 2> at > java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) > [junit4] 2> at > java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335) > [junit4] 2> at > org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:154) > [junit4] 2> at java.lang.Thread.run(Thread.java:748) > [junit4] 2> 674158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger > [junit4] 2> 674158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 4 > [junit4] 2> 674158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr, > 127.0.0.1:10000_solr] > [junit4] 2> 674158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion -1 > [junit4] 2> 674158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 4 > [junit4] 2> 674158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers > [junit4] 2> 674158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 > [junit4] 2> 674159 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674189 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674219 DEBUG (ScheduledTrigger-2537-thread-2) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674246 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674268 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674288 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674309 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674330 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674335 DEBUG (simCloudManagerPool-2536-thread-1) [ ] > o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration > [junit4] 2> 674335 DEBUG (simCloudManagerPool-2536-thread-1) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 5 > [junit4] 2> 674336 DEBUG (simCloudManagerPool-2536-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr, > 127.0.0.1:10000_solr] > [junit4] 2> 674336 DEBUG (simCloudManagerPool-2536-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr, > 127.0.0.1:10000_solr] > [junit4] 2> 674336 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 5 > [junit4] 2> 674336 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers > [junit4] 2> 674336 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 > [junit4] 2> 674336 DEBUG (simCloudManagerPool-2536-thread-2) [ ] > o.a.s.c.o.ClusterStateMutator building a new cName: testIntegration > [junit4] 2> 674336 DEBUG (ScheduledTrigger-2537-thread-4) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with > currently live nodes: 2 > [junit4] 2> 674350 DEBUG (ScheduledTrigger-2537-thread-3) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674350 DEBUG (simCloudManagerPool-2536-thread-2) [ ] > o.a.s.c.Assign Identify nodes using default > [junit4] 2> 674360 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with > currently live nodes: 2 > [junit4] 2> 674372 DEBUG (ScheduledTrigger-2537-thread-2) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 2 > [junit4] 2> 674382 DEBUG (ScheduledTrigger-2537-thread-4) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with > currently live nodes: 2 > [junit4] 2> 674384 DEBUG (simCloudManagerPool-2536-thread-5) [ ] > o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testIntegration > / shard1: {"core_node1":{ > [junit4] 2> "node_name":"127.0.0.1:10001_solr", > [junit4] 2> "core":"testIntegration_shard1_replica_n1", > [junit4] 2> "leader":"true", > [junit4] 2> "state":"active", > [junit4] 2> "type":"NRT", > [junit4] 2> "INDEX.sizeInBytes":123450000, > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"testIntegration"}} > [junit4] 2> 674392 DEBUG (ScheduledTrigger-2537-thread-3) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 1 > [junit4] 2> 674392 DEBUG (ScheduledTrigger-2537-thread-3) [ ] > o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10000_solr > [junit4] 2> 674403 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with > currently live nodes: 1 > [junit4] 2> 674403 DEBUG (ScheduledTrigger-2537-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10000_solr > [junit4] 2> 674413 DEBUG (ScheduledTrigger-2537-thread-2) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 1 > [junit4] 2> 674428 DEBUG (ScheduledTrigger-2537-thread-4) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with > currently live nodes: 1 > [junit4] 2> 674428 DEBUG (ScheduledTrigger-2537-thread-4) [ ] > o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for > lost nodes: [127.0.0.1:10000_solr] > [junit4] 2> 674429 DEBUG (ScheduledTrigger-2537-thread-4) [ ] > o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { > [junit4] 2> "id":"13f7ce395121f4T33nqmts7exph1isszkrommojx", > [junit4] 2> "source":"node_lost_trigger", > [junit4] 2> "eventTime":5620489654444532, > [junit4] 2> "eventType":"NODELOST", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[5620489654444532], > [junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}} > [junit4] 2> 674429 DEBUG (AutoscalingActionExecutor-2538-thread-1) [ ] > o.a.s.c.a.ScheduledTriggers -- processing actions for { > [junit4] 2> "id":"13f7ce395121f4T33nqmts7exph1isszkrommojx", > [junit4] 2> "source":"node_lost_trigger", > [junit4] 2> "eventTime":5620489654444532, > [junit4] 2> "eventType":"NODELOST", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[5620489654444532], > [junit4] 2> "_enqueue_time_":5620490924258232, > [junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}} > [junit4] 2> 674429 DEBUG (AutoscalingActionExecutor-2538-thread-1) [ ] > o.a.s.c.a.ComputePlanAction -- processing event: { > [junit4] 2> "id":"13f7ce395121f4T33nqmts7exph1isszkrommojx", > [junit4] 2> "source":"node_lost_trigger", > [junit4] 2> "eventTime":5620489654444532, > [junit4] 2> "eventType":"NODELOST", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[5620489654444532], > [junit4] 2> "_enqueue_time_":5620490924258232, > [junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}} with context > properties: {BEFORE_ACTION=[compute_plan]} > [junit4] 2> 674431 INFO (AutoscalingActionExecutor-2538-thread-1) [ ] > o.a.s.c.a.ComputePlanAction Computed Plan: > action=MOVEREPLICA&collection=testIntegration&targetNode=127.0.0.1:10001_solr&inPlaceMove=true&replica=core_node2 > [junit4] 2> 674432 INFO (AutoscalingActionExecutor-2538-thread-1) [ ] > o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 5620491088 > sessionWrapper.createTime 5620491008758182, this.sessionWrapper.createTime > 5620491008758182 > [junit4] 2> 674432 DEBUG (AutoscalingActionExecutor-2538-thread-1) [ ] > o.a.s.c.a.ExecutePlanAction -- processing event: { > [junit4] 2> "id":"13f7ce395121f4T33nqmts7exph1isszkrommojx", > [junit4] 2> "source":"node_lost_trigger", > [junit4] 2> "eventTime":5620489654444532, > [junit4] 2> "eventType":"NODELOST", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[5620489654444532], > [junit4] 2> "_enqueue_time_":5620490924258232, > [junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}} with context > properties: > {operations=[org.apache.solr.client.solrj.request.CollectionAdminRequest$MoveReplica@1c4d6e44], > AFTER_ACTION=[compute_plan], BEFORE_ACTION=[compute_plan, execute_plan]} > [junit4] 2> 674432 DEBUG (AutoscalingActionExecutor-2538-thread-1) [ ] > o.a.s.c.a.ExecutePlanAction Executing operation: > action=MOVEREPLICA&collection=testIntegration&targetNode=127.0.0.1:10001_solr&inPlaceMove=true&replica=core_node2 > [junit4] 2> 674533 DEBUG (simCloudManagerPool-2536-thread-7) [ ] > o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node4":{ > [junit4] 2> "core":"testIntegration_shard1_replica_n3", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"testIntegration", > [junit4] 2> "node_name":"127.0.0.1:10001_solr", > [junit4] 2> "type":"NRT"}} > [junit4] 2> 674650 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction -------------_ FINAL STATE -------------- > [junit4] 2> 674650 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction * Node values: { > [junit4] 2> "127.0.0.1:10001_solr":{ > [junit4] 2> "sysprop.java.vendor":"Oracle Corporation", > [junit4] 2> "cores":2, > [junit4] 2> "host":"127.0.0.1", > [junit4] 2> "freedisk":980, > [junit4] 2> "sysprop.java.version":"1.8.0_144", > [junit4] 2> "ip_1":"127", > [junit4] 2> "ip_3":"0", > [junit4] 2> "ip_2":"0", > [junit4] 2> "ip_4":"1", > [junit4] 2> "node":"127.0.0.1:10001_solr", > [junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0, > [junit4] 2> "port":10001, > [junit4] 2> "heapUsage":123450000, > [junit4] 2> "sysLoadAvg":1.0, > [junit4] 2> > "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0}, > [junit4] 2> "127.0.0.1:10000_solr":{ > [junit4] 2> "sysprop.java.vendor":"Oracle Corporation", > [junit4] 2> "cores":1, > [junit4] 2> "host":"127.0.0.1", > [junit4] 2> "freedisk":970, > [junit4] 2> "sysprop.java.version":"1.8.0_144", > [junit4] 2> "ip_1":"127", > [junit4] 2> "ip_3":"0", > [junit4] 2> "ip_2":"0", > [junit4] 2> "ip_4":"1", > [junit4] 2> "node":"127.0.0.1:10000_solr", > [junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0, > [junit4] 2> "port":10000, > [junit4] 2> "heapUsage":123450000, > [junit4] 2> "sysLoadAvg":1.0, > [junit4] 2> > "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0}} > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction * Live nodes: [127.0.0.1:10001_solr] > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.TestExecutePlanAction * Collection testIntegration state: > DocCollection(testIntegration//clusterstate.json/10)={ > [junit4] 2> "replicationFactor":"2", > [junit4] 2> "pullReplicas":"0", > [junit4] 2> "router":{"name":"compositeId"}, > [junit4] 2> "maxShardsPerNode":"1", > [junit4] 2> "autoAddReplicas":"false", > [junit4] 2> "nrtReplicas":"2", > [junit4] 2> "tlogReplicas":"0", > [junit4] 2> "autoCreated":"true", > [junit4] 2> "shards":{"shard1":{ > [junit4] 2> "replicas":{ > [junit4] 2> "core_node1":{ > [junit4] 2> "core":"testIntegration_shard1_replica_n1", > [junit4] 2> "leader":"true", > [junit4] 2> "INDEX.sizeInBytes":123450000, > [junit4] 2> "node_name":"127.0.0.1:10001_solr", > [junit4] 2> "state":"active", > [junit4] 2> "type":"NRT"}, > [junit4] 2> "core_node4":{ > [junit4] 2> "node_name":"127.0.0.1:10001_solr", > [junit4] 2> "core":"testIntegration_shard1_replica_n3", > [junit4] 2> "state":"active", > [junit4] 2> "INDEX.sizeInBytes":123450000, > [junit4] 2> "type":"NRT"}}, > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active"}}} > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.SolrTestCaseJ4 ###Ending testIntegration > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase > [junit4] 2> > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ############################################# > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############ > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ############################################# > [junit4] 2> > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 0 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## 127.0.0.1:10000_solr > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [testIntegration] > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 2 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 2 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 2 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## * testIntegration 2 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - active 2 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ########## > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - DELETESTATUS 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - MOVEREPLICA 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - REQUESTSTATUS 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ## - update 1 > [junit4] 2> 674651 INFO > (TEST-TestExecutePlanAction.testIntegration-seed#[DB943222460F3A98]) [ ] > o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts > ########### > [junit4] 2> 674651 DEBUG (AutoscalingActionExecutor-2538-thread-1) [ ] > o.a.s.c.a.ScheduledTriggers -- processing took 11101 ms for event > id=13f7ce395121f4T33nqmts7exph1isszkrommojx > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=TestExecutePlanAction -Dtests.method=testIntegration > -Dtests.seed=DB943222460F3A98 -Dtests.slow=true -Dtests.locale=sv > -Dtests.timezone=Africa/Porto-Novo -Dtests.asserts=true > -Dtests.file.encoding=US-ASCII > [junit4] ERROR 0.50s J0 | TestExecutePlanAction.testIntegration <<< > [junit4] > Throwable #1: java.util.ConcurrentModificationException > [junit4] > at > __randomizedtesting.SeedInfo.seed([DB943222460F3A98:6BF53C0E63309BBD]:0) > [junit4] > at > java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901) > [junit4] > at java.util.ArrayList$Itr.next(ArrayList.java:851) > [junit4] > at > org.apache.solr.cloud.autoscaling.sim.SimSolrCloudTestCase.tearDown(SimSolrCloudTestCase.java:141) > [junit4] > at java.lang.Thread.run(Thread.java:748) > [junit4] 2> 674654 WARN (Simulated OverseerAutoScalingTriggerThread) [ > ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are > closed, exiting. > [junit4] 2> 674654 DEBUG > (SUITE-TestExecutePlanAction-seed#[DB943222460F3A98]-worker) [ ] > o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed > explicitly > [junit4] 2> NOTE: leaving temporary files on disk at: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.sim.TestExecutePlanAction_DB943222460F3A98-001 > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, > docValues:{}, maxPointsInLeafNode=839, maxMBSortInHeap=5.924442087858636, > sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6fef801c), > locale=sv, timezone=Africa/Porto-Novo > [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 > (64-bit)/cpus=3,threads=1,free=218802608,total=536870912 > [junit4] 2> NOTE: All tests run in this JVM: [ResponseLogComponentTest, > SpellCheckCollatorTest, TestSolrConfigHandlerConcurrent, CursorPagingTest, > EnumFieldTest, TestBinaryField, TestComplexPhraseQParserPlugin, > AtomicUpdateProcessorFactoryTest, MultiTermTest, NotRequiredUniqueKeyTest, > TestIBSimilarityFactory, TestSweetSpotSimilarityFactory, > TestMaxScoreQueryParser, BlobRepositoryCloudTest, > TestCopyFieldCollectionResource, TestDynamicFieldResource, > DirectUpdateHandlerTest, TestManagedResource, TestOrdValues, > TestAuthenticationFramework, CdcrVersionReplicationTest, > BigEndianAscendingWordSerializerTest, SolrCloudExampleTest, > UnloadDistributedZkTest, TestCoreBackup, RegexBytesRefFilterTest, > SuggesterTest, InfixSuggestersTest, TestDFRSimilarityFactory, TestDistribIDF, > TestUseDocValuesAsStored, HdfsWriteToMultipleCollectionsTest, > TestRandomCollapseQParserPlugin, TestXIncludeConfig, > TestFieldCacheVsDocValues, RequiredFieldsTest, SpatialRPTFieldTypeTest, > TestSolrCoreProperties, PeerSyncWithIndexFingerprintCachingTest, > TestFieldCacheSort, TestFiltering, TestRawResponseWriter, DistanceUnitsTest, > SSLMigrationTest, TestRestoreCore, TestCloudInspectUtil, > TestCursorMarkWithoutUniqueKey, TestRandomDVFaceting, BJQParserTest, > TestFieldTypeResource, DeleteInactiveReplicaTest, TestLFUCache, TestLockTree, > TestStressLiveNodes, MissingSegmentRecoveryTest, TestQuerySenderListener, > SegmentsInfoRequestHandlerTest, SyncSliceTest, CircularListTest, > TestHdfsUpdateLog, HdfsTlogReplayBufferedWhileIndexingTest, > CreateCollectionCleanupTest, TestDistributedStatsComponentCardinality, > TestStressCloudBlindAtomicUpdates, TestTolerantUpdateProcessorCloud, > TestXmlQParser, TestSchemaVersionResource, TestLegacyField, > DocExpirationUpdateProcessorFactoryTest, TestLMDirichletSimilarityFactory, > TestInPlaceUpdatesStandalone, TestScoreJoinQPScore, TestFreeTextSuggestions, > BasicAuthIntegrationTest, TestSurroundQueryParser, TestMultiWordSynonyms, > LegacyCloudClusterPropTest, TestSha256AuthenticationProvider, > TestZkAclsWithHadoopAuth, DistributedFacetPivotLongTailTest, > TestHttpShardHandlerFactory, TestCustomDocTransformer, > ChaosMonkeyNothingIsSafeTest, TestReloadDeadlock, CoreSorterTest, > ZkStateWriterTest, TestNonDefinedSimilarityFactory, > LeaderFailoverAfterPartitionTest, DistributedDebugComponentTest, > TestExecutePlanAction] > [junit4] Completed [164/765 (1!)] on J0 in 0.94s, 2 tests, 1 error <<< > FAILURES! > > [...truncated 9343 lines...] > [junit4] Suite: org.apache.solr.ltr.TestLTRQParserExplain > [junit4] 2> 49795 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: > test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom > [junit4] 2> Creating dataDir: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/init-core-data-001 > [junit4] 2> 49797 WARN > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=24 numCloses=24 > [junit4] 2> 49797 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) > w/NUMERIC_DOCVALUES_SYSPROP=true > [junit4] 2> 49801 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: > @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, > clientAuth=NaN) w/ MAC_OS_X supressed clientAuth > [junit4] 2> 49802 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 ####initCore > [junit4] 2> 49818 WARN > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.SolrConfig Couldn't add files from > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/ltr/src/test-files/solr/models > filtered by null to classpath: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/ltr/src/test-files/solr/models > [junit4] 2> 49827 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 > [junit4] 2> 49838 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.s.IndexSchema [null] Schema name=example > [junit4] 2> 49849 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id > [junit4] 2> 49941 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry > 'solr.node') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 49961 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') > enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 49962 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry > 'solr.jetty') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 49964 INFO (coreLoadExecutor-329-thread-1) [ ] > o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for > 2147483647 transient cores > [junit4] 2> 49979 WARN (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.c.SolrConfig Couldn't add files from > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/ltr/src/test-files/solr/models > filtered by null to classpath: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/ltr/src/test-files/solr/models > [junit4] 2> 49981 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 > [junit4] 2> 49991 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example > [junit4] 2> 50016 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid > field id > [junit4] 2> 50019 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using > configuration from instancedir > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/ltr/src/test-files/solr/collection1, > trusted=true > [junit4] 2> 50019 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 50019 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder > [junit4] 2> 50019 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at > [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/ltr/src/test-files/solr/collection1], > > dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/init-core-data-001/] > [junit4] 2> 50131 WARN (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.c.RequestHandlers no default request handler is > registered (either '/select' or 'standard') > [junit4] 2> 50132 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 50132 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= > defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 > numVersionBuckets=65536 > [junit4] 2> 50163 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for > 15000ms; > [junit4] 2> 50163 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for > 1000ms; > [junit4] 2> 50167 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.s.SolrIndexSearcher Opening > [Searcher@436f5833[collection1] main] > [junit4] 2> 50167 WARN (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config > directory > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/ltr/src/test-files/solr/collection1/conf; > switching to use InMemory storage instead. > [junit4] 2> 50167 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered ManagedResource impl > org.apache.solr.ltr.store.rest.ManagedFeatureStore for path > /schema/feature-store > [junit4] 2> 50167 WARN (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.ManagedResource No stored data found for > /schema/feature-store > [junit4] 2> 50167 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ > loading ------ > [junit4] 2> 50167 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.ManagedResource Notified 1 observers of > /schema/feature-store > [junit4] 2> 50167 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered new managed resource > /schema/feature-store > [junit4] 2> 50167 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered ManagedResource impl > org.apache.solr.ltr.store.rest.ManagedModelStore for path /schema/model-store > [junit4] 2> 50168 WARN (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.ManagedResource No stored data found for > /schema/model-store > [junit4] 2> 50168 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store > [junit4] 2> 50168 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading > ------ > [junit4] 2> 50168 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.ManagedResource Notified 1 observers of > /schema/model-store > [junit4] 2> 50168 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered new managed resource > /schema/model-store > [junit4] 2> 50168 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for > 10000ms. > [junit4] 2> 50170 INFO > (searcherExecutor-330-thread-1-processing-x:collection1) [ x:collection1] > o.a.s.c.SolrCore [collection1] Registered new searcher > Searcher@436f5833[collection1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 50171 INFO (coreLoadExecutor-329-thread-1) [ > x:collection1] o.a.s.u.UpdateLog Could not find max version in index or > recent updates, using new clock 1588486125193789440 > [junit4] 2> 50176 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 ####initCore end > [junit4] 2> 50186 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 ####initCore > [junit4] 2> 50186 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 ####initCore end > [junit4] 2> 50187 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 Writing core.properties file to > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-003/cores/core > [junit4] 2> 50189 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.e.j.s.Server jetty-9.3.20.v20170531 > [junit4] 2> 50191 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@3399363e{/solr,null,AVAILABLE} > [junit4] 2> 50192 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.e.j.s.AbstractConnector Started > ServerConnector@3795cdb7{HTTP/1.1,[http/1.1]}{127.0.0.1:53047} > [junit4] 2> 50192 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.e.j.s.Server Started @53029ms > [junit4] 2> 50192 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=53047, > configSetBaseDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001, > > coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-003/cores} > [junit4] 2> 50193 ERROR > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be > missing or incomplete. > [junit4] 2> 50193 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version > 8.0.0 > [junit4] 2> 50193 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on > port null > [junit4] 2> 50193 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null > [junit4] 2> 50194 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2018-01-02T13:06:59.587Z > [junit4] 2> 50194 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.SolrXmlConfig Loading container configuration from > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/solr.xml > [junit4] 2> 50301 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.SolrXmlConfig MBean server found: > com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592, but no JMX reporters were > configured - adding default JMX reporter. > [junit4] 2> 50472 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry > 'solr.node') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 50503 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') > enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 50517 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry > 'solr.jetty') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 50524 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-003/cores > [junit4] 2> 50524 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.CorePropertiesLocator Cores are: [collection1] > [junit4] 2> 50528 INFO (coreLoadExecutor-340-thread-1) [ ] > o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for > 2147483647 transient cores > [junit4] 2> 50577 WARN (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.c.SolrConfig Couldn't add files from > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/models > filtered by null to classpath: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/models > [junit4] 2> 50580 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 > [junit4] 2> 50658 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example > [junit4] 2> 50669 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid > field id > [junit4] 2> 50683 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using > configuration from configset > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/collection1, > trusted=true > [junit4] 2> 50693 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@6e063592 > [junit4] 2> 50693 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder > [junit4] 2> 50693 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at > [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/collection1], > > dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-003/cores/core/data/] > [junit4] 2> 50886 WARN (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.c.RequestHandlers no default request handler is > registered (either '/select' or 'standard') > [junit4] 2> 50887 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 50887 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= > defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 > numVersionBuckets=65536 > [junit4] 2> 50890 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for > 15000ms; > [junit4] 2> 50890 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for > 1000ms; > [junit4] 2> 50929 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.s.SolrIndexSearcher Opening > [Searcher@4b836e02[collection1] main] > [junit4] 2> 50930 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized > to use dir: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/collection1/conf > [junit4] 2> 50931 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered ManagedResource impl > org.apache.solr.ltr.store.rest.ManagedFeatureStore for path > /schema/feature-store > [junit4] 2> 50931 WARN (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.ManagedResource No stored data found for > /schema/feature-store > [junit4] 2> 50931 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ > loading ------ > [junit4] 2> 50931 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.ManagedResource Notified 1 observers of > /schema/feature-store > [junit4] 2> 50931 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered new managed resource > /schema/feature-store > [junit4] 2> 50931 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered ManagedResource impl > org.apache.solr.ltr.store.rest.ManagedModelStore for path /schema/model-store > [junit4] 2> 50932 WARN (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.ManagedResource No stored data found for > /schema/model-store > [junit4] 2> 50932 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store > [junit4] 2> 50932 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading > ------ > [junit4] 2> 50932 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.ManagedResource Notified 1 observers of > /schema/model-store > [junit4] 2> 50932 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.r.RestManager Registered new managed resource > /schema/model-store > [junit4] 2> 50933 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for > 10000ms. > [junit4] 2> 50933 INFO (coreLoadExecutor-340-thread-1) [ > x:collection1] o.a.s.u.UpdateLog Could not find max version in index or > recent updates, using new clock 1588486125992804352 > [junit4] 2> 50936 INFO > (searcherExecutor-341-thread-1-processing-x:collection1) [ x:collection1] > o.a.s.c.SolrCore [collection1] Registered new searcher > Searcher@4b836e02[collection1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 50937 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrJettyTestBase Jetty Assigned Port#53047 > [junit4] 2> 50961 INFO (qtp511526317-485) [ x:collection1] > o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update > params={stream.body=<add><doc><field+name%3D"title">bloomberg+different+bla</field><field+name%3D"description">bloomberg</field><field+name%3D"id">6</field><field+name%3D"popularity">1</field></doc></add>}{add=[6 > (1588486126007484416)]} 0 15 > [junit4] 2> 50971 INFO (qtp511526317-486) [ x:collection1] > o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update > params={stream.body=<add><doc><field+name%3D"title">bloomberg+bloomberg+</field><field+name%3D"description">bloomberg</field><field+name%3D"id">7</field><field+name%3D"popularity">2</field></doc></add>}{add=[7 > (1588486126030553088)]} 0 3 > [junit4] 2> 50975 INFO (qtp511526317-487) [ x:collection1] > o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update > params={stream.body=<add><doc><field+name%3D"title">bloomberg+bloomberg+bloomberg</field><field+name%3D"description">bloomberg</field><field+name%3D"id">8</field><field+name%3D"popularity">3</field></doc></add>}{add=[8 > (1588486126035795968)]} 0 1 > [junit4] 2> 50982 INFO (qtp511526317-488) [ x:collection1] > o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update > params={stream.body=<add><doc><field+name%3D"title">bloomberg+bloomberg+bloomberg+bloomberg</field><field+name%3D"description">bloomberg</field><field+name%3D"id">9</field><field+name%3D"popularity">5</field></doc></add>}{add=[9 > (1588486126043136000)]} 0 1 > [junit4] 2> 50985 INFO (qtp511526317-489) [ x:collection1] > o.a.s.u.DirectUpdateHandler2 start > commit{_version_=1588486126047330304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 50985 INFO (qtp511526317-489) [ x:collection1] > o.a.s.u.SolrIndexWriter Calling setCommitData with > IW:org.apache.solr.update.SolrIndexWriter@212b3cf > commitCommandVersion:1588486126047330304 > [junit4] 2> 51172 INFO (qtp511526317-489) [ x:collection1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@598abb28[collection1] main] > [junit4] 2> 51173 INFO (qtp511526317-489) [ x:collection1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 51176 INFO > (searcherExecutor-341-thread-1-processing-x:collection1) [ x:collection1] > o.a.s.c.SolrCore [collection1] Registered new searcher > Searcher@598abb28[collection1] > main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C4)))} > [junit4] 2> 51177 INFO (qtp511526317-489) [ x:collection1] > o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update > params={stream.body=<commit/>}{commit=} 0 192 > [junit4] 2> 51185 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.l.TestRerankBase send > [junit4] 2> [ > [junit4] 2> { > [junit4] 2> "name": "constant1", > [junit4] 2> "class": "org.apache.solr.ltr.feature.ValueFeature", > [junit4] 2> "store":"test", > [junit4] 2> "params": { > [junit4] 2> "value": 1 > [junit4] 2> } > [junit4] 2> }, > [junit4] 2> { > [junit4] 2> "name": "constant2", > [junit4] 2> "class": "org.apache.solr.ltr.feature.ValueFeature", > [junit4] 2> "store":"test", > [junit4] 2> "params": { > [junit4] 2> "value": 2 > [junit4] 2> } > [junit4] 2> }, > [junit4] 2> { > [junit4] 2> "name": "constant3", > [junit4] 2> "class": "org.apache.solr.ltr.feature.ValueFeature", > [junit4] 2> "store":"test", > [junit4] 2> "params": { > [junit4] 2> "value": 3 > [junit4] 2> } > [junit4] 2> }, > [junit4] 2> { > [junit4] 2> "name": "constant4", > [junit4] 2> "class": "org.apache.solr.ltr.feature.ValueFeature", > [junit4] 2> "store":"test", > [junit4] 2> "params": { > [junit4] 2> "value": 4 > [junit4] 2> } > [junit4] 2> }, > [junit4] 2> { > [junit4] 2> "name": "constant5", > [junit4] 2> "class": "org.apache.solr.ltr.feature.ValueFeature", > [junit4] 2> "store":"test", > [junit4] 2> "params": { > [junit4] 2> "value": 5 > [junit4] 2> } > [junit4] 2> }, > [junit4] 2> { > [junit4] 2> "name": "pop", > [junit4] 2> "class": > "org.apache.solr.ltr.feature.FieldValueFeature", > [junit4] 2> "store":"test", > [junit4] 2> "params": { > [junit4] 2> "field": "popularity" > [junit4] 2> } > [junit4] 2> } > [junit4] 2> > [junit4] 2> ] > [junit4] 2> > [junit4] 2> 51187 INFO (qtp511526317-490) [ x:collection1] /solr > SolrSchemaRestApi: [Restlet] ServerServlet: component class is null > [junit4] 2> 51188 INFO (qtp511526317-490) [ x:collection1] /solr > SolrSchemaRestApi: [Restlet] Attaching application: > org.apache.solr.rest.SolrSchemaRestApi@a5c75eb to URI: /solr/schema > [junit4] 2> Jan 02, 2018 8:07:00 AM org.restlet.Application start > [junit4] 2> INFO: Starting org.apache.solr.rest.SolrSchemaRestApi > application > [junit4] 2> 51211 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.SolrSchemaRestApi createInboundRoot started for /schema > [junit4] 2> 51211 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.RestManager Attached managed resource at path: /feature-store > [junit4] 2> 51211 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.RestManager Attached managed resource at path: /managed > [junit4] 2> 51212 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.RestManager Attached managed resource at path: /model-store > [junit4] 2> 51212 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.RestManager Attached 3 ManagedResource endpoints to Restlet router: > /schema > [junit4] 2> 51212 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.SolrSchemaRestApi createInboundRoot complete for /schema > [junit4] 2> 51212 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.RestManager Found ManagedResource > [org.apache.solr.ltr.store.rest.ManagedFeatureStore@3ad3c526] for > /schema/feature-store > [junit4] 2> 51213 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.ManagedResource Processing update to /schema/feature-store: > [{name=constant1, class=org.apache.solr.ltr.feature.ValueFeature, store=test, > params={value=1}}, {name=constant2, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, > params={value=2}}, {name=constant3, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, > params={value=3}}, {name=constant4, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, > params={value=4}}, {name=constant5, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, > params={value=5}}, {name=pop, > class=org.apache.solr.ltr.feature.FieldValueFeature, store=test, > params={field=popularity}}] is a java.util.ArrayList > [junit4] 2> 51213 INFO (qtp511526317-490) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=constant1, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, params={value=1}} > [junit4] 2> 51253 INFO (qtp511526317-490) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=constant2, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, params={value=2}} > [junit4] 2> 51253 INFO (qtp511526317-490) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=constant3, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, params={value=3}} > [junit4] 2> 51253 INFO (qtp511526317-490) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=constant4, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, params={value=4}} > [junit4] 2> 51254 INFO (qtp511526317-490) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=constant5, > class=org.apache.solr.ltr.feature.ValueFeature, store=test, params={value=5}} > [junit4] 2> 51254 INFO (qtp511526317-490) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=pop, > class=org.apache.solr.ltr.feature.FieldValueFeature, store=test, > params={field=popularity}} > [junit4] 2> 51267 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.ManagedResourceStorage Saved JSON object to path > _schema_feature-store.json using > file:dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/collection1/conf > [junit4] 2> 51268 INFO (qtp511526317-490) [ x:collection1] > o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store > params={indent=on&wt=json} status=0 QTime=81 > [junit4] 2> Jan 02, 2018 8:07:00 AM org.restlet.engine.log.LogFilter > afterHandle > [junit4] 2> INFO: 2018-01-02 08:07:00 127.0.0.1 - > 127.0.0.1 53047 PUT /solr/schema/feature-store > wt=json&indent=on 200 - 1077 79 > http://127.0.0.1:53047 Apache-HttpClient/4.5.3 (Java/1.8.0_144) - > [junit4] 2> 51269 WARN (qtp511526317-490) [ x:collection1] > o.e.j.s.ServletHandler Error for /solr/collection1/schema/feature-store > [junit4] 2> java.lang.AssertionError: Attempted close of response output > stream. > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter$2$1.close(SolrDispatchFilter.java:525) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.commitResponse(Dispatcher.java:270) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:202) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:74) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:391) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) > [junit4] 2> at > org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) > [junit4] 2> at > org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) > [junit4] 2> at > org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426) > [junit4] 2> at > org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) > [junit4] 2> at > org.eclipse.jetty.server.Server.handle(Server.java:534) > [junit4] 2> at > org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) > [junit4] 2> at > org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) > [junit4] 2> at > org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) > [junit4] 2> at > org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) > [junit4] 2> at > org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) > [junit4] 2> at > org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) > [junit4] 2> at > org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) > [junit4] 2> at > org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) > [junit4] 2> at > org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) > [junit4] 2> at > org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) > [junit4] 2> at java.lang.Thread.run(Thread.java:748) > [junit4] 2> 51278 INFO > (TEST-TestLTRQParserExplain.multipleAdditiveTreesScoreExplainMissingEfiFeatureShouldReturnDefaultScore-seed#[BF50F24C695B12A1]) > [ ] o.a.s.SolrTestCaseJ4 ###Starting > multipleAdditiveTreesScoreExplainMissingEfiFeatureShouldReturnDefaultScore > [junit4] 2> 51279 INFO > (TEST-TestLTRQParserExplain.multipleAdditiveTreesScoreExplainMissingEfiFeatureShouldReturnDefaultScore-seed#[BF50F24C695B12A1]) > [ ] o.a.s.l.TestRerankBase send > [junit4] 2> [{ > [junit4] 2> "name" : "user_device_smartphone", > [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", > [junit4] 2> "params" : { > [junit4] 2> "value": "${user_device_smartphone}" > [junit4] 2> } > [junit4] 2> }, > [junit4] 2> { > [junit4] 2> "name" : "user_device_tablet", > [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", > [junit4] 2> "params" : { > [junit4] 2> "value": "${user_device_tablet}" > [junit4] 2> } > [junit4] 2> } > [junit4] 2> > [junit4] 2> > [junit4] 2> > [junit4] 2> ] > [junit4] 2> > [junit4] 2> 51282 INFO (qtp511526317-483) [ x:collection1] > o.a.s.r.RestManager Found ManagedResource > [org.apache.solr.ltr.store.rest.ManagedFeatureStore@3ad3c526] for > /schema/feature-store > [junit4] 2> 51282 INFO (qtp511526317-483) [ x:collection1] > o.a.s.r.ManagedResource Processing update to /schema/feature-store: > [{name=user_device_smartphone, > class=org.apache.solr.ltr.feature.ValueFeature, > params={value=${user_device_smartphone}}}, {name=user_device_tablet, > class=org.apache.solr.ltr.feature.ValueFeature, > params={value=${user_device_tablet}}}] is a java.util.ArrayList > [junit4] 2> 51282 INFO (qtp511526317-483) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on > {name=user_device_smartphone, class=org.apache.solr.ltr.feature.ValueFeature, > params={value=${user_device_smartphone}}} > [junit4] 2> 51282 INFO (qtp511526317-483) [ x:collection1] > o.a.s.l.s.r.ManagedFeatureStore register feature based on > {name=user_device_tablet, class=org.apache.solr.ltr.feature.ValueFeature, > params={value=${user_device_tablet}}} > [junit4] 2> 51283 INFO (qtp511526317-483) [ x:collection1] > o.a.s.r.ManagedResourceStorage Saved JSON object to path > _schema_feature-store.json using > file:dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001/tempDir-001/collection1/conf > [junit4] 2> 51283 INFO (qtp511526317-483) [ x:collection1] > o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store > params={indent=on&wt=json} status=0 QTime=3 > [junit4] 2> Jan 02, 2018 8:07:00 AM org.restlet.engine.log.LogFilter > afterHandle > [junit4] 2> INFO: 2018-01-02 08:07:00 127.0.0.1 - > 127.0.0.1 53047 PUT /solr/schema/feature-store > wt=json&indent=on 200 - 323 2 > http://127.0.0.1:53047 Apache-HttpClient/4.5.3 (Java/1.8.0_144) - > [junit4] 2> 51284 WARN (qtp511526317-483) [ x:collection1] > o.e.j.s.ServletHandler Error for /solr/collection1/schema/feature-store > [junit4] 2> java.lang.AssertionError: Attempted close of response output > stream. > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter$2$1.close(SolrDispatchFilter.java:525) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.commitResponse(Dispatcher.java:270) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:202) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:74) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:391) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) > [junit4] 2> at > org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) > [junit4] 2> at > org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) > [junit4] 2> at > org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426) > [junit4] 2> at > org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) > [junit4] 2> at > org.eclipse.jetty.server.Server.handle(Server.java:534) > [junit4] 2> at > org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) > [junit4] 2> at > org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) > [junit4] 2> at > org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) > [junit4] 2> at org.eclipse.jetty.io.FillInterest. > > [...truncated too long message...] > > mitResponse(Dispatcher.java:270) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:202) > [junit4] 2> at > org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:74) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:391) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) > [junit4] 2> at > org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) > [junit4] 2> at > org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) > [junit4] 2> at > org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426) > [junit4] 2> at > org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) > [junit4] 2> at > org.eclipse.jetty.server.Server.handle(Server.java:534) > [junit4] 2> at > org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) > [junit4] 2> at > org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) > [junit4] 2> at > org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) > [junit4] 2> at > org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) > [junit4] 2> at > org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) > [junit4] 2> at > org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) > [junit4] 2> at > org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) > [junit4] 2> at > org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) > [junit4] 2> at > org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) > [junit4] 2> at > org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) > [junit4] 2> at java.lang.Thread.run(Thread.java:748) > [junit4] 2> 51497 INFO (qtp511526317-488) [ x:collection1] > o.a.s.c.S.Request [collection1] webapp=/solr path=/query > params={q=title:bloomberg&indent=on&fl=*,score&rows=4&wt=json&debugQuery=on&rq={!ltr+reRankDocs%3D4+model%3Dlinear-efi}} > hits=4 status=0 QTime=2 > [junit4] 2> 51501 INFO (qtp511526317-489) [ x:collection1] > o.a.s.c.S.Request [collection1] webapp=/solr path=/query > params={q=title:bloomberg&indent=on&fl=*,score&rows=4&wt=json&debugQuery=on&rq={!ltr+reRankDocs%3D4+model%3Dlinear-efi}} > hits=4 status=0 QTime=2 > [junit4] 2> 51502 INFO > (TEST-TestLTRQParserExplain.LinearScoreExplainMissingEfiFeatureShouldReturnDefaultScore-seed#[BF50F24C695B12A1]) > [ ] o.a.s.SolrTestCaseJ4 ###Ending > LinearScoreExplainMissingEfiFeatureShouldReturnDefaultScore > [junit4] 2> 51504 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.e.j.s.AbstractConnector Stopped > ServerConnector@3795cdb7{HTTP/1.1,[http/1.1]}{127.0.0.1:0} > [junit4] 2> 51504 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.CoreContainer Shutting down CoreContainer instance=322867989 > [junit4] 2> 51504 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, > tag=null > [junit4] 2> 51504 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@3cb2da54: rootName = null, > domain = solr.node, service url = null, agent id = null] for registry > solr.node / com.codahale.metrics.MetricRegistry@1f9092bf > [junit4] 2> 51514 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, > tag=null > [junit4] 2> 51514 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@14ecc590: rootName = null, > domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm > / com.codahale.metrics.MetricRegistry@6e6a01b9 > [junit4] 2> 51521 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, > tag=null > [junit4] 2> 51521 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@4a6f3ed6: rootName = null, > domain = solr.jetty, service url = null, agent id = null] for registry > solr.jetty / com.codahale.metrics.MetricRegistry@6a5598ac > [junit4] 2> 51523 INFO (coreCloseExecutor-346-thread-1) [ > x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore > org.apache.solr.core.SolrCore@31fc24c5 > [junit4] 2> 51523 INFO (coreCloseExecutor-346-thread-1) [ > x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for > registry=solr.core.collection1, tag=838608069 > [junit4] 2> 51523 INFO (coreCloseExecutor-346-thread-1) [ > x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@573053f4: rootName = null, > domain = solr.core.collection1, service url = null, agent id = null] for > registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7fcb7594 > [junit4] 2> 51551 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.e.j.s.h.ContextHandler Stopped > o.e.j.s.ServletContextHandler@3399363e{/solr,null,UNAVAILABLE} > [junit4] 2> 51573 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.SolrTestCaseJ4 ###deleteCore > [junit4] 2> 51573 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.c.CoreContainer Shutting down CoreContainer instance=1918236122 > [junit4] 2> 51573 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, > tag=null > [junit4] 2> 51573 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@4ab04e5b: rootName = null, > domain = solr.node, service url = null, agent id = null] for registry > solr.node / com.codahale.metrics.MetricRegistry@6987c674 > [junit4] 2> 51574 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, > tag=null > [junit4] 2> 51574 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@720828e1: rootName = null, > domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm > / com.codahale.metrics.MetricRegistry@6e6a01b9 > [junit4] 2> 51575 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, > tag=null > [junit4] 2> 51575 INFO > (SUITE-TestLTRQParserExplain-seed#[BF50F24C695B12A1]-worker) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@1871c8a5: rootName = null, > domain = solr.jetty, service url = null, agent id = null] for registry > solr.jetty / com.codahale.metrics.MetricRegistry@6a5598ac > [junit4] 2> 51576 INFO (coreCloseExecutor-348-thread-1) [ > x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore > org.apache.solr.core.SolrCore@46946a09 > [junit4] 2> 51576 INFO (coreCloseExecutor-348-thread-1) [ > x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for > registry=solr.core.collection1, tag=1184131593 > [junit4] 2> 51576 INFO (coreCloseExecutor-348-thread-1) [ > x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@3717fa34: rootName = null, > domain = solr.core.collection1, service url = null, agent id = null] for > registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@495237ee > [junit4] 2> NOTE: leaving temporary files on disk at: > /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestLTRQParserExplain_BF50F24C695B12A1-001 > [junit4] 2> NOTE: test params are: codec=Lucene70, > sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6b4359a7), > locale=en-GB, timezone=America/Montreal > [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 > (64-bit)/cpus=3,threads=1,free=106751488,total=198180864 > [junit4] 2> NOTE: All tests run in this JVM: > [TestModelManagerPersistence, TestRerankBase, TestMultipleAdditiveTreesModel, > TestOriginalScoreScorer, TestFeatureLogging, TestExternalValueFeatures, > TestUserTermScoreWithQ, TestFieldValueFeature, TestEdisMaxSolrFeature, > TestLTROnSolrCloud, TestLTRQParserExplain] > [junit4] Completed [20/35 (1!)] on J0 in 1.83s, 4 tests, 1 error <<< > FAILURES! > > [...truncated 40705 lines...] > > --------------------------------------------------------------------- > To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org > For additional commands, e-mail: dev-h...@lucene.apache.org
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
