Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/408/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.update.AutoCommitTest.testMaxDocs Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query at __randomizedtesting.SeedInfo.seed([532D432337C310F:BCB302ED1F963585]:0) at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:782) at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:198) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1] xml response was: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> </response> request was:q=id:14&qt=standard&start=0&rows=20&version=2.2 at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:775) ... 40 more Build Log: [...truncated 11219 lines...] [junit4] Suite: org.apache.solr.update.AutoCommitTest [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001 [junit4] 2> 768737 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 768737 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 768737 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 768762 WARN (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 768764 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 768776 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 768779 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 768835 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 768836 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.s.IndexSchema [null] unique key field: id [junit4] 2> 768843 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true [junit4] 2> 768845 INFO (coreLoadExecutor-3142-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 768866 WARN (coreLoadExecutor-3142-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 768867 INFO (coreLoadExecutor-3142-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 768890 INFO (coreLoadExecutor-3142-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 768894 INFO (coreLoadExecutor-3142-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 768954 INFO (coreLoadExecutor-3142-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 768955 INFO (coreLoadExecutor-3142-thread-1) [ ] o.a.s.s.IndexSchema [collection1] unique key field: id [junit4] 2> 768962 INFO (coreLoadExecutor-3142-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1 [junit4] 2> 768962 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/] [junit4] 2> 768962 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@431185e3 [junit4] 2> 768963 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index/ [junit4] 2> 768963 WARN (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index' doesn't exist. Creating new index... [junit4] 2> 768963 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4746793919137946] [junit4] 2> 768963 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_1,generation=1} [junit4] 2> 768964 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 768964 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryResponseWriter: [xml] [junit4] 2> 768965 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryParser: [foo] [junit4] 2> 768966 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 4 plugins of type searchComponent: [spellcheck, termsComp, tvComponent, highlight] [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 768967 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 768975 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 39 plugins of type requestHandler: [/update, /update/json, /update/csv, /update/json/docs, /config, /schema, /replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system, /admin/mbeans, /admin/plugins, /admin/threads, /admin/properties, /admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, standard, dismax, mock, defaults, lazy, spellCheckCompRH, spellCheckCompRH_Direct, spellCheckWithWordbreak, spellCheckWithWordbreak_Direct, spellCheckCompRH1, mltrh, tvrh, /mlt, /debug/dump, /search-facet-def, /search-facet-invariants] [junit4] 2> 768975 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 768975 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 768975 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 768976 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 768976 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 768976 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=6, maxMergeAtOnceExplicit=7, maxMergedSegmentMB=95.24609375, floorSegmentMB=2.126953125, forceMergeDeletesPctAllowed=21.54506215993077, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 768976 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_1,generation=1} [junit4] 2> 768976 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 768977 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6681b74b[collection1] main] [junit4] 2> 768977 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 768977 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 768977 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 768977 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 768980 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 768994 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 768994 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 768995 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 768995 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 768995 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 768995 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1546372613108924416 [junit4] 2> 768995 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 768995 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 768995 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 768995 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 768996 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 768996 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 768996 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 768996 INFO (coreLoadExecutor-3142-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1546372613108924416 [junit4] 2> 768996 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 768997 INFO (searcherExecutor-3143-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6681b74b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 768998 INFO (SUITE-AutoCommitTest-seed#[532D432337C310F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 769000 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin [junit4] 2> 769003 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1546372613114167296)} 0 2 [junit4] 2> 769004 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 769029 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 769030 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 769042 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 769046 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 769107 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 769108 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.s.IndexSchema [collection1] unique key field: id [junit4] 2> 769116 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1 [junit4] 2> 769116 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/] [junit4] 2> 769116 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@431185e3 [junit4] 2> 769116 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index/ [junit4] 2> 769116 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryResponseWriter: [xml] [junit4] 2> 769118 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryParser: [foo] [junit4] 2> 769121 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 4 plugins of type searchComponent: [spellcheck, termsComp, tvComponent, highlight] [junit4] 2> 769121 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 769121 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 769121 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 769122 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 769133 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 39 plugins of type requestHandler: [/update, /update/json, /update/csv, /update/json/docs, /config, /schema, /replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system, /admin/mbeans, /admin/plugins, /admin/threads, /admin/properties, /admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, standard, dismax, mock, defaults, lazy, spellCheckCompRH, spellCheckCompRH_Direct, spellCheckWithWordbreak, spellCheckWithWordbreak_Direct, spellCheckCompRH1, mltrh, tvrh, /mlt, /debug/dump, /search-facet-def, /search-facet-invariants] [junit4] 2> 769133 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 769133 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 769133 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 769133 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6762ca1b[collection1] main] [junit4] 2> 769134 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 769134 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 769134 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 769134 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 769135 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 769138 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 769138 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 769139 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 769139 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 769139 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 769139 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 769139 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 769139 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 769139 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 769140 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 769140 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 769140 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 769141 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 769141 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6762ca1b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 769141 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1546372613114167296 [junit4] 2> 769141 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1 [junit4] 2> 769143 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15467206705771988] [junit4] 2> 769143 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_1,generation=1} [junit4] 2> 769143 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 769144 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 769144 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@64853203[collection1] main] [junit4] 2> 769144 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@68273777 [junit4] 2> 769144 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 769144 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64853203[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 769145 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 769153 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1546372613273550848)]} 0 1 [junit4] 2> 769154 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 773153 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 773157 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f399f9f[collection1] main] [junit4] 2> 773157 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 773158 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f399f9f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))} [junit4] 2> 773185 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1546372617501409280)]} 0 4034 [junit4] 2> 773185 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 773186 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1546372617503506432)]} 0 4035 [junit4] 2> 773187 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 774187 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 774189 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c8bc6c3[collection1] main] [junit4] 2> 774189 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 774190 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3c8bc6c3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.3.0):C1)))} [junit4] 2> 774438 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 774438 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 774440 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618817372160)]} 0 5289 [junit4] 2> 774440 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618818420736)]} 0 5289 [junit4] 2> 774440 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618818420737)]} 0 5289 [junit4] 2> 774440 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618818420738)]} 0 5289 [junit4] 2> 774440 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618818420739)]} 0 5289 [junit4] 2> 774441 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618819469312)]} 0 5290 [junit4] 2> 774441 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618819469313)]} 0 5290 [junit4] 2> 774441 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618819469314)]} 0 5290 [junit4] 2> 774441 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618819469315)]} 0 5290 [junit4] 2> 774441 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372618819469316)]} 0 5290 [junit4] 2> 774441 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 774442 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618820517888)]} 0 5291 [junit4] 2> 774442 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618820517889)]} 0 5291 [junit4] 2> 774442 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618820517890)]} 0 5291 [junit4] 2> 774442 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618820517891)]} 0 5291 [junit4] 2> 774442 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618820517892)]} 0 5291 [junit4] 2> 774442 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618820517893)]} 0 5292 [junit4] 2> 774443 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618821566464)]} 0 5292 [junit4] 2> 774443 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618821566465)]} 0 5292 [junit4] 2> 774443 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618821566466)]} 0 5292 [junit4] 2> 774443 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1546372618821566467)]} 0 5292 [junit4] 2> 774443 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 775443 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 775445 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@59bea466[collection1] main] [junit4] 2> 775445 INFO (commitScheduler-3151-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 775447 INFO (searcherExecutor-3148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@59bea466[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.3.0):C10/9:delGen=1)))} [junit4] 2> 775450 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 775451 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 775451 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[532D432337C310F]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin [junit4] 2> 775454 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime [junit4] 2> 775457 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d041081[collection1] realtime] [junit4] 2> 775457 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1546372619882725376)} 0 2 [junit4] 2> 775458 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 775498 WARN (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 775500 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 775521 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 775526 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 775608 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 775609 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.IndexSchema [collection1] unique key field: id [junit4] 2> 775642 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1 [junit4] 2> 775642 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/] [junit4] 2> 775642 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@431185e3 [junit4] 2> 775643 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index/ [junit4] 2> 775643 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryResponseWriter: [xml] [junit4] 2> 775645 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryParser: [foo] [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 4 plugins of type searchComponent: [spellcheck, termsComp, tvComponent, highlight] [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 775648 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 775649 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 775649 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 775649 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 775649 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 775672 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 39 plugins of type requestHandler: [/update, /update/json, /update/csv, /update/json/docs, /config, /schema, /replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system, /admin/mbeans, /admin/plugins, /admin/threads, /admin/properties, /admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, standard, dismax, mock, defaults, lazy, spellCheckCompRH, spellCheckCompRH_Direct, spellCheckWithWordbreak, spellCheckWithWordbreak_Direct, spellCheckCompRH1, mltrh, tvrh, /mlt, /debug/dump, /search-facet-def, /search-facet-invariants] [junit4] 2> 775672 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 775672 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 775672 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 775673 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4bc8aeac[collection1] main] [junit4] 2> 775673 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 775673 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 775673 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 775673 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 775674 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 775677 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 775677 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 775677 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 775677 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 775677 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 775677 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 775677 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 775677 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 775678 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 775678 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 775679 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 775679 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1546372619882725376 [junit4] 2> 775679 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 775680 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1 [junit4] 2> 775680 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_2,generation=2} [junit4] 2> 775680 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 775680 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 775680 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=67.19921875, floorSegmentMB=1.22265625, forceMergeDeletesPctAllowed=3.849413944478377, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3674895973747593 [junit4] 2> 775681 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_2,generation=2} [junit4] 2> 775681 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 775681 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 775681 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4bc8aeac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 775681 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@709ec478[collection1] main] [junit4] 2> 775682 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@44ef71e5 [junit4] 2> 775682 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=12,adds=0,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=12,cumulative_deletesById=11,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 775682 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 775683 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@709ec478[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 775689 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1546372620127043584)]} 0 0 [junit4] 2> 775689 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 776690 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 776693 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2234ee17[collection1] main] [junit4] 2> 776693 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 776694 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2234ee17[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.3.0):C1)))} [junit4] 2> 776944 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1546372621443006464)]} 0 1256 [junit4] 2> 776944 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 776945 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 776946 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[529 (-1546372621445103616)]} 0 0 [junit4] 2> 776946 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 777953 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 777955 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@39b3c9c8[collection1] main] [junit4] 2> 777955 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 777956 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39b3c9c8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.3.0):C1)))} [junit4] 2> 778205 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[550 (1546372622766309376)]} 0 2517 [junit4] 2> 778205 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 778206 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622767357952)]} 0 2518 [junit4] 2> 778206 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622767357953)]} 0 2518 [junit4] 2> 778207 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622767357954)]} 0 2519 [junit4] 2> 778207 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622768406528)]} 0 2519 [junit4] 2> 778207 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622768406529)]} 0 2519 [junit4] 2> 778207 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622768406530)]} 0 2519 [junit4] 2> 778207 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622768406531)]} 0 2519 [junit4] 2> 778207 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622768406532)]} 0 2519 [junit4] 2> 778208 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622769455104)]} 0 2520 [junit4] 2> 778208 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1546372622769455105)]} 0 2520 [junit4] 2> 778208 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 779206 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 779208 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@32b59de[collection1] main] [junit4] 2> 779208 INFO (commitScheduler-3155-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 779209 INFO (searcherExecutor-3152-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@32b59de[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.3.0):C1) Uninverting(_5(6.3.0):C11/9:delGen=1)))} [junit4] 2> 779214 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[531 (1546372623824322560)]} 0 3527 [junit4] 2> 779215 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 779215 INFO (TEST-AutoCommitTest.testMaxTime-seed#[532D432337C310F]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime [junit4] 2> 779216 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs [junit4] 2> 779222 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@648dbe6f[collection1] realtime] [junit4] 2> 779222 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1546372623826419712)} 0 5 [junit4] 2> 779222 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 779247 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 779249 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 779261 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 779265 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 779317 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 779317 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.IndexSchema [collection1] unique key field: id [junit4] 2> 779324 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1 [junit4] 2> 779324 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/] [junit4] 2> 779324 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@431185e3 [junit4] 2> 779324 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index/ [junit4] 2> 779325 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryResponseWriter: [xml] [junit4] 2> 779326 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 1 plugins of type queryParser: [foo] [junit4] 2> 779328 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 4 plugins of type searchComponent: [spellcheck, termsComp, tvComponent, highlight] [junit4] 2> 779328 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 779329 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 779338 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.PluginBag [collection1] Initialized 39 plugins of type requestHandler: [/update, /update/json, /update/csv, /update/json/docs, /config, /schema, /replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system, /admin/mbeans, /admin/plugins, /admin/threads, /admin/properties, /admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, standard, dismax, mock, defaults, lazy, spellCheckCompRH, spellCheckCompRH_Direct, spellCheckWithWordbreak, spellCheckWithWordbreak_Direct, spellCheckCompRH1, mltrh, tvrh, /mlt, /debug/dump, /search-facet-def, /search-facet-invariants] [junit4] 2> 779338 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 779339 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 779339 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 779339 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7894257c[collection1] main] [junit4] 2> 779339 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 779339 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 779339 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 779340 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 779340 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 779343 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 779343 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 779344 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 779344 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 779344 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 779344 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 779344 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 779344 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 779344 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 779344 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 779345 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 779345 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 779345 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 779346 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1546372623826419712 [junit4] 2> 779346 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7894257c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 779346 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1 [junit4] 2> 779346 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_3,generation=3} [junit4] 2> 779346 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 779346 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=753065285, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 779347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_3,generation=3} [junit4] 2> 779347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 779347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 779347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a48c83a[collection1] main] [junit4] 2> 779347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@19812d6e [junit4] 2> 779347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommit maxTime=1000ms,soft autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=14,adds=1,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=14,cumulative_deletesById=1,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 779348 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 779356 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a48c83a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 779356 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1546372623971123200)]} 0 2 [junit4] 2> 779356 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1546372623973220352)]} 0 2 [junit4] 2> 779357 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1546372623974268928)]} 0 2 [junit4] 2> 779357 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1546372623974268929)]} 0 2 [junit4] 2> 779357 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1546372623974268930)]} 0 2 [junit4] 2> 779357 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1546372623974268931)]} 0 2 [junit4] 2> 779357 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1546372623974268932)]} 0 3 [junit4] 2> 779358 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1546372623975317504)]} 0 4 [junit4] 2> 779359 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1546372623975317505)]} 0 4 [junit4] 2> 779359 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1546372623976366080)]} 0 4 [junit4] 2> 779359 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1546372623976366081)]} 0 4 [junit4] 2> 779359 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1546372623976366082)]} 0 4 [junit4] 2> 779360 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1546372623976366083)]} 0 5 [junit4] 2> 779360 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1546372623977414656)]} 0 5 [junit4] 2> 779360 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 779361 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1546372623977414657)]} 0 6 [junit4] 2> 779361 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1546372623978463232)]} 0 7 [junit4] 2> 779361 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 779362 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1] [junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?> [junit4] 2> <response> [junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:q=id:14&qt=standard&start=0&rows=20&version=2.2 [junit4] 2> 779362 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[532D432337C310F]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:14&qt=standard&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1] [junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?> [junit4] 2> <response> [junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:q=id:14&qt=standard&start=0&rows=20&version=2.2 [junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:775) [junit4] 2> at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:198) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [j [...truncated too long message...] FO (commitScheduler-3159-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@591dbbc7[collection1] main] [junit4] 2> 779380 INFO (commitScheduler-3159-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 779382 INFO (searcherExecutor-3156-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@591dbbc7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(6.3.0):C16)))} [junit4] 2> 779382 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@42b3ffc4 [junit4] 2> 779382 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommit maxDocs=14,soft autocommits=1,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=16,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=16,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 779382 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState [junit4] 2> 779382 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 779382 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser [junit4] 2> 779382 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 779383 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41212dd [junit4] 2> 779383 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c207cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f38a9bd),segFN=segments_4,generation=4} [junit4] 2> 779383 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 4 [junit4] 2> 779384 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 directories currently being tracked [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/snapshot_metadata [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/snapshot_metadata;done=false>>] [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/snapshot_metadata [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001 [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001;done=false>>] [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001 [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index;done=false>>] [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true [junit4] 2> 779390 INFO (coreCloseExecutor-3160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001/init-core-data-001/index [junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_532D432337C310F-001 [junit4] 2> Sep 24, 2016 4:50:55 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=21740, maxDocsPerChunk=10, blockSize=5), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=21740, blockSize=5)), sim=ClassicSimilarity, locale=en, timezone=Asia/Sakhalin [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=154686464,total=441450496 [junit4] 2> NOTE: All tests run in this JVM: [ShowFileRequestHandlerTest, SolrCoreCheckLockOnStartupTest, StatsReloadRaceTest, RequestLoggingTest, TestSlowCompositeReaderWrapper, TestFileDictionaryLookup, ResponseHeaderTest, TestDFRSimilarityFactory, TestCustomDocTransformer, NotRequiredUniqueKeyTest, SpatialRPTFieldTypeTest, TestRandomRequestDistribution, TestFastWriter, TestRuleBasedAuthorizationPlugin, ReplaceNodeTest, TestDocSet, HLLUtilTest, ForceLeaderTest, SearchHandlerTest, IndexSchemaTest, SecurityConfHandlerTest, TestIndexingPerformance, TestCloudManagedSchema, CSVRequestHandlerTest, LukeRequestHandlerTest, BlockDirectoryTest, DebugComponentTest, SpellPossibilityIteratorTest, TestSimpleTrackingShardHandler, OverseerCollectionConfigSetProcessorTest, CdcrReplicationDistributedZkTest, TestConfig, RAMDirectoryFactoryTest, FileBasedSpellCheckerTest, SSLMigrationTest, TestSubQueryTransformerDistrib, TestReRankQParserPlugin, SmileWriterTest, TestTolerantUpdateProcessorCloud, MergeStrategyTest, SolrCmdDistributorTest, TestLeaderInitiatedRecoveryThread, TestConfigSetsAPIExclusivity, UpdateRequestProcessorFactoryTest, UUIDFieldTest, TestReversedWildcardFilterFactory, TestDistribIDF, TestPivotHelperCode, TestMergePolicyConfig, UUIDUpdateProcessorFallbackTest, HdfsTlogReplayBufferedWhileIndexingTest, TestSolrCLIRunExample, TestInitQParser, TestObjectReleaseTracker, TestMacros, RuleEngineTest, DeleteStatusTest, TestBulkSchemaAPI, TestSortingResponseWriter, TestDownShardTolerantSearch, TestSubQueryTransformer, TlogReplayBufferedWhileIndexingTest, TestImplicitCoreProperties, HdfsRecoveryZkTest, ZkSolrClientTest, BinaryUpdateRequestHandlerTest, HdfsChaosMonkeySafeLeaderTest, TestRealTimeGet, SuggesterTSTTest, AsyncCallRequestStatusResponseTest, TestFiltering, TestReloadAndDeleteDocs, TestPHPSerializedResponseWriter, ConnectionReuseTest, SuggesterTest, TestSchemaSimilarityResource, DistributedQueueTest, CustomCollectionTest, TestCrossCoreJoin, ChangedSchemaMergeTest, DateMathParserTest, SolrXmlInZkTest, TestUtils, TestMissingGroups, TestCodecSupport, MBeansHandlerTest, TestInitParams, SpellCheckCollatorTest, TestManagedResourceStorage, TestReplicaProperties, CopyFieldTest, TestCloudPivotFacet, TestMinMaxOnMultiValuedField, TestScoreJoinQPNoScore, ExternalFileFieldSortTest, TestRawTransformer, DefaultValueUpdateProcessorTest, TestSortByMinMaxFunction, TestTrieFacet, DocValuesMissingTest, TestLMDirichletSimilarityFactory, TestJoin, MigrateRouteKeyTest, SliceStateTest, OpenCloseCoreStressTest, TestWriterPerf, FieldAnalysisRequestHandlerTest, BadIndexSchemaTest, TestSimpleQParserPlugin, AutoCommitTest] [junit4] Completed [199/635 (1!)] on J1 in 10.92s, 3 tests, 1 error <<< FAILURES! [...truncated 61669 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org