[INFO] [INFO] ------------------------------------------------------------------------ [INFO] Building NetarchiveSuite - heritrix1 controller 5.3-SNAPSHOT [INFO] ------------------------------------------------------------------------ [INFO] [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ heritrix1-controller --- [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] Copying 1 resource [INFO] [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ heritrix1-controller --- [INFO] Changes detected - recompiling the module! [INFO] Compiling 21 source files to /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/classes [INFO] [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ heritrix1-controller --- [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] Copying 104 resources [INFO] [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ heritrix1-controller --- [INFO] Changes detected - recompiling the module! [INFO] Compiling 16 source files to /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-classes [WARNING] /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/src/test/java/dk/netarkivet/externalsoftware/HeritrixTests.java: Some input files use unchecked or unsafe operations. [WARNING] /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/src/test/java/dk/netarkivet/externalsoftware/HeritrixTests.java: Recompile with -Xlint:unchecked for details. [INFO] [INFO] --- maven-surefire-plugin:2.17:test (default-test) @ heritrix1-controller --- [INFO] Surefire report directory: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/surefire-reports ------------------------------------------------------- T E S T S ------------------------------------------------------- Running dk.netarkivet.harvester.tools.CreateCDXMetadataFileTester Tests run: 3, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 0.157 sec - in dk.netarkivet.harvester.tools.CreateCDXMetadataFileTester Running dk.netarkivet.harvester.harvesting.HarvestDocumentationTester 13:37:39.936 [main] DEBUG d.n.h.h.HarvestDocumentation - Creating an arcfiles-report.txt 13:37:39.999 [main] ERROR d.n.h.h.ArchiveFilesReportGenerator - java.io.FileNotFoundException: target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722/heritrix.out (No such file or directory) at java.io.FileInputStream.open0(Native Method) ~[na:1.8.0_121] at java.io.FileInputStream.open(FileInputStream.java:195) ~[na:1.8.0_121] at java.io.FileInputStream.(FileInputStream.java:138) ~[na:1.8.0_121] at java.io.FileReader.(FileReader.java:72) ~[na:1.8.0_121] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.parseHeritrixOut(ArchiveFilesReportGenerator.java:232) [classes/:na] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.generateReport(ArchiveFilesReportGenerator.java:173) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.writeHarvestDetails(HarvestDocumentation.java:289) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.documentHarvest(HarvestDocumentation.java:134) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testDocumentHarvestBug722(HarvestDocumentationTester.java:427) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:40.025 [main] DEBUG d.n.h.h.HarvestDocumentation - No logs dir found in crawldir: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722 13:37:40.028 [main] DEBUG d.n.h.h.HarvestDocumentation - No settings directory found in crawldir: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722 13:37:40.030 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722/archivefiles-report.txt' to ARC file: 39 13:37:40.088 [main] INFO d.n.h.h.HarvestDocumentation - Looking for files not having harvestprefix '42-142' 13:37:40.090 [main] INFO d.n.h.h.HarvestDocumentation - removing unidentified file /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722/arcs/42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc 13:37:40.097 [main] INFO d.n.h.h.HarvestDocumentation - removing unidentified file /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc 13:37:40.098 [main] WARN d.n.h.h.HarvestDocumentation - Found files not belonging to job 42, the following files have been stored for later: [oldjobs/lost-files-1487594260088/42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc, oldjobs/lost-files-1487594260088/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc] 13:37:40.175 [main] WARN d.n.common.utils.cdx.CDXUtils - Found no related arcfiles to process in the archive dir '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722/arcs'. 13:37:40.176 [main] DEBUG d.n.h.h.metadata.MetadataFileWriter - Now inserting 0 files from /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722/tmp-meta/cdx6051556941315311422.tmp'. 13:37:40.271 [main] WARN d.n.h.h.HarvestDocumentation - The metadata-file '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestBug722/metadata/42-metadata-1.arc' already exists, so we don't make another one! 13:37:40.309 [main] WARN dk.netarkivet.common.utils.FileUtils - Could not create directory '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/src/test/resources/originals/order.xml/metadata' 13:37:40.366 [main] INFO d.n.h.h.HarvestDocumentation - Looking for files not having harvestprefix '42-117' 13:37:40.366 [main] INFO d.n.h.h.HarvestDocumentation - removing unidentified file /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testMoveAwayForeignFiles/arcs/43-117-20051212141241-00000-sb-test-har-001.statsbiblioteket.dk.arc 13:37:40.373 [main] INFO d.n.h.h.HarvestDocumentation - removing unidentified file /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testMoveAwayForeignFiles/arcs/43-metadata-1.arc 13:37:40.374 [main] WARN d.n.h.h.HarvestDocumentation - Found files not belonging to job 42, the following files have been stored for later: [/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testMoveAwayForeignFiles/oddjobs/lost-files-1487594260366/43-117-20051212141241-00000-sb-test-har-001.statsbiblioteket.dk.arc, /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testMoveAwayForeignFiles/oddjobs/lost-files-1487594260366/43-metadata-1.arc] Locating lost-files directory /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testMoveAwayForeignFiles/oddjobs/lost-files-1487594260366 13:37:40.444 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Found 2 related arcfiles to process in the archive dir '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/src/test/resources/crawldir/arcs/realistically-named-arcs/arcs'. 13:37:40.455 [main] INFO d.n.c.utils.batch.BatchLocalFiles - The batchjob 'class dk.netarkivet.common.utils.cdx.ArchiveExtractCDXJob' has run for 0 seconds and has reached file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc', which is number 1 out of 1 13:37:40.457 [main] INFO d.n.c.utils.archive.ArchiveBatchJob - Processing archive file: 42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc 13:37:40.549 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Starting processing records in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.600 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #0 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.614 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #1 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.614 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #2 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.620 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #3 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.622 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #4 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.649 [main] DEBUG httpclient.wire.header - << "[\n]" 13:37:40.657 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #5 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.659 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #6 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.660 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #7 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. WARNING Premature EOF before end-of-record: {subject-uri=/www.pligtaflevering.dk/robots.txt, ip-address=130.226.231.6, length=476, absolute-offset=3140, creation-date=20051214154356, content-type=text/html, version=1.1} 13:37:40.666 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Created index of 8 records on file 'src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc' 13:37:40.668 [main] INFO d.n.c.utils.batch.BatchLocalFiles - The batchjob 'class dk.netarkivet.common.utils.cdx.ArchiveExtractCDXJob' has run for 0 seconds and has reached file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc', which is number 1 out of 1 13:37:40.668 [main] INFO d.n.c.utils.archive.ArchiveBatchJob - Processing archive file: 42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc 13:37:40.670 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Starting processing records in archive file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc'. Feb 20, 2017 1:37:40 PM org.archive.io.ArchiveReader$ArchiveRecordIterator next WARNING: Bad Record. Trying skip (Current offset 150): Failed parse of http status line. (Offset 150). 13:37:40.694 [main] DEBUG d.n.c.u.archive.ArchiveBatchJobBase - Caught exception while running batch job on file src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc, position 0: java.lang.RuntimeException: After retry (Offset 150) 13:37:40.705 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Exceptions during generation of index on file 'src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc': java.lang.RuntimeException: After retry (Offset 150) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:535) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:461) at dk.netarkivet.common.utils.archive.ArchiveBatchJob.processFile(ArchiveBatchJob.java:108) at dk.netarkivet.common.utils.batch.BatchLocalFiles.processFile(BatchLocalFiles.java:156) at dk.netarkivet.common.utils.batch.BatchLocalFiles.run(BatchLocalFiles.java:105) at dk.netarkivet.common.utils.cdx.CDXUtils.writeCDXInfo(CDXUtils.java:60) at dk.netarkivet.common.utils.cdx.CDXUtils.generateCDX(CDXUtils.java:114) at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testGenerateCDX(HarvestDocumentationTester.java:329) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) at org.junit.runners.ParentRunner.run(ParentRunner.java:309) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) Caused by: java.io.IOException: Unparseable header line: [XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX] at org.archive.io.arc.ARCReader.getTokenizedHeaderLine(ARCReader.java:308) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:183) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:77) at org.archive.io.ArchiveReader.get(ArchiveReader.java:151) at org.archive.io.ArchiveReader$ArchiveRecordIterator.innerNext(ArchiveReader.java:585) at org.archive.io.ArchiveReader$ArchiveRecordIterator.exceptionNext(ArchiveReader.java:560) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:528) ... 33 more 13:37:40.706 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Created index of 0 records on file 'src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc' 13:37:40.712 [main] INFO d.n.c.utils.batch.BatchLocalFiles - The batchjob 'class dk.netarkivet.common.utils.cdx.ArchiveExtractCDXJob' has run for 0 seconds and has reached file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc', which is number 1 out of 1 13:37:40.717 [main] INFO d.n.c.utils.archive.ArchiveBatchJob - Processing archive file: 42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc 13:37:40.719 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Starting processing records in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.724 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #0 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.726 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #1 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.732 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #2 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.733 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #3 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.737 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #4 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.741 [main] DEBUG httpclient.wire.header - << "[\n]" 13:37:40.741 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #5 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.742 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #6 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:40.747 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #7 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. WARNING Premature EOF before end-of-record: {subject-uri=/www.pligtaflevering.dk/robots.txt, ip-address=130.226.231.6, length=476, absolute-offset=3140, creation-date=20051214154356, content-type=text/html, version=1.1} 13:37:40.751 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Created index of 8 records on file 'src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc' 13:37:40.752 [main] INFO d.n.c.utils.batch.BatchLocalFiles - The batchjob 'class dk.netarkivet.common.utils.cdx.ArchiveExtractCDXJob' has run for 0 seconds and has reached file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc', which is number 1 out of 1 13:37:40.757 [main] INFO d.n.c.utils.archive.ArchiveBatchJob - Processing archive file: 42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc 13:37:40.757 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Starting processing records in archive file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc'. Feb 20, 2017 1:37:40 PM org.archive.io.ArchiveReader$ArchiveRecordIterator next WARNING: Bad Record. Trying skip (Current offset 150): Failed parse of http status line. (Offset 150). 13:37:40.765 [main] DEBUG d.n.c.u.archive.ArchiveBatchJobBase - Caught exception while running batch job on file src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc, position 0: java.lang.RuntimeException: After retry (Offset 150) 13:37:40.768 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Exceptions during generation of index on file 'src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc': java.lang.RuntimeException: After retry (Offset 150) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:535) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:461) at dk.netarkivet.common.utils.archive.ArchiveBatchJob.processFile(ArchiveBatchJob.java:108) at dk.netarkivet.common.utils.batch.BatchLocalFiles.processFile(BatchLocalFiles.java:156) at dk.netarkivet.common.utils.batch.BatchLocalFiles.run(BatchLocalFiles.java:105) at dk.netarkivet.common.utils.cdx.CDXUtils.writeCDXInfo(CDXUtils.java:60) at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testGenerateCDX(HarvestDocumentationTester.java:339) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) at org.junit.runners.ParentRunner.run(ParentRunner.java:309) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) Caused by: java.io.IOException: Unparseable header line: [XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX] at org.archive.io.arc.ARCReader.getTokenizedHeaderLine(ARCReader.java:308) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:183) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:77) at org.archive.io.ArchiveReader.get(ArchiveReader.java:151) at org.archive.io.ArchiveReader$ArchiveRecordIterator.innerNext(ArchiveReader.java:585) at org.archive.io.ArchiveReader$ArchiveRecordIterator.exceptionNext(ArchiveReader.java:560) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:528) ... 32 more 13:37:40.768 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Created index of 0 records on file 'src/test/resources/crawldir/arcs/realistically-named-arcs/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc' 13:37:40.895 [main] DEBUG d.n.h.h.HarvestDocumentation - Creating an arcfiles-report.txt 13:37:40.899 [main] ERROR d.n.h.h.ArchiveFilesReportGenerator - java.io.FileNotFoundException: target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/heritrix.out (No such file or directory) at java.io.FileInputStream.open0(Native Method) ~[na:1.8.0_121] at java.io.FileInputStream.open(FileInputStream.java:195) ~[na:1.8.0_121] at java.io.FileInputStream.(FileInputStream.java:138) ~[na:1.8.0_121] at java.io.FileReader.(FileReader.java:72) ~[na:1.8.0_121] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.parseHeritrixOut(ArchiveFilesReportGenerator.java:232) [classes/:na] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.generateReport(ArchiveFilesReportGenerator.java:173) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.writeHarvestDetails(HarvestDocumentation.java:289) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.documentHarvest(HarvestDocumentation.java:134) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testWriteHarvestDetails(HarvestDocumentationTester.java:460) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:40.922 [main] INFO d.n.h.h.HarvestDocumentation - Found Heritrix log file runtime-errors.log 13:37:40.923 [main] INFO d.n.h.h.HarvestDocumentation - Found Heritrix log file local-errors.log 13:37:40.925 [main] INFO d.n.h.h.HarvestDocumentation - Found Heritrix log file uri-errors.log 13:37:40.930 [main] INFO d.n.h.h.HarvestDocumentation - Found Heritrix log file crawl.log 13:37:40.932 [main] INFO d.n.h.h.HarvestDocumentation - Found Heritrix log file progress-statistics.log 13:37:40.933 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/crawl-manifest.txt' to ARC file: 1079 13:37:40.938 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/harvestInfo.xml' to ARC file: 635 13:37:40.940 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/order.xml' to ARC file: 10426 13:37:40.941 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/seeds.txt' to ARC file: 41 13:37:40.943 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/settings/dk/kb/settings.xml' to ARC file: 406 13:37:40.945 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/archivefiles-report.txt' to ARC file: 39 13:37:40.947 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/crawl-report.txt' to ARC file: 310 13:37:40.948 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/hosts-report.txt' to ARC file: 207 13:37:40.949 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/mimetype-report.txt' to ARC file: 510 13:37:40.952 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/processors-report.txt' to ARC file: 1105 13:37:40.953 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/responsecode-report.txt' to ARC file: 96 13:37:40.955 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/seeds-report.txt' to ARC file: 136 13:37:40.955 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/logs/crawl.log' to ARC file: 14863 13:37:40.960 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/logs/local-errors.log' to ARC file: 2121 13:37:40.960 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/logs/progress-statistics.log' to ARC file: 821 13:37:40.962 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/logs/runtime-errors.log' to ARC file: 0 13:37:40.963 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/logs/uri-errors.log' to ARC file: 2827 13:37:40.967 [main] INFO d.n.h.h.HarvestDocumentation - Looking for files not having harvestprefix '42-142' 13:37:40.971 [main] INFO d.n.h.h.HarvestDocumentation - removing unidentified file /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/arcs/NetarchiveSuite-netarkivet.arc.gz 13:37:40.972 [main] WARN d.n.h.h.HarvestDocumentation - Found files not belonging to job 42, the following files have been stored for later: [oldjobs/lost-files-1487594260967/NetarchiveSuite-netarkivet.arc.gz] 13:37:40.973 [main] WARN d.n.common.utils.cdx.CDXUtils - Found no related arcfiles to process in the archive dir '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/arcs'. 13:37:40.976 [main] DEBUG d.n.h.h.metadata.MetadataFileWriter - Now inserting 0 files from /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testWriteHarvestDetails/tmp-meta/cdx165712623306513892.tmp'. 13:37:41.099 [main] DEBUG d.n.h.h.HarvestDocumentation - Creating an arcfiles-report.txt 13:37:41.105 [main] ERROR d.n.h.h.ArchiveFilesReportGenerator - java.io.FileNotFoundException: target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/heritrix.out (No such file or directory) at java.io.FileInputStream.open0(Native Method) ~[na:1.8.0_121] at java.io.FileInputStream.open(FileInputStream.java:195) ~[na:1.8.0_121] at java.io.FileInputStream.(FileInputStream.java:138) ~[na:1.8.0_121] at java.io.FileReader.(FileReader.java:72) ~[na:1.8.0_121] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.parseHeritrixOut(ArchiveFilesReportGenerator.java:232) [classes/:na] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.generateReport(ArchiveFilesReportGenerator.java:173) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.writeHarvestDetails(HarvestDocumentation.java:289) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.documentHarvest(HarvestDocumentation.java:134) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testDocumentHarvestExceptionalCases(HarvestDocumentationTester.java:202) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:41.107 [main] DEBUG d.n.h.h.HarvestDocumentation - No logs dir found in crawldir: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases 13:37:41.110 [main] DEBUG d.n.h.h.HarvestDocumentation - No settings directory found in crawldir: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases 13:37:41.110 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/archivefiles-report.txt' to ARC file: 39 13:37:41.115 [main] INFO d.n.h.h.HarvestDocumentation - Looking for files not having harvestprefix '42-117' 13:37:41.115 [main] INFO d.n.h.h.HarvestDocumentation - removing unidentified file /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/arcs/43-117-20051212141241-00000-sb-test-har-001.statsbiblioteket.dk.arc 13:37:41.116 [main] WARN d.n.h.h.HarvestDocumentation - Found files not belonging to job 42, the following files have been stored for later: [oldjobs/lost-files-1487594261115/43-117-20051212141241-00000-sb-test-har-001.statsbiblioteket.dk.arc] 13:37:41.119 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Found 1 related arcfiles to process in the archive dir '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/arcs'. 13:37:41.121 [main] INFO d.n.c.utils.batch.BatchLocalFiles - The batchjob 'class dk.netarkivet.common.utils.cdx.ArchiveExtractCDXJob' has run for 0 seconds and has reached file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc', which is number 1 out of 1 13:37:41.122 [main] INFO d.n.c.utils.archive.ArchiveBatchJob - Processing archive file: 42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc 13:37:41.127 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Starting processing records in archive file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc'. Feb 20, 2017 1:37:41 PM org.archive.io.ArchiveReader$ArchiveRecordIterator next WARNING: Bad Record. Trying skip (Current offset 150): Failed parse of http status line. (Offset 150). 13:37:41.134 [main] DEBUG d.n.c.u.archive.ArchiveBatchJobBase - Caught exception while running batch job on file target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc, position 0: java.lang.RuntimeException: After retry (Offset 150) 13:37:41.135 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Exceptions during generation of index on file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc': java.lang.RuntimeException: After retry (Offset 150) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:535) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:461) at dk.netarkivet.common.utils.archive.ArchiveBatchJob.processFile(ArchiveBatchJob.java:108) at dk.netarkivet.common.utils.batch.BatchLocalFiles.processFile(BatchLocalFiles.java:156) at dk.netarkivet.common.utils.batch.BatchLocalFiles.run(BatchLocalFiles.java:105) at dk.netarkivet.common.utils.cdx.CDXUtils.writeCDXInfo(CDXUtils.java:60) at dk.netarkivet.common.utils.cdx.CDXUtils.generateCDX(CDXUtils.java:114) at dk.netarkivet.harvester.harvesting.HarvestDocumentation.addCDXes(HarvestDocumentation.java:195) at dk.netarkivet.harvester.harvesting.HarvestDocumentation.documentHarvest(HarvestDocumentation.java:160) at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testDocumentHarvestExceptionalCases(HarvestDocumentationTester.java:202) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) at org.junit.runners.ParentRunner.run(ParentRunner.java:309) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) Caused by: java.io.IOException: Unparseable header line: [XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX] at org.archive.io.arc.ARCReader.getTokenizedHeaderLine(ARCReader.java:308) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:183) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:77) at org.archive.io.ArchiveReader.get(ArchiveReader.java:151) at org.archive.io.ArchiveReader$ArchiveRecordIterator.innerNext(ArchiveReader.java:585) at org.archive.io.ArchiveReader$ArchiveRecordIterator.exceptionNext(ArchiveReader.java:560) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:528) ... 35 more 13:37:41.136 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Created index of 0 records on file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc' 13:37:41.136 [main] DEBUG d.n.h.h.metadata.MetadataFileWriter - Now inserting 1 files from /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/tmp-meta/cdx8520419432473528905.tmp'. 13:37:41.136 [main] DEBUG d.n.h.h.metadata.MetadataFileWriter - Inserting the file '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/tmp-meta/cdx8520419432473528905.tmp/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc.cdx' 13:37:41.137 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestExceptionalCases/tmp-meta/cdx8520419432473528905.tmp/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc.cdx' to ARC file: 0 13:37:41.514 [main] DEBUG d.n.h.h.HarvestDocumentation - Creating an arcfiles-report.txt 13:37:41.516 [main] ERROR d.n.h.h.ArchiveFilesReportGenerator - java.io.FileNotFoundException: target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/heritrix.out (No such file or directory) at java.io.FileInputStream.open0(Native Method) ~[na:1.8.0_121] at java.io.FileInputStream.open(FileInputStream.java:195) ~[na:1.8.0_121] at java.io.FileInputStream.(FileInputStream.java:138) ~[na:1.8.0_121] at java.io.FileReader.(FileReader.java:72) ~[na:1.8.0_121] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.parseHeritrixOut(ArchiveFilesReportGenerator.java:232) [classes/:na] at dk.netarkivet.harvester.harvesting.ArchiveFilesReportGenerator.generateReport(ArchiveFilesReportGenerator.java:173) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.writeHarvestDetails(HarvestDocumentation.java:289) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentation.documentHarvest(HarvestDocumentation.java:134) [classes/:na] at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testDocumentHarvestOrdinaryCase(HarvestDocumentationTester.java:130) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:41.519 [main] DEBUG d.n.h.h.HarvestDocumentation - No logs dir found in crawldir: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase 13:37:41.520 [main] DEBUG d.n.h.h.HarvestDocumentation - No settings directory found in crawldir: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase 13:37:41.520 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/archivefiles-report.txt' to ARC file: 39 13:37:41.528 [main] INFO d.n.h.h.HarvestDocumentation - Looking for files not having harvestprefix '42-117' 13:37:41.532 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Found 2 related arcfiles to process in the archive dir '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/arcs'. 13:37:41.533 [main] INFO d.n.c.utils.batch.BatchLocalFiles - The batchjob 'class dk.netarkivet.common.utils.cdx.ArchiveExtractCDXJob' has run for 0 seconds and has reached file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc', which is number 1 out of 1 13:37:41.533 [main] INFO d.n.c.utils.archive.ArchiveBatchJob - Processing archive file: 42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc 13:37:41.535 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Starting processing records in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.542 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #0 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.542 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #1 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.545 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #2 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.546 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #3 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.548 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #4 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.552 [main] DEBUG httpclient.wire.header - << "[\n]" 13:37:41.552 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #5 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.555 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #6 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. 13:37:41.555 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Processing record #7 in archive file '42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc'. WARNING Premature EOF before end-of-record: {subject-uri=/www.pligtaflevering.dk/robots.txt, ip-address=130.226.231.6, length=476, absolute-offset=3140, creation-date=20051214154356, content-type=text/html, version=1.1} 13:37:41.559 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Created index of 8 records on file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/arcs/42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc' 13:37:41.561 [main] INFO d.n.c.utils.batch.BatchLocalFiles - The batchjob 'class dk.netarkivet.common.utils.cdx.ArchiveExtractCDXJob' has run for 0 seconds and has reached file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc', which is number 1 out of 1 13:37:41.562 [main] INFO d.n.c.utils.archive.ArchiveBatchJob - Processing archive file: 42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc 13:37:41.565 [main] DEBUG d.n.c.utils.archive.ArchiveBatchJob - Starting processing records in archive file '42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc'. Feb 20, 2017 1:37:41 PM org.archive.io.ArchiveReader$ArchiveRecordIterator next WARNING: Bad Record. Trying skip (Current offset 150): Failed parse of http status line. (Offset 150). 13:37:41.574 [main] DEBUG d.n.c.u.archive.ArchiveBatchJobBase - Caught exception while running batch job on file target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc, position 0: java.lang.RuntimeException: After retry (Offset 150) 13:37:41.575 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Exceptions during generation of index on file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc': java.lang.RuntimeException: After retry (Offset 150) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:535) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:461) at dk.netarkivet.common.utils.archive.ArchiveBatchJob.processFile(ArchiveBatchJob.java:108) at dk.netarkivet.common.utils.batch.BatchLocalFiles.processFile(BatchLocalFiles.java:156) at dk.netarkivet.common.utils.batch.BatchLocalFiles.run(BatchLocalFiles.java:105) at dk.netarkivet.common.utils.cdx.CDXUtils.writeCDXInfo(CDXUtils.java:60) at dk.netarkivet.common.utils.cdx.CDXUtils.generateCDX(CDXUtils.java:114) at dk.netarkivet.harvester.harvesting.HarvestDocumentation.addCDXes(HarvestDocumentation.java:195) at dk.netarkivet.harvester.harvesting.HarvestDocumentation.documentHarvest(HarvestDocumentation.java:160) at dk.netarkivet.harvester.harvesting.HarvestDocumentationTester.testDocumentHarvestOrdinaryCase(HarvestDocumentationTester.java:130) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) at org.junit.rules.RunRules.evaluate(RunRules.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) at org.junit.runners.ParentRunner.run(ParentRunner.java:309) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) Caused by: java.io.IOException: Unparseable header line: [XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX] at org.archive.io.arc.ARCReader.getTokenizedHeaderLine(ARCReader.java:308) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:183) at org.archive.io.arc.ARCReader.createArchiveRecord(ARCReader.java:77) at org.archive.io.ArchiveReader.get(ArchiveReader.java:151) at org.archive.io.ArchiveReader$ArchiveRecordIterator.innerNext(ArchiveReader.java:585) at org.archive.io.ArchiveReader$ArchiveRecordIterator.exceptionNext(ArchiveReader.java:560) at org.archive.io.ArchiveReader$ArchiveRecordIterator.next(ArchiveReader.java:528) ... 35 more 13:37:41.576 [main] DEBUG d.n.common.utils.cdx.CDXUtils - Created index of 0 records on file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/arcs/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc' 13:37:41.577 [main] DEBUG d.n.h.h.metadata.MetadataFileWriter - Now inserting 2 files from /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/tmp-meta/cdx8941891103728973423.tmp'. 13:37:41.578 [main] DEBUG d.n.h.h.metadata.MetadataFileWriter - Inserting the file '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/tmp-meta/cdx8941891103728973423.tmp/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc.cdx' 13:37:41.578 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/tmp-meta/cdx8941891103728973423.tmp/42-117-20051212141240-00000-sb-test-har-001.statsbiblioteket.dk.arc.cdx' to ARC file: 0 13:37:41.580 [main] DEBUG d.n.h.h.metadata.MetadataFileWriter - Inserting the file '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/tmp-meta/cdx8941891103728973423.tmp/42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc.cdx' 13:37:41.581 [main] INFO d.n.h.h.m.MetadataFileWriterArc - Writing file 'target/test-output/HarvestDocumentationTester/testDocumentHarvestOrdinaryCase/tmp-meta/cdx8941891103728973423.tmp/42-117-20051212141241-00001-sb-test-har-001.statsbiblioteket.dk.arc.cdx' to ARC file: 1287 Tests run: 18, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 3.424 sec - in dk.netarkivet.harvester.harvesting.HarvestDocumentationTester Running dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServerTester 13:37:41.736 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:41.738 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:41.746 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:41.746 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:41.746 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:41.747 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:41.748 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:41.790 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:41.792 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:41.798 [main] WARN d.n.common.utils.ApplicationUtils - Non-existing directory created '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testCreateServerDir/serverdir/server/server' 13:37:41.799 [main] INFO d.n.h.h.d.HarvestControllerServer - Serverdir: '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testCreateServerDir/serverdir/server/server' 13:37:41.802 [main] INFO d.n.h.h.d.HarvestControllerServer - Harvesting requires at least 400000000 bytes free. 13:37:41.843 [main] DEBUG d.n.h.h.d.HarvestControllerServer - Obtained JMS connection. 13:37:41.852 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer@158da8e as listener to DEV_COMMON_HCHAN_VAL_RESP 13:37:41.876 [main] DEBUG dk.netarkivet.common.utils.Settings - Value found in classpath data: ONE,TWO,THREE 13:37:41.909 [main] INFO d.n.h.h.d.HarvestControllerServer - Requested to check the validity of harvest channel 'FOCUSED' 13:37:41.912 [main] INFO d.n.h.h.d.HarvestControllerServer - Closing HarvestControllerServer. 13:37:41.912 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:41.923 [main] INFO d.n.h.h.d.HarvestControllerServer - Closed down HarvestControllerServer 13:37:41.925 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.038 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:42.040 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:42.040 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.044 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.044 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.046 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:42.054 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:42.055 [main] INFO d.n.h.h.d.HarvestControllerServer - Serverdir: '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testClose/serverdir' 13:37:42.057 [main] INFO d.n.h.h.d.HarvestControllerServer - Harvesting requires at least 400000000 bytes free. 13:37:42.063 [main] DEBUG d.n.h.h.d.HarvestControllerServer - Obtained JMS connection. 13:37:42.068 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer@5876a9af as listener to DEV_COMMON_HCHAN_VAL_RESP 13:37:42.075 [main] INFO d.n.h.h.d.HarvestControllerServer - Requested to check the validity of harvest channel 'FOCUSED' 13:37:42.076 [main] INFO d.n.h.h.d.HarvestControllerServer - Closing HarvestControllerServer. 13:37:42.076 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:42.078 [main] INFO d.n.h.h.d.HarvestControllerServer - Closed down HarvestControllerServer 13:37:42.079 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.175 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:42.178 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:42.179 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.180 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.181 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.200 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:42.200 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:42.201 [main] INFO d.n.h.h.d.HarvestControllerServer - Serverdir: '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testIsSingleton' 13:37:42.201 [main] INFO d.n.h.h.d.HarvestControllerServer - Harvesting requires at least 400000000 bytes free. 13:37:42.206 [main] DEBUG d.n.h.h.d.HarvestControllerServer - Obtained JMS connection. 13:37:42.219 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer@5158b42f as listener to DEV_COMMON_HCHAN_VAL_RESP 13:37:42.221 [main] INFO d.n.h.h.d.HarvestControllerServer - Requested to check the validity of harvest channel 'FOCUSED' 13:37:42.227 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:42.227 [main] INFO d.n.h.h.d.HarvestControllerServer - Closing HarvestControllerServer. 13:37:42.227 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:42.228 [main] INFO d.n.h.h.d.HarvestControllerServer - Closed down HarvestControllerServer 13:37:42.228 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.311 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:42.312 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:42.313 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.313 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.314 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:42.319 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:42.323 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:42.324 [main] INFO d.n.h.h.d.HarvestControllerServer - Serverdir: '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testMessagesSentByFailedJob/serverdir' 13:37:42.324 [main] INFO d.n.h.h.d.HarvestControllerServer - Harvesting requires at least 400000000 bytes free. 13:37:42.327 [main] DEBUG d.n.h.h.d.HarvestControllerServer - Obtained JMS connection. 13:37:42.332 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer@3a93b025 as listener to DEV_COMMON_HCHAN_VAL_RESP 13:37:42.341 [main] INFO d.n.h.h.d.HarvestControllerServer - Requested to check the validity of harvest channel 'FOCUSED' 13:37:42.849 [main] DEBUG d.n.h.datamodel.H1HeritrixTemplate - WARC format selected to be used by Heritrix 13:37:43.019 [main] WARN d.n.h.datamodel.H1HeritrixTemplate - No attribute insertion is done for H1 templates 13:37:43.020 [main] DEBUG d.n.h.datamodel.H1HeritrixTemplate - In H1 templates we don't enable/disable deduplication. 13:37:43.035 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.CrawlStatusMessageListener@5c7bfdc1 as listener to DEV_COMMON_THE_SCHED 13:37:43.045 [main] DEBUG d.n.h.h.d.HarvestControllerServer - No longer accepting jobs. 13:37:43.046 [main] INFO d.n.h.h.d.HarvestControllerServer - Received crawlrequest for job 1: 'UNIQUE_ID: To DEV_COMMON_THIS_REPOS_CLIENT_130_225_27_166_NA ReplyTo DEV_COMMON_ERROR OK Job: Job 1 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' 13:37:43.053 [main] WARN d.n.h.h.d.HarvestControllerServer - Message 'UNIQUE_ID: To DEV_COMMON_THIS_REPOS_CLIENT_130_225_27_166_NA ReplyTo DEV_COMMON_ERROR OK Job: Job 1 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' arrived with status DONE for job 1, should have been STATUS_SUBMITTED 13:37:43.063 [main] WARN d.n.h.d.HarvesterMessageHandler - Error processing message 'TestObjectMessage: UNIQUE_ID: To DEV_COMMON_THIS_REPOS_CLIENT_130_225_27_166_NA ReplyTo DEV_COMMON_ERROR OK Job: Job 1 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' dk.netarkivet.common.exceptions.ArgumentNotValid: Message 'UNIQUE_ID: To DEV_COMMON_THIS_REPOS_CLIENT_130_225_27_166_NA ReplyTo DEV_COMMON_ERROR OK Job: Job 1 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' arrived with status DONE for job 1, should have been STATUS_SUBMITTED at dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer.onDoOneCrawl(HarvestControllerServer.java:361) ~[classes/:na] at dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer.visit(HarvestControllerServer.java:486) ~[classes/:na] at dk.netarkivet.harvester.harvesting.distribute.DoOneCrawlMessage.accept(DoOneCrawlMessage.java:101) ~[harvester-core-5.3-SNAPSHOT.jar:na] at dk.netarkivet.harvester.distribute.HarvesterMessageHandler.onMessage(HarvesterMessageHandler.java:69) ~[harvester-core-5.3-SNAPSHOT.jar:3f346df4e93d68218c8b4b9e39aa4dae9c36eadc] at dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServerTester.testMessagesSentByFailedJob(HarvestControllerServerTester.java:211) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:43.065 [main] INFO d.n.h.h.d.HarvestControllerServer - Closing HarvestControllerServer. 13:37:43.070 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:43.070 [main] INFO d.n.h.h.d.HarvestControllerServer - Closed down HarvestControllerServer 13:37:43.072 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.152 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:43.152 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:43.153 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.156 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.156 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.164 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:43.166 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:43.167 [main] INFO d.n.h.h.d.HarvestControllerServer - Serverdir: '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testServerStarting/serverdir' 13:37:43.168 [main] INFO d.n.h.h.d.HarvestControllerServer - Harvesting requires at least 400000000 bytes free. 13:37:43.175 [main] DEBUG d.n.h.h.d.HarvestControllerServer - Obtained JMS connection. 13:37:43.179 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer@7d3e8655 as listener to DEV_COMMON_HCHAN_VAL_RESP 13:37:43.183 [main] INFO d.n.h.h.d.HarvestControllerServer - Requested to check the validity of harvest channel 'FOCUSED' 13:37:43.190 [main] INFO d.n.h.h.d.HarvestControllerServer - Closing HarvestControllerServer. 13:37:43.190 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:43.191 [main] INFO d.n.h.h.d.HarvestControllerServer - Closed down HarvestControllerServer 13:37:43.192 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.274 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:43.279 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:43.283 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.283 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.283 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.288 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.testutils.GenericMessageListener@66ea810 as listener to DEV_COMMON_THE_SCHED 13:37:43.293 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:43.293 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:43.294 [main] INFO d.n.h.h.d.HarvestControllerServer - Serverdir: '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testJobFailedOnBadMessage' 13:37:43.296 [main] INFO d.n.h.h.d.HarvestControllerServer - Harvesting requires at least 400000000 bytes free. 13:37:43.297 [main] DEBUG d.n.h.h.d.HarvestControllerServer - Obtained JMS connection. 13:37:43.298 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer@6cd24612 as listener to DEV_COMMON_HCHAN_VAL_RESP 13:37:43.306 [main] INFO d.n.h.h.d.HarvestControllerServer - Requested to check the validity of harvest channel 'FOCUSED' 13:37:43.458 [main] DEBUG d.n.h.datamodel.H1HeritrixTemplate - WARC format selected to be used by Heritrix 13:37:43.547 [main] WARN d.n.h.datamodel.H1HeritrixTemplate - No attribute insertion is done for H1 templates 13:37:43.553 [main] DEBUG d.n.h.datamodel.H1HeritrixTemplate - In H1 templates we don't enable/disable deduplication. 13:37:43.562 [main] DEBUG d.n.h.h.d.HarvestControllerServer - No longer accepting jobs. 13:37:43.565 [main] INFO d.n.h.h.d.HarvestControllerServer - Received crawlrequest for job 42: 'id1: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Job 42 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' 13:37:43.566 [main] WARN d.n.h.h.d.HarvestControllerServer - Message 'id1: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Job 42 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' arrived with status DONE for job 42, should have been STATUS_SUBMITTED 13:37:43.576 [main] WARN d.n.h.d.HarvesterMessageHandler - Error processing message 'TestObjectMessage: id1: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Job 42 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' dk.netarkivet.common.exceptions.ArgumentNotValid: Message 'id1: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Job 42 (state = DONE, HD = 1, channel = FOCUSED, snapshot = false, forcemaxcount = -1, forcemaxbytes = 1000000000, forcemaxrunningtime = -1, orderxml = FullSite-order, numconfigs = 1, created = null), metadata: []' arrived with status DONE for job 42, should have been STATUS_SUBMITTED at dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer.onDoOneCrawl(HarvestControllerServer.java:361) ~[classes/:na] at dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer.visit(HarvestControllerServer.java:486) ~[classes/:na] at dk.netarkivet.harvester.harvesting.distribute.DoOneCrawlMessage.accept(DoOneCrawlMessage.java:101) ~[harvester-core-5.3-SNAPSHOT.jar:na] at dk.netarkivet.harvester.distribute.HarvesterMessageHandler.onMessage(HarvesterMessageHandler.java:69) ~[harvester-core-5.3-SNAPSHOT.jar:3f346df4e93d68218c8b4b9e39aa4dae9c36eadc] at dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServerTester.testJobFailedOnBadMessage(HarvestControllerServerTester.java:266) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:43.577 [main] INFO d.n.h.h.d.HarvestControllerServer - Closing HarvestControllerServer. 13:37:43.577 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:43.577 [main] INFO d.n.h.h.d.HarvestControllerServer - Closed down HarvestControllerServer 13:37:43.578 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.658 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:43.659 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:43.660 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.660 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.663 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.672 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:43.675 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:43.675 [main] INFO d.n.h.h.d.HarvestControllerServer - Serverdir: '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/HarvestControllerServerTester/testBug852' 13:37:43.676 [main] INFO d.n.h.h.d.HarvestControllerServer - Harvesting requires at least 400000000 bytes free. 13:37:43.679 [main] DEBUG d.n.h.h.d.HarvestControllerServer - Obtained JMS connection. 13:37:43.681 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServer@3e7dd664 as listener to DEV_COMMON_HCHAN_VAL_RESP 13:37:43.699 [main] INFO d.n.h.h.d.HarvestControllerServer - Requested to check the validity of harvest channel 'FOCUSED' 13:37:43.699 [main] INFO d.n.h.h.d.HarvestControllerServer - Closing HarvestControllerServer. 13:37:43.699 [main] INFO d.n.common.distribute.JMSConnection - Removing listener from channel 'DEV_COMMON_HCHAN_VAL_RESP' 13:37:43.699 [main] INFO d.n.h.h.d.HarvestControllerServer - Closed down HarvestControllerServer 13:37:43.700 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.772 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:43.774 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:43.774 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.774 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.775 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:43.784 [main] INFO d.n.h.h.d.HarvestControllerServer - Starting HarvestControllerServer. 13:37:43.784 [main] INFO d.n.h.h.d.HarvestControllerServer - Bound to harvest channel 'FOCUSED' 13:37:43.785 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ Tests run: 11, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 2.182 sec - in dk.netarkivet.harvester.harvesting.distribute.HarvestControllerServerTester Running dk.netarkivet.harvester.harvesting.HeritrixControllerFactoryTester class dk.netarkivet.harvester.harvesting.HeritrixControllerFactoryTester$DummyHeritrixController Tests run: 3, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.252 sec - in dk.netarkivet.harvester.harvesting.HeritrixControllerFactoryTester Running dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester 13:37:44.073 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:44.097 [main] INFO dk.netarkivet.common.utils.TLD - Did not found external public suffix list at '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/conf/public_suffix_list.dat'! Reading instead the public suffixes list from embedded file 'dk/netarkivet/common/utils/public_suffix_list.dat' in common-core.jar-VERSION.jar. 13:37:44.135 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.bd', ignoring 13:37:44.147 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.bn', ignoring 13:37:44.152 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.nom.br', ignoring 13:37:44.162 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ck', ignoring 13:37:44.163 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!www.ck', ignoring 13:37:44.179 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.er', ignoring 13:37:44.183 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.fj', ignoring 13:37:44.185 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.fk', ignoring 13:37:44.194 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.gu', ignoring 13:37:44.233 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.jm', ignoring 13:37:44.241 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kawasaki.jp', ignoring 13:37:44.247 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kitakyushu.jp', ignoring 13:37:44.247 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kobe.jp', ignoring 13:37:44.247 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.nagoya.jp', ignoring 13:37:44.248 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.sapporo.jp', ignoring 13:37:44.248 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.sendai.jp', ignoring 13:37:44.248 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.yokohama.jp', ignoring 13:37:44.248 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.kawasaki.jp', ignoring 13:37:44.249 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.kitakyushu.jp', ignoring 13:37:44.250 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.kobe.jp', ignoring 13:37:44.250 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.nagoya.jp', ignoring 13:37:44.250 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.sapporo.jp', ignoring 13:37:44.250 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.sendai.jp', ignoring 13:37:44.251 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.yokohama.jp', ignoring 13:37:44.350 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ke', ignoring 13:37:44.351 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kh', ignoring 13:37:44.355 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kw', ignoring 13:37:44.364 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.mm', ignoring 13:37:44.392 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.mz', ignoring 13:37:44.392 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!teledata.mz', ignoring 13:37:44.426 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.np', ignoring 13:37:44.429 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.pg', ignoring 13:37:44.462 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.sch.uk', ignoring 13:37:44.486 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ye', ignoring 13:37:44.490 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.zw', ignoring 13:37:44.541 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.compute.estate', ignoring 13:37:44.546 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.alces.network', ignoring 13:37:44.549 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.platform.sh', ignoring 13:37:44.553 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.cryptonomic.net', ignoring 13:37:44.572 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.api.githubcloud.com', ignoring 13:37:44.575 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ext.githubcloud.com', ignoring 13:37:44.576 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.githubcloudusercontent.com', ignoring 13:37:44.577 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.0emm.com', ignoring 13:37:44.581 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.magentosite.cloud', ignoring 13:37:44.596 [main] DEBUG dk.netarkivet.common.utils.TLD - No tlds found in settingsfiles /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/src/test/resources/originals/metadata_settings.xml 13:37:44.597 [main] INFO dk.netarkivet.common.utils.TLD - Did not found external public suffix list at '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/conf/public_suffix_list.dat'! Reading instead the public suffixes list from embedded file 'dk/netarkivet/common/utils/public_suffix_list.dat' in common-core.jar-VERSION.jar. 13:37:44.617 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.bd', ignoring 13:37:44.621 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.bn', ignoring 13:37:44.625 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.nom.br', ignoring 13:37:44.626 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ck', ignoring 13:37:44.629 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!www.ck', ignoring 13:37:44.632 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.er', ignoring 13:37:44.640 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.fj', ignoring 13:37:44.640 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.fk', ignoring 13:37:44.641 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.gu', ignoring 13:37:44.660 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.jm', ignoring 13:37:44.670 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kawasaki.jp', ignoring 13:37:44.673 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kitakyushu.jp', ignoring 13:37:44.673 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kobe.jp', ignoring 13:37:44.673 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.nagoya.jp', ignoring 13:37:44.674 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.sapporo.jp', ignoring 13:37:44.676 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.sendai.jp', ignoring 13:37:44.676 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.yokohama.jp', ignoring 13:37:44.676 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.kawasaki.jp', ignoring 13:37:44.677 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.kitakyushu.jp', ignoring 13:37:44.677 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.kobe.jp', ignoring 13:37:44.678 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.nagoya.jp', ignoring 13:37:44.678 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.sapporo.jp', ignoring 13:37:44.679 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.sendai.jp', ignoring 13:37:44.681 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!city.yokohama.jp', ignoring 13:37:44.734 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ke', ignoring 13:37:44.735 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kh', ignoring 13:37:44.739 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.kw', ignoring 13:37:44.741 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.mm', ignoring 13:37:44.760 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.mz', ignoring 13:37:44.761 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '!teledata.mz', ignoring 13:37:44.780 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.np', ignoring 13:37:44.783 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.pg', ignoring 13:37:44.805 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.sch.uk', ignoring 13:37:44.814 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ye', ignoring 13:37:44.816 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.zw', ignoring 13:37:44.852 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.compute.estate', ignoring 13:37:44.853 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.alces.network', ignoring 13:37:44.857 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.platform.sh', ignoring 13:37:44.863 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.cryptonomic.net', ignoring 13:37:44.881 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.api.githubcloud.com', ignoring 13:37:44.881 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.ext.githubcloud.com', ignoring 13:37:44.881 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.githubcloudusercontent.com', ignoring 13:37:44.882 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.0emm.com', ignoring 13:37:44.885 [main] WARN dk.netarkivet.common.utils.TLD - Invalid tld '*.magentosite.cloud', ignoring 13:37:44.893 [main] DEBUG dk.netarkivet.common.utils.TLD - No tlds found in settingsfiles /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/src/test/resources/originals/metadata_settings.xml 13:37:45.031 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:15:53 13:37:45.041 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:45.087 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:40 13:37:45.106 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:45.111 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:02 13:37:45.121 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:45.131 [main] DEBUG d.n.h.h.r.HarvestReportGenerator - Invalid line in '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/LegacyHarvestReportTester/testIDNA/logs/crawl.log' line 1: 'metadata://netarkivet.dk/crawl/logs/crawl.log?heritrixVersion=1.14.4&harvestid=4&jobid=6 130.226.228.8 20120523150916 text/plain 412905'. Ignoring due to reason: Not enough fields for line in crawl.log: 'metadata://netarkivet.dk/crawl/logs/crawl.log?heritrixVersion=1.14.4&harvestid=4&jobid=6 130.226.228.8 20120523150916 text/plain 412905'. Was only 5 fields. Should have been at least 11 dk.netarkivet.common.exceptions.ArgumentNotValid: Not enough fields for line in crawl.log: 'metadata://netarkivet.dk/crawl/logs/crawl.log?heritrixVersion=1.14.4&harvestid=4&jobid=6 130.226.228.8 20120523150916 text/plain 412905'. Was only 5 fields. Should have been at least 11 at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.processHarvestLine(HarvestReportGenerator.java:255) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.parseCrawlLog(HarvestReportGenerator.java:219) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.preProcess(HarvestReportGenerator.java:148) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.(HarvestReportGenerator.java:131) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.getDomainStatsReport(HarvestReportGenerator.java:402) [classes/:na] at dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester.testIDNA(LegacyHarvestReportTester.java:229) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:47.264 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:35:38 13:37:47.275 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:47.296 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:19 13:37:47.308 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:47.321 [main] DEBUG d.n.h.h.r.HarvestReportGenerator - Unable to extract a domain from the object URL found in field 4 of crawl.log: '000nourl000'. java.net.URISyntaxException: Invalid URI - relative URIs not allowed: 000nourl000 at org.jwat.common.Uri.(Uri.java:158) ~[jwat-common-1.0.4.jar:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.getDomainNameFromURIString(HarvestReportGenerator.java:376) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.processHarvestLine(HarvestReportGenerator.java:291) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.parseCrawlLog(HarvestReportGenerator.java:219) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.preProcess(HarvestReportGenerator.java:148) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.(HarvestReportGenerator.java:131) [classes/:na] at dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester.testConstructorInvalidReportFile(LegacyHarvestReportTester.java:88) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:47.323 [main] DEBUG d.n.h.h.r.HarvestReportGenerator - Invalid line in '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/LegacyHarvestReportTester/testConstructorInvalidReportFile/logs/crawl.log' line 3: '2005-05-06T11:47:28.464Z 404 278 000nourl000 P http://www.netarkivet.dk/ text/html #028 20050506114728458+5 NYN2HPNQGIPJTPMGAV4QPBUCVJVNMM54 -'. Ignoring due to reason: Unable to find a domainName in the line: '2005-05-06T11:47:28.464Z 404 278 000nourl000 P http://www.netarkivet.dk/ text/html #028 20050506114728458+5 NYN2HPNQGIPJTPMGAV4QPBUCVJVNMM54 -'. dk.netarkivet.common.exceptions.ArgumentNotValid: Unable to find a domainName in the line: '2005-05-06T11:47:28.464Z 404 278 000nourl000 P http://www.netarkivet.dk/ text/html #028 20050506114728458+5 NYN2HPNQGIPJTPMGAV4QPBUCVJVNMM54 -'. at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.processHarvestLine(HarvestReportGenerator.java:302) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.parseCrawlLog(HarvestReportGenerator.java:219) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.preProcess(HarvestReportGenerator.java:148) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.(HarvestReportGenerator.java:131) [classes/:na] at dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester.testConstructorInvalidReportFile(LegacyHarvestReportTester.java:88) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:47.329 [main] DEBUG d.n.h.h.r.HarvestReportGenerator - Invalid line in '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/LegacyHarvestReportTester/testConstructorInvalidReportFile/logs/crawl.log' line 4: '2005-05-06T11:47:28.465Z 200 14 http://tooshortline/'. Ignoring due to reason: Not enough fields for line in crawl.log: '2005-05-06T11:47:28.465Z 200 14 http://tooshortline/'. Was only 4 fields. Should have been at least 11 dk.netarkivet.common.exceptions.ArgumentNotValid: Not enough fields for line in crawl.log: '2005-05-06T11:47:28.465Z 200 14 http://tooshortline/'. Was only 4 fields. Should have been at least 11 at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.processHarvestLine(HarvestReportGenerator.java:255) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.parseCrawlLog(HarvestReportGenerator.java:219) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.preProcess(HarvestReportGenerator.java:148) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.(HarvestReportGenerator.java:131) [classes/:na] at dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester.testConstructorInvalidReportFile(LegacyHarvestReportTester.java:88) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:47.331 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:17 13:37:47.337 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:47.351 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:11 13:37:47.361 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:47.364 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:03 13:37:47.377 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:47.390 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:12 13:37:47.431 [main] INFO d.n.h.h.r.HarvestReportGenerator - Starting pre-processing of harvest report for job 1 13:37:47.436 [main] DEBUG d.n.h.h.r.HarvestReportGenerator - Unable to extract a domain from the object URL found in field 4 of crawl.log: '000nourl000'. java.net.URISyntaxException: Invalid URI - relative URIs not allowed: 000nourl000 at org.jwat.common.Uri.(Uri.java:158) ~[jwat-common-1.0.4.jar:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.getDomainNameFromURIString(HarvestReportGenerator.java:376) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.processHarvestLine(HarvestReportGenerator.java:291) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.parseCrawlLog(HarvestReportGenerator.java:219) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.preProcess(HarvestReportGenerator.java:148) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.(HarvestReportGenerator.java:131) [classes/:na] at dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester.testConstructor(LegacyHarvestReportTester.java:98) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:47.438 [main] DEBUG d.n.h.h.r.HarvestReportGenerator - Invalid line in '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/LegacyHarvestReportTester/testConstructor/logs/crawl.log' line 3: '2005-05-06T11:47:28.464Z 404 278 000nourl000 P http://www.netarkivet.dk/ text/html #028 20050506114728458+5 NYN2HPNQGIPJTPMGAV4QPBUCVJVNMM54 -'. Ignoring due to reason: Unable to find a domainName in the line: '2005-05-06T11:47:28.464Z 404 278 000nourl000 P http://www.netarkivet.dk/ text/html #028 20050506114728458+5 NYN2HPNQGIPJTPMGAV4QPBUCVJVNMM54 -'. dk.netarkivet.common.exceptions.ArgumentNotValid: Unable to find a domainName in the line: '2005-05-06T11:47:28.464Z 404 278 000nourl000 P http://www.netarkivet.dk/ text/html #028 20050506114728458+5 NYN2HPNQGIPJTPMGAV4QPBUCVJVNMM54 -'. at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.processHarvestLine(HarvestReportGenerator.java:302) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.parseCrawlLog(HarvestReportGenerator.java:219) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.preProcess(HarvestReportGenerator.java:148) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.(HarvestReportGenerator.java:131) [classes/:na] at dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester.testConstructor(LegacyHarvestReportTester.java:98) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:47.446 [main] DEBUG d.n.h.h.r.HarvestReportGenerator - Invalid line in '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/LegacyHarvestReportTester/testConstructor/logs/crawl.log' line 4: '2005-05-06T11:47:28.465Z 200 14 http://tooshortline/'. Ignoring due to reason: Not enough fields for line in crawl.log: '2005-05-06T11:47:28.465Z 200 14 http://tooshortline/'. Was only 4 fields. Should have been at least 11 dk.netarkivet.common.exceptions.ArgumentNotValid: Not enough fields for line in crawl.log: '2005-05-06T11:47:28.465Z 200 14 http://tooshortline/'. Was only 4 fields. Should have been at least 11 at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.processHarvestLine(HarvestReportGenerator.java:255) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.parseCrawlLog(HarvestReportGenerator.java:219) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.preProcess(HarvestReportGenerator.java:148) [classes/:na] at dk.netarkivet.harvester.harvesting.report.HarvestReportGenerator.(HarvestReportGenerator.java:131) [classes/:na] at dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester.testConstructor(LegacyHarvestReportTester.java:98) [test-classes/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_121] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.11.jar:na] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na] at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55) [junit-4.11.jar:na] at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na] at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na] at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17] at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17] 13:37:47.449 [main] INFO d.n.h.h.r.HarvestReportGenerator - Finished pre-processing of harvest report for job 1, operation took 00d 00:00:17 Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.401 sec - in dk.netarkivet.harvester.harvesting.report.LegacyHarvestReportTester Running dk.netarkivet.harvester.harvesting.ArchiveFilesReportGeneratorTest Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.027 sec - in dk.netarkivet.harvester.harvesting.ArchiveFilesReportGeneratorTest Running dk.netarkivet.harvester.harvesting.IngestableFilesTester Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.481 sec - in dk.netarkivet.harvester.harvesting.IngestableFilesTester Running dk.netarkivet.harvester.harvesting.HarvestControllerTester 13:37:47.994 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:47.997 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:47.997 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:47.997 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:47.998 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.072 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.175 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:48.177 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:48.177 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.177 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.178 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.205 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.233 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:48.238 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:48.240 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.240 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.240 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.275 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.305 [main] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:48.305 [main] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:48.306 [main] INFO d.n.c.d.JMSConnectionMockupMQ - Creating instance of dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.306 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.306 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:48.445 [main] DEBUG d.n.h.datamodel.H1HeritrixTemplate - WARC format selected to be used by Heritrix 13:37:48.546 [main] WARN d.n.h.datamodel.H1HeritrixTemplate - No attribute insertion is done for H1 templates 13:37:48.555 [main] DEBUG d.n.h.datamodel.H1HeritrixTemplate - In H1 templates we don't enable/disable deduplication. 13:37:48.604 [main] INFO d.n.a.a.d.JMSArcRepositoryClient - JMSArcRepositoryClient will retry a store 3 times and timeout on each try after 3600000 milliseconds, and timeout on each getrequest after 300000 milliseconds. 13:37:48.611 [main] DEBUG d.n.common.distribute.JMSConnection - Adding dk.netarkivet.archive.arcrepository.distribute.JMSArcRepositoryClient@19fe4644 as listener to DEV_COMMON_THIS_REPOS_CLIENT_130_225_27_166_NA 13:37:48.615 [main] INFO d.n.a.a.d.JMSArcRepositoryClient - JMSArcRepository listens for replies on channel '[Queue 'DEV_COMMON_THIS_REPOS_CLIENT_130_225_27_166_NA']' 13:37:48.620 [main] DEBUG d.n.common.distribute.JMSConnection - Initializing a JMS connection dk.netarkivet.common.distribute.JMSConnectionMockupMQ Tests run: 6, Failures: 0, Errors: 0, Skipped: 2, Time elapsed: 0.667 sec - in dk.netarkivet.harvester.harvesting.HarvestControllerTester Running dk.netarkivet.harvester.harvesting.HeritrixLauncherTester 13:37:48.721 [main] DEBUG d.n.h.harvesting.HeritrixFiles - Writing order-file to disk as file: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/heritrix_out/order.xml 13:37:49.033 [main] INFO d.n.h.h.c.AbstractJMXHeritrixController - Starting Heritrix for job 42 of harvest 117 in target/test-output/heritrix_out 13:37:49.038 [main] WARN d.n.h.h.c.AbstractJMXHeritrixController - Failed to read the password file '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/conf/jmxremote.password'. It is possibly missing. 13:37:49.477 [main] DEBUG d.n.h.harvesting.HeritrixFiles - Writing order-file to disk as file: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/heritrix_out/order.xml 13:37:49.488 [main] INFO d.n.h.h.c.AbstractJMXHeritrixController - Starting Heritrix for job 42 of harvest 117 in target/test-output/heritrix_out 13:37:49.489 [main] WARN d.n.h.h.c.AbstractJMXHeritrixController - Failed to read the password file '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/conf/jmxremote.password'. It is possibly missing. 13:37:49.556 [main] DEBUG d.n.h.harvesting.HeritrixFiles - Writing order-file to disk as file: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/test-output/heritrix_out/order.xml 13:37:49.563 [main] INFO d.n.h.h.c.AbstractJMXHeritrixController - Starting Heritrix for job 42 of harvest 117 in target/test-output/heritrix_out 13:37:49.565 [main] WARN d.n.h.h.c.AbstractJMXHeritrixController - Failed to read the password file '/home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/conf/jmxremote.password'. It is possibly missing. Tests run: 15, Failures: 0, Errors: 0, Skipped: 6, Time elapsed: 0.938 sec - in dk.netarkivet.harvester.harvesting.HeritrixLauncherTester Running dk.netarkivet.externalsoftware.HeritrixTests Tests run: 1, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0 sec - in dk.netarkivet.externalsoftware.HeritrixTests Running dk.netarkivet.externalsoftware.Heritrix1ConstantsTester Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.052 sec - in dk.netarkivet.externalsoftware.Heritrix1ConstantsTester 13:37:49.635 [Thread-19] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.635 [Thread-19] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.637 [Thread-24] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.640 [Thread-24] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.640 [Thread-17] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.640 [Thread-17] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.641 [Thread-41] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.642 [Thread-41] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.646 [Thread-2] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.647 [Thread-2] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.647 [Thread-5] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.647 [Thread-5] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.648 [Thread-3] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.650 [Thread-33] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.650 [Thread-33] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.652 [Thread-25] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.654 [Thread-27] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.654 [Thread-27] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.656 [Thread-8] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.656 [Thread-8] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.658 [Thread-16] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.661 [Thread-36] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.661 [Thread-36] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.662 [Thread-39] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.664 [Thread-34] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.666 [Thread-37] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.668 [Thread-40] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.689 [Thread-11] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.689 [Thread-11] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.690 [Thread-12] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.690 [Thread-9] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.690 [Thread-6] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.691 [Thread-30] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.691 [Thread-30] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.691 [Thread-31] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.691 [Thread-28] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.691 [Thread-20] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaning up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.693 [Thread-11] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.693 [Thread-11] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.693 [Thread-19] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.693 [Thread-19] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.693 [Thread-12] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.694 [Thread-24] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.694 [Thread-20] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.694 [Thread-17] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.694 [Thread-25] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-41] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.695 [Thread-41] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.695 [Thread-16] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-2] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-39] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-39] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-5] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-3] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-40] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-33] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-6] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-27] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-34] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-8] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-28] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-36] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-9] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-37] INFO d.n.common.distribute.JMSConnection - Starting cleanup 13:37:49.695 [Thread-30] INFO d.n.common.distribute.JMSConnection - Cleanup finished 13:37:49.695 [Thread-31] INFO d.n.common.distribute.JMSConnection - Starting cleanup Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-24] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-20] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-20] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-17] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-25] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-25] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-16] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-16] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-2] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-39] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.701 [Thread-5] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-3] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-3] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-40] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-40] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-33] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-6] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-6] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-27] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-34] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-34] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-8] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-28] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-28] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-36] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-37] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.702 [Thread-37] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.703 [Thread-30] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.705 [Thread-12] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.705 [Thread-12] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.705 [Thread-9] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.705 [Thread-9] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.705 [Thread-31] INFO d.n.common.distribute.JMSConnection - Cleanup finished Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ 13:37:49.705 [Thread-31] INFO d.n.c.d.JMSConnectionMockupMQ - Cleaned up dk.netarkivet.common.distribute.JMSConnectionMockupMQ Results : Tests run: 81, Failures: 0, Errors: 0, Skipped: 17 [JENKINS] Recording test results [INFO] [INFO] --- maven-jgit-buildnumber-plugin:1.2.9:extract-buildnumber (git-buildnumber) @ heritrix1-controller --- [INFO] [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ heritrix1-controller --- [INFO] Building jar: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/heritrix1-controller-5.3-SNAPSHOT.jar [INFO] [INFO] >>> maven-source-plugin:2.2.1:jar (attach-sources) > generate-sources @ heritrix1-controller >>> [WARNING] Failed to getClass for org.apache.maven.plugin.source.SourceJarMojo [INFO] [INFO] <<< maven-source-plugin:2.2.1:jar (attach-sources) < generate-sources @ heritrix1-controller <<< [INFO] [INFO] --- maven-source-plugin:2.2.1:jar (attach-sources) @ heritrix1-controller --- [INFO] Building jar: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/heritrix1-controller-5.3-SNAPSHOT-sources.jar [INFO] [INFO] --- maven-javadoc-plugin:2.10.3:jar (attach-javadocs) @ heritrix1-controller --- [INFO] Building jar: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/heritrix1-controller-5.3-SNAPSHOT-javadoc.jar [INFO] [INFO] --- maven-install-plugin:2.4:install (default-install) @ heritrix1-controller --- [INFO] Installing /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/heritrix1-controller-5.3-SNAPSHOT.jar to /home/cibuild02/.m2/repository/org/netarchivesuite/heritrix1-controller/5.3-SNAPSHOT/heritrix1-controller-5.3-SNAPSHOT.jar [INFO] Installing /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/pom.xml to /home/cibuild02/.m2/repository/org/netarchivesuite/heritrix1-controller/5.3-SNAPSHOT/heritrix1-controller-5.3-SNAPSHOT.pom [INFO] Installing /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/heritrix1-controller-5.3-SNAPSHOT-sources.jar to /home/cibuild02/.m2/repository/org/netarchivesuite/heritrix1-controller/5.3-SNAPSHOT/heritrix1-controller-5.3-SNAPSHOT-sources.jar [INFO] Installing /home/cibuild02/workspace/NetarchiveSuite-template/harvester/heritrix1/heritrix1-controller/target/heritrix1-controller-5.3-SNAPSHOT-javadoc.jar to /home/cibuild02/.m2/repository/org/netarchivesuite/heritrix1-controller/5.3-SNAPSHOT/heritrix1-controller-5.3-SNAPSHOT-javadoc.jar [JENKINS] Archiving disabled