You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2019/08/29 05:21:24 UTC
[JENKINS] Lucene-Solr-BadApples-Tests-8.x - Build # 199 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-8.x/199/
1 tests failed.
FAILED: org.apache.solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters
Error Message:
Captured an uncaught exception in thread: Thread[id=5232, name=h2sc-2087-thread-6, state=RUNNABLE, group=TGRP-ConcurrentUpdateHttp2SolrClientTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5232, name=h2sc-2087-thread-6, state=RUNNABLE, group=TGRP-ConcurrentUpdateHttp2SolrClientTest]
at __randomizedtesting.SeedInfo.seed([68236FDA71F417A9:5D074ABF00134EAB]:0)
Caused by: java.lang.ClassCastException: org.eclipse.jetty.io.WriteFlusher$IdleState cannot be cast to org.eclipse.jetty.io.WriteFlusher$FailedState
at __randomizedtesting.SeedInfo.seed([68236FDA71F417A9]:0)
at org.eclipse.jetty.io.WriteFlusher.fail(WriteFlusher.java:310)
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:376)
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.lambda$fill$1(SslConnection.java:670)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 16983 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest
[junit4] 2> 250012 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 250013 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/data-dir-28-001
[junit4] 2> 250013 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 250013 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 250015 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-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> 250058 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 250058 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 250058 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-002/cores/core
[junit4] 2> 250063 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 250063 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 250063 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 250063 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_191-b12
[junit4] 2> 250067 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 250067 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 250067 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 250067 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d53b5a6{/solr,null,AVAILABLE}
[junit4] 2> 250068 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5116641f{SSL,[ssl, http/1.1]}{127.0.0.1:38435}
[junit4] 2> 250068 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.s.Server Started @250131ms
[junit4] 2> 250068 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38435, configSetBaseDir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-002/cores}
[junit4] 2> 250069 ERROR (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 250069 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 250069 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 250069 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 250069 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 250069 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-08-29T04:58:47.335Z
[junit4] 2> 250070 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/solr.xml
[junit4] 2> 250076 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@676144a0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 250240 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 250241 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-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> 250242 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@23763c54[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 250242 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@23763c54[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 250245 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-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> 250246 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@14d39e0f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 250246 WARN (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@14d39e0f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 250277 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 250277 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 250301 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@676144a0
[junit4] 2> 250315 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@676144a0
[junit4] 2> 250316 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@676144a0
[junit4] 2> 250342 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001
[junit4] 2> 250342 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 250366 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/extraction/lib
[junit4] 2> 250366 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 250366 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/clustering/lib
[junit4] 2> 250366 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 250366 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/langid/lib
[junit4] 2> 250366 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 250367 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 250367 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../contrib/velocity/lib
[junit4] 2> 250367 WARN (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 250367 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
[junit4] 2> 250377 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled
[junit4] 2> 250436 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 250621 INFO (coreLoadExecutor-1097-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> 250621 INFO (coreLoadExecutor-1097-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> 250621 INFO (coreLoadExecutor-1097-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> 250621 INFO (coreLoadExecutor-1097-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> 250623 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 250623 INFO (coreLoadExecutor-1097-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> 250623 INFO (coreLoadExecutor-1097-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> 250623 INFO (coreLoadExecutor-1097-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> 250623 INFO (coreLoadExecutor-1097-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> 250643 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1, trusted=true
[junit4] 2> 250643 INFO (coreLoadExecutor-1097-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@676144a0
[junit4] 2> 250643 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/data/]
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@6115c8e1
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: 0 msec to checkpoint
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: init: create=true reader=null
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@11fa2c1a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33788b9d)
[junit4] 2> index=
[junit4] 2> version=8.3.0
[junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=CREATE
[junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
[junit4] 2> codec=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@1bc12661
[junit4] 2> readerPooling=true
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> indexSort=null
[junit4] 2> checkPendingFlushOnUpdate=true
[junit4] 2> softDeletesField=null
[junit4] 2> readerAttributes={}
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@545cb738
[junit4] 2>
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: now flush at close
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: index before flush
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: startFullFlush
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: coreLoadExecutor-1097-thread-1 finishFullFlush success=true
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-1097-thread-1]: waitApply: no deletes to apply
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-1097-thread-1]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-1097-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-1097-thread-1]: now merge
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-1097-thread-1]: index:
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-1097-thread-1]: no more merges pending; now return
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: waitForMerges
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: waitForMerges done
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: commit: start
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: commit: enter lock
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: commit: now prepare
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: prepareCommit: flush
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: index before flush
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: startFullFlush
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-1097-thread-1]: waitApply: no deletes to apply
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: coreLoadExecutor-1097-thread-1 finishFullFlush success=true
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: startCommit(): start
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: startCommit index= changeCount=2
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: startCommit: wrote pending segments file "pending_segments_1"
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: done all syncs: []
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: commit: pendingCommit != null
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: commit: done writing segments file "segments_1"
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: 0 msec to checkpoint
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: commit: took 0.3 msec
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: commit: done
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: rollback
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: all running merges have aborted
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: rollback: done finish merges
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: abort
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: done abort success=true
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: rollback: infos=
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 250646 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: 0 msec to checkpoint
[junit4] 2> 250647 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 250709 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 250709 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 250710 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 250710 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@6115c8e1
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: init: load commit "segments_1"
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-1097-thread-1]: 0 msec to checkpoint
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: init: create=false reader=null
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@11fa2c1a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33788b9d)
[junit4] 2> index=
[junit4] 2> version=8.3.0
[junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=APPEND
[junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
[junit4] 2> codec=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@4433203b
[junit4] 2> readerPooling=true
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> indexSort=null
[junit4] 2> checkPendingFlushOnUpdate=true
[junit4] 2> softDeletesField=null
[junit4] 2> readerAttributes={}
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@289f3d4b
[junit4] 2>
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: flush at getReader
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: startFullFlush
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-1097-thread-1]: waitApply: no deletes to apply
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: incRefDeleter for NRT reader version=2 segments=
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-1097-thread-1]: coreLoadExecutor-1097-thread-1 finishFullFlush success=true
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-1097-thread-1]: getReader took 0 msec
[junit4] 2> 250712 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@b73febb[collection1] main]
[junit4] 2> 250713 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/conf
[junit4] 2> 250713 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _rest_managed.json using file:dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/conf
[junit4] 2> 250713 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Loaded initArgs {} for /rest/managed
[junit4] 2> 250714 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/conf
[junit4] 2> 250714 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
[junit4] 2> 250714 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for /schema/analysis/stopwords/english
[junit4] 2> 250714 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Notified 4 observers of /schema/analysis/stopwords/english
[junit4] 2> 250714 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using file:dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/conf
[junit4] 2> 250714 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
[junit4] 2> 250714 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.s.a.ManagedSynonymGraphFilterFactory Loaded 3 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 250715 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.r.ManagedResource Notified 4 observers of /schema/analysis/synonyms/english
[junit4] 2> 250715 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 250715 INFO (coreLoadExecutor-1097-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> 250716 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 250716 INFO (coreLoadExecutor-1097-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> 250716 INFO (coreLoadExecutor-1097-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> 250717 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.impl.ConcurrentUpdateHttp2SolrClientTest_68236FDA71F417A9-001/tempDir-001/collection1/conf/elevate.xml
[junit4] 2> 250722 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 250722 INFO (coreLoadExecutor-1097-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1643175978454745088
[junit4] 2> 250725 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@b73febb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 250725 INFO (SUITE-ConcurrentUpdateHttp2SolrClientTest-seed#[68236FDA71F417A9]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#38435
[junit4] 2> 250725 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher} hits=0 status=0 QTime=0
[junit4] 2> 250725 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 250725 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 250725 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 250733 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@b73febb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 250747 INFO (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConcurrentCollectionUpdate
[junit4] 2> 250748 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] 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> 250753 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@49f1caae[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 250753 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@49f1caae[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 250796 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 250796 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 250796 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
[junit4] 2> 250796 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: flush at getReader
[junit4] 2> 250796 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: startFullFlush
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: qtp1919480400-5144: flush naked frozen global deletes
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: compressed 76 to 48 bytes (63.16%) for deletes/updates; private segment null
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: push new packet (delGen=1 numDeleteQueries=1 bytesUsed=48), packetCount=1, bytesUsed=0.000 MB
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: flush: push buffered updates: delGen=1 numDeleteQueries=1 bytesUsed=48
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: packet matches no segments
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: finished packet delGen=1 now completedDelGen=1
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: done apply del packet (delGen=1 numDeleteQueries=1 bytesUsed=48) to 0 segments; 0 new deletes/updates; took 0.000 sec; 0 packets remain
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: waitApply: no deletes to apply
[junit4] 2> 250797 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: incRefDeleter for NRT reader version=2 segments=
[junit4] 2> 250798 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
[junit4] 2> 250798 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: qtp1919480400-5144 finishFullFlush success=true
[junit4] 2> 250798 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: getReader took 2 msec
[junit4] 2> 250798 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: decRefDeleter for NRT reader version=2 segments=
[junit4] 2> 250801 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1643175978529193984)} 0 9
[junit4] 2> 250858 INFO (qtp1919480400-5145) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0_0 (1643175978559602688), 1_0 (1643175978561699840), 0_2 (1643175978561699841), 0_3 (1643175978561699842), 0_4 (1643175978562748416), 0_5 (1643175978562748417), 0_6 (1643175978562748418), 0_7 (1643175978562748419), 0_8 (1643175978563796992), 0_9 (1643175978563796993), ... (107 adds)]} 0 36
[junit4] 2> 250871 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0_1 (1643175978575331329), 1_2 (1643175978575331330), 1_4 (1643175978575331332), 1_5 (1643175978575331333), 1_7 (1643175978576379905), 0_17 (1643175978576379906), 1_8 (1643175978576379908), 1_9 (1643175978576379909), 1_10 (1643175978576379910), 0_19 (1643175978576379912), ... (103 adds)]} 0 34
[junit4] 2> 250931 INFO (qtp1919480400-5141) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3_64 (1643175978671800320), 4_83 (1643175978671800322), 4_85 (1643175978671800324), 4_87 (1643175978672848896), 4_89 (1643175978672848899), 4_91 (1643175978672848901), 4_93 (1643175978672848903), 4_95 (1643175978672848905), 4_96 (1643175978672848907), 4_97 (1643175978672848909), ... (12 adds)]} 0 1
[junit4] 2> 250934 INFO (qtp1919480400-5145) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1_26 (1643175978605740034), 1_27 (1643175978605740036), 1_28 (1643175978605740038), 1_29 (1643175978605740040), 1_30 (1643175978605740042), 1_31 (1643175978605740044), 1_32 (1643175978605740046), 1_33 (1643175978609934336), 1_34 (1643175978609934338), 1_35 (1643175978609934340), ... (278 adds)]} 0 68
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1643175983804579840,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@289f3d4b commitCommandVersion:1643175983804579840
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: commit: start
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: commit: enter lock
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: commit: now prepare
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: prepareCommit: flush
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: index before flush
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: startFullFlush
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: anyChanges? numDocsInRam=500 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWFC][qtp1919480400-5144]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 471 deleted terms (unique count=471) bytesUsed=82847, segment=_0, aborted=false, numDocsInRAM=217, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 255824 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWFC][qtp1919480400-5144]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 293 deleted terms (unique count=293) bytesUsed=51553, segment=_1, aborted=false, numDocsInRAM=283, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 255825 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: compressed 87950 to 2064 bytes (2.35%) for deletes/updates; private segment null
[junit4] 2> 255825 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flush postings as segment _0 numDocs=217
[junit4] 2> 255825 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to write norms
[junit4] 2> 255829 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 4 msec to write docValues
[junit4] 2> 255829 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to write points
[junit4] 2> 255830 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to finish stored fields
[junit4] 2> 255831 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 1 msec to write postings and finish vectors
[junit4] 2> 255831 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to write fieldInfos
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: new segment has 0 deleted docs
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: new segment has 0 soft-deleted docs
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: new segment has no vectors; no norms; docValues; no prox; freqs
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: 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> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flushed codec=Lucene80
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flushed: segment=_0 ramUsed=0.183 MB newFlushedSize=0.007 MB docs/MB=32,454.856
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flush time 6.782385 msec
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flush postings as segment _1 numDocs=283
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to write norms
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to write docValues
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to write points
[junit4] 2> 255832 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to finish stored fields
[junit4] 2> 255833 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 1 msec to write postings and finish vectors
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: 0 msec to write fieldInfos
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: new segment has 0 deleted docs
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: new segment has 0 soft-deleted docs
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: new segment has no vectors; no norms; docValues; no prox; freqs
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: 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> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flushed codec=Lucene80
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flushed: segment=_1 ramUsed=0.155 MB newFlushedSize=0.007 MB docs/MB=40,204.174
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5144]: flush time 1.967059 msec
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: publishFlushedSegment seg-private updates=null
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: publishFlushedSegment _0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: push new packet (delGen=2 numDeleteTerms=500 bytesUsed=2064), packetCount=1, bytesUsed=0.002 MB
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: finished packet delGen=3 now completedDelGen=1
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: publish sets newSegment delGen=3 seg=_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: now checkpoint "_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: 0 msec to checkpoint
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: publishFlushedSegment seg-private updates=null
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: publishFlushedSegment _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: finished packet delGen=4 now completedDelGen=1
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: publish sets newSegment delGen=4 seg=_1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: now checkpoint "_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [2 segments ; isCommit = false]
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: 0 msec to checkpoint
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: packet matches no segments
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: finished packet delGen=2 now completedDelGen=4
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: done apply del packet (delGen=2 numDeleteTerms=500 bytesUsed=2064) to 0 segments; 0 new deletes/updates; took 0.000 sec; 0 packets remain
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: waitApply: no deletes to apply
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: qtp1919480400-5144 finishFullFlush success=true
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: startCommit(): start
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: startCommit index=_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] changeCount=6
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: startCommit: wrote pending segments file "pending_segments_2"
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: done all syncs: [_0.si, _0_Lucene50_0.doc, _0_Lucene50_0.tim, _1.si, _1_Lucene80_0.dvd, _0.fdx, _0_Lucene50_0.tip, _1.fnm, _0.fdt, _0_Lucene80_0.dvm, _1_Lucene50_0.doc, _1_Lucene50_0.tim, _0_Lucene80_0.dvd, _1.fdx, _1_Lucene80_0.dvm, _0.fnm, _1.fdt, _1_Lucene50_0.tip]
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: commit: pendingCommit != null
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: commit: done writing segments file "segments_2"
[junit4] 2> 255834 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: now checkpoint "_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [2 segments ; isCommit = true]
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: delete [segments_1]
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5144]: 0 msec to checkpoint
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: commit: took 11.4 msec
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: commit: done
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MP][qtp1919480400-5144]: seg=_1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] size=0.007 MB [floored]
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MP][qtp1919480400-5144]: seg=_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] size=0.007 MB [floored]
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MP][qtp1919480400-5144]: findMerges: 2 segments
[junit4] 2> 255835 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MP][qtp1919480400-5144]: allowedSegmentCount=10 vs count=2 (eligible count=2)
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp1919480400-5144]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp1919480400-5144]: now merge
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp1919480400-5144]: index: _0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [MS][qtp1919480400-5144]: no more merges pending; now return
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: flush at getReader
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: startFullFlush
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 255836 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5144]: waitApply: no deletes to apply
[junit4] 2> 255837 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: incRefDeleter for NRT reader version=8 segments=_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255837 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: return reader version=8 reader=StandardDirectoryReader(segments_2:8:nrt _0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
[junit4] 2> 255837 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5144]: qtp1919480400-5144 finishFullFlush success=true
[junit4] 2> 255837 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5144]: getReader took 1 msec
[junit4] 2> 255837 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6de284d0[collection1] main]
[junit4] 2> 255838 INFO (qtp1919480400-5144) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 255838 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@6de284d0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 255838 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 255841 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6de284d0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 255841 INFO (searcherExecutor-1098-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][searcherExecutor-1098-thread-1-processing-x:collection1]: decRefDeleter for NRT reader version=2 segments=
[junit4] 2> 255842 INFO (qtp1919480400-5144) [ 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> 255844 INFO (qtp1919480400-5142) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=500 status=0 QTime=0
[junit4] 2> 255853 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] 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> 255858 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a9abfc4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 255858 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a9abfc4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 255887 INFO (qtp1919480400-5142) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=500 status=0 QTime=0
[junit4] 2> 255900 INFO (TEST-ConcurrentUpdateHttp2SolrClientTest.testConcurrentCollectionUpdate-seed#[68236FDA71F417A9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConcurrentCollectionUpdate
[junit4] 2> 255907 INFO (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[68236FDA71F417A9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionParameters
[junit4] 2> 255908 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[68236FDA71F417A9]) [ ] 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> 255910 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[68236FDA71F417A9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3a1ee76c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 255910 WARN (TEST-ConcurrentUpdateHttp2SolrClientTest.testCollectionParameters-seed#[68236FDA71F417A9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3a1ee76c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 255941 INFO (qtp1919480400-5145) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[collection (1643175983923068928)]} 0 3
[junit4] 2> 255950 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1643175983936700416,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 255950 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@289f3d4b commitCommandVersion:1643175983936700416
[junit4] 2> 255950 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: commit: start
[junit4] 2> 255950 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: commit: enter lock
[junit4] 2> 255950 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: commit: now prepare
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: prepareCommit: flush
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: index before flush _0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5143]: startFullFlush
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5143]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWFC][qtp1919480400-5143]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_2, aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 4 ]]
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: compressed 182 to 1040 bytes (571.43%) for deletes/updates; private segment null
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: flush postings as segment _2 numDocs=1
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: 0 msec to write norms
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: 0 msec to write docValues
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: 0 msec to write points
[junit4] 2> 255951 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: 0 msec to finish stored fields
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: 0 msec to write postings and finish vectors
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: 0 msec to write fieldInfos
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: new segment has 0 deleted docs
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: new segment has 0 soft-deleted docs
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: new segment has no vectors; no norms; docValues; no prox; freqs
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: 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> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: flushed codec=Lucene80
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: flushed: segment=_2 ramUsed=0.087 MB newFlushedSize=0.001 MB docs/MB=860.9
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1919480400-5143]: flush time 1.384085 msec
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: publishFlushedSegment seg-private updates=null
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: publishFlushedSegment _2(8.3.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733218}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255952 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: push new packet (delGen=5 numDeleteTerms=1 bytesUsed=1040), packetCount=1, bytesUsed=0.001 MB
[junit4] 2> 255953 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: finished packet delGen=6 now completedDelGen=4
[junit4] 2> 255953 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: publish sets newSegment delGen=6 seg=_2(8.3.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733218}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 255953 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5143]: now checkpoint "_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _2(8.3.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733218}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [3 segments ; isCommit = false]
[junit4] 2> 255953 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5143]: 0 msec to checkpoint
[junit4] 2> 255953 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: now apply del packet (delGen=5 numDeleteTerms=1 bytesUsed=1040) to 2 segments, mergeGen 0
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: applyTermDeletes took 0.13 msec for 2 segments and 1 del terms; 0 new deletions
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: closeSegmentStates: 0 new deleted documents; pool 1 packets; bytesUsed=0
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: done inner apply del packet (delGen=5 numDeleteTerms=1 bytesUsed=1040) to 2 segments; 0 new deletes/updates; took 0.000 sec
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: finished packet delGen=5 now completedDelGen=6
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: done apply del packet (delGen=5 numDeleteTerms=1 bytesUsed=1040) to 2 segments; 0 new deletes/updates; took 0.000 sec; 0 packets remain
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [BD][qtp1919480400-5143]: waitApply: no deletes to apply
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [DW][qtp1919480400-5143]: qtp1919480400-5143 finishFullFlush success=true
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: startCommit(): start
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: startCommit index=_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _2(8.3.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733218}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] changeCount=10
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: startCommit: wrote pending segments file "pending_segments_3"
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: done all syncs: [_0_Lucene50_0.doc, _1.si, _2.fdt, _2_Lucene80_0.dvm, _1.fnm, _2.fdx, _2_Lucene50_0.tim, _2_Lucene50_0.tip, _1_Lucene50_0.tim, _0_Lucene80_0.dvd, _2.fnm, _1.fdx, _1_Lucene80_0.dvm, _1.fdt, _1_Lucene50_0.tip, _0.si, _0_Lucene50_0.tim, _2.si, _1_Lucene80_0.dvd, _0.fdx, _0_Lucene50_0.tip, _2_Lucene50_0.doc, _0.fdt, _0_Lucene80_0.dvm, _1_Lucene50_0.doc, _2_Lucene80_0.dvd, _0.fnm]
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: commit: pendingCommit != null
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IW][qtp1919480400-5143]: commit: done writing segments file "segments_3"
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5143]: now checkpoint "_0(8.3.0):C217:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733098}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _1(8.3.0):C283:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733100}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] _2(8.3.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.3.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.15.0-54-generic, timestamp=1567054733218}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [3 segments ; isCommit = true]
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5143]: deleteCommits: now decRef commit "segments_2"
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5143]: delete [segments_2]
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1919480400-5143]: 0 msec to checkpoint
[junit4] 2> 255954 INFO (qtp1919480400-5143) [ x:collection1 ] o.a.s
[...truncated too long message...]
o 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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/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 = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/null1532565683
[copy] Copying 249 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/null1532565683
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/null1532565683
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: #;working@lucene1-us-west
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.54 in public
[ivy:cachepath] found com.jcraft#jzlib;1.1.1 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] found org.bouncycastle#bcpg-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcprov-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcpkix-jdk15on;1.60 in public
[ivy:cachepath] found org.slf4j#slf4j-nop;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 40ms :: artifacts dl 2ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 9 | 0 | 0 | 0 || 9 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 119 minutes 57 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:744)
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)