Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1364/ Java: 64bit/jdk1.8.0_162 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.JettyWebappTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.client.solrj.embedded.JettyWebappTest: 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.client.solrj.embedded.JettyWebappTest: 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([AFFD4EB1F050DC9]:0) FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.JettyWebappTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([AFFD4EB1F050DC9]:0) Build Log: [...truncated 15525 lines...] [junit4] Suite: org.apache.solr.client.solrj.embedded.JettyWebappTest [junit4] 2> 54126 INFO (SUITE-JettyWebappTest-seed#[AFFD4EB1F050DC9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/init-core-data-001 [junit4] 2> 54127 WARN (SUITE-JettyWebappTest-seed#[AFFD4EB1F050DC9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4 [junit4] 2> 54127 INFO (SUITE-JettyWebappTest-seed#[AFFD4EB1F050DC9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 54128 INFO (SUITE-JettyWebappTest-seed#[AFFD4EB1F050DC9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 54130 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAdminUI [junit4] 2> 54165 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 54165 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 54165 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 54165 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 54226 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.w.StandardDescriptorProcessor NO JSP Support for /test, did not find org.eclipse.jetty.jsp.JettyJspServlet [junit4] 2> 54234 ERROR (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 54234 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 54234 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 54234 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 54234 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-15T21:24:01.331Z [junit4] 2> 54236 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/solr.xml [junit4] 2> 54275 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@76754603, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 54306 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@76754603 [junit4] 2> 54315 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@76754603 [junit4] 2> 54316 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@76754603 [junit4] 2> 54322 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001 [junit4] 2> 54322 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 54323 INFO (coreLoadExecutor-327-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 54367 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/extraction/lib [junit4] 2> 54367 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist [junit4] 2> 54367 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/clustering/lib [junit4] 2> 54367 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist [junit4] 2> 54367 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/langid/lib [junit4] 2> 54368 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist [junit4] 2> 54368 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist [junit4] 2> 54368 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../contrib/velocity/lib [junit4] 2> 54368 WARN (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/../../../../dist [junit4] 2> 54389 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled [junit4] 2> 54390 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 54428 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.w.WebAppContext@508d69c7{/test,file:///home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/webapp/web/,AVAILABLE}{/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/webapp/web} [junit4] 2> 54428 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1735be87{HTTP/1.1,[http/1.1]}{0.0.0.0:46547} [junit4] 2> 54428 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.Server Started @57123ms [junit4] 2> 54478 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 54527 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1735be87{HTTP/1.1,[http/1.1]}{0.0.0.0:0} [junit4] 2> 54527 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=703574954 [junit4] 2> 54722 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english [junit4] 2> 54722 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymGraphFilterFactory$SynonymManager for path /schema/analysis/synonyms/english [junit4] 2> 54722 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedStopFilterFactory to existing ManagedResource /schema/analysis/stopwords/english [junit4] 2> 54722 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedSynonymGraphFilterFactory to existing ManagedResource /schema/analysis/synonyms/english [junit4] 2> 54723 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id [junit4] 2> 54723 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedStopFilterFactory to existing ManagedResource /schema/analysis/stopwords/english [junit4] 2> 54723 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedSynonymGraphFilterFactory to existing ManagedResource /schema/analysis/synonyms/english [junit4] 2> 54723 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedStopFilterFactory to existing ManagedResource /schema/analysis/stopwords/english [junit4] 2> 54723 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedSynonymGraphFilterFactory to existing ManagedResource /schema/analysis/synonyms/english [junit4] 2> 54760 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1, trusted=true [junit4] 2> 54761 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@76754603 [junit4] 2> 54761 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 54761 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-002/] [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@3e2a7d26 [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: 0 msec to checkpoint [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: init: create=true [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: [junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@57a67694 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a467afb) [junit4] 2> index= [junit4] 2> version=7.3.0 [junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer [junit4] 2> ramBufferSizeMB=100.0 [junit4] 2> maxBufferedDocs=-1 [junit4] 2> mergedSegmentWarmer=null [junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper [junit4] 2> commit=null [junit4] 2> openMode=CREATE [junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity [junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true [junit4] 2> codec=Lucene70 [junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream [junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1 [junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@116d76c4 [junit4] 2> readerPooling=true [junit4] 2> perThreadHardLimitMB=1945 [junit4] 2> useCompoundFile=false [junit4] 2> commitOnClose=true [junit4] 2> indexSort=null [junit4] 2> checkPendingFlushOnUpdate=true [junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@759ba0ab [junit4] 2> [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: now flush at close [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: start flush: applyAllDeletes=true [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: index before flush [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: startFullFlush [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: coreLoadExecutor-327-thread-1 finishFullFlush success=true [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-327-thread-1]: waitApply: no deletes to apply [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: initDynamicDefaults spins=false maxThreadCount=2 maxMergeCount=7 [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: now merge [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: index: [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: no more merges pending; now return [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: waitForMerges [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: waitForMerges done [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: start [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: enter lock [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: now prepare [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: prepareCommit: flush [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: index before flush [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: startFullFlush [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-327-thread-1]: waitApply: no deletes to apply [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: coreLoadExecutor-327-thread-1 finishFullFlush success=true [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: startCommit(): start [junit4] 2> 54763 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: startCommit index= changeCount=2 [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: startCommit: wrote pending segments file "pending_segments_1" [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: done all syncs: [] [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: pendingCommit != null [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: done writing segments file "segments_1" [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: now checkpoint "" [0 segments ; isCommit = true] [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: 0 msec to checkpoint [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: took 0.3 msec [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: done [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: rollback [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: all running merges have aborted [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: rollback: done finish merges [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: abort [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: done abort success=true [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: rollback: infos= [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 54764 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: 0 msec to checkpoint [junit4] 2> 54781 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5 [junit4] 2> 54828 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 54828 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 54829 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 54829 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@3e2a7d26 [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: init: load commit "segments_1" [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: 0 msec to checkpoint [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: init: create=false [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: [junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@57a67694 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a467afb) [junit4] 2> index= [junit4] 2> version=7.3.0 [junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer [junit4] 2> ramBufferSizeMB=100.0 [junit4] 2> maxBufferedDocs=-1 [junit4] 2> mergedSegmentWarmer=null [junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper [junit4] 2> commit=null [junit4] 2> openMode=APPEND [junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity [junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true [junit4] 2> codec=Lucene70 [junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream [junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1 [junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@16e99e1c [junit4] 2> readerPooling=true [junit4] 2> perThreadHardLimitMB=1945 [junit4] 2> useCompoundFile=false [junit4] 2> commitOnClose=true [junit4] 2> indexSort=null [junit4] 2> checkPendingFlushOnUpdate=true [junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@5218e58f [junit4] 2> [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: flush at getReader [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: startFullFlush [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-327-thread-1]: waitApply: no deletes to apply [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: incRefDeleter for NRT reader version=2 segments= [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt) [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: coreLoadExecutor-327-thread-1 finishFullFlush success=true [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: getReader took 0 msec [junit4] 2> 54830 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@780360ac[collection1] main] [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/conf [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/conf [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResource Loaded initArgs {} for /rest/managed [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/conf [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for /schema/analysis/stopwords/english [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 4 observers of /schema/analysis/stopwords/english [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using file:dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/conf [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true,format=solr} for /schema/analysis/synonyms/english [junit4] 2> 54831 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.s.a.ManagedSynonymGraphFilterFactory Loaded 3 synonym mappings for /schema/analysis/synonyms/english [junit4] 2> 54832 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 4 observers of /schema/analysis/synonyms/english [junit4] 2> 54832 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 54832 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01} [junit4] 2> 54832 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent [junit4] 2> 54832 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false} [junit4] 2> 54833 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false} [junit4] 2> 54833 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.JettyWebappTest_AFFD4EB1F050DC9-001/tempDir-001/collection1/conf/elevate.xml [junit4] 2> 54840 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 54840 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1592503662738931712 [junit4] 2> 54842 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@55c08b03 [junit4] 2> 54842 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1438681859 [junit4] 2> 54842 INFO (searcherExecutor-328-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@780360ac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 54842 INFO (searcherExecutor-328-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher} hits=0 status=0 QTime=0 [junit4] 2> 54842 INFO (searcherExecutor-328-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 54842 INFO (searcherExecutor-328-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 54842 INFO (searcherExecutor-328-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 54843 INFO (searcherExecutor-328-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@780360ac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 54843 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5ca49ca3: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@231039df [junit4] 2> 54853 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: now flush at close [junit4] 2> 54853 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: start flush: applyAllDeletes=true [junit4] 2> 54853 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: index before flush [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: startFullFlush [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: coreLoadExecutor-327-thread-1 finishFullFlush success=true [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-327-thread-1]: waitApply: no deletes to apply [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: initDynamicDefaults spins=false maxThreadCount=2 maxMergeCount=7 [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: now merge [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: index: [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-327-thread-1]: no more merges pending; now return [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: waitForMerges [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: waitForMerges done [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: start [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: enter lock [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: now prepare [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: prepareCommit: flush [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: index before flush [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: startFullFlush [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-327-thread-1]: waitApply: no deletes to apply [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: coreLoadExecutor-327-thread-1 finishFullFlush success=true [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: startCommit(): start [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: skip startCommit(): no changes pending [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: pendingCommit == null; skip [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: took 0.1 msec [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: commit: done [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: rollback [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: all running merges have aborted [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: rollback: done finish merges [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: abort [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-327-thread-1]: done abort success=true [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-327-thread-1]: rollback: infos= [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 54854 INFO (coreLoadExecutor-327-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-327-thread-1]: 0 msec to checkpoint [junit4] 2> 54857 ERROR (coreContainerWorkExecutor-323-thread-1) [ ] o.a.s.c.CoreContainer Error waiting for SolrCore to be created [junit4] 2> java.util.concurrent.ExecutionException: org.apache.solr.common.SolrException: Unable to create core [collection1] [junit4] 2> at java.util.concurrent.FutureTask.report(FutureTask.java:122) [junit4] 2> at java.util.concurrent.FutureTask.get(FutureTask.java:192) [junit4] 2> at org.apache.solr.core.CoreContainer.lambda$load$14(CoreContainer.java:669) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: org.apache.solr.common.SolrException: Unable to create core [collection1] [junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1055) [junit4] 2> at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:640) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197) [junit4] 2> ... 5 more [junit4] 2> Caused by: java.lang.IllegalStateException: This CoreContainer has been closed [junit4] 2> at org.apache.solr.core.CoreContainer.registerCore(CoreContainer.java:873) [junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1049) [junit4] 2> ... 7 more [junit4] 2> 54859 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 54859 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@135a88b7: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@14351993 [junit4] 2> 54865 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 54865 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f2e4007: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@54d1dd42 [junit4] 2> 54870 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 54870 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6815b1e9: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@50ee5300 [junit4] 2> 54872 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.w.WebAppContext@508d69c7{/test,null,UNAVAILABLE}{/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/webapp/web} [junit4] 2> 54872 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 84870 WARN (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.e.j.u.t.QueuedThreadPool QueuedThreadPool@qtp1863335310{STOPPING,8<=8<=200,i=0,q=1} Couldn't stop Thread[qtp1863335310-896,5,TGRP-JettyWebappTest] [junit4] 2> 84870 INFO (TEST-JettyWebappTest.testAdminUI-seed#[AFFD4EB1F050DC9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAdminUI [junit4] 2> 84871 INFO (SUITE-JettyWebappTest-seed#[AFFD4EB1F050DC9]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Feb 15, 2018 9:24:31 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Feb 15, 2018 9:24:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.client.solrj.embedded.JettyWebappTest: [junit4] 2> 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Feb 15, 2018 9:24:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] [junit4] 2> Feb 15, 2018 9:24:55 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=558, maxMBSortInHeap=6.758677707704769, sim=RandomSimilarity(queryNorm=false): {}, locale=id, timezone=Africa/Timbuktu [junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=8,threads=2,free=226488480,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [SubtractEvaluatorTest, FloorEvaluatorTest, CommonAdminParamsTest, EqualToEvaluatorTest, ArcTangentEvaluatorTest, StreamExpressionToExpessionTest, TestDelegationTokenResponse, SolrPingTest, JDBCStreamTest, CeilingEvaluatorTest, TestUpdateRequestCodec, MergeIndexesEmbeddedTest, SolrDocumentTest, SolrQueryTest, BasicHttpSolrClientTest, JsonValidatorTest, SolrExampleStreamingBinaryTest, ClientUtilsTest, LengthEvaluatorTest, TestLBHttpSolrClient, ModuloEvaluatorTest, HyperbolicCosineEvaluatorTest, ConcatOperationTest, SolrExampleBinaryTest, SolrExampleXMLTest, LargeVolumeBinaryJettyTest, JettyWebappTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=JettyWebappTest -Dtests.seed=AFFD4EB1F050DC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=id -Dtests.timezone=Africa/Timbuktu -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J1 | JettyWebappTest (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.client.solrj.embedded.JettyWebappTest: [junit4] > 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([AFFD4EB1F050DC9]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=896, name=qtp1863335310-896, state=TIMED_WAITING, group=TGRP-JettyWebappTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([AFFD4EB1F050DC9]:0) [junit4] Completed [104/146 (1!)] on J1 in 53.81s, 1 test, 2 errors <<< FAILURES! [...truncated 47705 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org