Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1532/
8 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.ltr.feature.TestExternalFeatures
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.ltr.feature.TestExternalFeatures: 1) Thread[id=126,
name=qtp784058621-126, state=TIMED_WAITING, group=TGRP-TestExternalFeatures]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.ltr.feature.TestExternalFeatures:
1) Thread[id=126, name=qtp784058621-126, state=TIMED_WAITING,
group=TGRP-TestExternalFeatures]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([83E976A018F08D7C]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.ltr.feature.TestExternalFeatures
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=126, name=qtp784058621-126, state=TIMED_WAITING,
group=TGRP-TestExternalFeatures] at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=126, name=qtp784058621-126, state=TIMED_WAITING,
group=TGRP-TestExternalFeatures]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([83E976A018F08D7C]:0)
FAILED: org.apache.solr.uninverting.TestDocTermOrds.testEmptyIndex
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
FAILED: org.apache.solr.uninverting.TestDocTermOrds.testActuallySingleValued
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
FAILED: org.apache.solr.uninverting.TestDocTermOrds.testSortedTermsEnum
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
FAILED: org.apache.solr.uninverting.TestDocTermOrds.testRandom
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
FAILED: junit.framework.TestSuite.org.apache.solr.uninverting.TestDocTermOrds
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
FAILED: org.apache.solr.uninverting.TestDocTermOrds.testNumericEncoded64
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
Build Log:
[...truncated 15649 lines...]
[junit4] Suite: org.apache.solr.uninverting.TestDocTermOrds
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDocTermOrds
-Dtests.method=testTriggerUnInvertLimit -Dtests.seed=74EC5E24D6A423CC
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=no-NO -Dtests.timezone=Antarctica/Palmer -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDocTermOrds
-Dtests.method=testEmptyIndex -Dtests.seed=74EC5E24D6A423CC
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=no-NO -Dtests.timezone=Antarctica/Palmer -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 1.56s J0 | TestDocTermOrds.testEmptyIndex <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: GC overhead limit
exceeded
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDocTermOrds
-Dtests.method=testActuallySingleValued -Dtests.seed=74EC5E24D6A423CC
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=no-NO -Dtests.timezone=Antarctica/Palmer -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 2.42s J0 | TestDocTermOrds.testActuallySingleValued <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: GC overhead limit
exceeded
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDocTermOrds
-Dtests.method=testSortedTermsEnum -Dtests.seed=74EC5E24D6A423CC
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=no-NO -Dtests.timezone=Antarctica/Palmer -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 2.46s J0 | TestDocTermOrds.testSortedTermsEnum <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: GC overhead limit
exceeded
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDocTermOrds
-Dtests.method=testNumericEncoded64 -Dtests.seed=74EC5E24D6A423CC
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=no-NO -Dtests.timezone=Antarctica/Palmer -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 2.26s J0 | TestDocTermOrds.testNumericEncoded64 <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: GC overhead limit
exceeded
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDocTermOrds
-Dtests.method=testRandom -Dtests.seed=74EC5E24D6A423CC -Dtests.multiplier=2
-Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=no-NO -Dtests.timezone=Antarctica/Palmer -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 2.56s J0 | TestDocTermOrds.testRandom <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: GC overhead limit
exceeded
[junit4] 2> NOTE: test params are: codec=SimpleText,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@17c014),
locale=no-NO, timezone=Antarctica/Palmer
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=383835512,total=477626368
[junit4] 2> NOTE: All tests run in this JVM:
[PeerSyncWithIndexFingerprintCachingTest, MoveReplicaHDFSTest,
RestartWhileUpdatingTest, BigEndianAscendingWordDeserializerTest,
ExplicitHLLTest, OverseerRolesTest, SuggesterWFSTTest, TestIntervalFaceting,
DistributedSuggestComponentTest, TestComponentsName, TestManagedSchemaAPI,
TestHashQParserPlugin, ZkNodePropsTest, TestScoreJoinQPScore,
ConjunctionSolrSpellCheckerTest, TokenizerChainTest,
TestJsonFacetsWithNestedObjects, TestIBSimilarityFactory,
SpellCheckCollatorTest, TestExecutePlanAction, MBeansHandlerTest,
TestSimpleTextCodec, MetricsConfigTest, ChaosMonkeyNothingIsSafeTest,
TestCSVLoader, SortSpecParsingTest, SolrSlf4jReporterTest, InfoHandlerTest,
FullSolrCloudDistribCmdsTest, TestLuceneMatchVersion, CreateRoutedAliasTest,
TestClusterStateProvider, TestCrossCoreJoin, DeleteStatusTest,
TestOmitPositions, SynonymTokenizerTest, TestCloudNestedDocsSort,
TestDelegationWithHadoopAuth, SuggestComponentContextFilterQueryTest,
CursorPagingTest, TermVectorComponentDistributedTest, TestCloudRecovery,
MultiThreadedOCPTest, DocumentAnalysisRequestHandlerTest, TestTlogReplica,
CollectionStateFormat2Test, TestFieldSortValues,
HdfsAutoAddReplicasIntegrationTest, TestReplicationHandlerBackup,
ReplicaListTransformerTest, BadCopyFieldTest, SearchRateTriggerTest,
OpenCloseCoreStressTest, UtilsToolTest, TestMacroExpander,
TestStressCloudBlindAtomicUpdates, TestConfigReload, SolrIndexConfigTest,
TestReloadDeadlock, OpenExchangeRatesOrgProviderTest, TestExportWriter,
TestCloudJSONFacetJoinDomain, DistributedTermsComponentTest,
TestSchemaNameResource, HdfsUnloadDistributedZkTest, CurrencyFieldTypeTest,
PluginInfoTest, TestRTimerTree, SimpleFacetsTest, TemplateUpdateProcessorTest,
TestDocSet, TestNodeAddedTrigger, ReplaceNodeNoTargetTest,
TestShortCircuitedRequests, SchemaApiFailureTest, AliasIntegrationTest,
DistributedFacetPivotWhiteBoxTest, ExitableDirectoryReaderTest,
IndexBasedSpellCheckerTest, PeerSyncReplicationTest,
TestSolrCloudWithDelegationTokens, TestBackupRepositoryFactory,
DistributedFacetPivotLongTailTest, TestUnifiedSolrHighlighter,
CleanupOldIndexTest, TestGroupingSearch, TestCollationField,
TestSolrDeletionPolicy1, TriggerCooldownIntegrationTest,
TestBlendedInfixSuggestions, TestIndexingPerformance, TestCoreAdminApis,
ScheduledTriggerIntegrationTest, CloneFieldUpdateProcessorFactoryTest,
UUIDFieldTest, SimpleMLTQParserTest, BadIndexSchemaTest,
TestHdfsCloudBackupRestore, ExternalFileFieldSortTest,
BinaryUpdateRequestHandlerTest, TestPseudoReturnFields,
TestClusterStateMutator, FieldAnalysisRequestHandlerTest,
MetricTriggerIntegrationTest, DistributedExpandComponentTest,
TestJavabinTupleStreamParser, TestBulkSchemaConcurrent, TestCustomStream,
TestExtendedDismaxParser, XmlUpdateRequestHandlerTest, DocValuesNotIndexedTest,
DistributedQueryElevationComponentTest, TestDynamicFieldCollectionResource,
EnumFieldTest, VMParamsZkACLAndCredentialsProvidersTest,
OverriddenZkACLAndCredentialsProvidersTest, TestDistributedSearch,
TestSolrConfigHandlerConcurrent, TestImplicitCoreProperties,
TestExactStatsCache, LargeFieldTest, TestConfig, TestWriterPerf,
TestDefaultStatsCache, TestPHPSerializedResponseWriter,
TestRequestStatusCollectionAPI, TestDistributedMap, TestNoOpRegenerator,
ConnectionReuseTest, HdfsRecoverLeaseTest, UpdateParamsTest,
TestRawTransformer, BasicDistributedZkTest, SyncSliceTest, ShardRoutingTest,
ClusterStateUpdateTest, ZkSolrClientTest, ZkCLITest, TestFaceting,
DistributedSpellCheckComponentTest, TestReload, SolrCmdDistributorTest,
ConvertedLegacyTest, TestSort, DirectUpdateHandlerTest, TestBadConfig,
SoftAutoCommitTest, SpellPossibilityIteratorTest, TestCharFilters,
TestXIncludeConfig, EchoParamsTest, TestDFRSimilarityFactory,
TestLMDirichletSimilarityFactory, ResourceLoaderTest, URLClassifyProcessorTest,
DateMathParserTest, SpellingQueryConverterTest, RAMDirectoryFactoryTest,
TestLRUCache, TestDocumentBuilder, FileUtilsTest, TestRTGBase,
TestHighlightDedupGrouping, TestTolerantSearch,
TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors,
AddReplicaTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
DistribJoinFromCollectionTest, DistributedVersionInfoTest,
HttpPartitionOnCommitTest, LIRRollingUpdatesTest, LegacyCloudClusterPropTest,
MigrateRouteKeyTest, MultiSolrCloudTestCaseTest, NodeMutatorTest,
OverseerCollectionConfigSetProcessorTest, OverseerStatusTest, ReplaceNodeTest,
RollingRestartTest, TestRequestForwarding, TestSSLRandomization,
TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, TestUtilizeNode,
ZkShardTermsTest, AssignTest, CollectionReloadTest,
CollectionsAPIAsyncDistributedZkTest, CustomCollectionTest,
HdfsCollectionsAPIDistributedZkTest, MetricTriggerTest,
NodeLostTriggerIntegrationTest, ScheduledTriggerTest, TestComputePlanAction,
TestDistribStateManager, TestSimDistributedQueue, CdcrBidirectionalTest,
HDFSCollectionsAPITest, HdfsChaosMonkeyNothingIsSafeTest, HdfsNNFailoverTest,
HdfsTlogReplayBufferedWhileIndexingTest, HdfsWriteToMultipleCollectionsTest,
ImplicitSnitchTest, RuleEngineTest, BlobRepositoryCloudTest,
ConfigureRecoveryStrategyTest, CoreSorterTest, HdfsDirectoryFactoryTest,
TestConfigSetImmutable, TestConfigSetProperties, TestConfigSets,
TestDynamicLoading, TestInitParams, TestNRTOpen, TestReloadAndDeleteDocs,
TestShardHandlerFactory, TestSolrConfigHandler, ResponseBuilderTest,
TestCoreBackup, TestSQLHandler, TestNumericRangeQuery64,
TestSubQueryTransformerDistrib, AnalyticsQueryTest,
TestComplexPhraseLeadingWildcard, TestInitQParser, TestMissingGroups,
TestXmlQParser, TestJsonFacets, TestZkAclsWithHadoopAuth,
HttpSolrCallGetCoreTest, BufferStoreTest, TestDocTermOrds]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDocTermOrds
-Dtests.seed=74EC5E24D6A423CC -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=no-NO -Dtests.timezone=Antarctica/Palmer -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestDocTermOrds (suite) <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: GC overhead limit
exceeded
[junit4] Completed [785/802 (1!)] on J0 in 252.15s, 10 tests, 6 errors <<<
FAILURES!
[...truncated 46 lines...]
[junit4] JVM J0: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J0-20180416_153327_527822181018809972841.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/heapdumps/java_pid24166.hprof
...
[junit4] Heap dump file created [467861673 bytes in 1.113 secs]
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J0: stderr was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J0-20180416_153327_5273173350778028939000.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the
UncaughtExceptionHandler in thread "Lucene Merge Thread #207"
[junit4] WARN: Event serializer exception. -> java.lang.OutOfMemoryError: GC
overhead limit exceeded
[junit4] <<< JVM J0: EOF ----
[...truncated 7683 lines...]
[junit4] Suite: org.apache.solr.ltr.feature.TestExternalFeatures
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/init-core-data-001
[junit4] 2> 40140 WARN
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 40152 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 40154 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-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> 40154 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 40155 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 40176 WARN
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/ltr/src/test-files/solr/models
filtered by null to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/ltr/src/test-files/solr/models
[junit4] 2> 40191 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 40222 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=example
[junit4] 2> 40234 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id
[junit4] 2> 40289 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40303 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40303 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40308 INFO (coreLoadExecutor-84-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 40327 WARN (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/ltr/src/test-files/solr/models
filtered by null to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/ltr/src/test-files/solr/models
[junit4] 2> 40338 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 40366 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 40407 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id
[junit4] 2> 40409 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/ltr/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 40410 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry
'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40410 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 40410 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/ltr/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/init-core-data-001/]
[junit4] 2> 40523 WARN (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.c.RequestHandlers no default request handler is registered (either
'/select' or 'standard')
[junit4] 2> 40524 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 40524 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 40525 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 40525 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms;
[junit4] 2> 40528 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@389e6dad[collection1] main]
[junit4] 2> 40528 WARN (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.r.ManagedResourceStorage Cannot write to config directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/contrib/ltr/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 40528 INFO (coreLoadExecutor-84-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> 40529 WARN (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.r.ManagedResource No stored data found for /schema/feature-store
[junit4] 2> 40529 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------
[junit4] 2> 40529 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store
[junit4] 2> 40529 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.r.RestManager Registered new managed resource /schema/feature-store
[junit4] 2> 40529 INFO (coreLoadExecutor-84-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> 40529 WARN (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.r.ManagedResource No stored data found for /schema/model-store
[junit4] 2> 40529 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.l.s.r.ManagedModelStore INIT model store
[junit4] 2> 40529 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------
[junit4] 2> 40529 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store
[junit4] 2> 40529 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.r.RestManager Registered new managed resource /schema/model-store
[junit4] 2> 40530 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 40530 INFO (coreLoadExecutor-84-thread-1) [ x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1597922889723543552
[junit4] 2> 40534 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 40539 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 40539 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 40540 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-003/cores/core
[junit4] 2> 40547 INFO
(searcherExecutor-85-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@389e6dad[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 40548 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z,
git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 40550 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 40550 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 40550 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 40551 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1c7c6669{/solr,null,AVAILABLE}
[junit4] 2> 40551 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@280b2a4c{HTTP/1.1,[http/1.1]}{127.0.0.1:34624}
[junit4] 2> 40551 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.Server Started @40657ms
[junit4] 2> 40551 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34624,
configSetBaseDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-003/cores}
[junit4] 2> 40554 ERROR
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 40559 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 40559 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 40559 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 40559 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 40559 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-16T17:00:19.556Z
[junit4] 2> 40560 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/solr.xml
[junit4] 2> 40581 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 40694 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40710 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40711 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40718 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-003/cores
[junit4] 2> 40719 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 40733 INFO (coreLoadExecutor-95-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 40749 WARN (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/models
filtered by null to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/models
[junit4] 2> 40752 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 40765 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 40813 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id
[junit4] 2> 40827 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
configset
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/collection1,
trusted=true
[junit4] 2> 40828 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry
'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7645a22f
[junit4] 2> 40828 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 40828 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-003/cores/core/data/]
[junit4] 2> 40941 WARN (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.c.RequestHandlers no default request handler is registered (either
'/select' or 'standard')
[junit4] 2> 40942 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 40942 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 40943 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 40943 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms;
[junit4] 2> 40946 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@74678992[collection1] main]
[junit4] 2> 40947 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/collection1/conf
[junit4] 2> 40947 INFO (coreLoadExecutor-95-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> 40947 WARN (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.r.ManagedResource No stored data found for /schema/feature-store
[junit4] 2> 40948 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------
[junit4] 2> 40948 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store
[junit4] 2> 40948 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.r.RestManager Registered new managed resource /schema/feature-store
[junit4] 2> 40948 INFO (coreLoadExecutor-95-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> 40948 WARN (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.r.ManagedResource No stored data found for /schema/model-store
[junit4] 2> 40948 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.l.s.r.ManagedModelStore INIT model store
[junit4] 2> 40948 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------
[junit4] 2> 40948 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store
[junit4] 2> 40948 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.r.RestManager Registered new managed resource /schema/model-store
[junit4] 2> 40949 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 40949 INFO (coreLoadExecutor-95-thread-1) [ x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1597922890162896896
[junit4] 2> 40953 INFO
(searcherExecutor-96-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@74678992[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 41478 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrJettyTestBase Jetty Assigned Port#34624
[junit4] 2> 41548 INFO (qtp784058621-121) [ 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><field+name%3D"popularity">1</field></doc></add>}{add=[1
(1597922890749050880)]} 0 46
[junit4] 2> 41556 INFO (qtp784058621-123) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={stream.body=<add><doc><field+name%3D"id">2</field><field+name%3D"title">w2</field><field+name%3D"description">w2</field><field+name%3D"popularity">2</field></doc></add>}{add=[2
(1597922890797285376)]} 0 2
[junit4] 2> 41559 INFO (qtp784058621-125) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={stream.body=<add><doc><field+name%3D"id">3</field><field+name%3D"title">w3</field><field+name%3D"description">w3</field><field+name%3D"popularity">3</field></doc></add>}{add=[3
(1597922890801479680)]} 0 1
[junit4] 2> 41581 INFO (qtp784058621-123) [ 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><field+name%3D"popularity">4</field></doc></add>}{add=[4
(1597922890824548352)]} 0 17
[junit4] 2> 41585 INFO (qtp784058621-125) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={stream.body=<add><doc><field+name%3D"id">5</field><field+name%3D"title">w5</field><field+name%3D"description">w5</field><field+name%3D"popularity">5</field></doc></add>}{add=[5
(1597922890828742656)]} 0 1
[junit4] 2> 41597 INFO (qtp784058621-123) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1597922890842374144,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 41597 INFO (qtp784058621-123) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1a493621
commitCommandVersion:1597922890842374144
[junit4] 2> 41614 INFO (qtp784058621-123) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@31d26759[collection1] main]
[junit4] 2> 41617 INFO
(searcherExecutor-96-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@31d26759[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C5)))}
[junit4] 2> 41633 INFO (qtp784058621-123) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 41634 INFO (qtp784058621-123) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={stream.body=<commit/>}{commit=} 0 37
[junit4] 2> 41637 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.l.TestRerankBase send
[junit4] 2> [ {
[junit4] 2> "name" : "matchedTitle",
[junit4] 2> "class" : "org.apache.solr.ltr.feature.SolrFeature",
[junit4] 2> "params" : {
[junit4] 2> "q" : "{!terms f=title}${user_query}"
[junit4] 2> }
[junit4] 2> }, {
[junit4] 2> "name" : "docPopularity",
[junit4] 2> "class" : "org.apache.solr.ltr.feature.FieldValueFeature",
[junit4] 2> "store": "fstore2",
[junit4] 2> "params" : {
[junit4] 2> "field" : "popularity"
[junit4] 2> }
[junit4] 2> }, {
[junit4] 2> "name":"originalScore",
[junit4] 2> "class":"org.apache.solr.ltr.feature.OriginalScoreFeature",
[junit4] 2> "store": "fstore2",
[junit4] 2> "params":{}
[junit4] 2> }, {
[junit4] 2> "name" : "occurrences",
[junit4] 2> "class" : "org.apache.solr.ltr.feature.ValueFeature",
[junit4] 2> "store": "fstore3",
[junit4] 2> "params" : {
[junit4] 2> "value" : "${myOcc}",
[junit4] 2> "required" : false
[junit4] 2> }
[junit4] 2> }, {
[junit4] 2> "name":"originalScore",
[junit4] 2> "class":"org.apache.solr.ltr.feature.OriginalScoreFeature",
[junit4] 2> "store": "fstore3",
[junit4] 2> "params":{}
[junit4] 2> }, {
[junit4] 2> "name" : "popularity",
[junit4] 2> "class" : "org.apache.solr.ltr.feature.ValueFeature",
[junit4] 2> "store": "fstore4",
[junit4] 2> "params" : {
[junit4] 2> "value" : "${myPop}",
[junit4] 2> "required" : true
[junit4] 2> }
[junit4] 2> }, {
[junit4] 2> "name":"originalScore",
[junit4] 2> "class":"org.apache.solr.ltr.feature.OriginalScoreFeature",
[junit4] 2> "store": "fstore4",
[junit4] 2> "params":{}
[junit4] 2> }, {
[junit4] 2> "name" : "titlePhraseMatch",
[junit4] 2> "class" : "org.apache.solr.ltr.feature.SolrFeature",
[junit4] 2> "params" : {
[junit4] 2> "q" : "{!field f=title}${user_query}"
[junit4] 2> }
[junit4] 2> }, {
[junit4] 2> "name" : "titlePhrasesMatch",
[junit4] 2> "class" : "org.apache.solr.ltr.feature.SolrFeature",
[junit4] 2> "params" : {
[junit4] 2> "fq" : [ "{!field f=title}${userTitlePhrase1}",
"{!field f=title}${userTitlePhrase2}"]
[junit4] 2> }
[junit4] 2> } ]
[junit4] 2>
[junit4] 2> 41640 INFO (qtp784058621-125) [ x:collection1]
o.e.j.s.h.C.solr SolrSchemaRestApi: [Restlet] ServerServlet: component class is
null
[junit4] 2> 41642 INFO (qtp784058621-125) [ x:collection1]
o.e.j.s.h.C.solr SolrSchemaRestApi: [Restlet] Attaching application:
org.apache.solr.rest.SolrSchemaRestApi@5bbb9a9d to URI: /solr/schema
[junit4] 2> ápr. 16, 2018 2:00:20 DU org.restlet.Application start
[junit4] 2> INFO: Starting org.apache.solr.rest.SolrSchemaRestApi
application
[junit4] 2> 41723 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.SolrSchemaRestApi createInboundRoot started for /schema
[junit4] 2> 41725 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager Attached managed resource at path: /feature-store
[junit4] 2> 41726 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager Attached managed resource at path: /managed
[junit4] 2> 41726 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager Attached managed resource at path: /model-store
[junit4] 2> 41726 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager Attached 3 ManagedResource endpoints to Restlet router:
/schema
[junit4] 2> 41726 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.SolrSchemaRestApi createInboundRoot complete for /schema
[junit4] 2> 41740 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager Found ManagedResource
[org.apache.solr.ltr.store.rest.ManagedFeatureStore@18309245] for
/schema/feature-store
[junit4] 2> 41741 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.ManagedResource Processing update to /schema/feature-store:
[{name=matchedTitle, class=org.apache.solr.ltr.feature.SolrFeature,
params={q={!terms f=title}${user_query}}}, {name=docPopularity,
class=org.apache.solr.ltr.feature.FieldValueFeature, store=fstore2,
params={field=popularity}}, {name=originalScore,
class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore2,
params={}}, {name=occurrences, class=org.apache.solr.ltr.feature.ValueFeature,
store=fstore3, params={value=${myOcc}, required=false}}, {name=originalScore,
class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore3,
params={}}, {name=popularity, class=org.apache.solr.ltr.feature.ValueFeature,
store=fstore4, params={value=${myPop}, required=true}}, {name=originalScore,
class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore4,
params={}}, {name=titlePhraseMatch,
class=org.apache.solr.ltr.feature.SolrFeature, params={q={!field
f=title}${user_query}}}, {name=titlePhrasesMatch,
class=org.apache.solr.ltr.feature.SolrFeature, params={fq=[{!field
f=title}${userTitlePhrase1}, {!field f=title}${userTitlePhrase2}]}}] is a
java.util.ArrayList
[junit4] 2> 41742 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=matchedTitle,
class=org.apache.solr.ltr.feature.SolrFeature, params={q={!terms
f=title}${user_query}}}
[junit4] 2> 41757 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=docPopularity,
class=org.apache.solr.ltr.feature.FieldValueFeature, store=fstore2,
params={field=popularity}}
[junit4] 2> 41772 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=originalScore,
class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore2,
params={}}
[junit4] 2> 41774 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=occurrences,
class=org.apache.solr.ltr.feature.ValueFeature, store=fstore3,
params={value=${myOcc}, required=false}}
[junit4] 2> 41788 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=originalScore,
class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore3,
params={}}
[junit4] 2> 41788 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=popularity,
class=org.apache.solr.ltr.feature.ValueFeature, store=fstore4,
params={value=${myPop}, required=true}}
[junit4] 2> 41791 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=originalScore,
class=org.apache.solr.ltr.feature.OriginalScoreFeature, store=fstore4,
params={}}
[junit4] 2> 41791 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on
{name=titlePhraseMatch, class=org.apache.solr.ltr.feature.SolrFeature,
params={q={!field f=title}${user_query}}}
[junit4] 2> 41796 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedFeatureStore register feature based on
{name=titlePhrasesMatch, class=org.apache.solr.ltr.feature.SolrFeature,
params={fq=[{!field f=title}${userTitlePhrase1}, {!field
f=title}${userTitlePhrase2}]}}
[junit4] 2> 41800 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.ManagedResourceStorage Saved JSON object to path
_schema_feature-store.json using
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/collection1/conf
[junit4] 2> 41800 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store
params={indent=on&wt=json} status=0 QTime=160
[junit4] 2> ápr. 16, 2018 2:00:20 DU org.restlet.engine.log.LogFilter
afterHandle
[junit4] 2> INFO: 2018-04-16 14:00:20 127.0.0.1 -
127.0.0.1 34624 PUT /solr/schema/feature-store
wt=json&indent=on 200 - 1516 158 http://127.0.0.1:34624
Apache-HttpClient/4.5.3 (Java/1.8.0_152) -
[junit4] 2> 41807 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore
[availableModels=[]],
featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@18309245]] for
/schema/model-store
[junit4] 2> 41816 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.ManagedResource Processing update to /schema/model-store:
{class=org.apache.solr.ltr.model.LinearModel, name=externalmodel,
features=[{name=titlePhraseMatch}], params={weights={titlePhraseMatch=0.555}}}
is a java.util.LinkedHashMap
[junit4] 2> 41831 INFO (qtp784058621-125) [ x:collection1]
o.a.s.l.s.r.ManagedModelStore adding model externalmodel
[junit4] 2> 41834 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.ManagedResourceStorage Saved JSON object to path
_schema_model-store.json using
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-ltr/test/J2/temp/solr.ltr.feature.TestExternalFeatures_83E976A018F08D7C-001/tempDir-001/collection1/conf
[junit4] 2> 41834 INFO (qtp784058621-125) [ x:collection1]
o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store
params={indent=on&wt=json} status=0 QTime=28
[junit4] 2> ápr. 16, 2018 2:00:20 DU org.restlet.engine.log.LogFilter
afterHandle
[junit4] 2> INFO: 2018-04-16 14:00:20 127.0.0.1 -
127.0.0.1 34624 PUT /solr/schema/model-store
wt=json&indent=on 200 - 237 29 http://127.0.0.1:34624
Apache-HttpClient/4.5.3 (Java/1.8.0_152) -
[junit4] 2> 41847 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureRequiredInFq_shouldThrowException-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
featureExtraction_valueFeatureRequiredInFq_shouldThrowException
[junit4] 2> 41849 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=score,features:[fv+efi.user_query%3Duq+userTitlePhrase1%3Dutpp]&rows=1&wt=json}
hits=5 status=0 QTime=0
[junit4] 2> 41851 ERROR (qtp784058621-123) [ x:collection1]
o.a.s.s.HttpSolrCall null:java.lang.RuntimeException: Exception from
createWeight for SolrFeature [name=titlePhrasesMatch, params={fq=[{!field
f=title}${userTitlePhrase1}, {!field f=title}${userTitlePhrase2}]}]
SolrFeatureWeight requires efi parameter that was not passed in request.
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeights(LTRScoringQuery.java:242)
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeight(LTRScoringQuery.java:209)
[junit4] 2> at
org.apache.solr.ltr.response.transform.LTRFeatureLoggerTransformerFactory$FeatureTransformer.setContext(LTRFeatureLoggerTransformerFactory.java:246)
[junit4] 2> at
org.apache.solr.response.transform.DocTransformers.setContext(DocTransformers.java:69)
[junit4] 2> at
org.apache.solr.response.DocsStreamer.<init>(DocsStreamer.java:82)
[junit4] 2> at
org.apache.solr.response.ResultContext.getProcessedDocuments(ResultContext.java:55)
[junit4] 2> at
org.apache.solr.response.TextResponseWriter.writeDocuments(TextResponseWriter.java:270)
[junit4] 2> at
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:162)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeNamedListAsMapWithDups(JSONResponseWriter.java:210)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeNamedList(JSONResponseWriter.java:326)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeResponse(JSONResponseWriter.java:121)
[junit4] 2> at
org.apache.solr.response.JSONResponseWriter.write(JSONResponseWriter.java:72)
[junit4] 2> at
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:65)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:789)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:526)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:393)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:339)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[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:1637)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[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:527)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.solr.ltr.feature.FeatureException:
SolrFeatureWeight requires efi parameter that was not passed in request.
[junit4] 2> at
org.apache.solr.ltr.feature.SolrFeature$SolrFeatureWeight.<init>(SolrFeature.java:162)
[junit4] 2> at
org.apache.solr.ltr.feature.SolrFeature.createWeight(SolrFeature.java:114)
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeights(LTRScoringQuery.java:238)
[junit4] 2> ... 45 more
[junit4] 2>
[junit4] 2> 41854 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureRequiredInFq_shouldThrowException-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
featureExtraction_valueFeatureRequiredInFq_shouldThrowException
[junit4] 2> 41883 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureRequired_shouldThrowException-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
featureExtraction_valueFeatureRequired_shouldThrowException
[junit4] 2> 41886 INFO (qtp784058621-126) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=fvalias:[fv+store%3Dfstore4]&rows=1&wt=json} hits=5
status=0 QTime=2
[junit4] 2> 41887 ERROR (qtp784058621-126) [ x:collection1]
o.a.s.s.HttpSolrCall null:java.lang.RuntimeException: Exception from
createWeight for ValueFeature [name=popularity, params={value=${myPop},
required=true}] ValueFeatureWeight requires efi parameter that was not passed
in request.
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeights(LTRScoringQuery.java:242)
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeight(LTRScoringQuery.java:209)
[junit4] 2> at
org.apache.solr.ltr.response.transform.LTRFeatureLoggerTransformerFactory$FeatureTransformer.setContext(LTRFeatureLoggerTransformerFactory.java:246)
[junit4] 2> at
org.apache.solr.response.DocsStreamer.<init>(DocsStreamer.java:82)
[junit4] 2> at
org.apache.solr.response.ResultContext.getProcessedDocuments(ResultContext.java:55)
[junit4] 2> at
org.apache.solr.response.TextResponseWriter.writeDocuments(TextResponseWriter.java:270)
[junit4] 2> at
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:162)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeNamedListAsMapWithDups(JSONResponseWriter.java:210)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeNamedList(JSONResponseWriter.java:326)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeResponse(JSONResponseWriter.java:121)
[junit4] 2> at
org.apache.solr.response.JSONResponseWriter.write(JSONResponseWriter.java:72)
[junit4] 2> at
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:65)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:789)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:526)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:393)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:339)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[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:1637)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[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:527)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.solr.ltr.feature.FeatureException:
ValueFeatureWeight requires efi parameter that was not passed in request.
[junit4] 2> at
org.apache.solr.ltr.feature.ValueFeature$ValueFeatureWeight.<init>(ValueFeature.java:123)
[junit4] 2> at
org.apache.solr.ltr.feature.ValueFeature.createWeight(ValueFeature.java:108)
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeights(LTRScoringQuery.java:238)
[junit4] 2> ... 44 more
[junit4] 2>
[junit4] 2> 41893 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureRequired_shouldThrowException-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
featureExtraction_valueFeatureRequired_shouldThrowException
[junit4] 2> 41904 INFO
(TEST-TestExternalFeatures.testFeaturesUseStopwordQueryReturnEmptyFeatureVector-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testFeaturesUseStopwordQueryReturnEmptyFeatureVector
[junit4] 2> 41909 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,fv:[fv]&rows=1&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3D'a'+efi.userTitlePhrase1%3D'b'+efi.userTitlePhrase2%3D'c'}}
hits=5 status=0 QTime=3
[junit4] 2> 41912 INFO
(TEST-TestExternalFeatures.testFeaturesUseStopwordQueryReturnEmptyFeatureVector-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testFeaturesUseStopwordQueryReturnEmptyFeatureVector
[junit4] 2> 41926 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureImplicitlyNotRequired_shouldNotScoreFeature-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
featureExtraction_valueFeatureImplicitlyNotRequired_shouldNotScoreFeature
[junit4] 2> 41928 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=fvalias:[fv+store%3Dfstore3]&rows=1&wt=json} hits=5
status=0 QTime=0
[junit4] 2> 41933 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureImplicitlyNotRequired_shouldNotScoreFeature-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
featureExtraction_valueFeatureImplicitlyNotRequired_shouldNotScoreFeature
[junit4] 2> 42035 INFO
(TEST-TestExternalFeatures.testEfiInTransformerShouldNotChangeOrderOfRerankedResults-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testEfiInTransformerShouldNotChangeOrderOfRerankedResults
[junit4] 2> 42038 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score&rows=3&wt=json} hits=5 status=0 QTime=0
[junit4] 2> 42044 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score&rows=3&wt=json} hits=5 status=0 QTime=0
[junit4] 2> 42055 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score&rows=3&wt=json} hits=5 status=0 QTime=0
[junit4] 2> 42060 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score&rows=3&wt=json} hits=5 status=0 QTime=0
[junit4] 2> 42069 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score&rows=3&wt=json} hits=5 status=0 QTime=0
[junit4] 2> 42071 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score&rows=3&wt=json} hits=5 status=0 QTime=0
[junit4] 2> 42075 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=2
[junit4] 2> 42079 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=1
[junit4] 2> 42084 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=1
[junit4] 2> 42088 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=1
[junit4] 2> 42107 INFO (qtp784058621-123) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv+efi.user_query%3Dw2+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=1
[junit4] 2> 42386 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv+efi.user_query%3Dw2+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=268
[junit4] 2> 42401 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv+efi.user_query%3Dw2+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=1
[junit4] 2> 42404 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=*,score,[fv+efi.user_query%3Dw2+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5]&rows=3&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3+efi.userTitlePhrase1%3Dw4+efi.userTitlePhrase2%3Dw5}}
hits=5 status=0 QTime=1
[junit4] 2> 42406 INFO
(TEST-TestExternalFeatures.testEfiInTransformerShouldNotChangeOrderOfRerankedResults-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testEfiInTransformerShouldNotChangeOrderOfRerankedResults
[junit4] 2> 42418 INFO
(TEST-TestExternalFeatures.testEfiFeatureExtraction-seed#[83E976A018F08D7C]) [
] o.a.s.SolrTestCaseJ4 ###Starting testEfiFeatureExtraction
[junit4] 2> 42422 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=[fv]&rows=1&wt=json} hits=5 status=0 QTime=1
[junit4] 2> 42425 ERROR (qtp784058621-125) [ x:collection1]
o.a.s.s.HttpSolrCall null:java.lang.RuntimeException: Exception from
createWeight for SolrFeature [name=matchedTitle, params={q={!terms
f=title}${user_query}}] SolrFeatureWeight requires efi parameter that was not
passed in request.
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeights(LTRScoringQuery.java:242)
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeight(LTRScoringQuery.java:209)
[junit4] 2> at
org.apache.solr.ltr.response.transform.LTRFeatureLoggerTransformerFactory$FeatureTransformer.setContext(LTRFeatureLoggerTransformerFactory.java:246)
[junit4] 2> at
org.apache.solr.response.DocsStreamer.<init>(DocsStreamer.java:82)
[junit4] 2> at
org.apache.solr.response.ResultContext.getProcessedDocuments(ResultContext.java:55)
[junit4] 2> at
org.apache.solr.response.TextResponseWriter.writeDocuments(TextResponseWriter.java:270)
[junit4] 2> at
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:162)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeNamedListAsMapWithDups(JSONResponseWriter.java:210)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeNamedList(JSONResponseWriter.java:326)
[junit4] 2> at
org.apache.solr.response.JSONWriter.writeResponse(JSONResponseWriter.java:121)
[junit4] 2> at
org.apache.solr.response.JSONResponseWriter.write(JSONResponseWriter.java:72)
[junit4] 2> at
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:65)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:789)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:526)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:393)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:339)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[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:1637)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[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:527)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.solr.ltr.feature.FeatureException:
SolrFeatureWeight requires efi parameter that was not passed in request.
[junit4] 2> at
org.apache.solr.ltr.feature.SolrFeature$SolrFeatureWeight.<init>(SolrFeature.java:146)
[junit4] 2> at
org.apache.solr.ltr.feature.SolrFeature.createWeight(SolrFeature.java:114)
[junit4] 2> at
org.apache.solr.ltr.LTRScoringQuery.createWeights(LTRScoringQuery.java:238)
[junit4] 2> ... 44 more
[junit4] 2>
[junit4] 2> 42428 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=score,fvalias:[fv+store%3Dfstore3+efi.myOcc%3D2.3]&rows=1&wt=json}
hits=5 status=0 QTime=0
[junit4] 2> 42431 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=score,fvalias:[fv+store%3Dfstore3+efi.myOcc%3D2.3]&rows=1&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3}}
hits=5 status=0 QTime=0
[junit4] 2> 42432 INFO
(TEST-TestExternalFeatures.testEfiFeatureExtraction-seed#[83E976A018F08D7C]) [
] o.a.s.SolrTestCaseJ4 ###Ending testEfiFeatureExtraction
[junit4] 2> 42439 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureExplicitlyNotRequired_shouldNotScoreFeature-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
featureExtraction_valueFeatureExplicitlyNotRequired_shouldNotScoreFeature
[junit4] 2> 42483 INFO (qtp784058621-125) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/query
params={q=*:*&indent=on&fl=fvalias:[fv+store%3Dfstore3]&rows=1&wt=json} hits=5
status=0 QTime=0
[junit4] 2> 42485 INFO
(TEST-TestExternalFeatures.featureExtraction_valueFeatureExplicitlyNotRequired_shouldNotScoreFeature-seed#[83E976A018F08D7C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
featureExtraction_valueFeatureExplicitlyNotRequired_shouldNotScoreFeature
[junit4] 2> 42490 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@280b2a4c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 42495 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=464665807
[junit4] 2> 42495 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 42495 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@72818452: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@3fde3b8a
[junit4] 2> 42517 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 42517 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6603faed: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@599fc216
[junit4] 2> 42522 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 42522 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2002a407: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@5d8f1de8
[junit4] 2> 42531 INFO (coreCloseExecutor-101-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@5638523a
[junit4] 2> 42531 INFO (coreCloseExecutor-101-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=5638523a
[junit4] 2> 42532 INFO (coreCloseExecutor-101-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@222a8288: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@d50c865
[junit4] 2> 42593 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1c7c6669{/solr,null,UNAVAILABLE}
[junit4] 2> 42593 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 72593 WARN
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.u.t.QueuedThreadPool
QueuedThreadPool@qtp784058621{STOPPING,8<=8<=10000,i=0,q=3} Couldn't stop
Thread[qtp784058621-125,5,TGRP-TestExternalFeatures]
[junit4] 2> 72593 WARN
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.u.t.QueuedThreadPool
QueuedThreadPool@qtp784058621{STOPPING,8<=8<=10000,i=0,q=3} Couldn't stop
Thread[qtp784058621-123,5,TGRP-TestExternalFeatures]
[junit4] 2> 72593 WARN
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.e.j.u.t.QueuedThreadPool
QueuedThreadPool@qtp784058621{STOPPING,8<=8<=10000,i=0,q=3} Couldn't stop
Thread[qtp784058621-126,5,TGRP-TestExternalFeatures]
[junit4] 2> 72596 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 72596 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=416643198
[junit4] 2> 72596 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 72596 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4183cec1: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@204e831b
[junit4] 2> 72597 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 72597 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@fdf36a1: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@599fc216
[junit4] 2> 72598 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 72598 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@24e92dfc: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@5d8f1de8
[junit4] 2> 72600 INFO (coreCloseExecutor-103-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@350c4429
[junit4] 2> 72600 INFO (coreCloseExecutor-103-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=350c4429
[junit4] 2> 72601 INFO (coreCloseExecutor-103-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4134dd29: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@33b5a759
[junit4] 2> 72614 INFO
(SUITE-TestExternalFeatures-seed#[83E976A018F08D7C]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> Apr 16, 2018 5:00:51 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 4 leaked
thread(s).
[junit4] 2> Apr 16, 2018 5:02:11 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.ltr.feature.TestExternalFeatures:
[junit4] 2> 1) Thread[id=126, name=qtp784058621-126,
state=TIMED_WAITING, group=TGRP-TestExternalFeatures]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Apr 16, 2018 5:02:11 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=126, name=qtp784058621-126,
state=TIMED_WAITING, group=TGRP-TestExternalFeatures]
[junit4] 2> Apr 16, 2018 5:02:14 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=126, name=qtp784058621-126,
state=TIMED_WAITING, group=TGRP-TestExternalFeatures]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are:
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=31556, maxDocsPerChunk=8, blockSize=258),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=31556, blockSize=258)),
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4eb9dde4),
locale=hu-HU, timezone=America/Halifax
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=2,free=226781008,total=295174144
[junit4] 2> NOTE: All tests run in this JVM: [TestOriginalScoreScorer,
TestFilterSolrFeature, TestRerankBase, TestLTRQParserPlugin, TestAdapterModel,
TestExternalFeatures]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestExternalFeatures -Dtests.seed=83E976A018F08D7C
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=hu-HU -Dtests.timezone=America/Halifax -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | TestExternalFeatures (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.ltr.feature.TestExternalFeatures:
[junit4] > 1) Thread[id=126, name=qtp784058621-126,
state=TIMED_WAITING, group=TGRP-TestExternalFeatures]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([83E976A018F08D7C]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=126, name=qtp784058621-126,
state=TIMED_WAITING, group=TGRP-TestExternalFeatures]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([83E976A018F08D7C]:0)
[junit4] Completed [37/37 (1!)] on J2 in 115.58s, 7 tests, 2 errors <<<
FAILURES!
[...truncated 1754 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:651:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:585:
Some of the tests produced a heap dump, but did not fail. Maybe a suppressed
OutOfMemoryError? Dumps created:
* java_pid24166.hprof
Total time: 317 minutes 3 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
