You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/02/19 18:42:20 UTC
[JENKINS] Lucene-Solr-Tests-8.x - Build # 1146 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-8.x/1146/
1 tests failed.
FAILED: org.apache.solr.client.solrj.impl.Http2SolrClientTest.testQueryString
Error Message:
Captured an uncaught exception in thread: Thread[id=943, name=h2sc-415-thread-13, state=RUNNABLE, group=TGRP-Http2SolrClientTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=943, name=h2sc-415-thread-13, state=RUNNABLE, group=TGRP-Http2SolrClientTest]
at __randomizedtesting.SeedInfo.seed([864B452C40FBCA0C:13246AACC468D2D9]:0)
Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@52ffe05e rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5c380471[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
at __randomizedtesting.SeedInfo.seed([864B452C40FBCA0C]:0)
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 16742 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.Http2SolrClientTest
[junit4] 2> 54117 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 54117 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/data-dir-3-001
[junit4] 2> 54117 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 54117 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 54118 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 54132 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 54151 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 54151 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 54151 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-002/cores/core
[junit4] 2> 54160 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 54160 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 54160 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 54160 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_191-b12
[junit4] 2> 54161 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 54161 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 54161 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 54161 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41473a65{/solr,null,AVAILABLE}
[junit4] 2> 54162 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7a06638b{SSL,[ssl, http/1.1]}{127.0.0.1:38837}
[junit4] 2> 54162 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.Server Started @54192ms
[junit4] 2> 54162 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38837, configSetBaseDir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-002/cores}
[junit4] 2> 54163 ERROR (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 54163 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 54163 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 54163 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 54163 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 54163 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-02-19T18:25:55.121Z
[junit4] 2> 54163 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/solr.xml
[junit4] 2> 54167 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@71146ea5, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 54646 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 54647 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 54652 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7d0138ba[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 54652 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7d0138ba[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 54657 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 54662 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@595fc647[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 54662 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@595fc647[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 54663 WARN (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 54681 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 54681 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 54705 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@71146ea5
[junit4] 2> 54712 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@71146ea5
[junit4] 2> 54712 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@71146ea5
[junit4] 2> 54721 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001
[junit4] 2> 54721 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 54739 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/extraction/lib
[junit4] 2> 54739 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist
[junit4] 2> 54739 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/clustering/lib
[junit4] 2> 54739 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist
[junit4] 2> 54739 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/langid/lib
[junit4] 2> 54739 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist
[junit4] 2> 54739 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist
[junit4] 2> 54740 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/contrib/velocity/lib
[junit4] 2> 54740 WARN (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/dist
[junit4] 2> 54740 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 54747 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled
[junit4] 2> 54773 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 54923 INFO (coreLoadExecutor-116-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> 54923 INFO (coreLoadExecutor-116-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> 54923 INFO (coreLoadExecutor-116-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> 54923 INFO (coreLoadExecutor-116-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> 54925 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 54925 INFO (coreLoadExecutor-116-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> 54925 INFO (coreLoadExecutor-116-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> 54925 INFO (coreLoadExecutor-116-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> 54925 INFO (coreLoadExecutor-116-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> 54940 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1, trusted=true
[junit4] 2> 54941 INFO (coreLoadExecutor-116-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@71146ea5
[junit4] 2> 54941 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1/data/]
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@66db3e08
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: 0 msec to checkpoint
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: init: create=true reader=null
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@245ea534 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e5040b)
[junit4] 2> index=
[junit4] 2> version=8.5.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=Lucene84
[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, deletesPctAllowed=33.0
[junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@374e0d96
[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> softDeletesField=null
[junit4] 2> readerAttributes={}
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@85a4e09
[junit4] 2>
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: now flush at close
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: index before flush
[junit4] 2> 54942 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: startFullFlush
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: coreLoadExecutor-116-thread-1 finishFullFlush success=true
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-116-thread-1]: waitApply: no deletes to apply
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-116-thread-1]: initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-116-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-116-thread-1]: now merge
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-116-thread-1]: index:
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-116-thread-1]: no more merges pending; now return
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: waitForMerges
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: waitForMerges done
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: commit: start
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: commit: enter lock
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: commit: now prepare
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: prepareCommit: flush
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: index before flush
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: startFullFlush
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-116-thread-1]: waitApply: no deletes to apply
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: coreLoadExecutor-116-thread-1 finishFullFlush success=true
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: startCommit(): start
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: startCommit index= changeCount=2
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: startCommit: wrote pending segments file "pending_segments_1"
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: done all syncs: []
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: commit: pendingCommit != null
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: commit: done writing segments file "segments_1"
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: 0 msec to checkpoint
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: commit: took 0.2 msec
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: commit: done
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: rollback
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: all running merges have aborted
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: rollback: done finish merges
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: abort
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: done abort success=true
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: rollback: infos=
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: 0 msec to checkpoint
[junit4] 2> 54943 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 54980 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 54980 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 54981 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 54981 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@66db3e08
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: init: load commit "segments_1"
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-116-thread-1]: 0 msec to checkpoint
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: init: create=false reader=null
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@245ea534 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e5040b)
[junit4] 2> index=
[junit4] 2> version=8.5.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=Lucene84
[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, deletesPctAllowed=33.0
[junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@10293438
[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> softDeletesField=null
[junit4] 2> readerAttributes={}
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@30d37c14
[junit4] 2>
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: flush at getReader
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: startFullFlush
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-116-thread-1]: waitApply: no deletes to apply
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: incRefDeleter for NRT reader version=2 segments=
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-116-thread-1]: coreLoadExecutor-116-thread-1 finishFullFlush success=true
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-116-thread-1]: getReader took 0 msec
[junit4] 2> 54982 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5dc0f600[collection1] main]
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1/conf
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _rest_managed.json using file:dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1/conf
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Loaded initArgs {} for /rest/managed
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1/conf
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for /schema/analysis/stopwords/english
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/stopwords/english
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using file:dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1/conf
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.s.a.ManagedSynonymGraphFilterFactory Loaded 3 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 54983 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/synonyms/english
[junit4] 2> 54984 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 54984 INFO (coreLoadExecutor-116-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> 54984 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 54984 INFO (coreLoadExecutor-116-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> 54984 INFO (coreLoadExecutor-116-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> 54985 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.Http2SolrClientTest_864B452C40FBCA0C-001/tempDir-001/collection1/conf/elevate.xml
[junit4] 2> 54988 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 54988 INFO (coreLoadExecutor-116-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1658990631002832896
[junit4] 2> 54989 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#38837
[junit4] 2> 54989 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5dc0f600[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 54990 INFO (searcherExecutor-117-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> 54990 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 54990 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 54990 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 55004 INFO (TEST-Http2SolrClientTest.testQuery-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testQuery
[junit4] 2> 55004 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5dc0f600[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 55005 WARN (TEST-Http2SolrClientTest.testQuery-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55006 WARN (TEST-Http2SolrClientTest.testQuery-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@72d03528[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55006 WARN (TEST-Http2SolrClientTest.testQuery-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@72d03528[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55140 WARN (h2sc-408-thread-16) [ ] o.e.j.i.ManagedSelector java.lang.IllegalStateException: !STARTED: Client@72d03528[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55142 INFO (TEST-Http2SolrClientTest.testQuery-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testQuery
[junit4] 2> 55146 INFO (TEST-Http2SolrClientTest.testDelete-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelete
[junit4] 2> 55147 WARN (TEST-Http2SolrClientTest.testDelete-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55149 WARN (TEST-Http2SolrClientTest.testDelete-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2955ff21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55149 WARN (TEST-Http2SolrClientTest.testDelete-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2955ff21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55215 INFO (TEST-Http2SolrClientTest.testDelete-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelete
[junit4] 2> 55219 INFO (TEST-Http2SolrClientTest.test0IdleTimeout-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test0IdleTimeout
[junit4] 2> 55219 WARN (TEST-Http2SolrClientTest.test0IdleTimeout-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55223 WARN (TEST-Http2SolrClientTest.test0IdleTimeout-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ef10605[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55223 WARN (TEST-Http2SolrClientTest.test0IdleTimeout-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ef10605[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55273 INFO (TEST-Http2SolrClientTest.test0IdleTimeout-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test0IdleTimeout
[junit4] 2> 55276 INFO (TEST-Http2SolrClientTest.testGetDefaultSslContextFactory-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetDefaultSslContextFactory
[junit4] 2> 55277 INFO (TEST-Http2SolrClientTest.testGetDefaultSslContextFactory-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGetDefaultSslContextFactory
[junit4] 2> 55280 INFO (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionParameters
[junit4] 2> 55280 WARN (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55283 WARN (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7de24bb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55283 WARN (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7de24bb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55313 INFO (qtp968772935-809) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[collection (1658990631340474368)]} 0 3
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1658990631354105856,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@30d37c14 commitCommandVersion:1658990631354105856
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: commit: start
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: commit: enter lock
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: commit: now prepare
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: prepareCommit: flush
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: index before flush
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp968772935-811]: startFullFlush
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp968772935-811]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWFC][qtp968772935-811]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_0, aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 1 ]]
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: compressed 182 to 1040 bytes (571.43%) for deletes/updates; private segment null
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: flush postings as segment _0 numDocs=1
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: 0 msec to write norms
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: 0 msec to write docValues
[junit4] 2> 55323 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: 0 msec to write points
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: 0 msec to finish stored fields
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: 0 msec to write postings and finish vectors
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: 0 msec to write fieldInfos
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: new segment has 0 deleted docs
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: new segment has 0 soft-deleted docs
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: new segment has no vectors; no norms; docValues; no prox; freqs
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: flushedFiles=[_0_Lucene80_0.dvd, _0.fdm, _0_Lucene84_0.doc, _0_Lucene84_0.tim, _0_Lucene84_0.tip, _0.fdx, _0.fdt, _0_Lucene80_0.dvm, _0.fnm]
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: flushed codec=Lucene84
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: flushed: segment=_0 ramUsed=0.087 MB newFlushedSize=0.001 MB docs/MB=795.581
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp968772935-811]: flush time 1.028255 msec
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: publishFlushedSegment seg-private updates=null
[junit4] 2> 55324 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: publishFlushedSegment _0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: push new packet (delGen=1 numDeleteTerms=1 bytesUsed=1040), packetCount=1, bytesUsed=0.001 MB
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: finished packet delGen=2 now completedDelGen=0
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: publish sets newSegment delGen=2 seg=_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp968772935-811]: now checkpoint "_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp968772935-811]: 0 msec to checkpoint
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: packet matches no segments
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: finished packet delGen=1 now completedDelGen=2
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: done apply del packet (delGen=1 numDeleteTerms=1 bytesUsed=1040) to 0 segments; 0 new deletes/updates; took 0.000 sec; 0 packets remain
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: waitApply: no deletes to apply
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp968772935-811]: qtp968772935-811 finishFullFlush success=true
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: startCommit(): start
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: startCommit index=_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] changeCount=4
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: startCommit: wrote pending segments file "pending_segments_2"
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: done all syncs: [_0.si, _0_Lucene80_0.dvd, _0.fdm, _0_Lucene84_0.doc, _0_Lucene84_0.tim, _0_Lucene84_0.tip, _0.fdx, _0.fdt, _0_Lucene80_0.dvm, _0.fnm]
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: commit: pendingCommit != null
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: commit: done writing segments file "segments_2"
[junit4] 2> 55325 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp968772935-811]: now checkpoint "_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = true]
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp968772935-811]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp968772935-811]: delete [segments_1]
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp968772935-811]: 0 msec to checkpoint
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: commit: took 2.9 msec
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: commit: done
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MP][qtp968772935-811]: seg=_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] size=0.001 MB [floored]
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MP][qtp968772935-811]: findMerges: 1 segments
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MP][qtp968772935-811]: allowedSegmentCount=10 vs count=1 (eligible count=1)
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp968772935-811]: initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp968772935-811]: now merge
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp968772935-811]: index: _0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp968772935-811]: no more merges pending; now return
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: flush at getReader
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp968772935-811]: startFullFlush
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 55326 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp968772935-811]: waitApply: no deletes to apply
[junit4] 2> 55327 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: incRefDeleter for NRT reader version=6 segments=_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 55327 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: return reader version=6 reader=StandardDirectoryReader(segments_2:6:nrt _0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
[junit4] 2> 55327 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp968772935-811]: qtp968772935-811 finishFullFlush success=true
[junit4] 2> 55327 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp968772935-811]: getReader took 1 msec
[junit4] 2> 55327 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15f33f50[collection1] main]
[junit4] 2> 55327 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@15f33f50[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 55327 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 55332 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15f33f50[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 55332 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 55332 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][searcherExecutor-117-thread-1-processing-x:collection1]: decRefDeleter for NRT reader version=2 segments=
[junit4] 2> 55332 INFO (qtp968772935-811) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 55334 INFO (qtp968772935-812) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=id:collection&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 55348 WARN (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55351 WARN (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d369771[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55352 WARN (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d369771[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55390 INFO (qtp968772935-809) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=id:collection&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 55406 INFO (TEST-Http2SolrClientTest.testCollectionParameters-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollectionParameters
[junit4] 2> 55411 INFO (TEST-Http2SolrClientTest.testUpdate-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUpdate
[junit4] 2> 55412 WARN (TEST-Http2SolrClientTest.testUpdate-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55414 WARN (TEST-Http2SolrClientTest.testUpdate-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7eeef64d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55414 WARN (TEST-Http2SolrClientTest.testUpdate-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7eeef64d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55486 INFO (TEST-Http2SolrClientTest.testUpdate-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUpdate
[junit4] 2> 55490 INFO (TEST-Http2SolrClientTest.testRedirect-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRedirect
[junit4] 2> 55491 WARN (TEST-Http2SolrClientTest.testRedirect-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55492 WARN (TEST-Http2SolrClientTest.testRedirect-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1e87dd8f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55492 WARN (TEST-Http2SolrClientTest.testRedirect-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1e87dd8f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55535 INFO (qtp968772935-809) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 55562 INFO (TEST-Http2SolrClientTest.testRedirect-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRedirect
[junit4] 2> 55566 INFO (TEST-Http2SolrClientTest.testQueryString-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testQueryString
[junit4] 2> 55566 WARN (TEST-Http2SolrClientTest.testQueryString-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55567 WARN (TEST-Http2SolrClientTest.testQueryString-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7b3280b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55568 WARN (TEST-Http2SolrClientTest.testQueryString-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7b3280b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> feb 19, 2020 2:25:56 PM com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[h2sc-415-thread-13,5,TGRP-Http2SolrClientTest]
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@52ffe05e rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5c380471[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] 2> at __randomizedtesting.SeedInfo.seed([864B452C40FBCA0C]:0)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
[junit4] 2> at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
[junit4] 2> at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[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>
[junit4] 2> 55623 ERROR (h2sc-415-thread-13) [ ] o.a.s.c.u.ExecutorUtil Uncaught exception java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@52ffe05e rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5c380471[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0] thrown by thread: h2sc-415-thread-13
[junit4] 2> => java.lang.Exception: Submitter stack trace
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:185)
[junit4] 2> java.lang.Exception: Submitter stack trace
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:185) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.util.SocketAddressResolver$Async.resolve(SocketAddressResolver.java:150) ~[jetty-util-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpClient.newConnection(HttpClient.java:576) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpDestination.createConnection(HttpDestination.java:387) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpDestination.newConnection(HttpDestination.java:382) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.AbstractConnectionPool.tryCreate(AbstractConnectionPool.java:133) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.AbstractConnectionPool.acquire(AbstractConnectionPool.java:105) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpDestination.process(HttpDestination.java:317) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:310) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:285) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:262) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpClient.send(HttpClient.java:570) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:754) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:746) ~[jetty-client-9.4.24.v20191120.jar:9.4.24.v20191120]
[junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:397) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:753) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClientTest.testQueryString(Http2SolrClientTest.java:593) ~[test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_191]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_191]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_191]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_191]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_191]
[junit4] 2> 55638 WARN (h2sc-415-thread-15) [ ] o.e.j.i.ManagedSelector java.lang.IllegalStateException: !STARTED: Client@7b3280b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=Http2SolrClientTest -Dtests.method=testQueryString -Dtests.seed=864B452C40FBCA0C -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=no -Dtests.timezone=Atlantic/Bermuda -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.08s J0 | Http2SolrClientTest.testQueryString <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=943, name=h2sc-415-thread-13, state=RUNNABLE, group=TGRP-Http2SolrClientTest]
[junit4] > at __randomizedtesting.SeedInfo.seed([864B452C40FBCA0C:13246AACC468D2D9]:0)
[junit4] > Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@52ffe05e rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5c380471[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] > at __randomizedtesting.SeedInfo.seed([864B452C40FBCA0C]:0)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
[junit4] > at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
[junit4] > at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 55640 INFO (TEST-Http2SolrClientTest.testQueryString-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testQueryString
[junit4] 2> 55647 INFO (TEST-Http2SolrClientTest.testTimeout-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTimeout
[junit4] 2> 55647 WARN (TEST-Http2SolrClientTest.testTimeout-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 55648 WARN (TEST-Http2SolrClientTest.testTimeout-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1bbe46b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 55648 WARN (TEST-Http2SolrClientTest.testTimeout-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1bbe46b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 57652 INFO (TEST-Http2SolrClientTest.testTimeout-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTimeout
[junit4] 2> 57658 INFO (TEST-Http2SolrClientTest.testGetById-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetById
[junit4] 2> 57659 WARN (TEST-Http2SolrClientTest.testGetById-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 57660 WARN (TEST-Http2SolrClientTest.testGetById-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a132a5f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 57660 WARN (TEST-Http2SolrClientTest.testGetById-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a132a5f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 57706 INFO (TEST-Http2SolrClientTest.testGetById-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGetById
[junit4] 2> 57710 INFO (TEST-Http2SolrClientTest.testSolrExceptionCodeNotFromSolr-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSolrExceptionCodeNotFromSolr
[junit4] 2> 57711 WARN (TEST-Http2SolrClientTest.testSolrExceptionCodeNotFromSolr-seed#[864B452C40FBCA0C]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 57712 WARN (TEST-Http2SolrClientTest.testSolrExceptionCodeNotFromSolr-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@23669ecb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 57712 WARN (TEST-Http2SolrClientTest.testSolrExceptionCodeNotFromSolr-seed#[864B452C40FBCA0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@23669ecb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 57761 INFO (TEST-Http2SolrClientTest.testSolrExceptionCodeNotFromSolr-seed#[864B452C40FBCA0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSolrExceptionCodeNotFromSolr
[junit4] 2> 57763 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1362844714
[junit4] 2> 57764 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5d6ec5ff
[junit4] 2> 57764 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@5d6ec5ff
[junit4] 2> 57764 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3cd3de61: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2a1bba4
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: now flush at close
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: index before flush _0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-122-thread-1]: startFullFlush
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-122-thread-1]: coreCloseExecutor-122-thread-1 finishFullFlush success=true
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 57777 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-122-thread-1]: waitApply: no deletes to apply
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-122-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-122-thread-1]: now merge
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-122-thread-1]: index: _0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-122-thread-1]: no more merges pending; now return
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: waitForMerges
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: waitForMerges done
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: commit: start
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: commit: enter lock
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: commit: now prepare
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: prepareCommit: flush
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: index before flush _0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-122-thread-1]: startFullFlush
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-122-thread-1]: waitApply: no deletes to apply
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-122-thread-1]: coreCloseExecutor-122-thread-1 finishFullFlush success=true
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: startCommit(): start
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: skip startCommit(): no changes pending
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: commit: pendingCommit == null; skip
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: commit: took 0.1 msec
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: commit: done
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: rollback
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: all running merges have aborted
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: rollback: done finish merges
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-122-thread-1]: abort
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-122-thread-1]: done abort success=true
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-122-thread-1]: rollback: infos=_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-122-thread-1]: now checkpoint "_0(8.5.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.5.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1582136756282}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
[junit4] 2> 57778 INFO (coreCloseExecutor-122-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-122-thread-1]: 0 msec to checkpoint
[junit4] 2> 57779 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 57779 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f309ffb: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5931ce52
[junit4] 2> 57783 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 57783 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@152af60e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1debd702
[junit4] 2> 57785 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 57785 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58a5f72b: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@12048094
[junit4] 2> 57788 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7a06638b{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 57788 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@41473a65{/solr,null,UNAVAILABLE}
[junit4] 2> 57788 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 60687 INFO (SUITE-Http2SolrClientTest-seed#[864B452C40FBCA0C]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1e2ef86b), locale=no, timezone=Atlantic/Bermuda
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=1,free=297746608,total=475004928
[junit4] 2> NOTE: All tests run in this JVM: [NotEvaluatorTest, OperationsTest, StreamExpressionTest, TestSolrJErrorHandling, ClientUtilsTest, EmpiricalDistributionEvaluatorTest, HyperbolicTangentEvaluatorTest, Http2SolrClientTest]
[junit4] Completed [50/208 (1!)] on J0 in 6.76s, 11 tests, 1 error <<< FAILURES!
[...truncated 51900 lines...]