lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-master - Build # 3165 - Still Unstable
Date Sat, 02 Feb 2019 17:52:24 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/3165/

1 tests failed.
FAILED:  org.apache.solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters

Error Message:
Captured an uncaught exception in thread: Thread[id=2013, name=h2sc-1283-thread-13, state=RUNNABLE, group=TGRP-ConcurrentUpdateHttp2SolrClientTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=2013, name=h2sc-1283-thread-13, state=RUNNABLE, group=TGRP-ConcurrentUpdateHttp2SolrClientTest]
	at __randomizedtesting.SeedInfo.seed([E9B29D7F21FC8E55:DC96B81A501BD757]:0)
Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@7ae8ad1 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@9697454[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
	at __randomizedtesting.SeedInfo.seed([E9B29D7F21FC8E55]: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:102)
	at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:154)
	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
	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 16361 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/init-core-data-001
   [junit4]   2> 151947 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 151949 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 151950 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 151989 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 151990 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 151990 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-002/cores/core
   [junit4]   2> 151992 WARN  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 151992 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 151992 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 151993 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 151994 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 151994 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 151994 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 151995 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6ee39453{/solr,null,AVAILABLE}
   [junit4]   2> 151997 WARN  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@74730e4b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 151998 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.AbstractConnector Started ServerConnector@30f595b2{SSL,[ssl, http/1.1]}{127.0.0.1:41399}
   [junit4]   2> 151998 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.s.Server Started @152055ms
   [junit4]   2> 151998 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41399, configSetBaseDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-002/cores}
   [junit4]   2> 151999 ERROR (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 151999 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 151999 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 9.0.0
   [junit4]   2> 151999 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 151999 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 151999 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-02-02T17:30:04.317Z
   [junit4]   2> 151999 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/solr.xml
   [junit4]   2> 152006 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@d8e72dd, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 152995 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 152997 WARN  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-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> 152999 WARN  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1ba9b0c9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 153003 WARN  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-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> 153004 WARN  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@644df2e8[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 153039 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 153039 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 153073 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d8e72dd
   [junit4]   2> 153090 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d8e72dd
   [junit4]   2> 153090 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d8e72dd
   [junit4]   2> 153106 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001
   [junit4]   2> 153106 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 153183 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/extraction/lib
   [junit4]   2> 153183 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist
   [junit4]   2> 153183 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/clustering/lib
   [junit4]   2> 153184 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist
   [junit4]   2> 153184 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/langid/lib
   [junit4]   2> 153184 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist
   [junit4]   2> 153184 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist
   [junit4]   2> 153184 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../contrib/velocity/lib
   [junit4]   2> 153184 WARN  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/../../../../dist
   [junit4]   2> 153216 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled
   [junit4]   2> 153223 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 153344 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 153596 INFO  (coreLoadExecutor-199-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> 153596 INFO  (coreLoadExecutor-199-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> 153596 INFO  (coreLoadExecutor-199-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> 153596 INFO  (coreLoadExecutor-199-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> 153597 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 153597 INFO  (coreLoadExecutor-199-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> 153597 INFO  (coreLoadExecutor-199-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> 153597 INFO  (coreLoadExecutor-199-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> 153597 INFO  (coreLoadExecutor-199-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> 153619 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1, trusted=true
   [junit4]   2> 153620 INFO  (coreLoadExecutor-199-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@d8e72dd
   [junit4]   2> 153620 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/data/]
   [junit4]   2> 153623 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@74204a48
   [junit4]   2> 153623 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 153623 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: 0 msec to checkpoint
   [junit4]   2> 153623 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: init: create=true reader=null
   [junit4]   2> 153623 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: 
   [junit4]   2> dir=MockDirectoryWrapper(ByteBuffersDirectory@36440df2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53d17abd)
   [junit4]   2> index=
   [junit4]   2> version=9.0.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=Lucene80
   [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@844bec1
   [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> writer=org.apache.solr.update.SolrIndexWriter@7ec0d78b
   [junit4]   2> 
   [junit4]   2> 153623 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: now flush at close
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]:   start flush: applyAllDeletes=true
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]:   index before flush 
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: startFullFlush
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: coreLoadExecutor-199-thread-1 finishFullFlush success=true
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-199-thread-1]: waitApply: no deletes to apply
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-199-thread-1]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-199-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-199-thread-1]: now merge
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-199-thread-1]:   index: 
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-199-thread-1]:   no more merges pending; now return
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: waitForMerges
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: waitForMerges done
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: commit: start
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: commit: enter lock
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: commit: now prepare
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: prepareCommit: flush
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]:   index before flush 
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: startFullFlush
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-199-thread-1]: waitApply: no deletes to apply
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: coreLoadExecutor-199-thread-1 finishFullFlush success=true
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: startCommit(): start
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: startCommit index= changeCount=2
   [junit4]   2> 153624 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: startCommit: wrote pending segments file "pending_segments_1"
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: done all syncs: []
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: commit: pendingCommit != null
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: commit: done writing segments file "segments_1"
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: 0 msec to checkpoint
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: commit: took 0.7 msec
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: commit: done
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: rollback
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: all running merges have aborted
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: rollback: done finish merges
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: abort
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: done abort success=true
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: rollback: infos=
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: 0 msec to checkpoint
   [junit4]   2> 153625 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
   [junit4]   2> 153712 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 153712 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 153716 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; 
   [junit4]   2> 153716 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 153717 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@74204a48
   [junit4]   2> 153717 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: init: load commit "segments_1"
   [junit4]   2> 153717 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 153717 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-199-thread-1]: 0 msec to checkpoint
   [junit4]   2> 153717 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: init: create=false reader=null
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: 
   [junit4]   2> dir=MockDirectoryWrapper(ByteBuffersDirectory@36440df2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@53d17abd)
   [junit4]   2> index=
   [junit4]   2> version=9.0.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=Lucene80
   [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@fd60a57
   [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> writer=org.apache.solr.update.SolrIndexWriter@170bcf4d
   [junit4]   2> 
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: flush at getReader
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: startFullFlush
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-199-thread-1]: waitApply: no deletes to apply
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: incRefDeleter for NRT reader version=2 segments=
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-199-thread-1]: coreLoadExecutor-199-thread-1 finishFullFlush success=true
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-199-thread-1]: getReader took 0 msec
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d808a89[collection1] main]
   [junit4]   2> 153718 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/conf
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/conf
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResource Loaded initArgs {} for /rest/managed
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/conf
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for /schema/analysis/stopwords/english
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResource Notified 4 observers of /schema/analysis/stopwords/english
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/conf
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
   [junit4]   2> 153719 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.s.a.ManagedSynonymGraphFilterFactory Loaded 3 synonym mappings for /schema/analysis/synonyms/english
   [junit4]   2> 153720 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.r.ManagedResource Notified 4 observers of /schema/analysis/synonyms/english
   [junit4]   2> 153721 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 153721 INFO  (coreLoadExecutor-199-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> 153721 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
   [junit4]   2> 153721 INFO  (coreLoadExecutor-199-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> 153721 INFO  (coreLoadExecutor-199-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> 153722 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_E9B29D7F21FC8E55-001/tempDir-001/collection1/conf/elevate.xml
   [junit4]   2> 153728 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 153729 INFO  (coreLoadExecutor-199-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1624379077214339072
   [junit4]   2> 153730 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@6d808a89[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 153731 INFO  (searcherExecutor-200-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=1
   [junit4]   2> 153731 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 153731 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 153731 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 153733 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d808a89[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 153733 INFO  (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[E9B29D7F21FC8E55]-worker) [    ] o.a.s.SolrJettyTestBase Jetty Assigned Port#41399
   [junit4]   2> 153739 INFO  (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[E9B29D7F21FC8E55]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionParameters
   [junit4]   2> 153741 WARN  (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[E9B29D7F21FC8E55]) [    ] 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> 153742 WARN  (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[E9B29D7F21FC8E55]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@131aee4a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 153883 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[collection (1624379077361139712)]} 0 13
   [junit4]   2> 153918 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1624379077412519936,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@170bcf4d commitCommandVersion:1624379077412519936
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: commit: start
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: commit: enter lock
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: commit: now prepare
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: prepareCommit: flush
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]:   index before flush 
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1982]: startFullFlush
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1982]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWFC][qtp1651136634-1982]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_0, aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 1 ]]
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: compressed 182 to 1040 bytes (571.43%) for deletes/updates; private segment null
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: flush postings as segment _0 numDocs=1
   [junit4]   2> 153919 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: 0 msec to write norms
   [junit4]   2> 153920 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: 0 msec to write docValues
   [junit4]   2> 153920 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: 0 msec to write points
   [junit4]   2> 153920 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: 0 msec to finish stored fields
   [junit4]   2> 153921 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: 0 msec to write postings and finish vectors
   [junit4]   2> 153921 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: 0 msec to write fieldInfos
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: new segment has 0 deleted docs
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: new segment has 0 soft-deleted docs
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: new segment has no vectors; no norms; docValues; no prox; freqs
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene80_0.dvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0_Lucene80_0.dvm, _0.fnm]
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: flushed codec=Lucene80
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: flushed: segment=_0 ramUsed=0.087 MB newFlushedSize=0.001 MB docs/MB=860.9
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1982]: flush time 2.482258 msec
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: publishFlushedSegment _0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
   [junit4]   2> 153922 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: push new packet (delGen=1 numDeleteTerms=1 bytesUsed=1040), packetCount=1, bytesUsed=0.001 MB
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: finished packet delGen=2 now completedDelGen=0
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: publish sets newSegment delGen=2 seg=_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1982]: now checkpoint "_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1982]: 0 msec to checkpoint
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: packet matches no segments
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: finished packet delGen=1 now completedDelGen=2
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: 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> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: waitApply: no deletes to apply
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1982]: qtp1651136634-1982 finishFullFlush success=true
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: startCommit(): start
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: startCommit index=_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] changeCount=4
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: startCommit: wrote pending segments file "pending_segments_2"
   [junit4]   2> 153923 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: done all syncs: [_0.si, _0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene80_0.dvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0_Lucene80_0.dvm, _0.fnm]
   [junit4]   2> 153924 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: commit: pendingCommit != null
   [junit4]   2> 153924 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: commit: done writing segments file "segments_2"
   [junit4]   2> 153924 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1982]: now checkpoint "_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = true]
   [junit4]   2> 153925 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1982]: deleteCommits: now decRef commit "segments_1"
   [junit4]   2> 153925 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1982]: delete [segments_1]
   [junit4]   2> 153925 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1982]: 1 msec to checkpoint
   [junit4]   2> 153925 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: commit: took 6.5 msec
   [junit4]   2> 153925 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: commit: done
   [junit4]   2> 153926 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [MP][qtp1651136634-1982]:   seg=_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] size=0.001 MB [floored]
   [junit4]   2> 153926 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [MP][qtp1651136634-1982]: findMerges: 1 segments
   [junit4]   2> 153926 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [MP][qtp1651136634-1982]:   allowedSegmentCount=10 vs count=1 (eligible count=1)
   [junit4]   2> 153926 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1651136634-1982]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
   [junit4]   2> 153926 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1651136634-1982]: now merge
   [junit4]   2> 153926 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1651136634-1982]:   index: _0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
   [junit4]   2> 153926 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1651136634-1982]:   no more merges pending; now return
   [junit4]   2> 153927 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
   [junit4]   2> 153927 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: flush at getReader
   [junit4]   2> 153927 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1982]: startFullFlush
   [junit4]   2> 153927 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
   [junit4]   2> 153927 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1982]: waitApply: no deletes to apply
   [junit4]   2> 153932 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: incRefDeleter for NRT reader version=6 segments=_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
   [junit4]   2> 153932 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: return reader version=6 reader=StandardDirectoryReader(segments_2:6:nrt _0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
   [junit4]   2> 153932 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1982]: qtp1651136634-1982 finishFullFlush success=true
   [junit4]   2> 153932 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1982]: getReader took 5 msec
   [junit4]   2> 153933 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e9e69ac[collection1] main]
   [junit4]   2> 153933 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 153933 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@e9e69ac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 153933 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 153936 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e9e69ac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 153936 INFO  (searcherExecutor-200-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][searcherExecutor-200-thread-1-processing-x:collection1]: decRefDeleter for NRT reader version=2 segments=
   [junit4]   2> 153937 INFO  (qtp1651136634-1982) [    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 18
   [junit4]   2> 153967 INFO  (qtp1651136634-1978) [    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> 153983 ERROR (h2sc-1283-thread-13) [    ] o.a.s.c.u.ExecutorUtil Uncaught exception java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@7ae8ad1 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@9697454[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0] thrown by thread: h2sc-1283-thread-13
   [junit4]   2> java.lang.Exception: Submitter stack trace
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:184) ~[java/:?]
   [junit4]   2> 	at org.eclipse.jetty.util.SocketAddressResolver$Async.resolve(SocketAddressResolver.java:147) ~[jetty-util-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpClient.newConnection(HttpClient.java:588) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpDestination.createConnection(HttpDestination.java:371) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpDestination.newConnection(HttpDestination.java:366) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.AbstractConnectionPool.tryCreate(AbstractConnectionPool.java:122) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.AbstractConnectionPool.acquire(AbstractConnectionPool.java:94) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpDestination.process(HttpDestination.java:301) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:294) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:269) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpDestination.send(HttpDestination.java:246) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpClient.send(HttpClient.java:582) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:726) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:718) ~[jetty-client-9.4.14.v20181114.jar:9.4.14.v20181114]
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:390) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:732) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClient.request(ConcurrentUpdateHttp2SolrClient.java:334) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:987) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters(ConcurrentUpdateHttp2SolrClientTest.java:127) ~[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]   1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@d0ef7a[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 14]
   [junit4]   2> 154013 WARN  (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[E9B29D7F21FC8E55]) [    ] 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> فبر 02, 2019 6:30:06 م com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
   [junit4]   2> WARNING: Uncaught exception in thread: Thread[h2sc-1283-thread-13,5,TGRP-ConcurrentUpdateHttp2SolrClientTest]
   [junit4]   2> java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@7ae8ad1 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@9697454[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
   [junit4]   2> 	at __randomizedtesting.SeedInfo.seed([E9B29D7F21FC8E55]: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:102)
   [junit4]   2> 	at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:154)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
   [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> 154048 WARN  (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[E9B29D7F21FC8E55]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@37fd8695[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 154234 INFO  (qtp1651136634-1980) [    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]   1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@202ba580[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 11]
   [junit4]   2> 154261 INFO  (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[E9B29D7F21FC8E55]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollectionParameters
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ConcurrentUpdateHttp2SolrClientTest -Dtests.method=testCollectionParameters -Dtests.seed=E9B29D7F21FC8E55 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ar-QA -Dtests.timezone=Africa/Luanda -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.54s J2 | ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters <<<
   [junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=2013, name=h2sc-1283-thread-13, state=RUNNABLE, group=TGRP-ConcurrentUpdateHttp2SolrClientTest]
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([E9B29D7F21FC8E55:DC96B81A501BD757]:0)
   [junit4]    > Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@7ae8ad1 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@9697454[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([E9B29D7F21FC8E55]: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:102)
   [junit4]    > 	at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:154)
   [junit4]    > 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
   [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> 154289 INFO  (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[E9B29D7F21FC8E55]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testConcurrentCollectionUpdate
   [junit4]   2> 154290 WARN  (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[E9B29D7F21FC8E55]) [    ] 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> 154303 WARN  (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[E9B29D7F21FC8E55]) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5caee6eb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: flush at getReader
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: startFullFlush
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: qtp1651136634-1981: flush naked frozen global deletes
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: compressed 76 to 48 bytes (63.16%) for deletes/updates; private segment null
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: push new packet (delGen=3 numDeleteQueries=1 bytesUsed=48), packetCount=1, bytesUsed=0.000 MB
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: flush: push buffered updates: delGen=3 numDeleteQueries=1 bytesUsed=48
   [junit4]   2> 154437 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: now apply del packet (delGen=3 numDeleteQueries=1 bytesUsed=48) to 1 segments, mergeGen 0
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: applyQueryDeletes took 0.44 msec for 1 segments and 1 queries; 1 new deletions
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: closeSegmentStates: 1 new deleted documents; pool 1 packets; bytesUsed=0
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1981]: now checkpoint "_0(9.0.0):C1/1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1981]: 0 msec to checkpoint
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: drop 100% deleted segments: _0(9.0.0):C1/1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128606240}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1981]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1651136634-1981]: 0 msec to checkpoint
   [junit4]   2> 154438 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: done inner apply del packet (delGen=3 numDeleteQueries=1 bytesUsed=48) to 1 segments; 1 new deletes/updates; took 0.001 sec
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: finished packet delGen=3 now completedDelGen=3
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: done apply del packet (delGen=3 numDeleteQueries=1 bytesUsed=48) to 1 segments; 1 new deletes/updates; took 0.005 sec; 0 packets remain
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: waitApply: no deletes to apply
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: incRefDeleter for NRT reader version=8 segments=
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: return reader version=8 reader=StandardDirectoryReader(segments_2:8:nrt)
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: qtp1651136634-1981 finishFullFlush success=true
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [MP][qtp1651136634-1981]: findMerges: 0 segments
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1651136634-1981]: now merge
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1651136634-1981]:   index: 
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1651136634-1981]:   no more merges pending; now return
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: getReader took 5 msec
   [junit4]   2> 154442 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f31d2f3[collection1] realtime]
   [junit4]   2> 154443 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1624379077943099392)} 0 19
   [junit4]   2> 154747 INFO  (qtp1651136634-1982) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[0_0 (1624379078104580096), 1_0 (1624379078114017280), 2_0 (1624379078114017281), 3_0 (1624379078114017282), 4_0 (1624379078114017283), 0_1 (1624379078115065856), 0_2 (1624379078115065857), 0_3 (1624379078115065858), 0_4 (1624379078115065859), 0_5 (1624379078115065860), ... (277 adds)]} 0 168
   [junit4]   2> 154748 INFO  (qtp1651136634-1980) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[0_22 (1624379078122405888), 3_19 (1624379078136037376), 3_20 (1624379078136037377), 3_21 (1624379078136037378), 3_22 (1624379078136037379), 3_23 (1624379078137085952), 3_24 (1624379078137085953), 0_69 (1624379078137085954), 0_70 (1624379078137085955), 0_71 (1624379078137085956), ... (223 adds)]} 0 153
   [junit4]   2> 159494 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1624379083259379712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@170bcf4d commitCommandVersion:1624379083259379712
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: commit: start
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: commit: enter lock
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: commit: now prepare
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: prepareCommit: flush
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]:   index before flush 
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: startFullFlush
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1651136634-1981]: anyChanges? numDocsInRam=500 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWFC][qtp1651136634-1981]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 499 deleted terms (unique count=499) bytesUsed=87775, segment=_1, aborted=false, numDocsInRAM=240, deleteQueue=DWDQ: [ generation: 4 ]]
   [junit4]   2> 159495 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWFC][qtp1651136634-1981]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 357 deleted terms (unique count=357) bytesUsed=62796, segment=_2, aborted=false, numDocsInRAM=260, deleteQueue=DWDQ: [ generation: 4 ]]
   [junit4]   2> 159498 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: compressed 87950 to 2064 bytes (2.35%) for deletes/updates; private segment null
   [junit4]   2> 159498 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flush postings as segment _1 numDocs=240
   [junit4]   2> 159498 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to write norms
   [junit4]   2> 159499 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to write docValues
   [junit4]   2> 159499 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to write points
   [junit4]   2> 159499 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to finish stored fields
   [junit4]   2> 159505 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 5 msec to write postings and finish vectors
   [junit4]   2> 159505 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to write fieldInfos
   [junit4]   2> 159505 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: new segment has 0 deleted docs
   [junit4]   2> 159505 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: new segment has 0 soft-deleted docs
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: new segment has no vectors; no norms; docValues; no prox; freqs
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flushedFiles=[_1_Lucene50_0.doc, _1_Lucene50_0.tim, _1_Lucene80_0.dvd, _1.fdx, _1.fnm, _1_Lucene80_0.dvm, _1.fdt, _1_Lucene50_0.tip]
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flushed codec=Lucene80
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flushed: segment=_1 ramUsed=0.184 MB newFlushedSize=0.007 MB docs/MB=32,836.409
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flush time 7.577267 msec
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flush postings as segment _2 numDocs=260
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to write norms
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to write docValues
   [junit4]   2> 159506 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to write points
   [junit4]   2> 159507 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 0 msec to finish stored fields
   [junit4]   2> 159516 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 9 msec to write postings and finish vectors
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: 22 msec to write fieldInfos
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: new segment has 0 deleted docs
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: new segment has 0 soft-deleted docs
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: new segment has no vectors; no norms; docValues; no prox; freqs
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flushedFiles=[_2_Lucene80_0.dvd, _2.fdt, _2_Lucene80_0.dvm, _2.fnm, _2.fdx, _2_Lucene50_0.doc, _2_Lucene50_0.tim, _2_Lucene50_0.tip]
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flushed codec=Lucene80
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flushed: segment=_2 ramUsed=0.185 MB newFlushedSize=0.007 MB docs/MB=39,300.816
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1651136634-1981]: flush time 33.177348 msec
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1651136634-1981]: publishFlushedSegment _1(9.0.0):C240:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=9.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1549128611824}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: push new packet (delGen=4 numDeleteTerms=500 bytesUsed=2064), packetCount=1, bytesUsed=0.002 MB
   [junit4]   2> 159539 INFO  (qtp1651136634-1981) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1651136634-1981]: finished packet delGen=5 now completedDelGen=3
   [junit4]  

[...truncated too long message...]

llowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null388974350
     [copy] Copying 237 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null388974350
   [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null388974350

check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] 	confs: [default]
[ivy:cachepath] 	found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] 	found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] 	found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] 	found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] 	found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] 	found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] 	found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] 	found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 30ms :: artifacts dl 3ms
	---------------------------------------------------------------------
	|                  |            modules            ||   artifacts   |
	|       conf       | number| search|dwnlded|evicted|| number|dwnlded|
	---------------------------------------------------------------------
	|      default     |   8   |   0   |   0   |   0   ||   8   |   0   |
	---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...

-jenkins-base:

BUILD SUCCESSFUL
Total time: 105 minutes 25 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
	at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
	at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
		at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
		at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
		at hudson.remoting.Channel.call(Channel.java:955)
		at hudson.FilePath.act(FilePath.java:1072)
		at hudson.FilePath.act(FilePath.java:1061)
		at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
		at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
		at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
		at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
		at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
		at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
		at hudson.model.Build$BuildExecution.post2(Build.java:186)
		at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
		at hudson.model.Run.execute(Run.java:1835)
		at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
		at hudson.model.ResourceController.execute(ResourceController.java:97)
		at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088)
	at hudson.remoting.UserRequest.perform(UserRequest.java:212)
	at hudson.remoting.UserRequest.perform(UserRequest.java:54)
	at hudson.remoting.Request$2.run(Request.java:369)
	at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath.act(FilePath.java:1074)
	at hudson.FilePath.act(FilePath.java:1061)
	at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
	at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
	at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
	at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
	at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
	at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
	at hudson.model.Build$BuildExecution.post2(Build.java:186)
	at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
	at hudson.model.Run.execute(Run.java:1835)
	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
	at hudson.model.ResourceController.execute(ResourceController.java:97)
	at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)

Mime
View raw message