Build: https://jenkins.thetaphi.de/job/Lucene-Solr-http2-MacOSX/6/ Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseSerialGC
No tests ran. Build Log: [...truncated 13124 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> 509028 INFO (SUITE-TestDistributedSearch-seed#[3ADC37E1621BCB84]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/init-core-data-001 [junit4] 2> 509028 WARN (SUITE-TestDistributedSearch-seed#[3ADC37E1621BCB84]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 509029 INFO (SUITE-TestDistributedSearch-seed#[3ADC37E1621BCB84]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 509029 INFO (SUITE-TestDistributedSearch-seed#[3ADC37E1621BCB84]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-9061") w/ MAC_OS_X supressed clientAuth [junit4] 2> 509029 INFO (SUITE-TestDistributedSearch-seed#[3ADC37E1621BCB84]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 509321 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/control/cores/collection1 [junit4] 2> 509322 WARN (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 509323 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 509323 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 509323 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 9+181 [junit4] 2> 509324 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 509324 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 509324 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 509324 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a468f2a{/,null,AVAILABLE} [junit4] 2> 509325 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@c06a2c8{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56223} [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.Server Started @509412ms [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=56223, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/control/cores} [junit4] 2> 509326 ERROR (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-03T01:48:32.546702Z [junit4] 2> 509326 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/control/solr.xml [junit4] 2> 509328 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 509328 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 509329 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 509489 WARN (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4014d90c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 509494 WARN (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4188e433[provider=null,keyStore=null,trustStore=null] [junit4] 2> 509509 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 509509 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 509524 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6 [junit4] 2> 509532 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6 [junit4] 2> 509532 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6 [junit4] 2> 509533 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/control/cores [junit4] 2> 509533 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 509534 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 509569 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 509585 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 509664 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 509671 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 509671 INFO (coreLoadExecutor-2517-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@1edbbda6 [junit4] 2> 509671 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 509672 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 509727 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 509727 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 509728 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 509728 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 509729 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=55.2412109375, floorSegmentMB=0.2080078125, forceMergeDeletesPctAllowed=25.694646836591936, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=44.05216811376122 [junit4] 2> 509729 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f74e50d[collection1] main] [junit4] 2> 509729 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 509752 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 509799 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 509799 INFO (coreLoadExecutor-2517-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1618793425030610944 [junit4] 2> 509801 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 509801 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 509801 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 509801 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 509802 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 509802 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 509803 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 509803 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 509803 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 509804 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f74e50d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 510064 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 510065 WARN (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 510065 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 510065 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 510065 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 9+181 [junit4] 2> 510066 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 510066 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 510066 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 510067 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a6cd652{/,null,AVAILABLE} [junit4] 2> 510067 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3496f187{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56224} [junit4] 2> 510067 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.s.Server Started @510154ms [junit4] 2> 510067 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=56224, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/shard0/cores} [junit4] 2> 510067 ERROR (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 510067 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 510067 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 510068 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 510068 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 510068 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-03T01:48:33.288073Z [junit4] 2> 510068 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/shard0/solr.xml [junit4] 2> 510070 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 510070 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 510070 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 510337 WARN (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6af23059[provider=null,keyStore=null,trustStore=null] [junit4] 2> 510342 WARN (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@291c9012[provider=null,keyStore=null,trustStore=null] [junit4] 2> 510357 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 510357 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 510374 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6 [junit4] 2> 510386 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6 [junit4] 2> 510386 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1edbbda6 [junit4] 2> 510387 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/shard0/cores [junit4] 2> 510387 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 510388 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 510411 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 510428 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 510541 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 510549 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 510550 INFO (coreLoadExecutor-2531-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@1edbbda6 [junit4] 2> 510550 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 510551 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 510611 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 510611 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 510613 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 510613 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 510613 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=55.2412109375, floorSegmentMB=0.2080078125, forceMergeDeletesPctAllowed=25.694646836591936, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=44.05216811376122 [junit4] 2> 510614 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@14bec7f4[collection1] main] [junit4] 2> 510614 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 510624 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 510691 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 510691 INFO (coreLoadExecutor-2531-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1618793425965940736 [junit4] 2> 510693 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 510694 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 510694 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 510694 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 510695 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 510696 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 510696 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 510697 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 510698 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 510699 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14bec7f4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 511203 INFO (TEST-TestDistributedSearch.test-seed#[3ADC37E1621BCB84]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 511208 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1618793426505957376)} 0 2 [junit4] 2> 511211 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1618793426509103104)} 0 2 [junit4] 2> 511214 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1618793426512248832)]} 0 1 [junit4] 2> 511216 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1618793426515394560)]} 0 1 [junit4] 2> 511217 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1618793426516443136)]} 0 0 [junit4] 2> 511219 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1618793426518540288)]} 0 1 [junit4] 2> 511220 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1618793426520637440)]} 0 0 [junit4] 2> 511221 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1618793426521686016)]} 0 0 [junit4] 2> 511222 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1618793426522734592)]} 0 0 [junit4] 2> 511223 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1618793426523783168)]} 0 0 [junit4] 2> 511225 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1618793426525880320)]} 0 0 [junit4] 2> 511226 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1618793426526928896)]} 0 0 [junit4] 2> 511227 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1618793426527977472)]} 0 0 [junit4] 2> 511228 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1618793426529026048)]} 0 0 [junit4] 2> 511229 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1618793426530074624)]} 0 0 [junit4] 2> 511230 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1618793426530074624)]} 0 0 [junit4] 2> 511231 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1618793426532171776)]} 0 0 [junit4] 2> 511232 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1618793426533220352)]} 0 0 [junit4] 2> 511233 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1618793426534268928)]} 0 0 [junit4] 2> 511234 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1618793426534268928)]} 0 0 [junit4] 2> 511234 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1618793426535317504,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 511234 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3c2b5ff7 commitCommandVersion:1618793426535317504 [junit4] 2> 511243 INFO (qtp351068896-11019) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@47686516[collection1] main] [junit4] 2> 511243 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 511245 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@47686516[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=9, java.vm.version=9+181, lucene.version=8.0.0, os.arch=x86_64, java.runtime.version=9+181, source=flush, os.version=10.11.6, timestamp=1543801714460}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 511245 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10 [junit4] 2> 511245 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1618793426546851840,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 511245 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4046303e commitCommandVersion:1618793426546851840 [junit4] 2> 511251 INFO (qtp920117145-11070) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27c1085e[collection1] main] [junit4] 2> 511252 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 511253 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27c1085e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=9, java.vm.version=9+181, lucene.version=8.0.0, os.arch=x86_64, java.runtime.version=9+181, source=flush, os.version=10.11.6, timestamp=1543801714468}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 511253 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7 [junit4] 2> 511256 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1618793426556289024)]} 0 2 [junit4] 2> 511258 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1618793426559434752)]} 0 1 [junit4] 2> 511259 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1618793426561531904)]} 0 0 [junit4] 2> 511260 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1618793426562580480)]} 0 0 [junit4] 2> 511261 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1618793426563629056)]} 0 0 [junit4] 2> 511262 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1618793426564677632)]} 0 0 [junit4] 2> 511263 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1618793426565726208)]} 0 0 [junit4] 2> 511264 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1618793426566774784)]} 0 0 [junit4] 2> 511265 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1618793426566774784)]} 0 0 [junit4] 2> 511266 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1618793426567823360)]} 0 0 [junit4] 2> 511267 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1618793426568871936)]} 0 0 [junit4] 2> 511268 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1618793426569920512)]} 0 0 [junit4] 2> 511268 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1618793426570969088)]} 0 0 [junit4] 2> 511269 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1618793426572017664)]} 0 0 [junit4] 2> 511270 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1618793426573066240)]} 0 0 [junit4] 2> 511271 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1618793426573066240)]} 0 0 [junit4] 2> 511272 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1618793426574114816)]} 0 0 [junit4] 2> 511272 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1618793426575163392)]} 0 0 [junit4] 2> 511273 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1618793426576211968)]} 0 0 [junit4] 2> 511274 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1618793426577260544)]} 0 0 [junit4] 2> 511275 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1618793426578309120)]} 0 0 [junit4] 2> 511276 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1618793426578309120)]} 0 0 [junit4] 2> 511277 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1618793426579357696)]} 0 0 [junit4] 2> 511278 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1618793426580406272)]} 0 0 [junit4] 2> 511278 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1618793426581454848)]} 0 0 [junit4] 2> 511279 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1618793426582503424)]} 0 0 [junit4] 2> 511280 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1618793426583552000)]} 0 0 [junit4] 2> 511281 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1618793426584600576)]} 0 0 [junit4] 2> 511282 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1618793426584600576)]} 0 0 [junit4] 2> 511282 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1618793426585649152)]} 0 0 [junit4] 2> 511283 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1618793426586697728)]} 0 0 [junit4] 2> 511284 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1618793426587746304)]} 0 0 [junit4] 2> 511285 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1618793426588794880)]} 0 0 [junit4] 2> 511286 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1618793426588794880)]} 0 0 [junit4] 2> 511286 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1618793426589843456)]} 0 0 [junit4] 2> 511287 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1618793426590892032)]} 0 0 [junit4] 2> 511288 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1618793426591940608)]} 0 0 [junit4] 2> 511289 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1618793426591940608)]} 0 0 [junit4] 2> 511289 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1618793426592989184)]} 0 0 [junit4] 2> 511290 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1618793426594037760)]} 0 0 [junit4] 2> 511291 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1618793426595086336)]} 0 0 [junit4] 2> 511292 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1618793426596134912)]} 0 0 [junit4] 2> 511293 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1618793426596134912)]} 0 0 [junit4] 2> 511293 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1618793426597183488)]} 0 0 [junit4] 2> 511294 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1618793426598232064)]} 0 0 [junit4] 2> 511295 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1618793426599280640)]} 0 0 [junit4] 2> 511296 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1618793426600329216)]} 0 0 [junit4] 2> 511296 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1618793426600329216)]} 0 0 [junit4] 2> 511297 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1618793426601377792)]} 0 0 [junit4] 2> 511298 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1618793426602426368)]} 0 0 [junit4] 2> 511299 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1618793426603474944)]} 0 0 [junit4] 2> 511300 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1618793426604523520)]} 0 0 [junit4] 2> 511301 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1618793426605572096)]} 0 0 [junit4] 2> 511302 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1618793426606620672)]} 0 0 [junit4] 2> 511303 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1618793426607669248)]} 0 0 [junit4] 2> 511304 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1618793426608717824)]} 0 0 [junit4] 2> 511305 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1618793426609766400)]} 0 0 [junit4] 2> 511306 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1618793426610814976)]} 0 0 [junit4] 2> 511307 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1618793426611863552)]} 0 0 [junit4] 2> 511308 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1618793426612912128)]} 0 0 [junit4] 2> 511308 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1618793426612912128)]} 0 0 [junit4] 2> 511309 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1618793426613960704)]} 0 0 [junit4] 2> 511310 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1618793426615009280)]} 0 0 [junit4] 2> 511312 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1618793426616057856)]} 0 0 [junit4] 2> 511312 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1618793426617106432)]} 0 0 [junit4] 2> 511313 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1618793426618155008)]} 0 0 [junit4] 2> 511314 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1618793426619203584)]} 0 0 [junit4] 2> 511315 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1618793426620252160)]} 0 0 [junit4] 2> 511316 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1618793426621300736)]} 0 0 [junit4] 2> 511317 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1618793426622349312)]} 0 0 [junit4] 2> 511318 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1618793426623397888)]} 0 0 [junit4] 2> 511319 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1618793426623397888)]} 0 0 [junit4] 2> 511320 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1618793426625495040)]} 0 0 [junit4] 2> 511321 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1618793426626543616)]} 0 0 [junit4] 2> 511321 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1618793426626543616)]} 0 0 [junit4] 2> 511322 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1618793426627592192)]} 0 0 [junit4] 2> 511323 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1618793426628640768)]} 0 0 [junit4] 2> 511323 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1618793426628640768)]} 0 0 [junit4] 2> 511324 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1618793426629689344)]} 0 0 [junit4] 2> 511325 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1618793426630737920)]} 0 0 [junit4] 2> 511325 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1618793426630737920)]} 0 0 [junit4] 2> 511326 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1618793426631786496)]} 0 0 [junit4] 2> 511327 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1618793426632835072)]} 0 0 [junit4] 2> 511328 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1618793426633883648)]} 0 0 [junit4] 2> 511329 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1618793426634932224)]} 0 0 [junit4] 2> 511330 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1618793426634932224)]} 0 0 [junit4] 2> 511330 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1618793426635980800)]} 0 0 [junit4] 2> 511331 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1618793426637029376)]} 0 0 [junit4] 2> 511331 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1618793426637029376)]} 0 0 [junit4] 2> 511332 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1618793426638077952)]} 0 0 [junit4] 2> 511333 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1618793426639126528)]} 0 0 [junit4] 2> 511334 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1618793426640175104)]} 0 0 [junit4] 2> 511334 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1618793426640175104)]} 0 0 [junit4] 2> 511335 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1618793426641223680)]} 0 0 [junit4] 2> 511336 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1618793426642272256)]} 0 0 [junit4] 2> 511337 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1618793426642272256)]} 0 0 [junit4] 2> 511337 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1618793426643320832)]} 0 0 [junit4] 2> 511338 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1618793426644369408)]} 0 0 [junit4] 2> 511339 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1618793426645417984)]} 0 0 [junit4] 2> 511340 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1618793426645417984)]} 0 0 [junit4] 2> 511340 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1618793426646466560)]} 0 0 [junit4] 2> 511341 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1618793426647515136)]} 0 0 [junit4] 2> 511342 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1618793426648563712)]} 0 0 [junit4] 2> 511342 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1618793426648563712)]} 0 0 [junit4] 2> 511343 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1618793426649612288)]} 0 0 [junit4] 2> 511344 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1618793426650660864)]} 0 0 [junit4] 2> 511345 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1618793426650660864)]} 0 0 [junit4] 2> 511345 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1618793426651709440)]} 0 0 [junit4] 2> 511346 INFO (qtp351068896-11014) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1618793426652758016)]} 0 0 [junit4] 2> 511347 INFO (qtp920117145-11065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1618793426652758016)]} 0 0 [junit4] 2> 511348 INFO (qtp351068896-11015) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1618793426653806592)]} 0 0 [junit4] 2> 511348 INFO (qtp920117145-11066) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1618793426654855168)]} 0 0 [junit4] 2> 511349 INFO (qtp351068896-11016) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1618793426655903744)]} 0 0 [junit4] 2> 511350 INFO (qtp920117145-11067) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1618793426656952320)]} 0 0 [junit4] 2> 511350 INFO (qtp351068896-11017) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1618793426656952320)]} 0 0 [junit4] 2> 511351 INFO (qtp920117145-11068) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1618793426658000896)]} 0 0 [junit4] 2> 511352 INFO (qtp351068896-11018) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1618793426659049472)]} 0 0 [junit4] 2> 511353 INFO (qtp920117145-11069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1618793426659049472)]} 0 0 [junit4] 2> 511353 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1618793426660098048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 511353 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3c2b5ff7 commitCommandVersion:1618793426660098048 [junit4] 2> 511361 INFO (qtp351068896-11019) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34fef6c3[collection1] main] [junit4] 2> 511362 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 511363 INFO (searcherExecutor-2518-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34fef6c3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=9, java.vm.version=9+181, lucene.version=8.0.0, os.arch=x86_64, java.runtime.version=9+181, source=flush, os.version=10.11.6, timestamp=1543801714460}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.0.0):C59:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=9, java.vm.version=9+181, lucene.version=8.0.0, os.arch=x86_64, java.runtime.version=9+181, source=flush, os.version=10.11.6, timestamp=1543801714580}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 511363 INFO (qtp351068896-11019) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10 [junit4] 2> 511364 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1618793426671632384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 511364 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4046303e commitCommandVersion:1618793426671632384 [junit4] 2> 511371 INFO (qtp920117145-11070) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@605294e6[collection1] main] [junit4] 2> 511372 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 511373 INFO (searcherExecutor-2532-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@605294e6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=9, java.vm.version=9+181, lucene.version=8.0.0, os.arch=x86_64, java.runtime.version=9+181, source=flush, os.version=10.11.6, timestamp=1543801714468}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.0.0):C59:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=9, java.vm.version=9+181, lucene.version=8.0.0, os.arch=x86_64, java.runtime.version=9+181, source=flush, os.version=10.11.6, timestamp=1543801714590}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 511373 INFO (qtp920117145-11070) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9 [junit4] 2> 511375 ERROR (qtp351068896-11014) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value" [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236) [junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:270) [junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:185) [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2549) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:396) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:342) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:753) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value" [junit4] 2> at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) [junit4] 2> at java.base/java.lang.Integer.parseInt(Integer.java:652) [junit4] 2> at java.base/java.lang.Integer.valueOf(Integer.java:983) [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233) [junit4] 2> ... 34 more [junit4] 2> [junit4] 2> 511375 INFO (qtp351068896-11014) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 511376 INFO (qtp351068896-11015) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 511376 INFO (qtp351068896-11015) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 511377 INFO (qtp351068896-11016) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 511377 INFO (qtp351068896-11016) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 511378 ERROR (qtp351068896-11017) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value" [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236) [junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:273) [junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:185) [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2549) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:396) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:342) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:753) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value" [junit4] 2> at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) [junit4] 2> at java.base/java.lang.Integer.parseInt(Integer.java:652) [junit4] 2> at java.base/java.lang.Integer.valueOf(Integer.java:983) [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233) [junit4] 2> ... 34 more [junit4] 2> [junit4] 2> 511379 INFO (qtp351068896-11017) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 511380 INFO (qtp351068896-11018) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511385 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:56224/collection1&rows=10&version=2&q=*:*&NOW=1543801714602&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511389 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714602&ids=2,123,136,127,117,118,8,119,140,142&isShard=true&shard.url=http://127.0.0.1:56224/collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511395 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56224/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=12 [junit4] 2> 511396 INFO (qtp351068896-11019) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511399 INFO (qtp920117145-11066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1543801714617&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511401 INFO (qtp920117145-11067) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714617&ids=100,1,12,135,147,103,105,129,107,130&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511406 INFO (qtp920117145-11070) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=9 [junit4] 2> 511408 INFO (qtp351068896-11014) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511411 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:56224/collection1&rows=10&version=2&q=*:*&NOW=1543801714629&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511412 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714629&ids=143,110,123,134,4,147,103,106,120,131&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:56224/collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511415 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:56224/collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 511416 INFO (qtp351068896-11015) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511419 INFO (qtp920117145-11067) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:56224/collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1543801714638&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511420 INFO (qtp920117145-11070) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714638&ids=1,124,136,104,115,116,1001,118,9,10&isShard=true&shard.url=http://127.0.0.1:56224/collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511424 INFO (qtp920117145-11066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56224/collection1|[ff01::083]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 511425 INFO (qtp351068896-11016) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511427 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:56224/collection1&rows=10&version=2&q=*:*&NOW=1543801714646&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511429 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714646&ids=143,122,125,115,138,106,128,129,118,109&isShard=true&shard.url=http://127.0.0.1:56224/collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511432 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56224/collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 511433 INFO (qtp351068896-11017) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511435 INFO (qtp920117145-11070) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1543801714654&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511436 INFO (qtp920117145-11066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714654&ids=132,135,5,137,127,17,107,108,141,131&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511438 INFO (qtp920117145-11067) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 511439 INFO (qtp351068896-11018) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511441 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1543801714660&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511443 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714660&ids=122,111,145,134,146,124,14,115,6,1001&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511444 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 511444 INFO (qtp351068896-11019) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511446 INFO (qtp920117145-11066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:56224/collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1543801714665&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511448 INFO (qtp920117145-11067) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714665&ids=2,112,101,3,4,17,139,128,107,9&isShard=true&shard.url=http://127.0.0.1:56224/collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511449 INFO (qtp920117145-11070) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56224/collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 511450 INFO (qtp351068896-11014) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511453 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:56224/collection1&rows=10&version=2&q=*:*&NOW=1543801714671&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511454 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714671&ids=143,110,100,133,13,5,105,149,128,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:56224/collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511456 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:56224/collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 511457 INFO (qtp351068896-11015) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511458 INFO (qtp920117145-11067) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:56224/collection1&rows=10&version=2&q=*:*&NOW=1543801714677&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511460 INFO (qtp920117145-11070) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714677&ids=102,113,3,146,125,137,7,117,8,140&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:56224/collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511462 INFO (qtp920117145-11066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:56224/collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 511463 INFO (qtp351068896-11016) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511467 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:56224/collection1&rows=10&version=2&q=*:*&NOW=1543801714685&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511469 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714685&ids=144,112,135,113,147,5,127,105,142,10&isShard=true&shard.url=http://127.0.0.1:56224/collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511470 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56224/collection1&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 511471 INFO (qtp351068896-11017) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511473 INFO (qtp920117145-11070) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:56224/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1543801714692&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511474 INFO (qtp920117145-11066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714692&ids=121,111,101,145,114,138,1001,139,119,141&isShard=true&shard.url=http://127.0.0.1:56224/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511475 INFO (qtp920117145-11067) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56224/collection1|[ff01::114]:33332/&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 511477 INFO (qtp351068896-11018) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511479 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:56224/collection1&rows=10&version=2&q=*:*&NOW=1543801714698&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511480 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714698&ids=143,11,12,136,125,115,5,107,9,119&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:56224/collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511481 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:56224/collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 511482 INFO (qtp351068896-11019) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511484 INFO (qtp920117145-11066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1543801714703&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511486 INFO (qtp920117145-11067) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714703&ids=110,135,114,15,137,16,108,141,130,142&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511487 INFO (qtp920117145-11070) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:56224/collection1|[ff01::114]:33332/&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 511488 INFO (qtp351068896-11014) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 511490 INFO (qtp920117145-11069) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:56224/collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1543801714709&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 511491 INFO (qtp920117145-11065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1543801714709&ids=121,143,144,135,124,3,1001,139,118,119&isShard=true&shard.url=http://127.0.0.1:56224/collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 511491 INFO (qtp920117145-11068) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56224/collection1|[ff01::213]:33332/&sort=n_dt1+desc&wt=javabin&version=2} hits=68 sta [...truncated too long message...] ol-2227-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 512348 INFO (closeThreadPool-2227-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7ad8e330: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@268ecb5f [junit4] 2> 512352 INFO (closeThreadPool-2227-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 512352 INFO (closeThreadPool-2227-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1252083d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7a86875 [junit4] 2> 512354 INFO (closeThreadPool-2227-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 512354 INFO (closeThreadPool-2227-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7b15dcc5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@17fed00c [junit4] 2> 512354 INFO (closeThreadPool-2227-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 512354 INFO (closeThreadPool-2227-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 512354 INFO (closeThreadPool-2227-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e13380a: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@50441429 [junit4] 2> 512354 INFO (closeThreadPool-2227-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@26c86bb7: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7a86875 [junit4] 2> 512355 INFO (closeThreadPool-2227-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 512355 INFO (closeThreadPool-2227-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@69cdedb0: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@50441429 [junit4] 2> 512366 INFO (closeThreadPool-2227-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3496f187{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0} [junit4] 2> 512367 INFO (closeThreadPool-2227-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4a6cd652{/,null,UNAVAILABLE} [junit4] 2> 512367 INFO (closeThreadPool-2227-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 512367 INFO (closeThreadPool-2227-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@c06a2c8{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0} [junit4] 2> 512367 INFO (closeThreadPool-2227-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a468f2a{/,null,UNAVAILABLE} [junit4] 2> 512367 INFO (closeThreadPool-2227-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=3ADC37E1621BCB84 -Dtests.slow=true -Dtests.locale=fr-HT -Dtests.timezone=Pacific/Marquesas -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 3.35s J0 | TestDistributedSearch.test <<< [junit4] > Throwable #1: java.lang.AssertionError: did't see any stats=true shard requests [junit4] > at __randomizedtesting.SeedInfo.seed([3ADC37E1621BCB84:B288083BCCE7A67C]:0) [junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:615) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1096) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1042) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-http2-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3ADC37E1621BCB84-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {other_tl1=Lucene50(blocksize=128), range_facet_l_dv=BlockTreeOrds(blocksize=128), SubjectTerms_mfacet=Lucene50(blocksize=128), n_l1=Lucene50(blocksize=128), a_n_tdt=BlockTreeOrds(blocksize=128), multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), intDefault=Lucene50(blocksize=128), n_td1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), n_d1=Lucene50(blocksize=128), n_f1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=Lucene50(blocksize=128), n_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_s=FST50, oddField_s=FST50, n_tf1=Lucene50(blocksize=128), lowerfilt1and2=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_t=Lucene50(blocksize=128), lowerfilt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=BlockTreeOrds(blocksize=128), text=FST50, timestamp=Lucene50(blocksize=128), foo_sev_enum=BlockTreeOrds(blocksize=128), _root_=Lucene50(blocksize=128), n_dt1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), b_n_tdt=Lucene50(blocksize=128), n_ti1=BlockTreeOrds(blocksize=128), rnd_b=BlockTreeOrds(blocksize=128), foo_b=FST50, n_tdt1=Lucene50(blocksize=128), foo_d=BlockTreeOrds(blocksize=128), id_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=Lucene50(blocksize=128), foo_f=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), foo_sev_enum=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Lucene70), intDvoDefault=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene70), a_i_p=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=355, maxMBSortInHeap=7.481752043793379, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@360e1a53), locale=fr-HT, timezone=Pacific/Marquesas [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=173844584,total=473083904 [junit4] 2> NOTE: All tests run in this JVM: [RollingRestartTest, TestNonDefinedSimilarityFactory, SolrGraphiteReporterTest, TestReplicaProperties, TestHashPartitioner, TestFieldTypeResource, DisMaxRequestHandlerTest, PKIAuthenticationIntegrationTest, TestLeaderElectionZkExpiry, DistributedVersionInfoTest, TriggerSetPropertiesIntegrationTest, TestPerFieldSimilarityWithDefaultOverride, TestCloudPhrasesIdentificationComponent, ShardRoutingTest, MultiThreadedOCPTest, MoveReplicaHDFSFailoverTest, TestCollectionAPIs, HdfsBasicDistributedZk2Test, BooleanFieldTest, ReplicaListTransformerTest, PhrasesIdentificationComponentTest, ClassificationUpdateProcessorTest, RuleEngineTest, MaxSizeAutoCommitTest, ChaosMonkeySafeLeaderWithPullReplicasTest, TestPivotHelperCode, ExitableDirectoryReaderTest, AddBlockUpdateTest, TestUtils, TestSimExtremeIndexing, TestTlogReplica, ZkStateWriterTest, TestScoreJoinQPNoScore, TestTolerantUpdateProcessorRandomCloud, HdfsDirectoryTest, AnalysisAfterCoreReloadTest, TestFacetMethods, DistributedSuggestComponentTest, TestBadConfig, BasicDistributedZk2Test, TestSolrXml, CollectionTooManyReplicasTest, TestReplicationHandlerBackup, BadComponentTest, ReturnFieldsTest, CreateCollectionCleanupTest, ScheduledMaintenanceTriggerTest, TestSimDistributedQueue, TestDocTermOrds, SaslZkACLProviderTest, TestLegacyField, Tagger2Test, TestBlobHandler, FullHLLTest, TestPostingsSolrHighlighter, TestSchemaResource, CopyFieldTest, CoreAdminCreateDiscoverTest, TestMacros, CustomCollectionTest, TestSchemalessBufferedUpdates, TestReversedWildcardFilterFactory, ZkFailoverTest, ExecutePlanActionTest, AlternateDirectoryTest, TestDistributedSearch] [junit4] Completed [164/837 (1!)] on J0 in 3.36s, 1 test, 1 failure <<< FAILURES! [...truncated 949 lines...] ERROR: command execution failed. ERROR: Step ‘Archive the artifacts’ failed: no workspace for Lucene-Solr-http2-MacOSX #6 ERROR: Step ‘Scan for compiler warnings’ failed: no workspace for Lucene-Solr-http2-MacOSX #6 ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for Lucene-Solr-http2-MacOSX #6 Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ERROR: MacOSX VBOX is offline; cannot locate ANT 1.8.2 Setting ANT_1_8_2_HOME= ERROR: MacOSX VBOX is offline; cannot locate ANT 1.8.2 Setting ANT_1_8_2_HOME= ERROR: MacOSX VBOX is offline; cannot locate ANT 1.8.2 Setting ANT_1_8_2_HOME= ERROR: MacOSX VBOX is offline; cannot locate ANT 1.8.2 Setting ANT_1_8_2_HOME=
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org