Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21461/ Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.ltr.feature.TestValueFeature Error Message: 1 thread leaked from SUITE scope at org.apache.solr.ltr.feature.TestValueFeature: 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.ltr.feature.TestValueFeature: 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) at __randomizedtesting.SeedInfo.seed([4C88AA4CE6008AE3]:0) FAILED: junit.framework.TestSuite.org.apache.solr.ltr.feature.TestValueFeature Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at [email protected]/java.lang.Thread.run(Thread.java:844) at __randomizedtesting.SeedInfo.seed([4C88AA4CE6008AE3]:0) Build Log: [...truncated 22211 lines...] [junit4] Suite: org.apache.solr.ltr.feature.TestValueFeature [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/init-core-data-001 [junit4] 2> 17553 WARN (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 17553 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 17554 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 17555 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 17556 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 17561 WARN (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/ltr/src/test-files/solr/models filtered by null to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/ltr/src/test-files/solr/models [junit4] 2> 17563 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 17600 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=example [junit4] 2> 17607 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 17632 WARN (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 17632 WARN (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 17633 WARN (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 17633 INFO (coreLoadExecutor-61-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 17639 WARN (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/ltr/src/test-files/solr/models filtered by null to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/ltr/src/test-files/solr/models [junit4] 2> 17641 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 17652 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 17660 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 17662 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/ltr/src/test-files/solr/collection1, trusted=true [junit4] 2> 17663 WARN (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX. [junit4] 2> 17663 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 17663 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/ltr/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/init-core-data-001/] [junit4] 2> 17708 WARN (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 17708 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 17708 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 17709 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 17709 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 17710 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7e967ec5[collection1] main] [junit4] 2> 17710 WARN (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/ltr/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 17711 INFO (coreLoadExecutor-61-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> 17711 WARN (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 17711 INFO (coreLoadExecutor-61-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> 17711 WARN (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 17711 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 17712 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1592437962935631872 [junit4] 2> 17712 INFO (searcherExecutor-62-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7e967ec5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 17714 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 17717 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 17717 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 17718 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-003/cores/core [junit4] 2> 17720 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 17721 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 17721 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 17721 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 17722 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ea9ba52{/solr,null,AVAILABLE} [junit4] 2> 17723 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4af80d1a{SSL,[ssl, http/1.1]}{127.0.0.1:39905} [junit4] 2> 17723 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.Server Started @19292ms [junit4] 2> 17723 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, configSetBaseDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001, hostPort=39905, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-003/cores} [junit4] 2> 17723 ERROR (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 17723 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 17724 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 17724 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 17724 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-15T03:59:45.734145Z [junit4] 2> 17724 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/solr.xml [junit4] 2> 17767 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-003/cores [junit4] 2> 17767 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 17768 INFO (coreLoadExecutor-72-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 17778 WARN (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/models filtered by null to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/models [junit4] 2> 17780 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 17794 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 17801 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id [junit4] 2> 17804 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1, trusted=true [junit4] 2> 17804 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 17804 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-003/cores/core/data/] [junit4] 2> 17830 WARN (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 17831 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 17831 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 17831 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 17831 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; [junit4] 2> 17832 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5422429a[collection1] main] [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 17833 INFO (coreLoadExecutor-72-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> 17833 WARN (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/feature-store [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------ [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/feature-store [junit4] 2> 17833 INFO (coreLoadExecutor-72-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> 17833 WARN (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.r.ManagedResource No stored data found for /schema/model-store [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore INIT model store [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------ [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store [junit4] 2> 17833 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.r.RestManager Registered new managed resource /schema/model-store [junit4] 2> 17834 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 17834 INFO (coreLoadExecutor-72-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1592437963063558144 [junit4] 2> 17834 INFO (searcherExecutor-73-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5422429a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 17835 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#39905 [junit4] 2> 17878 INFO (qtp166588561-96) [ 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></doc></add>}{add=[1 (1592437963102355456)]} 0 7 [junit4] 2> 17884 INFO (qtp166588561-98) [ 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></doc></add>}{add=[2 (1592437963114938368)]} 0 1 [junit4] 2> 17887 INFO (qtp166588561-96) [ 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></doc></add>}{add=[3 (1592437963119132672)]} 0 1 [junit4] 2> 17891 INFO (qtp166588561-98) [ 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></doc></add>}{add=[4 (1592437963123326976)]} 0 0 [junit4] 2> 17894 INFO (qtp166588561-96) [ 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></doc></add>}{add=[5 (1592437963126472704)]} 0 0 [junit4] 2> 17898 INFO (qtp166588561-98) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">6</field><field+name%3D"title">w1+w2</field></doc></add>}{add=[6 (1592437963130667008)]} 0 0 [junit4] 2> 17902 INFO (qtp166588561-96) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">7</field><field+name%3D"title">w1+w2+w3+w4+w5</field></doc></add>}{add=[7 (1592437963134861312)]} 0 1 [junit4] 2> 17906 INFO (qtp166588561-98) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<add><doc><field+name%3D"id">8</field><field+name%3D"title">w1+w1+w1+w2+w2</field></doc></add>}{add=[8 (1592437963138007040)]} 0 1 [junit4] 2> 17909 INFO (qtp166588561-96) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1592437963142201344,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 17909 INFO (qtp166588561-96) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3503b309 commitCommandVersion:1592437963142201344 [junit4] 2> 17917 INFO (qtp166588561-96) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a382fcf[collection1] main] [junit4] 2> 17918 INFO (qtp166588561-96) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 17918 INFO (searcherExecutor-73-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3a382fcf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C8)))} [junit4] 2> 17918 INFO (qtp166588561-96) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={stream.body=<commit/>}{commit=} 0 9 [junit4] 2> 17929 INFO (TEST-TestValueFeature.testValueFeatureWithWhitespaceValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testValueFeatureWithWhitespaceValue [junit4] 2> 17929 INFO (TEST-TestValueFeature.testValueFeatureWithWhitespaceValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"c2", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "params":{"value":" "} [junit4] 2> } [junit4] 2> [junit4] 2> 17931 INFO (qtp166588561-98) [ x:collection1] o.e.j.s.h.C.solr SolrSchemaRestApi: [Restlet] ServerServlet: component class is null [junit4] 2> 17933 INFO (qtp166588561-98) [ x:collection1] o.e.j.s.h.C.solr SolrSchemaRestApi: [Restlet] Attaching application: org.apache.solr.rest.SolrSchemaRestApi@8a7cffa to URI: /solr/schema [junit4] 2> WKR 15, 2018 8:59:45 KIROKO org.restlet.Application start [junit4] 2> INFO: Starting org.apache.solr.rest.SolrSchemaRestApi application [junit4] 2> 17935 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.SolrSchemaRestApi createInboundRoot started for /schema [junit4] 2> 17935 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /feature-store [junit4] 2> 17936 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /managed [junit4] 2> 17936 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Attached managed resource at path: /model-store [junit4] 2> 17936 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Attached 3 ManagedResource endpoints to Restlet router: /schema [junit4] 2> 17936 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.SolrSchemaRestApi createInboundRoot complete for /schema [junit4] 2> 17936 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925] for /schema/feature-store [junit4] 2> 17936 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=c2, store=test, class=org.apache.solr.ltr.feature.ValueFeature, params={value= }} is a java.util.LinkedHashMap [junit4] 2> 17936 INFO (qtp166588561-98) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=c2, store=test, class=org.apache.solr.ltr.feature.ValueFeature, params={value= }} [junit4] 2> 17941 ERROR (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager null:org.apache.solr.ltr.feature.FeatureException: Empty field 'value' in params for ValueFeature [name=c2, params={value= }] [junit4] 2> at org.apache.solr.ltr.feature.ValueFeature.validate(ValueFeature.java:96) [junit4] 2> at org.apache.solr.ltr.feature.Feature.getInstance(Feature.java:79) [junit4] 2> at org.apache.solr.ltr.store.rest.ManagedFeatureStore.fromFeatureMap(ManagedFeatureStore.java:209) [junit4] 2> at org.apache.solr.ltr.store.rest.ManagedFeatureStore.addFeature(ManagedFeatureStore.java:121) [junit4] 2> at org.apache.solr.ltr.store.rest.ManagedFeatureStore.applyUpdatesToManagedData(ManagedFeatureStore.java:140) [junit4] 2> at org.apache.solr.rest.ManagedResource.doPut(ManagedResource.java:395) [junit4] 2> at org.apache.solr.rest.RestManager$ManagedEndpoint.put(RestManager.java:340) [junit4] 2> at org.restlet.resource.ServerResource.doHandle(ServerResource.java:447) [junit4] 2> at org.restlet.resource.ServerResource.doConditionalHandle(ServerResource.java:359) [junit4] 2> at org.restlet.resource.ServerResource.handle(ServerResource.java:1044) [junit4] 2> at org.restlet.resource.Finder.handle(Finder.java:236) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Router.doHandle(Router.java:422) [junit4] 2> at org.restlet.routing.Router.handle(Router.java:639) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.engine.application.StatusFilter.doHandle(StatusFilter.java:140) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202) [junit4] 2> at org.restlet.engine.application.ApplicationHelper.handle(ApplicationHelper.java:75) [junit4] 2> at org.restlet.Application.handle(Application.java:385) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Router.doHandle(Router.java:422) [junit4] 2> at org.restlet.routing.Router.handle(Router.java:639) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Router.doHandle(Router.java:422) [junit4] 2> at org.restlet.routing.Router.handle(Router.java:639) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202) [junit4] 2> at org.restlet.Component.handle(Component.java:408) [junit4] 2> at org.restlet.Server.handle(Server.java:507) [junit4] 2> at org.restlet.engine.connector.ServerHelper.handle(ServerHelper.java:63) [junit4] 2> at org.restlet.engine.adapter.HttpServerHelper.handle(HttpServerHelper.java:143) [junit4] 2> at org.restlet.ext.servlet.ServerServlet.service(ServerServlet.java:1117) [junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) [junit4] 2> at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:860) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [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.Dispatcher.forward(Dispatcher.java:203) [junit4] 2> at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:73) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:389) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) [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:455) [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.ssl.SslConnection.onFillable(SslConnection.java:289) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:149) [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.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 17941 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} msg={Empty field 'value' in params for ValueFeature [name=c2, params={value= }]} status=500 QTime=10 [junit4] 2> WKR 15, 2018 8:59:45 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:45 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/feature-store wt=json&indent=on 500 - 108 8 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 17945 ERROR (TEST-TestValueFeature.testValueFeatureWithWhitespaceValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.u.RestTestBase query failed JSON validation. error=mismatch: '0'!='500' @ responseHeader/status [junit4] 2> expected =/responseHeader/status==0 [junit4] 2> response = { [junit4] 2> "responseHeader":{ [junit4] 2> "status":500, [junit4] 2> "QTime":10}, [junit4] 2> "error":{ [junit4] 2> "msg":"Empty field 'value' in params for ValueFeature [name=c2, params={value= }]", [junit4] 2> "trace":"org.apache.solr.ltr.feature.FeatureException: Empty field 'value' in params for ValueFeature [name=c2, params={value= }]\n\tat org.apache.solr.ltr.feature.ValueFeature.validate(ValueFeature.java:96)\n\tat org.apache.solr.ltr.feature.Feature.getInstance(Feature.java:79)\n\tat org.apache.solr.ltr.store.rest.ManagedFeatureStore.fromFeatureMap(ManagedFeatureStore.java:209)\n\tat org.apache.solr.ltr.store.rest.ManagedFeatureStore.addFeature(ManagedFeatureStore.java:121)\n\tat org.apache.solr.ltr.store.rest.ManagedFeatureStore.applyUpdatesToManagedData(ManagedFeatureStore.java:140)\n\tat org.apache.solr.rest.ManagedResource.doPut(ManagedResource.java:395)\n\tat org.apache.solr.rest.RestManager$ManagedEndpoint.put(RestManager.java:340)\n\tat org.restlet.resource.ServerResource.doHandle(ServerResource.java:447)\n\tat org.restlet.resource.ServerResource.doConditionalHandle(ServerResource.java:359)\n\tat org.restlet.resource.ServerResource.handle(ServerResource.java:1044)\n\tat org.restlet.resource.Finder.handle(Finder.java:236)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Router.doHandle(Router.java:422)\n\tat org.restlet.routing.Router.handle(Router.java:639)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.engine.application.StatusFilter.doHandle(StatusFilter.java:140)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202)\n\tat org.restlet.engine.application.ApplicationHelper.handle(ApplicationHelper.java:75)\n\tat org.restlet.Application.handle(Application.java:385)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Router.doHandle(Router.java:422)\n\tat org.restlet.routing.Router.handle(Router.java:639)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Router.doHandle(Router.java:422)\n\tat org.restlet.routing.Router.handle(Router.java:639)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202)\n\tat org.restlet.Component.handle(Component.java:408)\n\tat org.restlet.Server.handle(Server.java:507)\n\tat org.restlet.engine.connector.ServerHelper.handle(ServerHelper.java:63)\n\tat org.restlet.engine.adapter.HttpServerHelper.handle(HttpServerHelper.java:143)\n\tat org.restlet.ext.servlet.ServerServlet.service(ServerServlet.java:1117)\n\tat javax.servlet.http.HttpServlet.service(HttpServlet.java:790)\n\tat org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:860)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:203)\n\tat org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:73)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:389)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:455)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:530)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:289)\n\tat org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:149)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)\n\tat org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)\n\tat org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)\n\tat org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)\n\tat org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)\n\tat org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)\n\tat java.base/java.lang.Thread.run(Thread.java:844)\n", [junit4] 2> "code":500}} [junit4] 2> [junit4] 2> request = /schema/feature-store?wt=json&indent=on [junit4] 2> [junit4] 2> 17945 INFO (TEST-TestValueFeature.testValueFeatureWithWhitespaceValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testValueFeatureWithWhitespaceValue [junit4] 2> 17949 INFO (TEST-TestValueFeature.testValueFeatureWithEmptyValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testValueFeatureWithEmptyValue [junit4] 2> 17949 INFO (TEST-TestValueFeature.testValueFeatureWithEmptyValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"c2", [junit4] 2> "store":"test", [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "params":{"value":""} [junit4] 2> } [junit4] 2> [junit4] 2> 17951 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925] for /schema/feature-store [junit4] 2> 17951 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=c2, store=test, class=org.apache.solr.ltr.feature.ValueFeature, params={value=}} is a java.util.LinkedHashMap [junit4] 2> 17951 INFO (qtp166588561-96) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=c2, store=test, class=org.apache.solr.ltr.feature.ValueFeature, params={value=}} [junit4] 2> 17953 ERROR (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager null:org.apache.solr.ltr.feature.FeatureException: Empty field 'value' in params for ValueFeature [name=c2, params={value=}] [junit4] 2> at org.apache.solr.ltr.feature.ValueFeature.validate(ValueFeature.java:96) [junit4] 2> at org.apache.solr.ltr.feature.Feature.getInstance(Feature.java:79) [junit4] 2> at org.apache.solr.ltr.store.rest.ManagedFeatureStore.fromFeatureMap(ManagedFeatureStore.java:209) [junit4] 2> at org.apache.solr.ltr.store.rest.ManagedFeatureStore.addFeature(ManagedFeatureStore.java:121) [junit4] 2> at org.apache.solr.ltr.store.rest.ManagedFeatureStore.applyUpdatesToManagedData(ManagedFeatureStore.java:140) [junit4] 2> at org.apache.solr.rest.ManagedResource.doPut(ManagedResource.java:395) [junit4] 2> at org.apache.solr.rest.RestManager$ManagedEndpoint.put(RestManager.java:340) [junit4] 2> at org.restlet.resource.ServerResource.doHandle(ServerResource.java:447) [junit4] 2> at org.restlet.resource.ServerResource.doConditionalHandle(ServerResource.java:359) [junit4] 2> at org.restlet.resource.ServerResource.handle(ServerResource.java:1044) [junit4] 2> at org.restlet.resource.Finder.handle(Finder.java:236) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Router.doHandle(Router.java:422) [junit4] 2> at org.restlet.routing.Router.handle(Router.java:639) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.engine.application.StatusFilter.doHandle(StatusFilter.java:140) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202) [junit4] 2> at org.restlet.engine.application.ApplicationHelper.handle(ApplicationHelper.java:75) [junit4] 2> at org.restlet.Application.handle(Application.java:385) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Router.doHandle(Router.java:422) [junit4] 2> at org.restlet.routing.Router.handle(Router.java:639) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.routing.Router.doHandle(Router.java:422) [junit4] 2> at org.restlet.routing.Router.handle(Router.java:639) [junit4] 2> at org.restlet.routing.Filter.doHandle(Filter.java:150) [junit4] 2> at org.restlet.routing.Filter.handle(Filter.java:197) [junit4] 2> at org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202) [junit4] 2> at org.restlet.Component.handle(Component.java:408) [junit4] 2> at org.restlet.Server.handle(Server.java:507) [junit4] 2> at org.restlet.engine.connector.ServerHelper.handle(ServerHelper.java:63) [junit4] 2> at org.restlet.engine.adapter.HttpServerHelper.handle(HttpServerHelper.java:143) [junit4] 2> at org.restlet.ext.servlet.ServerServlet.service(ServerServlet.java:1117) [junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) [junit4] 2> at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:860) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [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.Dispatcher.forward(Dispatcher.java:203) [junit4] 2> at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:73) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:389) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) [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:455) [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.ssl.SslConnection.onFillable(SslConnection.java:289) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:149) [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.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 17953 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} msg={Empty field 'value' in params for ValueFeature [name=c2, params={value=}]} status=500 QTime=3 [junit4] 2> WKR 15, 2018 8:59:45 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:45 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/feature-store wt=json&indent=on 500 - 107 4 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 17957 ERROR (TEST-TestValueFeature.testValueFeatureWithEmptyValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.u.RestTestBase query failed JSON validation. error=mismatch: '0'!='500' @ responseHeader/status [junit4] 2> expected =/responseHeader/status==0 [junit4] 2> response = { [junit4] 2> "responseHeader":{ [junit4] 2> "status":500, [junit4] 2> "QTime":3}, [junit4] 2> "error":{ [junit4] 2> "msg":"Empty field 'value' in params for ValueFeature [name=c2, params={value=}]", [junit4] 2> "trace":"org.apache.solr.ltr.feature.FeatureException: Empty field 'value' in params for ValueFeature [name=c2, params={value=}]\n\tat org.apache.solr.ltr.feature.ValueFeature.validate(ValueFeature.java:96)\n\tat org.apache.solr.ltr.feature.Feature.getInstance(Feature.java:79)\n\tat org.apache.solr.ltr.store.rest.ManagedFeatureStore.fromFeatureMap(ManagedFeatureStore.java:209)\n\tat org.apache.solr.ltr.store.rest.ManagedFeatureStore.addFeature(ManagedFeatureStore.java:121)\n\tat org.apache.solr.ltr.store.rest.ManagedFeatureStore.applyUpdatesToManagedData(ManagedFeatureStore.java:140)\n\tat org.apache.solr.rest.ManagedResource.doPut(ManagedResource.java:395)\n\tat org.apache.solr.rest.RestManager$ManagedEndpoint.put(RestManager.java:340)\n\tat org.restlet.resource.ServerResource.doHandle(ServerResource.java:447)\n\tat org.restlet.resource.ServerResource.doConditionalHandle(ServerResource.java:359)\n\tat org.restlet.resource.ServerResource.handle(ServerResource.java:1044)\n\tat org.restlet.resource.Finder.handle(Finder.java:236)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Router.doHandle(Router.java:422)\n\tat org.restlet.routing.Router.handle(Router.java:639)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.engine.application.StatusFilter.doHandle(StatusFilter.java:140)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202)\n\tat org.restlet.engine.application.ApplicationHelper.handle(ApplicationHelper.java:75)\n\tat org.restlet.Application.handle(Application.java:385)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Router.doHandle(Router.java:422)\n\tat org.restlet.routing.Router.handle(Router.java:639)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.routing.Router.doHandle(Router.java:422)\n\tat org.restlet.routing.Router.handle(Router.java:639)\n\tat org.restlet.routing.Filter.doHandle(Filter.java:150)\n\tat org.restlet.routing.Filter.handle(Filter.java:197)\n\tat org.restlet.engine.CompositeHelper.handle(CompositeHelper.java:202)\n\tat org.restlet.Component.handle(Component.java:408)\n\tat org.restlet.Server.handle(Server.java:507)\n\tat org.restlet.engine.connector.ServerHelper.handle(ServerHelper.java:63)\n\tat org.restlet.engine.adapter.HttpServerHelper.handle(HttpServerHelper.java:143)\n\tat org.restlet.ext.servlet.ServerServlet.service(ServerServlet.java:1117)\n\tat javax.servlet.http.HttpServlet.service(HttpServlet.java:790)\n\tat org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:860)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:203)\n\tat org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:73)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:389)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:455)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:530)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:289)\n\tat org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:149)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)\n\tat org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)\n\tat org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)\n\tat org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)\n\tat org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)\n\tat org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)\n\tat java.base/java.lang.Thread.run(Thread.java:844)\n", [junit4] 2> "code":500}} [junit4] 2> [junit4] 2> request = /schema/feature-store?wt=json&indent=on [junit4] 2> [junit4] 2> 17957 INFO (TEST-TestValueFeature.testValueFeatureWithEmptyValue-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testValueFeatureWithEmptyValue [junit4] 2> 17960 INFO (TEST-TestValueFeature.testRerankingWithEfiValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRerankingWithEfiValueFeatureReplacesDocScore [junit4] 2> 17960 INFO (TEST-TestValueFeature.testRerankingWithEfiValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"c6", [junit4] 2> "store":"c6", [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "params":{"value":"${val6}"} [junit4] 2> } [junit4] 2> [junit4] 2> 17967 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925] for /schema/feature-store [junit4] 2> 17967 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=c6, store=c6, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val6}}} is a java.util.LinkedHashMap [junit4] 2> 17967 INFO (qtp166588561-98) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=c6, store=c6, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val6}}} [junit4] 2> 17968 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 17968 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=1 [junit4] 2> WKR 15, 2018 8:59:45 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:45 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/feature-store wt=json&indent=on 200 - 112 1 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 17970 INFO (TEST-TestValueFeature.testRerankingWithEfiValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"m6", [junit4] 2> "store":"c6", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"c6"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"c6":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 17975 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925]] for /schema/model-store [junit4] 2> 17976 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=m6, store=c6, class=org.apache.solr.ltr.model.LinearModel, features=[{name=c6}], params={weights={c6=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 17978 INFO (qtp166588561-98) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model m6 [junit4] 2> 17978 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 17979 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=7 [junit4] 2> WKR 15, 2018 8:59:45 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:45 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/model-store wt=json&indent=on 200 - 145 6 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 17986 INFO (qtp166588561-96) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm6+reRankDocs%3D4+efi.val6%3D'2'}} hits=4 status=0 QTime=4 [junit4] 2> 17990 INFO (qtp166588561-96) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm6+reRankDocs%3D4+efi.val6%3D'2'}} hits=4 status=0 QTime=0 [junit4] 2> 17995 INFO (qtp166588561-96) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm6+reRankDocs%3D4+efi.val6%3D'2'}} hits=4 status=0 QTime=0 [junit4] 2> 17998 INFO (qtp166588561-98) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm6+reRankDocs%3D4+efi.val6%3D'2'}} hits=4 status=0 QTime=1 [junit4] 2> 17999 INFO (TEST-TestValueFeature.testRerankingWithEfiValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRerankingWithEfiValueFeatureReplacesDocScore [junit4] 2> 18007 INFO (TEST-TestValueFeature.testValueFeatureExplictlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testValueFeatureExplictlyNotRequiredShouldReturnOkStatusCode [junit4] 2> 18007 INFO (TEST-TestValueFeature.testValueFeatureExplictlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"c7", [junit4] 2> "store":"c7", [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "params":{"value":"${val7}","required":false} [junit4] 2> } [junit4] 2> [junit4] 2> 18016 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925] for /schema/feature-store [junit4] 2> 18016 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=c7, store=c7, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val7}, required=false}} is a java.util.LinkedHashMap [junit4] 2> 18016 INFO (qtp166588561-96) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=c7, store=c7, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val7}, required=false}} [junit4] 2> 18017 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18017 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=4 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/feature-store wt=json&indent=on 200 - 129 4 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18019 INFO (TEST-TestValueFeature.testValueFeatureExplictlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"m7", [junit4] 2> "store":"c7", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"c7"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"c7":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 18021 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[m6]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925]] for /schema/model-store [junit4] 2> 18021 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=m7, store=c7, class=org.apache.solr.ltr.model.LinearModel, features=[{name=c7}], params={weights={c7=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 18022 INFO (qtp166588561-98) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model m7 [junit4] 2> 18022 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18022 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=1 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/model-store wt=json&indent=on 200 - 145 1 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18033 INFO (qtp166588561-98) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score,fvonly:[fvonly]&rows=4&wt=json&rq={!ltr+model%3Dm7+reRankDocs%3D4}} hits=4 status=0 QTime=2 [junit4] 2> 18034 INFO (TEST-TestValueFeature.testValueFeatureExplictlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testValueFeatureExplictlyNotRequiredShouldReturnOkStatusCode [junit4] 2> 18045 INFO (TEST-TestValueFeature.testRerankingWithConstantValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRerankingWithConstantValueFeatureReplacesDocScore [junit4] 2> 18045 INFO (TEST-TestValueFeature.testRerankingWithConstantValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"c3", [junit4] 2> "store":"c3", [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "params":{"value":2} [junit4] 2> } [junit4] 2> [junit4] 2> 18047 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925] for /schema/feature-store [junit4] 2> 18047 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=c3, store=c3, class=org.apache.solr.ltr.feature.ValueFeature, params={value=2}} is a java.util.LinkedHashMap [junit4] 2> 18047 INFO (qtp166588561-96) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=c3, store=c3, class=org.apache.solr.ltr.feature.ValueFeature, params={value=2}} [junit4] 2> 18048 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18048 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=1 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/feature-store wt=json&indent=on 200 - 104 2 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18049 INFO (TEST-TestValueFeature.testRerankingWithConstantValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"m3", [junit4] 2> "store":"c3", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"c3"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"c3":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 18051 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[m6, m7]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925]] for /schema/model-store [junit4] 2> 18051 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=m3, store=c3, class=org.apache.solr.ltr.model.LinearModel, features=[{name=c3}], params={weights={c3=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 18051 INFO (qtp166588561-98) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model m3 [junit4] 2> 18052 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18052 INFO (qtp166588561-98) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=1 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/model-store wt=json&indent=on 200 - 145 1 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18057 INFO (qtp166588561-96) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm3+reRankDocs%3D4}} hits=4 status=0 QTime=0 [junit4] 2> 18060 INFO (qtp166588561-98) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm3+reRankDocs%3D4}} hits=4 status=0 QTime=0 [junit4] 2> 18064 INFO (qtp166588561-96) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm3+reRankDocs%3D4}} hits=4 status=0 QTime=1 [junit4] 2> 18068 INFO (qtp166588561-98) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&rq={!ltr+model%3Dm3+reRankDocs%3D4}} hits=4 status=0 QTime=0 [junit4] 2> 18070 INFO (TEST-TestValueFeature.testRerankingWithConstantValueFeatureReplacesDocScore-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRerankingWithConstantValueFeatureReplacesDocScore [junit4] 2> 18078 INFO (TEST-TestValueFeature.testValueFeatureImplicitlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testValueFeatureImplicitlyNotRequiredShouldReturnOkStatusCode [junit4] 2> 18079 INFO (TEST-TestValueFeature.testValueFeatureImplicitlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"c5", [junit4] 2> "store":"c5", [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "params":{"value":"${val6}"} [junit4] 2> } [junit4] 2> [junit4] 2> 18080 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925] for /schema/feature-store [junit4] 2> 18081 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=c5, store=c5, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val6}}} is a java.util.LinkedHashMap [junit4] 2> 18081 INFO (qtp166588561-96) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=c5, store=c5, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val6}}} [junit4] 2> 18081 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18081 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=1 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/feature-store wt=json&indent=on 200 - 112 1 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18083 INFO (TEST-TestValueFeature.testValueFeatureImplicitlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"m5", [junit4] 2> "store":"c5", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"c5"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"c5":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 18085 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[m3, m6, m7]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925]] for /schema/model-store [junit4] 2> 18085 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=m5, store=c5, class=org.apache.solr.ltr.model.LinearModel, features=[{name=c5}], params={weights={c5=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 18085 INFO (qtp166588561-96) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model m5 [junit4] 2> 18086 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18086 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=1 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/model-store wt=json&indent=on 200 - 145 2 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18090 INFO (qtp166588561-98) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/query params={q=title:w1&indent=on&fl=*,+score,fvonly:[fvonly]&rows=4&wt=json&rq={!ltr+model%3Dm5+reRankDocs%3D4}} hits=4 status=0 QTime=0 [junit4] 2> 18091 INFO (TEST-TestValueFeature.testValueFeatureImplicitlyNotRequiredShouldReturnOkStatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testValueFeatureImplicitlyNotRequiredShouldReturnOkStatusCode [junit4] 2> 18094 INFO (TEST-TestValueFeature.testValueFeatureRequiredShouldReturn400StatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testValueFeatureRequiredShouldReturn400StatusCode [junit4] 2> 18094 INFO (TEST-TestValueFeature.testValueFeatureRequiredShouldReturn400StatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading feauture [junit4] 2> { [junit4] 2> "name":"c8", [junit4] 2> "store":"c8", [junit4] 2> "class":"org.apache.solr.ltr.feature.ValueFeature", [junit4] 2> "params":{"value":"${val8}","required":true} [junit4] 2> } [junit4] 2> [junit4] 2> 18096 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925] for /schema/feature-store [junit4] 2> 18096 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/feature-store: {name=c8, store=c8, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val8}, required=true}} is a java.util.LinkedHashMap [junit4] 2> 18096 INFO (qtp166588561-96) [ x:collection1] o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=c8, store=c8, class=org.apache.solr.ltr.feature.ValueFeature, params={value=${val8}, required=true}} [junit4] 2> 18097 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_feature-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18097 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/feature-store params={indent=on&wt=json} status=0 QTime=1 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/feature-store wt=json&indent=on 200 - 128 1 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18099 INFO (TEST-TestValueFeature.testValueFeatureRequiredShouldReturn400StatusCode-seed#[4C88AA4CE6008AE3]) [ ] o.a.s.l.TestRerankBase loading model [junit4] 2> { [junit4] 2> "name":"m8", [junit4] 2> "store":"c8", [junit4] 2> "class":"org.apache.solr.ltr.model.LinearModel", [junit4] 2> "features":[ [junit4] 2> { "name":"c8"} [junit4] 2> ] [junit4] 2> , [junit4] 2> "params":{"weights":{"c8":1.0}} [junit4] 2> } [junit4] 2> [junit4] 2> 18102 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore [availableModels=[m3, m5, m6, m7]], featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@4e577925]] for /schema/model-store [junit4] 2> 18102 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResource Processing update to /schema/model-store: {name=m8, store=c8, class=org.apache.solr.ltr.model.LinearModel, features=[{name=c8}], params={weights={c8=1.0}}} is a java.util.LinkedHashMap [junit4] 2> 18102 INFO (qtp166588561-96) [ x:collection1] o.a.s.l.s.r.ManagedModelStore adding model m8 [junit4] 2> 18103 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.ManagedResourceStorage Saved JSON object to path _schema_model-store.json using file:dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/J1/temp/solr.ltr.feature.TestValueFeature_4C88AA4CE6008AE3-001/tempDir-001/collection1/conf [junit4] 2> 18103 INFO (qtp166588561-96) [ x:collection1] o.a.s.r.RestManager [collection1] webapp=/solr path=/schema/model-store params={indent=on&wt=json} status=0 QTime=2 [junit4] 2> WKR 15, 2018 8:59:46 KIROKO org.restlet.engine.log.LogFilter afterHandle [junit4] 2> INFO: 2018-02-15 08:59:46 127.0.0.1 - 127.0.0.1 39905 PUT /solr/schema/model-store wt=json&indent=on 200 - 145 2 https://127.0.0.1:39905 Apache-HttpClient/4.5.3 (Java/9.0.4) - [junit4] 2> 18107 ERROR (qtp166588561-98) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: java.lang.RuntimeException: Exception from createWeight for ValueFeature [name=c8, params={value=${val8}, required=true}] ValueFeatureWeight requires efi parameter that was not passed in request. [junit4] 2> at org.apache.solr.search.ReRankCollector.topDocs(ReRankCollector.java:139) [junit4] 2> at org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1602) [junit4] 2> at org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1416) [junit4] 2> at org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:583) [junit4] 2> at org.apache.solr.handler.component.QueryComponent.doProcessUngroupedSearch(QueryComponent.java:1435) [junit4] 2> at org.apache.solr.handler.component.QueryComponent.process(QueryComponent.java:375) [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:295) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) [junit4] [...truncated too long message...] [junit4] 2> 18113 INFO (coreCloseExecutor-78-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e8610e3 [junit4] 2> 18113 INFO (coreCloseExecutor-78-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=243667171 [junit4] 2> 18116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5ea9ba52{/solr,null,UNAVAILABLE} [junit4] 2> 18116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 48114 WARN (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.e.j.u.t.QueuedThreadPool QueuedThreadPool@qtp166588561{STOPPING,8<=8<=10000,i=0,q=1} Couldn't stop Thread[qtp166588561-92,5,TGRP-TestValueFeature] [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=694668793 [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@500d59d9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / null [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b39d5ce: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / null [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 48116 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@860b753: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / null [junit4] 2> 48117 INFO (coreCloseExecutor-80-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4dec71ce [junit4] 2> 48117 INFO (coreCloseExecutor-80-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1307341262 [junit4] 2> 48117 INFO (coreCloseExecutor-80-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d8d8036: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / null [junit4] 2> 48118 INFO (SUITE-TestValueFeature-seed#[4C88AA4CE6008AE3]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Feb 15, 2018 4:00:16 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Feb 15, 2018 4:00:36 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.ltr.feature.TestValueFeature: [junit4] 2> 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] 2> Feb 15, 2018 4:00:36 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] [junit4] 2> Feb 15, 2018 4:00:39 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@49fa917d), locale=ki-KE, timezone=Asia/Yekaterinburg [junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=2,free=376458944,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestParallelWeightCreation, TestEdisMaxSolrFeature, TestLTRScoringQuery, TestValueFeature] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestValueFeature -Dtests.seed=4C88AA4CE6008AE3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ki-KE -Dtests.timezone=Asia/Yekaterinburg -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J1 | TestValueFeature (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.ltr.feature.TestValueFeature: [junit4] > 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] [junit4] > at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] > at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] > at __randomizedtesting.SeedInfo.seed([4C88AA4CE6008AE3]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=92, name=qtp166588561-92, state=TIMED_WAITING, group=TGRP-TestValueFeature] [junit4] > at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] > at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2192) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at [email protected]/java.lang.Thread.run(Thread.java:844) [junit4] > at __randomizedtesting.SeedInfo.seed([4C88AA4CE6008AE3]:0) [junit4] Completed [34/36 (1!)] on J1 in 53.65s, 7 tests, 2 errors <<< FAILURES! [...truncated 33656 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
