Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.4-Windows/29/ Java: 64bit/jdk1.8.0_121 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup Error Message: C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\collection1\data\snapshot.dylyb\_0.fnm Stack Trace: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\collection1\data\snapshot.dylyb\_0.fnm at __randomizedtesting.SeedInfo.seed([813B5CE7C883F069:C0B07C82EF3D0326]:0) at sun.nio.fs.WindowsException.translateToIOException(WindowsException.java:79) at sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:97) at sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:102) at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:230) at java.nio.file.Files.newByteChannel(Files.java:361) at java.nio.file.Files.newByteChannel(Files.java:407) at org.apache.lucene.store.SimpleFSDirectory.openInput(SimpleFSDirectory.java:77) at org.apache.lucene.store.Directory.openChecksumInput(Directory.java:137) at org.apache.lucene.codecs.lucene60.Lucene60FieldInfosFormat.read(Lucene60FieldInfosFormat.java:113) at org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:104) at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:74) at org.apache.lucene.index.StandardDirectoryReader$1.doBody(StandardDirectoryReader.java:62) at org.apache.lucene.index.StandardDirectoryReader$1.doBody(StandardDirectoryReader.java:54) at org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:690) at org.apache.lucene.index.StandardDirectoryReader.open(StandardDirectoryReader.java:77) at org.apache.lucene.index.DirectoryReader.open(DirectoryReader.java:63) at org.apache.solr.handler.TestReplicationHandlerBackup.verify(TestReplicationHandlerBackup.java:150) at org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup(TestReplicationHandlerBackup.java:214) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:811) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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) Build Log: [...truncated 10966 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandlerBackup [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\init-core-data-001 [junit4] 2> 269032 INFO (SUITE-TestReplicationHandlerBackup-seed#[813B5CE7C883F069]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 269034 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackupOnCommit [junit4] 2> 269036 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\collection1 [junit4] 2> 269064 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 269066 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a6aa089{/solr,null,AVAILABLE} [junit4] 2> 269070 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1190b0e6{HTTP/1.1,[http/1.1]}{127.0.0.1:51236} [junit4] 2> 269070 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.e.j.s.Server Started @275523ms [junit4] 2> 269070 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=51236} [junit4] 2> 269070 ERROR (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 269070 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.4.3 [junit4] 2> 269070 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 269070 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 269070 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-11T10:42:39.281Z [junit4] 2> 269072 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\solr.xml [junit4] 2> 269116 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 269133 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\. [junit4] 2> 269134 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 269256 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.3 [junit4] 2> 269275 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 269284 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 269300 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\.\collection1 [junit4] 2> 269301 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\.\collection1\data\] [junit4] 2> 269338 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 269453 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 269453 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 269456 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=52.16796875, floorSegmentMB=0.8720703125, forceMergeDeletesPctAllowed=16.78009588906498, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5333628546629797 [junit4] 2> 269457 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ab293dd[collection1] main] [junit4] 2> 269457 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-001\collection1\conf [junit4] 2> 269459 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema-replication2.xml:schema.xml [junit4] 2> 269459 INFO (coreLoadExecutor-1419-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 269459 INFO (searcherExecutor-1420-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ab293dd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 269468 INFO (qtp1879406981-2450) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 269468 INFO (qtp1879406981-2450) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0 [junit4] 2> 269469 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.h.BackupRestoreUtils Indexing 6 test docs [junit4] 2> 269473 INFO (qtp1879406981-2450) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5]} 0 2 [junit4] 2> 269476 INFO (qtp1879406981-2453) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 269476 INFO (qtp1879406981-2453) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6275d256 [junit4] 2> 269499 INFO (Thread-546) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-001/./collection1/data/ [junit4] 2> 269505 INFO (qtp1879406981-2453) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ed11de2[collection1] main] [junit4] 2> 269505 INFO (searcherExecutor-1420-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4ed11de2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.3):C6)))} [junit4] 2> 269505 INFO (qtp1879406981-2453) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 269505 INFO (qtp1879406981-2453) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 29 [junit4] 2> 269518 INFO (qtp1879406981-2455) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=2 [junit4] 2> 269527 INFO (Thread-546) [ x:collection1] o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-001/./collection1/data/ [junit4] 2> 270524 INFO (qtp1879406981-2456) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0 [junit4] 2> 271533 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBackupOnCommit [junit4] 2> 271535 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1190b0e6{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 271535 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=178043922 [junit4] 2> 271536 INFO (coreCloseExecutor-1424-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4607f53f [junit4] 2> 271537 INFO (coreCloseExecutor-1424-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 271537 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 271538 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[813B5CE7C883F069]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a6aa089{/solr,null,UNAVAILABLE} [junit4] 2> 271541 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestBackup [junit4] 2> 271545 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\collection1 [junit4] 2> 271560 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 271561 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@337647db{/solr,null,AVAILABLE} [junit4] 2> 271562 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ed393cb{HTTP/1.1,[http/1.1]}{127.0.0.1:51241} [junit4] 2> 271562 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.e.j.s.Server Started @278014ms [junit4] 2> 271562 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=51241} [junit4] 2> 271562 ERROR (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 271562 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.4.3 [junit4] 2> 271562 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 271562 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 271562 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-11T10:42:41.773Z [junit4] 2> 271564 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\solr.xml [junit4] 2> 271584 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 271592 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\. [junit4] 2> 271592 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 271638 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.3 [junit4] 2> 271647 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 271655 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 271664 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\.\collection1 [junit4] 2> 271665 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\.\collection1\data\] [junit4] 2> 271679 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 271700 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 271700 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 271701 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=52.16796875, floorSegmentMB=0.8720703125, forceMergeDeletesPctAllowed=16.78009588906498, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5333628546629797 [junit4] 2> 271702 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@42c7f220[collection1] main] [junit4] 2> 271702 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\collection1\conf [junit4] 2> 271703 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema-replication2.xml:schema.xml [junit4] 2> 271703 INFO (coreLoadExecutor-1431-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 271703 INFO (searcherExecutor-1432-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@42c7f220[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 271864 INFO (qtp2065109710-2473) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 271864 INFO (qtp2065109710-2473) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0 [junit4] 2> 271864 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.h.BackupRestoreUtils Indexing 75 test docs [junit4] 2> 271874 INFO (qtp2065109710-2474) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, ... (75 adds)]} 0 7 [junit4] 2> 271876 INFO (qtp2065109710-2475) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 271876 INFO (qtp2065109710-2475) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@50794240 [junit4] 2> 271951 INFO (Thread-557) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 271954 INFO (qtp2065109710-2475) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e5e7c15[collection1] main] [junit4] 2> 271961 INFO (searcherExecutor-1432-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5e5e7c15[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.3):C75)))} [junit4] 2> 271961 INFO (qtp2065109710-2475) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 271961 INFO (qtp2065109710-2475) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 85 [junit4] 2> 271971 INFO (qtp2065109710-2476) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=7 [junit4] 2> 271982 INFO (Thread-557) [ x:collection1] o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 272976 INFO (qtp2065109710-2477) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=1 [junit4] 2> 274004 INFO (qtp2065109710-2478) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=tohwibdpe&command=backup} status=0 QTime=4 [junit4] 2> 274005 INFO (Thread-558) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot tohwibdpe at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 274050 INFO (Thread-558) [ x:collection1] o.a.s.h.SnapShooter Done creating backup snapshot: tohwibdpe at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 274080 INFO (qtp2065109710-2473) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0 [junit4] 2> 275091 INFO (qtp2065109710-2474) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=guiizwpvzwuek&command=backup} status=0 QTime=2 [junit4] 2> 275097 INFO (Thread-559) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot guiizwpvzwuek at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 275101 INFO (qtp2065109710-2475) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=7 [junit4] 2> 275126 INFO (Thread-559) [ x:collection1] o.a.s.h.SnapShooter Done creating backup snapshot: guiizwpvzwuek at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 276108 INFO (qtp2065109710-2476) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=dylyb&command=backup} status=0 QTime=1 [junit4] 2> 276108 INFO (Thread-560) [ x:collection1] o.a.s.h.SnapShooter Creating backup snapshot dylyb at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 276110 INFO (qtp2065109710-2477) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=details} status=0 QTime=0 [junit4] 2> 279305 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestBackup [junit4] 2> 279309 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5ed393cb{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 279309 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=909703438 [junit4] 2> 279319 INFO (coreCloseExecutor-1436-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7d33504 [junit4] 2> 279325 INFO (coreCloseExecutor-1436-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 279326 INFO (Thread-560) [ x:collection1] o.a.s.h.SnapShooter Done creating backup snapshot: dylyb at file:///C:/Users/jenkins/workspace/Lucene-Solr-6.4-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001/solr-instance-002/./collection1/data/ [junit4] 2> 279426 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 279427 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[813B5CE7C883F069]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@337647db{/solr,null,UNAVAILABLE} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandlerBackup -Dtests.method=doTestBackup -Dtests.seed=813B5CE7C883F069 -Dtests.slow=true -Dtests.locale=es-PR -Dtests.timezone=Africa/Harare -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 7.90s J1 | TestReplicationHandlerBackup.doTestBackup <<< [junit4] > Throwable #1: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001\solr-instance-002\collection1\data\snapshot.dylyb\_0.fnm [junit4] > at __randomizedtesting.SeedInfo.seed([813B5CE7C883F069:C0B07C82EF3D0326]:0) [junit4] > at sun.nio.fs.WindowsException.translateToIOException(WindowsException.java:79) [junit4] > at sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:97) [junit4] > at sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:102) [junit4] > at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:230) [junit4] > at java.nio.file.Files.newByteChannel(Files.java:361) [junit4] > at java.nio.file.Files.newByteChannel(Files.java:407) [junit4] > at org.apache.lucene.store.SimpleFSDirectory.openInput(SimpleFSDirectory.java:77) [junit4] > at org.apache.lucene.store.Directory.openChecksumInput(Directory.java:137) [junit4] > at org.apache.lucene.codecs.lucene60.Lucene60FieldInfosFormat.read(Lucene60FieldInfosFormat.java:113) [junit4] > at org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:104) [junit4] > at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:74) [junit4] > at org.apache.lucene.index.StandardDirectoryReader$1.doBody(StandardDirectoryReader.java:62) [junit4] > at org.apache.lucene.index.StandardDirectoryReader$1.doBody(StandardDirectoryReader.java:54) [junit4] > at org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:690) [junit4] > at org.apache.lucene.index.StandardDirectoryReader.open(StandardDirectoryReader.java:77) [junit4] > at org.apache.lucene.index.DirectoryReader.open(DirectoryReader.java:63) [junit4] > at org.apache.solr.handler.TestReplicationHandlerBackup.verify(TestReplicationHandlerBackup.java:150) [junit4] > at org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup(TestReplicationHandlerBackup.java:214) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 279436 INFO (SUITE-TestReplicationHandlerBackup-seed#[813B5CE7C883F069]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.4-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_813B5CE7C883F069-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {name=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{}, maxPointsInLeafNode=1310, maxMBSortInHeap=5.081074587584393, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=es-PR, timezone=Africa/Harare [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=3,threads=1,free=120497376,total=375914496 [junit4] 2> NOTE: All tests run in this JVM: [BasicFunctionalityTest, RulesTest, TestMacros, TestManagedSchema, FacetPivotSmallTest, SmileWriterTest, TestShortCircuitedRequests, TestSchemaSimilarityResource, SpellPossibilityIteratorTest, TestRawResponseWriter, FileBasedSpellCheckerTest, LoggingHandlerTest, TestManagedResourceStorage, HdfsRestartWhileUpdatingTest, HdfsUnloadDistributedZkTest, SolrCloudExampleTest, ConnectionReuseTest, HLLUtilTest, TestFiltering, TestCloudManagedSchema, HdfsRecoverLeaseTest, HdfsBasicDistributedZk2Test, TestAtomicUpdateErrorCases, HttpSolrCallGetCoreTest, TestSolrCoreParser, FullSolrCloudDistribCmdsTest, SignatureUpdateProcessorFactoryTest, CoreAdminRequestStatusTest, AutoCommitTest, SecurityConfHandlerTest, LukeRequestHandlerTest, TestFoldingMultitermQuery, JsonLoaderTest, CopyFieldTest, CloudMLTQParserTest, TolerantUpdateProcessorTest, TestDynamicFieldCollectionResource, TestSubQueryTransformerDistrib, TestReplicationHandlerBackup] [junit4] Completed [64/680 (1!)] on J1 in 10.44s, 2 tests, 1 error <<< FAILURES! [...truncated 64489 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org