SuccessConsole Output

[INFO]                                                                         
[INFO] ------------------------------------------------------------------------
[INFO] Building NetarchiveSuite - Harvest Scheduler 5.0-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO] 
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ harvest-scheduler ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/src/main/resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ harvest-scheduler ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 13 source files to /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/target/classes
[INFO] 
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ harvest-scheduler ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/src/test/resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ harvest-scheduler ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 5 source files to /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/target/test-classes
[INFO] 
[INFO] --- maven-surefire-plugin:2.17:test (default-test) @ harvest-scheduler ---
[INFO] Surefire report directory: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/target/surefire-reports
[INFO] parallel='none', perCoreThreadCount=true, threadCount=0, useUnlimitedThreads=false, threadCountSuites=0, threadCountClasses=0, threadCountMethods=0, parallelOptimized=true

-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running dk.netarkivet.harvester.scheduler.JobSupervisorTest
17:26:56.766 [main] INFO  d.n.h.scheduler.JobSupervisor - 0 jobs has been resubmitted.
17:26:56.949 [main] WARN  d.n.h.scheduler.JobSupervisor -  Job 1 has exceeded its timeout of 0 minutes. Changing status to FAILED.
17:26:56.950 [main] WARN  d.n.h.scheduler.JobSupervisor - Changed 1 jobs from STARTED to FAILED
17:26:56.956 [main] INFO  d.n.h.scheduler.JobSupervisor - Resubmitting old job 1 as 0
17:26:56.956 [main] INFO  d.n.h.scheduler.JobSupervisor - Resubmitting old job 3 as 0
17:26:56.956 [main] INFO  d.n.h.scheduler.JobSupervisor - 2 jobs has been resubmitted.
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.677 sec - in dk.netarkivet.harvester.scheduler.JobSupervisorTest
Running dk.netarkivet.harvester.scheduler.JobDispatcherTest
17:26:57.576 [main] INFO  d.n.h.scheduler.JobDispatcher - Creating JobDispatcher
17:26:57.764 [main] DEBUG d.n.h.scheduler.JobDispatcher - Submitting new FOCUSED job 1
17:26:57.793 [main] INFO  d.n.h.scheduler.JobDispatcher - As we're using WARC as archiveFormat WarcInfoMetadata is now added to the template
The list of loaded data settings is empty. Is this OK?17:26:57.939 [main] DEBUG dk.netarkivet.common.utils.Settings - Value found in classpath data: ONE,TWO,THREE
17:26:57.964 [main] DEBUG d.n.h.scheduler.JobDispatcher - Send crawl request: NO ID: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Mock for Job, hashCode: 159475521, metadata: [URL= metadata://netarkivet.dk/crawl/setup/duplicatereductionjobs?majorversion=1&minorversion=0&harvestid=9&harvestnum=0&jobid=1 ; mimetype= text/plain ; data= 1]
17:26:57.964 [main] INFO  d.n.h.scheduler.JobDispatcher - Job #1 submitted
17:26:57.968 [main] INFO  d.n.h.scheduler.JobDispatcher - Creating JobDispatcher
17:26:58.009 [main] DEBUG d.n.h.scheduler.JobDispatcher - Submitting new FOCUSED job 1
17:26:58.014 [main] INFO  d.n.h.scheduler.JobDispatcher - As we're using WARC as archiveFormat WarcInfoMetadata is now added to the template
17:26:58.025 [main] DEBUG d.n.h.scheduler.JobDispatcher - Send crawl request: NO ID: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Mock for Job, hashCode: 1792227359, metadata: []
17:26:58.025 [main] INFO  d.n.h.scheduler.JobDispatcher - Job #1 submitted
17:26:58.115 [main] DEBUG d.n.h.scheduler.JobDispatcher - Submitting new SNAPSHOT job 2
17:26:58.121 [main] INFO  d.n.h.scheduler.JobDispatcher - As we're using WARC as archiveFormat WarcInfoMetadata is now added to the template
17:26:58.136 [main] DEBUG d.n.h.scheduler.JobDispatcher - Send crawl request: NO ID: To DEV_COMMON_JOB_SNAPSHOT_SNAPSHOT ReplyTo DEV_COMMON_ERROR OK Job: Mock for Job, hashCode: 1591161296, metadata: []
17:26:58.139 [main] INFO  d.n.h.scheduler.JobDispatcher - Job #2 submitted
17:26:58.141 [main] INFO  d.n.h.scheduler.JobDispatcher - Creating JobDispatcher
17:26:58.188 [main] DEBUG d.n.h.scheduler.JobDispatcher - Submitting new FOCUSED job 1
17:26:58.196 [main] INFO  d.n.h.scheduler.JobDispatcher - As we're using WARC as archiveFormat WarcInfoMetadata is now added to the template
17:26:58.206 [main] DEBUG d.n.h.scheduler.JobDispatcher - Send crawl request: NO ID: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Mock for Job, hashCode: 1720865719, metadata: []
17:26:58.210 [main] INFO  d.n.h.scheduler.JobDispatcher - Job #1 submitted
17:26:58.260 [main] INFO  d.n.h.scheduler.JobDispatcher - Creating JobDispatcher
17:26:58.330 [main] DEBUG d.n.h.scheduler.JobDispatcher - Submitting new FOCUSED job 1
17:26:58.343 [main] INFO  d.n.h.scheduler.JobDispatcher - As we're using WARC as archiveFormat WarcInfoMetadata is now added to the template
17:26:58.490 [main] DEBUG d.n.h.scheduler.JobDispatcher - Send crawl request: NO ID: To DEV_COMMON_JOB_PARTIAL_FOCUSED ReplyTo DEV_COMMON_ERROR OK Job: Mock for Job, hashCode: 1544067887, metadata: [URL= metadata://netarkivet.dk/crawl/setup/aliases?majorversion=1&minorversion=0&harvestid=9&harvestnum=0&jobid=1 ; mimetype= text/plain ; data= netatarkivalias.dk is an alias for netarkiv.dk
]
17:26:58.491 [main] INFO  d.n.h.scheduler.JobDispatcher - Job #1 submitted
17:26:58.493 [main] INFO  d.n.h.scheduler.JobDispatcher - Creating JobDispatcher
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.415 sec - in dk.netarkivet.harvester.scheduler.JobDispatcherTest
Running dk.netarkivet.harvester.scheduler.HarvesterStatusReceiverTest
17:26:58.542 [main] WARN  d.n.h.d.HarvesterMessageHandler - Error processing message 'TestObjectMessage: NO ID: To DEV_COMMON_HARVESTMON ReplyTo DEV_COMMON_ERROR OK'
dk.netarkivet.common.exceptions.PermissionDenied: 'dk.netarkivet.harvester.scheduler.HarvesterStatusReceiver@25c5e994' provides no handling for NO ID: To DEV_COMMON_HARVESTMON ReplyTo DEV_COMMON_ERROR OK of type dk.netarkivet.harvester.harvesting.distribute.CrawlProgressMessage and should not be invoked!
	at dk.netarkivet.harvester.distribute.HarvesterMessageHandler.deny(HarvesterMessageHandler.java:83) ~[harvester-core-5.0-SNAPSHOT.jar:e37c42b3628d76ba5a828163969ffe64fd39372e]
	at dk.netarkivet.harvester.distribute.HarvesterMessageHandler.visit(HarvesterMessageHandler.java:119) ~[harvester-core-5.0-SNAPSHOT.jar:e37c42b3628d76ba5a828163969ffe64fd39372e]
	at dk.netarkivet.harvester.harvesting.distribute.CrawlProgressMessage.accept(CrawlProgressMessage.java:338) ~[harvester-core-5.0-SNAPSHOT.jar:e37c42b3628d76ba5a828163969ffe64fd39372e]
	at dk.netarkivet.harvester.distribute.HarvesterMessageHandler.onMessage(HarvesterMessageHandler.java:69) ~[harvester-core-5.0-SNAPSHOT.jar:e37c42b3628d76ba5a828163969ffe64fd39372e]
	at dk.netarkivet.harvester.scheduler.HarvesterStatusReceiverTest.testInvalidMessageType(HarvesterStatusReceiverTest.java:66) [test-classes/:na]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_11]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_11]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_11]
	at java.lang.reflect.Method.invoke(Method.java:483) ~[na:1.8.0_11]
	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.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.junit.runners.Suite.runChild(Suite.java:127) [junit-4.11.jar:na]
	at org.junit.runners.Suite.runChild(Suite.java:26) [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.junit.runner.JUnitCore.run(JUnitCore.java:160) [junit-4.11.jar:na]
	at org.junit.runner.JUnitCore.run(JUnitCore.java:138) [junit-4.11.jar:na]
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:113) [surefire-junit47-2.17.jar:2.17]
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:85) [surefire-junit47-2.17.jar:2.17]
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:54) [surefire-junit47-2.17.jar:2.17]
	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:134) [surefire-junit47-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]
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.159 sec - in dk.netarkivet.harvester.scheduler.HarvesterStatusReceiverTest
Running dk.netarkivet.harvester.scheduler.HarvestSchedulerMonitorServerTest
17:26:58.685 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status FAILED, current state is SUBMITTED
17:26:58.686 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Job 1 failed: HarvestErrors = Some harvesterrors
HarvestErrorDetails = Some harvestErrorDetails
UploadErrors = Some uploadErrors
UploadErrorDetails = Some uploadErrorDetails
17:26:58.693 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status FAILED, current state is RESUBMITTED
17:26:58.694 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Job 1 failed: HarvestErrors = Some harvesterrors
HarvestErrorDetails = Some harvestErrorDetails
UploadErrors = Some uploadErrors
UploadErrorDetails = Some uploadErrorDetails
17:26:58.698 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Job 1 failed: HarvestErrors = Some harvesterrors
HarvestErrorDetails = Some harvestErrorDetails
UploadErrors = Some uploadErrors
UploadErrorDetails = Some uploadErrorDetails
17:26:58.700 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status FAILED, current state is DONE. Marking job as FAILED
17:26:58.700 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Job 1 failed: Some harvesterrors
Received CrawlStatusMessage for job 1 with new status FAILED, current state is DONE. Marking job as FAILED
17:26:58.705 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 succesfully completed
17:26:58.710 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 succesfully completed
17:26:58.716 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 has been started by the harvester.
17:26:58.726 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 succesfully completed
17:26:58.728 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Job 1 failed: HarvestErrors = null
HarvestErrorDetails = null
UploadErrors = null
UploadErrorDetails = null
17:26:58.733 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received new status 'Started' for job 1 with current status DONE, ignoring.
17:26:58.736 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status DONE, current state is NEW
17:26:58.736 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 succesfully completed
17:26:58.738 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received new status 'Started' for job 1 with current status STARTED, ignoring.
17:26:58.739 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received new status 'Started' for job 1 with current status FAILED, ignoring.
17:26:58.744 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received new status 'Started' for unsubmitted job 1
17:26:58.744 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 has been started by the harvester.
17:26:58.747 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status DONE, current state is SUBMITTED
17:26:58.747 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 succesfully completed
17:26:58.755 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status DONE, current state is SUBMITTED
17:26:58.756 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 succesfully completed
17:26:58.758 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status DONE, current state is RESUBMITTED
17:26:58.758 [main] INFO  d.n.h.s.HarvestSchedulerMonitorServer - Job #1 succesfully completed
17:26:58.761 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Received CrawlStatusMessage for job 1 with new status FAILED, current state is NEW
17:26:58.762 [main] WARN  d.n.h.s.HarvestSchedulerMonitorServer - Job 1 failed: HarvestErrors = Some harvesterrors
HarvestErrorDetails = Some harvestErrorDetails
UploadErrors = Some uploadErrors
UploadErrorDetails = Some uploadErrorDetails
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.109 sec - in dk.netarkivet.harvester.scheduler.HarvestSchedulerMonitorServerTest

Results :

Tests run: 29, Failures: 0, Errors: 0, Skipped: 0

[JENKINS] Recording test results
[INFO] 
[INFO] --- maven-jgit-buildnumber-plugin:1.2.9:extract-buildnumber (git-buildnumber) @ harvest-scheduler ---
[INFO] 
[INFO] --- maven-jar-plugin:2.3.2:jar (default-jar) @ harvest-scheduler ---
[INFO] Building jar: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/target/harvest-scheduler-5.0-SNAPSHOT.jar
[INFO] 
[INFO] --- maven-source-plugin:2.2.1:jar-no-fork (attach-sources) @ harvest-scheduler ---
[INFO] Building jar: /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/target/harvest-scheduler-5.0-SNAPSHOT-sources.jar
[INFO] 
[INFO] --- maven-install-plugin:2.4:install (default-install) @ harvest-scheduler ---
[INFO] Installing /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/target/harvest-scheduler-5.0-SNAPSHOT.jar to /home/cibuild02/.m2/repository/org/netarchivesuite/harvest-scheduler/5.0-SNAPSHOT/harvest-scheduler-5.0-SNAPSHOT.jar
[INFO] Installing /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/pom.xml to /home/cibuild02/.m2/repository/org/netarchivesuite/harvest-scheduler/5.0-SNAPSHOT/harvest-scheduler-5.0-SNAPSHOT.pom
[INFO] Installing /home/cibuild02/workspace/NetarchiveSuite-template/harvester/harvest-scheduler/target/harvest-scheduler-5.0-SNAPSHOT-sources.jar to /home/cibuild02/.m2/repository/org/netarchivesuite/harvest-scheduler/5.0-SNAPSHOT/harvest-scheduler-5.0-SNAPSHOT-sources.jar
[JENKINS] Archiving disabled