Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1842/
4 tests failed. FAILED: org.apache.solr.ltr.TestLTRQParserPlugin.ltrMoreResultsThanReRankedTest Error Message: mismatch: '0.09271725'!='0.105360515' @ response/docs/[3]/score Stack Trace: java.lang.RuntimeException: mismatch: '0.09271725'!='0.105360515' @ response/docs/[3]/score at __randomizedtesting.SeedInfo.seed([4388A8044C06E361:8AF0196393C9B4A0]: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.TestLTRQParserPlugin.ltrMoreResultsThanReRankedTest(TestLTRQParserPlugin.java:94) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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:745) FAILED: org.apache.solr.ltr.TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder Error Message: mismatch: '3'!='4' @ response/docs/[0]/id Stack Trace: java.lang.RuntimeException: mismatch: '3'!='4' @ response/docs/[0]/id at __randomizedtesting.SeedInfo.seed([4388A8044C06E361:26C4886486648489]: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.TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder(TestParallelWeightCreation.java:45) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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:745) FAILED: org.apache.solr.ltr.TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore Error Message: mismatch: '3'!='4' @ response/docs/[0]/id Stack Trace: java.lang.RuntimeException: mismatch: '3'!='4' @ response/docs/[0]/id at __randomizedtesting.SeedInfo.seed([4388A8044C06E361:1EB9BFC1C13A940A]: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.TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore(TestSelectiveWeightCreation.java:230) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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:745) FAILED: org.apache.solr.ltr.feature.TestFieldLengthFeature.testRanking Error Message: mismatch: '8'!='1' @ response/docs/[0]/id Stack Trace: java.lang.RuntimeException: mismatch: '8'!='1' @ response/docs/[0]/id at __randomizedtesting.SeedInfo.seed([4388A8044C06E361:DCBE019AD47CFCAE]: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.feature.TestFieldLengthFeature.testRanking(TestFieldLengthFeature.java:117) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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:745) Build Log: [...truncated 19656 lines...] [junit4] Suite: org.apache.solr.ltr.TestSelectiveWeightCreation [junit4] 2> 0 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/init-core-data-001 [junit4] 2> 1145 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 1242 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.u.log Logging initialized @2959ms [junit4] 2> 1251 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1298 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 1378 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.SolrResourceLoader Using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/contrib/ltr/src/test-files/solr [junit4] 2> 1609 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1851 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=example [junit4] 2> 2270 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 3238 WARN (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 3238 WARN (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 3238 WARN (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 3303 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3389 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 3432 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 3464 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/contrib/ltr/src/test-files/solr/collection1, trusted=true [junit4] 2> 3536 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 3546 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 3554 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/contrib/ltr/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/init-core-data-001/] [junit4] 2> 4530 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 4574 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4575 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4593 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 4593 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 4679 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c586652[collection1] main] [junit4] 2> 4758 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/contrib/ltr/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 4815 INFO (coreLoadExecutor-6-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> 4816 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 4826 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 4827 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 4827 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 4830 INFO (coreLoadExecutor-6-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> 4833 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 4833 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 4833 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 4833 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 4835 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 4883 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 5144 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6c586652[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5145 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568008557222166528 [junit4] 2> 5158 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 5241 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 5241 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 5242 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-003/cores/core [junit4] 2> 5706 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 5869 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6f99fd3a{/solr,null,AVAILABLE} [junit4] 2> 5889 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@38345a53{HTTP/1.1,[http/1.1]}{127.0.0.1:56491} [junit4] 2> 5889 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.Server Started @7627ms [junit4] 2> 5890 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56491, configSetBaseDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-003/cores} [junit4] 2> 5929 ERROR (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 5930 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 5930 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 5930 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 5956 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-21T12:24:49.715Z [junit4] 2> 5970 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001/solr.xml [junit4] 2> 6147 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-003/cores [junit4] 2> 6148 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 6244 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 6302 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 6382 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 6401 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001/collection1, trusted=true [junit4] 2> 6402 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 6402 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-003/cores/core/data/] [junit4] 2> 6553 WARN (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 6572 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 6579 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 6581 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 6581 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 6583 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ab60ec2[collection1] main] [junit4] 2> 6585 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001/collection1/conf [junit4] 2> 6586 INFO (coreLoadExecutor-17-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> 6586 WARN (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 6587 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 6588 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 6588 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 6588 INFO (coreLoadExecutor-17-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> 6588 WARN (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 6591 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 6591 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 6591 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 6592 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 6592 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 6595 INFO (searcherExecutor-18-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ab60ec2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 6595 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568008558742601728 [junit4] 2> 6851 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#56491 [junit4] 2> 7606 INFO (qtp835896778-23) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">1</field><field+name%3D"title">w3+w1</field><field+name%3D"description">w1</field><field+name%3D"popularity">1</field></doc></add>}{add=[1 (1568008559630745600)]} 0 310 [junit4] 2> 7702 INFO (qtp835896778-24) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">2</field><field+name%3D"title">w2</field><field+name%3D"description">w2</field><field+name%3D"popularity">2</field></doc></add>}{add=[2 (1568008559902326784)]} 0 4 [junit4] 2> 7726 INFO (qtp835896778-25) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">3</field><field+name%3D"title">w3</field><field+name%3D"description">w3</field><field+name%3D"popularity">3</field></doc></add>}{add=[3 (1568008559927492608)]} 0 1 [junit4] 2> 7732 INFO (qtp835896778-26) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">4</field><field+name%3D"title">w3+w3</field><field+name%3D"description">w4</field><field+name%3D"popularity">4</field></doc></add>}{add=[4 (1568008559933784064)]} 0 1 [junit4] 2> 7737 INFO (qtp835896778-27) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">5</field><field+name%3D"title">w5</field><field+name%3D"description">w5</field><field+name%3D"popularity">5</field></doc></add>}{add=[5 (1568008559940075520)]} 0 1 [junit4] 2> 7741 INFO (qtp835896778-20) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 7741 INFO (qtp835896778-20) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7fb8ca3b commitCommandVersion:0 [junit4] 2> 8067 INFO (qtp835896778-20) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@26a2dbc6[collection1] main] [junit4] 2> 8079 INFO (qtp835896778-20) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 8081 INFO (searcherExecutor-18-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26a2dbc6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C5)))} [junit4] 2> 8081 INFO (qtp835896778-20) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<commit/>}{commit=} 0 340 [junit4] 2> 8088 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.l.TestRerankBase send [junit4] 2> [ { [junit4] 2> "name" : "matchedTitle", [junit4] 2> "class" : "org.apache.solr.ltr.feature.SolrFeature", [junit4] 2> "params" : { [junit4] 2> "q" : "{!terms f=title}${user_query}" [junit4] 2> } [junit4] 2> }, { [junit4] 2> "name" : "docPopularity", [junit4] 2> "class" : "org.apache.solr.ltr.feature.FieldValueFeature", [junit4] 2> "store": "fstore2", [junit4] 2> "params" : { [junit4] 2> "field" : "popularity" [junit4] 2> } [junit4] 2> }, { [junit4] 2> "name":"originalScore", [junit4] 2> "class":"org.apache.solr.ltr.feature.OriginalScoreFeature", [junit4] 2> "store": "fstore2", [junit4] 2> "params":{} [junit4] 2> }, { [junit4] 2> "name" : "occurrences", [junit4] 2> "class" : "org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "store": "fstore3", [junit4] 2> "params" : { [junit4] 2> "value" : "${myOcc}", [junit4] 2> "required" : false [junit4] 2> } [junit4] 2> }, { [junit4] 2> "name":"originalScore", [junit4] 2> "class":"org.apache.solr.ltr.feature.OriginalScoreFeature", [junit4] 2> "store": "fstore3", [junit4] 2> "params":{} [junit4] 2> }, { [junit4] 2> "name" : "popularity", [junit4] 2> "class" : "org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "store": "fstore4", [junit4] 2> "params" : { [junit4] 2> "value" : "${myPop}", [junit4] 2> "required" : true [junit4] 2> } [junit4] 2> }, { [junit4] 2> "name":"originalScore", [junit4] 2> "class":"org.apache.solr.ltr.feature.OriginalScoreFeature", [junit4] 2> "store": "fstore4", [junit4] 2> "params":{} [junit4] 2> }, { [junit4] 2> "name" : "titlePhraseMatch", [junit4] 2> "class" : "org.apache.solr.ltr.feature.SolrFeature", [junit4] 2> "params" : { [junit4] 2> "q" : "{!field f=title}${user_query}" [junit4] 2> } [junit4] 2> }, { [junit4] 2> "name" : "titlePhrasesMatch", [junit4] 2> "class" : "org.apache.solr.ltr.feature.SolrFeature", [junit4] 2> "params" : { [junit4] 2> "fq" : [ "{!field f=title}${userTitlePhrase1}", "{!field f=title}${userTitlePhrase2}"] [junit4] 2> } [junit4] 2> } ] [junit4] 2> [junit4] 2> 8135 INFO (qtp835896778-20) [ x:collection1] /solr SolrSchemaRestApi: [Restlet] ServerServlet: component class is null [junit4] 2> 8393 INFO (qtp835896778-20) [ x:collection1] /solr SolrSchemaRestApi: [Restlet] Attaching application: org.apache.solr.rest.SolrSchemaRestApi@74d6ec to URI: /solr/schema [junit4] 2> mei 21, 2017 8:24:52 AM org.restlet.Application start [junit4] 2> INFO: Starting org.apache.solr.rest.SolrSchemaRestApi application [junit4] 2> 8467 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.SolrSchemaRestApi createInboundRoot started for /schema [junit4] 2> 8484 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /feature-store [junit4] 2> 8484 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /managed [junit4] 2> 8484 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /model-store [junit4] 2> 8486 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.RestManager Attached 3 ManagedResource endpoints to Restlet router: /schema [junit4] 2> 8486 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.SolrSchemaRestApi createInboundRoot complete for /schema [junit4] 2> 8523 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@128402d] for /schema/feature-store [junit4] 2> 8537 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: [{name=matchedTitle, class=org.apache.solr.ltr.feature.SolrFeature, params={q={!terms f=title}${user_query}}}, {name=docPopularity, class=org.apache.solr.ltr.feature.FieldValueFeature, store=fstore2, params={field=popularity}}, {name=originalScore, class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore2, params={}}, {name=occurrences, class=org.apache.solr.ltr.feature.ValueFeature, store=fstore3, params={value=${myOcc}, required=false}}, {name=originalScore, class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore3, params={}}, {name=popularity, class=org.apache.solr.ltr.feature.ValueFeature, store=fstore4, params={value=${myPop}, required=true}}, {name=originalScore, class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore4, params={}}, {name=titlePhraseMatch, class=org.apache.solr.ltr.feature.SolrFeature, params={q={!field f=title}${user_query}}}, {name=titlePhrasesMatch, class=org.apache.solr.ltr.feature.SolrFeature, params={fq=[{!field f=title}${userTitlePhrase1}, {!field f=title}${userTitlePhrase2}]}}] is a java.util.ArrayList [junit4] 2> 8539 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=matchedTitle, class=org.apache.solr.ltr.feature.SolrFeature, params={q={!terms f=title}${user_query}}} [junit4] 2> 8565 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=docPopularity, class=org.apache.solr.ltr.feature.FieldValueFeature, store=fstore2, params={field=popularity}} [junit4] 2> 8571 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=originalScore, class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore2, params={}} [junit4] 2> 8573 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=occurrences, class=org.apache.solr.ltr.feature.ValueFeature, store=fstore3, params={value=${myOcc}, required=false}} [junit4] 2> 8581 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=originalScore, class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore3, params={}} [junit4] 2> 8581 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=popularity, class=org.apache.solr.ltr.feature.ValueFeature, store=fstore4, params={value=${myPop}, required=true}} [junit4] 2> 8585 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=originalScore, class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore4, params={}} [junit4] 2> 8589 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=titlePhraseMatch, class=org.apache.solr.ltr.feature.SolrFeature, params={q={!field f=title}${user_query}}} [junit4] 2> 8589 INFO (qtp835896778-20) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=titlePhrasesMatch, class=org.apache.solr.ltr.feature.SolrFeature, params={fq=[{!field f=title}${userTitlePhrase1}, {!field f=title}${userTitlePhrase2}]}} [junit4] 2> 8605 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001/collection1/conf [junit4] 2> 8607 INFO (qtp835896778-20) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=513 [junit4] 2> mei 21, 2017 8:24:52 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-21 08:24:52 127.0.0.1 - 127.0.0.1 56491 PUT /solr/schema/feature-store wt=json&indent=on 200 - 1516 179 http://127.0.0.1:56491 Apache-HttpClient/4.4.1 (Java/1.8.0_121) - [junit4] 2> 8635 WARN (qtp835896778-20) [ 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:517) [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:383) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [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:1699) [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:395) [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:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [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:745) [junit4] 2> 8648 INFO (qtp835896778-23) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@128402d]] for /schema/model-store [junit4] 2> 8650 INFO (qtp835896778-23) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {class=org.apache.solr.ltr.model.LinearModel, name=externalmodel, features=[{name=titlePhraseMatch}], params={weights={titlePhraseMatch=0.555}}} is a java.util.LinkedHashMap [junit4] 2> 8666 INFO (qtp835896778-23) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model externalmodel [junit4] 2> 8676 INFO (qtp835896778-23) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001/collection1/conf [junit4] 2> 8677 INFO (qtp835896778-23) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=31 [junit4] 2> mei 21, 2017 8:24:52 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-21 08:24:52 127.0.0.1 - 127.0.0.1 56491 PUT /solr/schema/model-store wt=json&indent=on 200 - 237 31 http://127.0.0.1:56491 Apache-HttpClient/4.4.1 (Java/1.8.0_121) - [junit4] 2> 8683 WARN (qtp835896778-23) [ x:collection1] o.e.j.s.ServletHandler Error for /solr/collection1/schema/model-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:517) [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:383) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [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:1699) [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:395) [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:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [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:745) [junit4] 2> 8688 INFO (qtp835896778-25) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[externalmodel]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@128402d]] for /schema/model-store [junit4] 2> 8688 INFO (qtp835896778-25) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {class=org.apache.solr.ltr.model.LinearModel, name=externalmodel2, store=fstore2, features=[{name=docPopularity}], params={weights={docPopularity=0.5}}} is a java.util.LinkedHashMap [junit4] 2> 8691 INFO (qtp835896778-25) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model externalmodel2 [junit4] 2> 8692 INFO (qtp835896778-25) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001/tempDir-001/collection1/conf [junit4] 2> 8692 INFO (qtp835896778-25) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=5 [junit4] 2> mei 21, 2017 8:24:52 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-21 08:24:52 127.0.0.1 - 127.0.0.1 56491 PUT /solr/schema/model-store wt=json&indent=on 200 - 254 5 http://127.0.0.1:56491 Apache-HttpClient/4.4.1 (Java/1.8.0_121) - [junit4] 2> 8697 WARN (qtp835896778-25) [ x:collection1] o.e.j.s.ServletHandler Error for /solr/collection1/schema/model-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:517) [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:383) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [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:1699) [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:395) [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:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [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:745) [junit4] 2> 8732 INFO (TEST-TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSelectiveWeightsRequestFeaturesFromDifferentStore [junit4] 2> 9117 INFO (qtp835896778-24) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=*:*&indent=on&fl=*,score,fv:[fv]&rows=5&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw2+efi.userTitlePhrase2%3Dw1}} hits=5 status=0 QTime=379 [junit4] 2> 9139 ERROR (TEST-TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore-seed#[4388A8044C06E361]) [ ] o.a.s.u.RestTestBase query failed JSON validation. error=mismatch: '3'!='4' @ response/docs/[0]/id [junit4] 2> expected =/response/docs/[0]/id=='3' [junit4] 2> response = { [junit4] 2> "responseHeader":{ [junit4] 2> "status":0, [junit4] 2> "QTime":379, [junit4] 2> "params":{ [junit4] 2> "q":"*:*", [junit4] 2> "indent":"on", [junit4] 2> "fl":"*,score,fv:[fv]", [junit4] 2> "rows":"5", [junit4] 2> "wt":"json", [junit4] 2> "rq":"{!ltr reRankDocs=10 model=externalmodel efi.user_query=w3 efi.userTitlePhrase1=w2 efi.userTitlePhrase2=w1}"}}, [junit4] 2> "response":{"numFound":5,"start":0,"maxScore":0.36707595,"docs":[ [junit4] 2> { [junit4] 2> "id":"4", [junit4] 2> "title":"w3 w3", [junit4] 2> "description":"w4", [junit4] 2> "popularity":4, [junit4] 2> "_version_":1568008559933784064, [junit4] 2> "score":0.36707595, [junit4] 2> "fv":"matchedTitle=1.0,titlePhraseMatch=0.6613981"}, [junit4] 2> { [junit4] 2> "id":"3", [junit4] 2> "title":"w3", [junit4] 2> "description":"w3", [junit4] 2> "popularity":3, [junit4] 2> "_version_":1568008559927492608, [junit4] 2> "score":0.33873552, [junit4] 2> "fv":"matchedTitle=1.0,titlePhraseMatch=0.6103343"}, [junit4] 2> { [junit4] 2> "id":"1", [junit4] 2> "title":"w3 w1", [junit4] 2> "description":"w1", [junit4] 2> "popularity":1, [junit4] 2> "_version_":1568008559630745600, [junit4] 2> "score":0.2545195, [junit4] 2> "fv":"matchedTitle=1.0,titlePhraseMatch=0.4585937"}, [junit4] 2> { [junit4] 2> "id":"2", [junit4] 2> "title":"w2", [junit4] 2> "description":"w2", [junit4] 2> "popularity":2, [junit4] 2> "_version_":1568008559902326784, [junit4] 2> "score":0.0, [junit4] 2> "fv":""}, [junit4] 2> { [junit4] 2> "id":"5", [junit4] 2> "title":"w5", [junit4] 2> "description":"w5", [junit4] 2> "popularity":5, [junit4] 2> "_version_":1568008559940075520, [junit4] 2> "score":0.0, [junit4] 2> "fv":""}] [junit4] 2> }} [junit4] 2> [junit4] 2> request = /query?q=*%3A*&fl=*%2Cscore%2Cfv%3A%5Bfv%5D&rows=5&rq=%7B%21ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw2+efi.userTitlePhrase2%3Dw1%7D&wt=json&indent=on [junit4] 2> [junit4] 2> 9141 INFO (TEST-TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSelectiveWeightsRequestFeaturesFromDifferentStore [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSelectiveWeightCreation -Dtests.method=testSelectiveWeightsRequestFeaturesFromDifferentStore -Dtests.seed=4388A8044C06E361 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=nl-BE -Dtests.timezone=America/Martinique -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.46s J0 | TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore <<< [junit4] > Throwable #1: java.lang.RuntimeException: mismatch: '3'!='4' @ response/docs/[0]/id [junit4] > at __randomizedtesting.SeedInfo.seed([4388A8044C06E361:1EB9BFC1C13A940A]:0) [junit4] > at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248) [junit4] > at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192) [junit4] > at org.apache.solr.ltr.TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore(TestSelectiveWeightCreation.java:230) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 9165 INFO (TEST-TestSelectiveWeightCreation.testScoringQueryWeightCreation-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScoringQueryWeightCreation [junit4] 2> 9335 INFO (qtp835896778-27) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<delete><id>10</id></delete>}{delete=[10 (-1568008561614651392)]} 0 2 [junit4] 2> 9351 INFO (qtp835896778-26) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<delete><id>11</id></delete>}{delete=[11 (-1568008561631428608)]} 0 1 [junit4] 2> 9439 INFO (TEST-TestSelectiveWeightCreation.testScoringQueryWeightCreation-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScoringQueryWeightCreation [junit4] 2> 9453 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@38345a53{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 9454 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1382719057 [junit4] 2> 9456 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 9456 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 9456 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 9460 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 9474 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7c8b1e0a [junit4] 2> 9475 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 9476 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7fb8ca3b commitCommandVersion:0 [junit4] 2> 9513 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2089491978 [junit4] 2> 9533 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6f99fd3a{/solr,null,UNAVAILABLE} [junit4] 2> 9549 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 9559 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1166208171 [junit4] 2> 9559 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 9561 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 9561 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 9561 INFO (SUITE-TestSelectiveWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 9571 INFO (coreCloseExecutor-25-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@975f240 [junit4] 2> 9577 INFO (coreCloseExecutor-25-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=158724672 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestSelectiveWeightCreation_4388A8044C06E361-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {field=FSTOrd50, _version_=PostingsFormat(name=LuceneVarGapFixedInterval), popularity=PostingsFormat(name=MockRandom), description=FSTOrd50, id=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=MockRandom), title=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{final-score=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=670, maxMBSortInHeap=5.3685141996965315, sim=RandomSimilarity(queryNorm=true): {field=DFR I(ne)LZ(0.3)}, locale=nl-BE, timezone=America/Martinique [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=4,threads=1,free=255883632,total=318767104 [junit4] 2> NOTE: All tests run in this JVM: [TestSelectiveWeightCreation] [junit4] Completed [1/33 (1!)] on J0 in 10.30s, 2 tests, 1 error <<< FAILURES! [...truncated 7 lines...] [junit4] Suite: org.apache.solr.ltr.feature.TestFieldLengthFeature [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/init-core-data-001 [junit4] 2> 9664 WARN (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 9664 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 9666 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 9946 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 9998 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 10070 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=example [junit4] 2> 10102 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 10413 WARN (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 10413 WARN (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 10413 WARN (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 10448 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 10502 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 10546 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 10558 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/contrib/ltr/src/test-files/solr/collection1, trusted=true [junit4] 2> 10559 WARN (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 10568 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 10568 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/contrib/ltr/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/init-core-data-001/] [junit4] 2> 10718 WARN (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 10722 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 10722 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 10727 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 10727 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 10728 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c32bf9a[collection1] main] [junit4] 2> 10728 WARN (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/contrib/ltr/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 10729 INFO (coreLoadExecutor-32-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> 10729 WARN (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 10729 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 10729 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 10729 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 10729 INFO (coreLoadExecutor-32-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> 10729 WARN (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 10729 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 10729 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 10729 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 10729 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 10730 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 10730 INFO (coreLoadExecutor-32-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568008563078463488 [junit4] 2> 10734 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 10740 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 10740 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 10740 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-003/cores/core [junit4] 2> 10744 INFO (searcherExecutor-33-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c32bf9a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 10771 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 10776 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@55a77d22{/solr,null,AVAILABLE} [junit4] 2> 10821 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@73db9c05{SSL,[ssl, http/1.1]}{127.0.0.1:42686} [junit4] 2> 10821 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.Server Started @12559ms [junit4] 2> 10822 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42686, configSetBaseDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-001, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-003/cores} [junit4] 2> 10822 ERROR (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 10822 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 10823 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 10823 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 10823 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-21T12:24:54.606Z [junit4] 2> 10836 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-001/solr.xml [junit4] 2> 10913 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-003/cores [junit4] 2> 10913 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 10965 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 10983 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 10997 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 11002 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-001/collection1, trusted=true [junit4] 2> 11002 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 11002 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-003/cores/core/data/] [junit4] 2> 11142 WARN (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 11152 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 11154 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 11156 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 11156 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 11158 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ff953db[collection1] main] [junit4] 2> 11159 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestFieldLengthFeature_4388A8044C06E361-001/tempDir-001/collection1/conf [junit4] 2> 11159 INFO (coreLoadExecutor-43-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> 11167 WARN (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 11167 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 11169 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 11170 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 11170 INFO (coreLoadExecutor-43-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> 11170 WARN (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 11170 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 11170 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 11170 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 11170 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 11171 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 11177 INFO (searcherExecutor-44-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ff953db[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 11177 INFO (coreLoadExecutor-43-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568008563547176960 [junit4] 2> 11453 INFO (SUITE-TestFieldLengthFeature-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#42686 [junit4] 2> 11884 INFO (qtp10567004-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">1</field><field+name%3D"title">w1</field><field+name%3D"description">w1</field></doc></add>}{add=[1 (1568008564249722880)]} 0 49 [junit4] 2> 11900 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">2</field><field+name%3D"title">w2+2asd+asdd+didid</field><field+name%3D"description">w2+2asd+asdd+didid</field></doc></add>}{add=[2 (1568008564303200256)]} 0 2 [junit4] 2> 11905 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">3</field><field+name%3D"title">w3</field><field+name%3D"description">w3</field></doc></add>}{add=[3 (1568008564309491712)]} 0 1 [junit4] 2> 11913 INFO (qtp10567004-53) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">4</field><field+name%3D"title">w4</field><field+name%3D"description">w4</field></doc></add>}{add=[4 (1568008564317880320)]} 0 3 [junit4] 2> 11921 INFO (qtp10567004-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">5</field><field+name%3D"title">w5</field><field+name%3D"description">w5</field></doc></add>}{add=[5 (1568008564326268928)]} 0 4 [junit4] 2> 11933 INFO (qtp10567004-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">6</field><field+name%3D"title">w1+w2</field><field+name%3D"description">w1+w2</field></doc></add>}{add=[6 (1568008564338851840)]} 0 6 [junit4] 2> 11943 INFO (qtp10567004-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">7</field><field+name%3D"title">w1+w2+w3+w4+w5</field><field+name%3D"description">w1+w2+w3+w4+w5+w8</field></doc></add>}{add=[7 (1568008564348289024)]} 0 1 [junit4] 2> 11958 INFO (qtp10567004-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">8</field><field+name%3D"title">w1+w1+w1+w2+w2+w8</field><field+name%3D"description">w1+w1+w1+w2+w2</field></doc></add>}{add=[8 (1568008564358774784)]} 0 7 [junit4] 2> 11962 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 11962 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@561988b2 commitCommandVersion:0 [junit4] 2> 12024 INFO (qtp10567004-60) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3372c01e[collection1] main] [junit4] 2> 12025 INFO (searcherExecutor-44-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3372c01e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C8)))} [junit4] 2> 12026 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 12026 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<commit/>}{commit=} 0 64 [junit4] 2> 12038 INFO (TEST-TestFieldLengthFeature.testIfFieldIsMissingInDocumentLengthIsZero-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIfFieldIsMissingInDocumentLengthIsZero [junit4] 2> 12051 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">42</field><field+name%3D"title">w10</field></doc></add>}{add=[42 (1568008564459438080)]} 0 7 [junit4] 2> 12059 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 12059 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@561988b2 commitCommandVersion:0 [junit4] 2> 12116 INFO (qtp10567004-60) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@60828a63[collection1] main] [junit4] 2> 12118 INFO (searcherExecutor-44-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@60828a63[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C8) Uninverting(_1(7.0.0):C1)))} [junit4] 2> 12119 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 12119 INFO (qtp10567004-60) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<commit/>}{commit=} 0 61 [junit4] 2> 12124 INFO (TEST-TestFieldLengthFeature.testIfFieldIsMissingInDocumentLengthIsZero-seed#[4388A8044C06E361]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"description-length2", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.feature.FieldLengthFeature", [...truncated too long message...] 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:395) [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:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [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:745) [junit4] 2> 17953 INFO (qtp1310220093-125) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=*:*&indent=on&fl=*,score&rows=4&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3}} hits=5 status=0 QTime=4 [junit4] 2> 17969 ERROR (TEST-TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder-seed#[4388A8044C06E361]) [ ] o.a.s.u.RestTestBase query failed JSON validation. error=mismatch: '3'!='4' @ response/docs/[0]/id [junit4] 2> expected =/response/docs/[0]/id=='3' [junit4] 2> response = { [junit4] 2> "responseHeader":{ [junit4] 2> "status":0, [junit4] 2> "QTime":4, [junit4] 2> "params":{ [junit4] 2> "q":"*:*", [junit4] 2> "indent":"on", [junit4] 2> "fl":"*,score", [junit4] 2> "rows":"4", [junit4] 2> "wt":"json", [junit4] 2> "rq":"{!ltr reRankDocs=10 model=externalmodel efi.user_query=w3}"}}, [junit4] 2> "response":{"numFound":5,"start":0,"maxScore":0.36707595,"docs":[ [junit4] 2> { [junit4] 2> "id":"4", [junit4] 2> "title":"w3 w3", [junit4] 2> "description":"w4", [junit4] 2> "popularity":4, [junit4] 2> "_version_":1568008571059175424, [junit4] 2> "score":0.36707595}, [junit4] 2> { [junit4] 2> "id":"3", [junit4] 2> "title":"w3", [junit4] 2> "description":"w3", [junit4] 2> "popularity":3, [junit4] 2> "_version_":1568008571054981120, [junit4] 2> "score":0.33873552}, [junit4] 2> { [junit4] 2> "id":"1", [junit4] 2> "title":"w1 w3", [junit4] 2> "description":"w1", [junit4] 2> "popularity":1, [junit4] 2> "_version_":1568008571011989504, [junit4] 2> "score":0.2545195}, [junit4] 2> { [junit4] 2> "id":"2", [junit4] 2> "title":"w2", [junit4] 2> "description":"w2", [junit4] 2> "popularity":2, [junit4] 2> "_version_":1568008571050786816, [junit4] 2> "score":0.0}] [junit4] 2> }} [junit4] 2> [junit4] 2> request = /query?q=*%3A*&fl=*%2Cscore&rows=4&rq=%7B%21ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3%7D&wt=json&indent=on [junit4] 2> [junit4] 2> 17969 INFO (TEST-TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLTRScoringQueryParallelWeightCreationResultOrder [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestParallelWeightCreation -Dtests.method=testLTRScoringQueryParallelWeightCreationResultOrder -Dtests.seed=4388A8044C06E361 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-BO -Dtests.timezone=Egypt -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 1.70s J1 | TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder <<< [junit4] > Throwable #1: java.lang.RuntimeException: mismatch: '3'!='4' @ response/docs/[0]/id [junit4] > at __randomizedtesting.SeedInfo.seed([4388A8044C06E361:26C4886486648489]:0) [junit4] > at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248) [junit4] > at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192) [junit4] > at org.apache.solr.ltr.TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder(TestParallelWeightCreation.java:45) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 17993 INFO (TEST-TestParallelWeightCreation.testLTRQParserThreadInitialization-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLTRQParserThreadInitialization [junit4] 2> 17994 INFO (TEST-TestParallelWeightCreation.testLTRQParserThreadInitialization-seed#[4388A8044C06E361]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLTRQParserThreadInitialization [junit4] 2> 18007 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@58d15c58{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 18007 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=945311438 [junit4] 2> 18009 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 18009 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 18009 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 18010 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 18010 INFO (coreCloseExecutor-103-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4442b7 [junit4] 2> 18014 INFO (coreCloseExecutor-103-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4473527 [junit4] 2> 18017 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@75cec4e2{/solr,null,UNAVAILABLE} [junit4] 2> 18018 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 18018 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1355579715 [junit4] 2> 18018 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 18018 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 18018 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 18018 INFO (SUITE-TestParallelWeightCreation-seed#[4388A8044C06E361]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 18019 INFO (coreCloseExecutor-105-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2d3d5cd6 [junit4] 2> 18021 INFO (coreCloseExecutor-105-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=758996182 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.TestParallelWeightCreation_4388A8044C06E361-001 [junit4] 2> May 21, 2017 12:25:02 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=es-BO, timezone=Egypt [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=4,threads=1,free=200123600,total=324534272 [junit4] 2> NOTE: All tests run in this JVM: [TestEdisMaxSolrFeature, TestFieldValueFeature, TestLTRQParserExplain, TestParallelWeightCreation] [junit4] Completed [19/33 (4!)] on J1 in 11.84s, 2 tests, 1 error <<< FAILURES! [...truncated 36981 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org