Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6576/ Java: 64bit/jdk1.8.0_131 -XX:-UseCompressedOops -XX:+UseG1GC
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([6AA5FF1407F7B09C:A3DD4E73D838E75D]: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:748) 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([6AA5FF1407F7B09C:FE9DF74CD95D774]: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:748) 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([6AA5FF1407F7B09C:3794E8D18ACBC7F7]: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:748) 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([6AA5FF1407F7B09C:F593568A9F8DAF53]: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:748) Build Log: [...truncated 19680 lines...] [junit4] Suite: org.apache.solr.ltr.feature.TestFieldLengthFeature [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\init-core-data-001 [junit4] 2> 2060 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 2113 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.u.log Logging initialized @3270ms [junit4] 2> 2122 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 2146 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 2218 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.SolrResourceLoader Using system property solr.solr.home: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\contrib\ltr\src\test-files\solr [junit4] 2> 2358 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2546 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=example [junit4] 2> 3090 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 4403 WARN (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 4406 WARN (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 4407 WARN (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 4490 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4535 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 4559 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 4579 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\contrib\ltr\src\test-files\solr\collection1, trusted=true [junit4] 2> 4716 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> 4730 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4747 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\contrib\ltr\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\init-core-data-001\] [junit4] 2> 6431 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 6464 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 6464 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> 6483 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 6483 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 6677 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@28b4db3d[collection1] main] [junit4] 2> 6720 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\contrib\ltr\src\test-files\solr\collection1\conf; switching to use InMemory storage instead. [junit4] 2> 6770 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> 6771 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 6771 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 6771 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 6771 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 6774 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> 6776 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 6776 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 6776 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 6776 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 6776 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 6827 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 7042 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@28b4db3d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 7043 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 1568077817894141952 [junit4] 2> 7053 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 7109 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 7109 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 7111 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-003\cores\core [junit4] 2> 7443 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 7509 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@706bf610{/solr,null,AVAILABLE} [junit4] 2> 7525 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@da55ac8{HTTP/1.1,[http/1.1]}{127.0.0.1:54697} [junit4] 2> 7525 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.Server Started @8688ms [junit4] 2> 7526 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54697, configSetBaseDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-003\cores} [junit4] 2> 7574 ERROR (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 7575 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 7575 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 7576 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 7588 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-22T06:45:41.586Z [junit4] 2> 7598 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\solr.xml [junit4] 2> 7947 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-003\cores [junit4] 2> 7948 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 8071 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 8152 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 8172 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 8179 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1, trusted=true [junit4] 2> 8180 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 8180 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-003\cores\core\data\] [junit4] 2> 8361 WARN (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 8364 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 8368 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> 8372 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 8372 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 8376 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@743ad139[collection1] main] [junit4] 2> 8377 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1\conf [junit4] 2> 8378 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> 8379 WARN (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 8379 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 8379 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 8380 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 8380 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> 8380 WARN (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 8380 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 8381 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 8381 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 8381 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 8381 INFO (coreLoadExecutor-17-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 8384 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 1568077819300282368 [junit4] 2> 8386 INFO (searcherExecutor-18-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@743ad139[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 8749 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#54697 [junit4] 2> 9750 INFO (qtp1064670338-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">w1</field><field+name%3D"description">w1</field></doc></add>}{add=[1 (1568077820502999040)]} 0 465 [junit4] 2> 9860 INFO (qtp1064670338-23) [ 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 (1568077820845883392)]} 0 3 [junit4] 2> 9867 INFO (qtp1064670338-23) [ 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 (1568077820854272000)]} 0 1 [junit4] 2> 9871 INFO (qtp1064670338-24) [ 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 (1568077820858466304)]} 0 2 [junit4] 2> 9876 INFO (qtp1064670338-24) [ 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 (1568077820863709184)]} 0 1 [junit4] 2> 9880 INFO (qtp1064670338-24) [ 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 (1568077820868952064)]} 0 1 [junit4] 2> 9885 INFO (qtp1064670338-25) [ 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 (1568077820874194944)]} 0 1 [junit4] 2> 9892 INFO (qtp1064670338-26) [ 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 (1568077820880486400)]} 0 3 [junit4] 2> 9896 INFO (qtp1064670338-27) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 9896 INFO (qtp1064670338-27) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1be7a647 commitCommandVersion:0 [junit4] 2> 10102 INFO (qtp1064670338-27) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e5e292e[collection1] main] [junit4] 2> 10104 INFO (qtp1064670338-27) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10107 INFO (searcherExecutor-18-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e5e292e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C8)))} [junit4] 2> 10107 INFO (qtp1064670338-27) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<commit/>}{commit=} 0 211 [junit4] 2> 10124 INFO (TEST-TestFieldLengthFeature.testIfFieldIsEmptyLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIfFieldIsEmptyLengthIsZero [junit4] 2> 10127 INFO (qtp1064670338-28) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">43</field><field+name%3D"title">w11</field><field+name%3D"description"></field></doc></add>}{add=[43 (1568077821125853184)]} 0 2 [junit4] 2> 10130 INFO (qtp1064670338-21) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 10130 INFO (qtp1064670338-21) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1be7a647 commitCommandVersion:0 [junit4] 2> 10189 INFO (qtp1064670338-21) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@362af12a[collection1] main] [junit4] 2> 10190 INFO (searcherExecutor-18-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@362af12a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C8) Uninverting(_1(7.0.0):C1)))} [junit4] 2> 10191 INFO (qtp1064670338-21) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 10191 INFO (qtp1064670338-21) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<commit/>}{commit=} 0 61 [junit4] 2> 10196 INFO (TEST-TestFieldLengthFeature.testIfFieldIsEmptyLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"description-length3", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.feature.FieldLengthFeature", [junit4] 2> "params":{"field":"description"} [junit4] 2> } [junit4] 2> [junit4] 2> 10228 INFO (qtp1064670338-23) [ x:collection1] /solr SolrSchemaRestApi: [Restlet] ServerServlet: component class is null [junit4] 2> 10487 INFO (qtp1064670338-23) [ x:collection1] /solr SolrSchemaRestApi: [Restlet] Attaching application: org.apache.solr.rest.SolrSchemaRestApi@2cfa3e6 to URI: /solr/schema [junit4] 2> mei 22, 2017 12:45:44 AM org.restlet.Application start [junit4] 2> INFO: Starting org.apache.solr.rest.SolrSchemaRestApi application [junit4] 2> 10549 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.SolrSchemaRestApi createInboundRoot started for /schema [junit4] 2> 10564 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /feature-store [junit4] 2> 10564 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /managed [junit4] 2> 10564 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /model-store [junit4] 2> 10564 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager Attached 3 ManagedResource endpoints to Restlet router: /schema [junit4] 2> 10564 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.SolrSchemaRestApi createInboundRoot complete for /schema [junit4] 2> 10596 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@5ae88c0e] for /schema/feature-store [junit4] 2> 10598 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=description-length3, store=test, class=org.apache.solr.ltr.feature.FieldLengthFeature, params={field=description}} is a java.util.LinkedHashMap [junit4] 2> 10598 INFO (qtp1064670338-23) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=description-length3, store=test, class=org.apache.solr.ltr.feature.FieldLengthFeature, params={field=description}} [junit4] 2> 10627 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1\conf [junit4] 2> 10627 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=422 [junit4] 2> mei 22, 2017 12:45:44 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-22 00:45:44 127.0.0.1 - 127.0.0.1 54697 PUT /solr/schema/feature-store wt=json&indent=on 200 - 141 110 http://127.0.0.1:54697 Apache-HttpClient/4.4.1 (Java/1.8.0_131) - [junit4] 2> 10699 WARN (qtp1064670338-23) [ 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:748) [junit4] 2> 10755 INFO (TEST-TestFieldLengthFeature.testIfFieldIsEmptyLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"description-model3", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"description-length3"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"description-length3":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 10758 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@5ae88c0e]] for /schema/model-store [junit4] 2> 10759 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=description-model3, store=test, class=org.apache.solr.ltr.model.LinearModel, features=[{name=description-length3}], params={weights={description-length3=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 10772 INFO (qtp1064670338-23) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model description-model3 [junit4] 2> 10773 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1\conf [junit4] 2> 10773 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=16 [junit4] 2> mei 22, 2017 12:45:44 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-22 00:45:44 127.0.0.1 - 127.0.0.1 54697 PUT /solr/schema/model-store wt=json&indent=on 200 - 197 16 http://127.0.0.1:54697 Apache-HttpClient/4.4.1 (Java/1.8.0_131) - [junit4] 2> 10775 WARN (qtp1064670338-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:748) [junit4] 2> 11006 INFO (qtp1064670338-23) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w11&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Ddescription-model3+reRankDocs%3D8}} hits=1 status=0 QTime=227 [junit4] 2> 11027 INFO (TEST-TestFieldLengthFeature.testIfFieldIsEmptyLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testIfFieldIsEmptyLengthIsZero [junit4] 2> 11042 INFO (TEST-TestFieldLengthFeature.testRanking-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRanking [junit4] 2> 11043 INFO (TEST-TestFieldLengthFeature.testRanking-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"title-length", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.feature.FieldLengthFeature", [junit4] 2> "params":{"field":"title"} [junit4] 2> } [junit4] 2> [junit4] 2> 11045 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@5ae88c0e] for /schema/feature-store [junit4] 2> 11046 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=title-length, store=test, class=org.apache.solr.ltr.feature.FieldLengthFeature, params={field=title}} is a java.util.LinkedHashMap [junit4] 2> 11046 INFO (qtp1064670338-25) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=title-length, store=test, class=org.apache.solr.ltr.feature.FieldLengthFeature, params={field=title}} [junit4] 2> 11048 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1\conf [junit4] 2> 11048 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=3 [junit4] 2> mei 22, 2017 12:45:45 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-22 00:45:45 127.0.0.1 - 127.0.0.1 54697 PUT /solr/schema/feature-store wt=json&indent=on 200 - 128 3 http://127.0.0.1:54697 Apache-HttpClient/4.4.1 (Java/1.8.0_131) - [junit4] 2> 11050 WARN (qtp1064670338-25) [ 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:748) [junit4] 2> 11051 INFO (TEST-TestFieldLengthFeature.testRanking-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"title-model", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"title-length"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"title-length":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 11056 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[description-model3]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@5ae88c0e]] for /schema/model-store [junit4] 2> 11056 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=title-model, store=test, class=org.apache.solr.ltr.model.LinearModel, features=[{name=title-length}], params={weights={title-length=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 11056 INFO (qtp1064670338-23) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model title-model [junit4] 2> 11057 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1\conf [junit4] 2> 11057 INFO (qtp1064670338-23) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=4 [junit4] 2> mei 22, 2017 12:45:45 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-22 00:45:45 127.0.0.1 - 127.0.0.1 54697 PUT /solr/schema/model-store wt=json&indent=on 200 - 176 3 http://127.0.0.1:54697 Apache-HttpClient/4.4.1 (Java/1.8.0_131) - [junit4] 2> 11058 WARN (qtp1064670338-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:748) [junit4] 2> 11065 INFO (qtp1064670338-26) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json} hits=4 status=0 QTime=4 [junit4] 2> 11069 INFO (qtp1064670338-27) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json} hits=4 status=0 QTime=1 [junit4] 2> 11072 INFO (qtp1064670338-28) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json} hits=4 status=0 QTime=1 [junit4] 2> 11078 INFO (qtp1064670338-21) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json} hits=4 status=0 QTime=2 [junit4] 2> 11082 INFO (qtp1064670338-21) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json} hits=4 status=0 QTime=1 [junit4] 2> 11104 INFO (qtp1064670338-24) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dtitle-model+reRankDocs%3D4}} hits=4 status=0 QTime=5 [junit4] 2> 11110 INFO (qtp1064670338-24) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dtitle-model+reRankDocs%3D4}} hits=4 status=0 QTime=3 [junit4] 2> 11113 ERROR (TEST-TestFieldLengthFeature.testRanking-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.u.RestTestBase query failed JSON validation. error=mismatch: '8'!='1' @ response/docs/[0]/id [junit4] 2> expected =/response/docs/[0]/id=='8' [junit4] 2> response = { [junit4] 2> "responseHeader":{ [junit4] 2> "status":0, [junit4] 2> "QTime":3, [junit4] 2> "params":{ [junit4] 2> "q":"title:w1", [junit4] 2> "indent":"on", [junit4] 2> "fl":"*, score", [junit4] 2> "rows":"4", [junit4] 2> "wt":"json", [junit4] 2> "rq":"{!ltr model=title-model reRankDocs=4}"}}, [junit4] 2> "response":{"numFound":4,"start":0,"maxScore":2.95147899E18,"docs":[ [junit4] 2> { [junit4] 2> "id":"1", [junit4] 2> "title":"w1", [junit4] 2> "description":"w1", [junit4] 2> "_version_":1568077820502999040, [junit4] 2> "score":2.95147899E18}, [junit4] 2> { [junit4] 2> "id":"6", [junit4] 2> "title":"w1 w2", [junit4] 2> "description":"w1 w2", [junit4] 2> "_version_":1568077820868952064, [junit4] 2> "score":2.04963825E18}, [junit4] 2> { [junit4] 2> "id":"7", [junit4] 2> "title":"w1 w2 w3 w4 w5", [junit4] 2> "description":"w1 w2 w3 w4 w5 w8", [junit4] 2> "_version_":1568077820874194944, [junit4] 2> "score":7.3786975E17}, [junit4] 2> { [junit4] 2> "id":"8", [junit4] 2> "title":"w1 w1 w1 w2 w2 w8", [junit4] 2> "description":"w1 w1 w1 w2 w2", [junit4] 2> "_version_":1568077820880486400, [junit4] 2> "score":5.12409561E17}] [junit4] 2> }} [junit4] 2> [junit4] 2> request = /query?q=title%3Aw1&fl=*%2C+score&rows=4&rq=%7B%21ltr+model%3Dtitle-model+reRankDocs%3D4%7D&wt=json&indent=on [junit4] 2> [junit4] 2> 11113 INFO (TEST-TestFieldLengthFeature.testRanking-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRanking [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestFieldLengthFeature -Dtests.method=testRanking -Dtests.seed=6AA5FF1407F7B09C -Dtests.slow=true -Dtests.locale=nl -Dtests.timezone=America/Edmonton -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.09s J1 | TestFieldLengthFeature.testRanking <<< [junit4] > Throwable #1: java.lang.RuntimeException: mismatch: '8'!='1' @ response/docs/[0]/id [junit4] > at __randomizedtesting.SeedInfo.seed([6AA5FF1407F7B09C:F593568A9F8DAF53]: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.feature.TestFieldLengthFeature.testRanking(TestFieldLengthFeature.java:117) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 11125 INFO (TEST-TestFieldLengthFeature.testIfFieldIsMissingInDocumentLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIfFieldIsMissingInDocumentLengthIsZero [junit4] 2> 11131 INFO (qtp1064670338-25) [ 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 (1568077822177574912)]} 0 4 [junit4] 2> 11134 INFO (qtp1064670338-23) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 11134 INFO (qtp1064670338-23) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1be7a647 commitCommandVersion:0 [junit4] 2> 11142 INFO (qtp1064670338-23) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ad5073a[collection1] main] [junit4] 2> 11143 INFO (qtp1064670338-23) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 11144 INFO (searcherExecutor-18-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6ad5073a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C8) Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1)))} [junit4] 2> 11144 INFO (qtp1064670338-23) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<commit/>}{commit=} 0 10 [junit4] 2> 11147 INFO (TEST-TestFieldLengthFeature.testIfFieldIsMissingInDocumentLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] 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", [junit4] 2> "params":{"field":"description"} [junit4] 2> } [junit4] 2> [junit4] 2> 11150 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@5ae88c0e] for /schema/feature-store [junit4] 2> 11150 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=description-length2, store=test, class=org.apache.solr.ltr.feature.FieldLengthFeature, params={field=description}} is a java.util.LinkedHashMap [junit4] 2> 11150 INFO (qtp1064670338-25) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=description-length2, store=test, class=org.apache.solr.ltr.feature.FieldLengthFeature, params={field=description}} [junit4] 2> 11151 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1\conf [junit4] 2> 11151 INFO (qtp1064670338-25) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=2 [junit4] 2> mei 22, 2017 12:45:45 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-22 00:45:45 127.0.0.1 - 127.0.0.1 54697 PUT /solr/schema/feature-store wt=json&indent=on 200 - 141 3 http://127.0.0.1:54697 Apache-HttpClient/4.4.1 (Java/1.8.0_131) - [junit4] 2> 11152 WARN (qtp1064670338-25) [ 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:748) [junit4] 2> 11153 INFO (TEST-TestFieldLengthFeature.testIfFieldIsMissingInDocumentLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"description-model2", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"description-length2"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"description-length2":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 11155 INFO (qtp1064670338-27) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[title-model, description-model3]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@5ae88c0e]] for /schema/model-store [junit4] 2> 11155 INFO (qtp1064670338-27) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=description-model2, store=test, class=org.apache.solr.ltr.model.LinearModel, features=[{name=description-length2}], params={weights={description-length2=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 11157 INFO (qtp1064670338-27) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model description-model2 [junit4] 2> 11157 INFO (qtp1064670338-27) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001\tempDir-001\collection1\conf [junit4] 2> 11157 INFO (qtp1064670338-27) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=3 [junit4] 2> mei 22, 2017 12:45:45 AM org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2017-05-22 00:45:45 127.0.0.1 - 127.0.0.1 54697 PUT /solr/schema/model-store wt=json&indent=on 200 - 197 3 http://127.0.0.1:54697 Apache-HttpClient/4.4.1 (Java/1.8.0_131) - [junit4] 2> 11158 WARN (qtp1064670338-27) [ 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:748) [junit4] 2> 11161 INFO (qtp1064670338-28) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w10&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Ddescription-model2+reRankDocs%3D8}} hits=1 status=0 QTime=1 [junit4] 2> 11162 INFO (TEST-TestFieldLengthFeature.testIfFieldIsMissingInDocumentLengthIsZero-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testIfFieldIsMissingInDocumentLengthIsZero [junit4] 2> 11174 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@da55ac8{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 11174 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=687618423 [junit4] 2> 11177 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 11177 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 11177 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 11177 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 11181 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@35b63ea9 [junit4] 2> 11188 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=901136041 [junit4] 2> 11200 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@706bf610{/solr,null,UNAVAILABLE} [junit4] 2> 11214 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 11214 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1616891015 [junit4] 2> 11214 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 11215 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 11215 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 11215 INFO (SUITE-TestFieldLengthFeature-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 11216 INFO (coreCloseExecutor-25-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2ac3dc71 [junit4] 2> 11218 INFO (coreCloseExecutor-25-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=717479025 [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.feature.TestFieldLengthFeature_6AA5FF1407F7B09C-001 [junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=7, maxDocsPerChunk=487, blockSize=698), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=7, blockSize=698)), sim=RandomSimilarity(queryNorm=false): {}, locale=nl, timezone=America/Edmonton [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=3,threads=1,free=32207480,total=67108864 [junit4] 2> NOTE: All tests run in this JVM: [TestFieldLengthFeature] [junit4] Completed [2/33 (1!)] on J1 in 10.44s, 3 tests, 1 error <<< FAILURES! [...truncated 37 lines...] [junit4] Suite: org.apache.solr.ltr.TestSelectiveWeightCreation [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.TestSelectiveWeightCreation_6AA5FF1407F7B09C-001\init-core-data-001 [junit4] 2> 20197 WARN (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 20198 INFO (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 20202 INFO (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 20203 INFO (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 20234 INFO (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 20266 INFO (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=example [junit4] 2> 20305 INFO (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 20418 WARN (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 20418 WARN (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 20418 WARN (SUITE-TestSelectiveWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 20449 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 20461 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 20491 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 20494 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\contrib\ltr\src\test-files\solr\collection1, trusted=true [junit4] 2> 20495 WARN (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 20497 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 20497 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\contrib\ltr\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.TestSelectiveWeightCreation_6AA5FF1407F7B09C-001\init-core-data-001\] [junit4] 2> 20614 WARN (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 20615 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 20615 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 20616 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 20616 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 20616 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@697a10ea[collection1] main] [junit4] 2> 20617 WARN (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\contrib\ltr\src\test-files\solr\collection1\conf; switching to use InMemory storage instead. [junit4] 2> 20617 INFO (coreLoadExecutor-136-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> 20617 WARN (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 20617 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 20617 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 20617 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 20617 INFO (coreLoadExecutor-136-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> 20617 WARN (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 20617 INFO (coreLoadExecutor-136-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 20617 INFO [...truncated too long message...] 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:748) [junit4] 2> 29921 INFO (qtp652006162-372) [ 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> 29922 ERROR (TEST-TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder-seed#[6AA5FF1407F7B09C]) [ ] 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_":1568077841763926016, [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_":1568077841760780288, [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_":1568077841739808768, [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_":1568077841756585984, [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> 29922 INFO (TEST-TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLTRScoringQueryParallelWeightCreationResultOrder [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestParallelWeightCreation -Dtests.method=testLTRScoringQueryParallelWeightCreationResultOrder -Dtests.seed=6AA5FF1407F7B09C -Dtests.slow=true -Dtests.locale=es-EC -Dtests.timezone=Pacific/Pohnpei -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 1.12s J1 | TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder <<< [junit4] > Throwable #1: java.lang.RuntimeException: mismatch: '3'!='4' @ response/docs/[0]/id [junit4] > at __randomizedtesting.SeedInfo.seed([6AA5FF1407F7B09C:FE9DF74CD95D774]: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:748) [junit4] 2> 29927 INFO (TEST-TestParallelWeightCreation.testLTRQParserThreadInitialization-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLTRQParserThreadInitialization [junit4] 2> 29927 INFO (TEST-TestParallelWeightCreation.testLTRQParserThreadInitialization-seed#[6AA5FF1407F7B09C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLTRQParserThreadInitialization [junit4] 2> 29928 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5058b484{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 29928 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1731629809 [junit4] 2> 29929 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 29929 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 29929 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 29929 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 29929 INFO (coreCloseExecutor-286-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@721f5442 [junit4] 2> 29929 INFO (coreCloseExecutor-286-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1914655810 [junit4] 2> 29932 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6df41c59{/solr,null,UNAVAILABLE} [junit4] 2> 29932 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 29932 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=826504744 [junit4] 2> 29932 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 29932 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 29932 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 29933 INFO (SUITE-TestParallelWeightCreation-seed#[6AA5FF1407F7B09C]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 29933 INFO (coreCloseExecutor-288-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1454338b [junit4] 2> 29935 INFO (coreCloseExecutor-288-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=341062539 [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\J1\temp\solr.ltr.TestParallelWeightCreation_6AA5FF1407F7B09C-001 [junit4] 2> May 22, 2017 6:46:03 AM 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=true): {}, locale=es-EC, timezone=Pacific/Pohnpei [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=3,threads=1,free=60604608,total=134217728 [junit4] 2> NOTE: All tests run in this JVM: [TestFieldLengthFeature, TestOriginalScoreFeature, TestUserTermScorerQuery, TestFeatureExtractionFromMultipleSegments, TestLTRQParserExplain, TestSelectiveWeightCreation, TestFilterSolrFeature, TestLTRQParserPlugin, TestLTRReRankingPipeline, TestLTRWithFacet, TestLTRWithSort, TestParallelWeightCreation] [junit4] Completed [30/33 (4!)] on J1 in 11.28s, 2 tests, 1 error <<< FAILURES! [...truncated 36999 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org