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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org