018-02-09 19:22:40,854 (ObjectFormatCache:refreshCache:203) Could not refresh ObjectFormat cache from CN: http://localhost/cn/
[ WARN] 2018-02-09 19:22:40,854 (ObjectFormatCache:refreshCache:206) Will temporarily use the locally cached list.
[ INFO] 2018-02-09 19:22:40,855 (ObjectFormatCache:refreshCache:229) refreshed cache from format list shipped with libclient_java.
[ INFO] 2018-02-09 19:22:40,855 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[DEBUG] 2018-02-09 19:22:40,856 (V2TransferObjectTask:validateChecksum:834) Task-urn:node:authMN-SyncUnitTest-1518204155887 entering validateChecksum...
[DEBUG] 2018-02-09 19:22:40,856 (V2TransferObjectTask:createObject:808) Task-urn:node:authMN-SyncUnitTest-1518204155887 Registering SystemMetadata...
[DEBUG] 2018-02-09 19:22:40,859 (V2TransferObjectTask:createObject:811) Task-urn:node:authMN-SyncUnitTest-1518204155887 ... Registered SystemMetadata
[ INFO] 2018-02-09 19:22:40,859 (V2TransferObjectTask:createObject:813) Task-urn:node:authMN-SyncUnitTest-1518204155887 - Completed CreateObject
[ INFO] 2018-02-09 19:22:40,860 (V2TransferObjectTask:call:234) Task-urn:node:authMN-SyncUnitTest-1518204155887 - Unlocked Pid.
[ INFO] 2018-02-09 19:22:40,860 (V2TransferObjectTask:call:294) Task-urn:node:authMN-SyncUnitTest-1518204155887 - exiting with callState: SUCCESS
[ INFO] 2018-02-09 19:22:40,861 (V2TransferObjectTask:call:202) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Locking task, attempt 1
[ INFO] 2018-02-09 19:22:40,862 (V2TransferObjectTask:call:207) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Processing SyncObject
[ INFO] 2018-02-09 19:22:40,862 (V2TransferObjectTask:retrieveMNSystemMetadata:317) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Retrieved SystemMetadata Identifier:SyncUnitTest-1518204155887 from node urn:node:otherMN for ObjectInfo Identifier SyncUnitTest-1518204155887
[DEBUG] 2018-02-09 19:22:40,862 (V2TransferObjectTask:call:209) Task-urn:node:otherMN-SyncUnitTest-1518204155887 MN system metadata retrieved...
[DEBUG] 2018-02-09 19:22:40,862 (V2TransferObjectTask:processTask:412) Task-urn:node:otherMN-SyncUnitTest-1518204155887 entering processTask...
[DEBUG] 2018-02-09 19:22:40,862 (V2TransferObjectTask:validateSeriesId:581) Task-urn:node:otherMN-SyncUnitTest-1518204155887 entering validateSeriesId...
[DEBUG] 2018-02-09 19:22:40,862 (V2TransferObjectTask:resolve:688) Task-urn:node:otherMN-SyncUnitTest-1518204155887 entering resolve...
[DEBUG] 2018-02-09 19:22:40,862 (V2TransferObjectTask:resolve:692) Task-urn:node:otherMN-SyncUnitTest-1518204155887 PID SyncUnitTest-1518204155887 exists on the CN.
[DEBUG] 2018-02-09 19:22:40,863 (V2TransferObjectTask:processUpdates:980) Task-urn:node:otherMN-SyncUnitTest-1518204155887 entering processUpdates...
[ INFO] 2018-02-09 19:22:40,863 (V2TransferObjectTask:processUpdates:985) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Start ProcessUpdate
[DEBUG] 2018-02-09 19:22:40,863 (V2TransferObjectTask:processUpdates:986) Task-urn:node:otherMN-SyncUnitTest-1518204155887 Getting sysMeta from HazelCast map
[ INFO] 2018-02-09 19:22:40,864 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:40,865 (SystemMetadataValidator:validateEssentialProperties:180) The submitted checksum matches existing one
[DEBUG] 2018-02-09 19:22:40,865 (V2TransferObjectTask:processPossibleNewReplica:1038) Task-urn:node:otherMN-SyncUnitTest-1518204155887 entering processPossibleNewReplica...
[ INFO] 2018-02-09 19:22:40,866 (V2TransferObjectTask:processPossibleNewReplica:1052) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Non-authoritative source, adding the node as a replica
[ INFO] 2018-02-09 19:22:40,866 (V2TransferObjectTask:notifyReplicaNodes:1297) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Entering notifyReplicaNodes...
[ERROR] 2018-02-09 19:22:40,868 (V2TransferObjectTask:notifyReplicaNodes:1318) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Failed to notify replica member node urn:node:authMN - ServiceFailure - Could not get CNode from the underlying context (D1Client.CN_URL)
org.dataone.service.exceptions.ServiceFailure: Could not get CNode from the underlying context (D1Client.CN_URL)
at org.dataone.client.v2.itk.D1Client.getCN(D1Client.java:168)
at org.dataone.cn.batch.synchronization.NodeCommSyncObjectFactory.createNodeComm(NodeCommSyncObjectFactory.java:151)
at org.dataone.cn.batch.synchronization.NodeCommSyncObjectFactory.getNodeComm(NodeCommSyncObjectFactory.java:137)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.notifyReplicaNode(V2TransferObjectTask.java:1385)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.notifyReplicaNodes(V2TransferObjectTask.java:1311)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processPossibleNewReplica(V2TransferObjectTask.java:1058)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processUpdates(V2TransferObjectTask.java:1008)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processTask(V2TransferObjectTask.java:423)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:211)
at org.dataone.cn.batch.synchronization.tasks.TransferObjectTaskTest.syncTheObject(TransferObjectTaskTest.java:984)
at org.dataone.cn.batch.synchronization.tasks.TransferObjectTaskTest.testSyncUpdate_NonAuthoritativeNode_addPossibleReplica(TransferObjectTaskTest.java:843)
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:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:123)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:104)
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.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:164)
at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:110)
at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:175)
at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcessWhenForked(SurefireStarter.java:107)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:68)
[ INFO] 2018-02-09 19:22:40,868 (V2TransferObjectTask:processUpdates:1010) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Completed ProcessUpdate
[ INFO] 2018-02-09 19:22:40,869 (V2TransferObjectTask:call:234) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - Unlocked Pid.
[ INFO] 2018-02-09 19:22:40,869 (V2TransferObjectTask:call:294) Task-urn:node:otherMN-SyncUnitTest-1518204155887 - exiting with callState: SUCCESS
Feb 09, 2018 7:22:40 PM com.hazelcast.impl.LifecycleServiceImpl
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Address[127.0.0.1]:5720 is SHUTTING_DOWN
Feb 09, 2018 7:22:41 PM com.hazelcast.nio.Connection
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Connection [Address[127.0.0.1]:37800] lost. Reason: Explicit close
Feb 09, 2018 7:22:41 PM com.hazelcast.client.ConnectionManager
WARNING: Connection to Connection [26] [localhost/127.0.0.1:5720 -> 127.0.0.1:5720] is lost
Feb 09, 2018 7:22:41 PM com.hazelcast.nio.Connection
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Connection [Address[127.0.0.1]:37801] lost. Reason: Explicit close
Feb 09, 2018 7:22:41 PM com.hazelcast.client.ConnectionManager
WARNING: Connection to Connection [26] [localhost/127.0.0.1:5720 -> 127.0.0.1:5720] is lost
Feb 09, 2018 7:22:41 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_LOST
Feb 09, 2018 7:22:41 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_LOST
Feb 09, 2018 7:22:41 PM com.hazelcast.client.ConnectionManager
WARNING: got an exception on getConnection:Connection reset
java.net.SocketException: Connection reset
at java.net.SocketInputStream.read(SocketInputStream.java:209)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
at java.io.DataInputStream.readFully(DataInputStream.java:195)
at java.io.DataInputStream.readFully(DataInputStream.java:169)
at com.hazelcast.client.PacketReader.readPacket(PacketReader.java:32)
at com.hazelcast.client.DefaultClientBinder.writeAndRead(DefaultClientBinder.java:74)
at com.hazelcast.client.DefaultClientBinder.auth(DefaultClientBinder.java:61)
at com.hazelcast.client.DefaultClientBinder.bind(DefaultClientBinder.java:44)
at com.hazelcast.client.ConnectionManager.bindConnection(ConnectionManager.java:225)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:139)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:115)
at com.hazelcast.client.OutRunnable$1.run(OutRunnable.java:176)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:344)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:342)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Feb 09, 2018 7:22:41 PM com.hazelcast.client.ConnectionManager
WARNING: got an exception on getConnection:Connection reset
java.net.SocketException: Connection reset
at java.net.SocketInputStream.read(SocketInputStream.java:209)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
at java.io.DataInputStream.readFully(DataInputStream.java:195)
at java.io.DataInputStream.readFully(DataInputStream.java:169)
at com.hazelcast.client.PacketReader.readPacket(PacketReader.java:32)
at com.hazelcast.client.DefaultClientBinder.writeAndRead(DefaultClientBinder.java:74)
at com.hazelcast.client.DefaultClientBinder.auth(DefaultClientBinder.java:61)
at com.hazelcast.client.DefaultClientBinder.bind(DefaultClientBinder.java:44)
at com.hazelcast.client.ConnectionManager.bindConnection(ConnectionManager.java:225)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:139)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:115)
at com.hazelcast.client.OutRunnable$1.run(OutRunnable.java:176)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:344)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:342)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Feb 09, 2018 7:22:41 PM com.hazelcast.client.ConnectionManager
INFO: Unable to get alive cluster connection, try in 4,997 ms later, attempt 1 of 1.
Feb 09, 2018 7:22:41 PM com.hazelcast.client.ConnectionManager
INFO: Unable to get alive cluster connection, try in 4,998 ms later, attempt 1 of 1.
Feb 09, 2018 7:22:41 PM com.hazelcast.initializer
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Destroying node initializer.
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.Node
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Hazelcast Shutdown is completed in 769 ms.
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.LifecycleServiceImpl
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Address[127.0.0.1]:5720 is SHUTDOWN
Feb 09, 2018 7:22:41 PM com.hazelcast.config.ClasspathXmlConfig
INFO: Configuring Hazelcast from 'org/dataone/configuration/hazelcast.xml'.
Hazelcast Group Config:
GroupConfig [name=DataONEBuildTest, password=*******************]
Hazelcast Maps: hzObjectPath hzNodes hzSystemMetadata
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.AddressPicker
INFO: Interfaces is disabled, trying to pick one address from TCP-IP config addresses: [127.0.0.1]
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.AddressPicker
INFO: Picked Address[127.0.0.1]:5720, using socket ServerSocket[addr=/0.0.0.0,localport=5720], bind any local is true
Feb 09, 2018 7:22:41 PM com.hazelcast.system
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Hazelcast Community Edition 2.4.1 (20121213) starting at Address[127.0.0.1]:5720
Feb 09, 2018 7:22:41 PM com.hazelcast.system
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Copyright (C) 2008-2012 Hazelcast.com
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.LifecycleServiceImpl
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Address[127.0.0.1]:5720 is STARTING
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.TcpIpJoiner
INFO: [127.0.0.1]:5720 [DataONEBuildTest]
Members [1] {
Member [127.0.0.1]:5720 this
}
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.LifecycleServiceImpl
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Address[127.0.0.1]:5720 is STARTED
Hazelcast member hzMember name: _hzInstance_16_DataONEBuildTest
Feb 09, 2018 7:22:41 PM com.hazelcast.impl.PartitionManager
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Initializing cluster partition table first arrangement...
[ INFO] 2018-02-09 19:22:41,654 (ObjectFormatCache:refreshCache:181) entering refreshCache()...
[ INFO] 2018-02-09 19:22:41,655 (ObjectFormatCache:refreshCache:196) refreshing objectFormatCache from cn: null
[ INFO] 2018-02-09 19:22:41,658 (RestClient:doRequestNoBody:268) RestClient.doRequestNoBody, thread(1) call Info: GET http://localhost/cn/v2/formats
[ INFO] 2018-02-09 19:22:41,658 (ExceptionHandler:filterErrors:93) response httpCode: 404
[ WARN] 2018-02-09 19:22:41,659 (ObjectFormatCache:refreshCache:203) Could not refresh ObjectFormat cache from CN: http://localhost/cn/
[ WARN] 2018-02-09 19:22:41,659 (ObjectFormatCache:refreshCache:206) Will temporarily use the locally cached list.
[ INFO] 2018-02-09 19:22:41,660 (ObjectFormatCache:refreshCache:229) refreshed cache from format list shipped with libclient_java.
pre-sync authMN value: org.dataone.service.types.v1.NodeReference@559d1138
post-create authMN value: null
[ INFO] 2018-02-09 19:22:41,667 (MockReserveIdentifierService:reserveIdentifier:41) entering reserveIdentifier...
[ INFO] 2018-02-09 19:22:41,667 (MockReserveIdentifierService:hasReservation:90) entering hasReservation...
[ INFO] 2018-02-09 19:22:41,668 (MockReserveIdentifierService:reserveIdentifier:50) adding reservation...
[ INFO] 2018-02-09 19:22:41,668 (V2TransferObjectTask:call:202) Task-urn:node:authMN-SyncUnitTest-1518204161654 - Locking task, attempt 1
Feb 09, 2018 7:22:46 PM com.hazelcast.nio.SocketAcceptor
INFO: [127.0.0.1]:5720 [DataONEBuildTest] 5720 is accepting socket connection from /127.0.0.1:37807
Feb 09, 2018 7:22:46 PM com.hazelcast.nio.ConnectionManager
INFO: [127.0.0.1]:5720 [DataONEBuildTest] 5720 accepted socket connection from /127.0.0.1:37807
Feb 09, 2018 7:22:46 PM com.hazelcast.nio.SocketAcceptor
INFO: [127.0.0.1]:5720 [DataONEBuildTest] 5720 is accepting socket connection from /127.0.0.1:37808
Feb 09, 2018 7:22:46 PM com.hazelcast.nio.ConnectionManager
INFO: [127.0.0.1]:5720 [DataONEBuildTest] 5720 accepted socket connection from /127.0.0.1:37808
Feb 09, 2018 7:22:46 PM com.hazelcast.impl.ClientHandlerService
INFO: [127.0.0.1]:5720 [DataONEBuildTest] received auth from Connection [/127.0.0.1:37807 -> null] live=true, client=true, type=CLIENT, successfully authenticated
Feb 09, 2018 7:22:46 PM com.hazelcast.impl.ClientHandlerService
INFO: [127.0.0.1]:5720 [DataONEBuildTest] received auth from Connection [/127.0.0.1:37808 -> null] live=true, client=true, type=CLIENT, successfully authenticated
Feb 09, 2018 7:22:46 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_OPENING
Feb 09, 2018 7:22:46 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_OPENING
Feb 09, 2018 7:22:46 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_OPENED
[ INFO] 2018-02-09 19:22:46,483 (V2TransferObjectTask:call:207) Task-urn:node:authMN-SyncUnitTest-1518204161654 - Processing SyncObject
[ INFO] 2018-02-09 19:22:46,483 (V2TransferObjectTask:retrieveMNSystemMetadata:317) Task-urn:node:authMN-SyncUnitTest-1518204161654 - Retrieved SystemMetadata Identifier:SyncUnitTest-1518204161654 from node urn:node:authMN for ObjectInfo Identifier SyncUnitTest-1518204161654
[DEBUG] 2018-02-09 19:22:46,484 (V2TransferObjectTask:call:209) Task-urn:node:authMN-SyncUnitTest-1518204161654 MN system metadata retrieved...
[DEBUG] 2018-02-09 19:22:46,484 (V2TransferObjectTask:processTask:412) Task-urn:node:authMN-SyncUnitTest-1518204161654 entering processTask...
[DEBUG] 2018-02-09 19:22:46,484 (V2TransferObjectTask:validateSeriesId:581) Task-urn:node:authMN-SyncUnitTest-1518204161654 entering validateSeriesId...
[DEBUG] 2018-02-09 19:22:46,484 (V2TransferObjectTask:resolve:688) Task-urn:node:authMN-SyncUnitTest-1518204161654 entering resolve...
[DEBUG] 2018-02-09 19:22:46,486 (V2TransferObjectTask:resolve:698) Task-urn:node:authMN-SyncUnitTest-1518204161654 PID SyncUnitTest-1518204161654 does not exist on the CN.
[DEBUG] 2018-02-09 19:22:46,486 (V2TransferObjectTask:processNewObject:464) Task-urn:node:authMN-SyncUnitTest-1518204161654 entering processNewObject...
[ INFO] 2018-02-09 19:22:46,486 (MockReserveIdentifierService:hasReservation:90) entering hasReservation...
[DEBUG] 2018-02-09 19:22:46,487 (V2TransferObjectTask:processNewObject:474) Task-urn:node:authMN-SyncUnitTest-1518204161654 Pid is reserved by this object's submitter.
[DEBUG] 2018-02-09 19:22:46,487 (V2TransferObjectTask:populateInitialReplicaList:513) Task-urn:node:authMN-SyncUnitTest-1518204161654 entering populateInitialReplicaList
[DEBUG] 2018-02-09 19:22:46,487 (V2TransferObjectTask:populateInitialReplicaList:528) Task-urn:node:authMN-SyncUnitTest-1518204161654 Included replica for original MN
[DEBUG] 2018-02-09 19:22:46,487 (V2TransferObjectTask:populateInitialReplicaList:532) Task-urn:node:authMN-SyncUnitTest-1518204161654 Get Object Format
[ INFO] 2018-02-09 19:22:46,487 (ObjectFormatCache:refreshCache:181) entering refreshCache()...
Feb 09, 2018 7:22:46 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_OPENED
[ INFO] 2018-02-09 19:22:46,491 (RestClient:doRequestNoBody:268) RestClient.doRequestNoBody, thread(1) call Info: GET http://localhost/cn/v2/node
[ INFO] 2018-02-09 19:22:46,491 (ExceptionHandler:filterErrors:93) response httpCode: 404
[ WARN] 2018-02-09 19:22:46,492 (D1Client:bestAttemptRefreshNodeLocator:327) Could not refresh D1Client's NodeLocator, using previous one.
org.dataone.service.exceptions.ServiceFailure: 404: Not Found: parser for deserializing HTML not written yet. Providing message body:
<!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
<html><head>
<title>404 Not Found</title>
</head><body>
<h1>Not Found</h1>
<p>The requested URL /cn/v2/node was not found on this server.</p>
<hr>
<address>Apache/2.2.22 (Ubuntu) Server at localhost Port 80</address>
</body></html>
at org.dataone.service.util.ExceptionHandler.deserializeHtmlAndThrowException(ExceptionHandler.java:423)
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:372)
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
at org.dataone.client.v2.impl.MultipartCNode.listNodes(MultipartCNode.java:445)
at org.dataone.client.v2.impl.SettingsContextNodeLocator.getNodeListFromSettingsCN(SettingsContextNodeLocator.java:116)
at org.dataone.client.v2.impl.SettingsContextNodeLocator.<init>(SettingsContextNodeLocator.java:83)
at org.dataone.client.v2.itk.D1Client.bestAttemptRefreshNodeLocator(D1Client.java:322)
at org.dataone.client.v2.itk.D1Client.getCN(D1Client.java:268)
at org.dataone.client.v2.formats.ObjectFormatCache.refreshCache(ObjectFormatCache.java:195)
at org.dataone.client.v2.formats.ObjectFormatCache.getFormat(ObjectFormatCache.java:283)
at org.dataone.cn.batch.harvest.mock.InMemoryCNReadCore.getFormat(InMemoryCNReadCore.java:597)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.populateInitialReplicaList(V2TransferObjectTask.java:533)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processNewObject(V2TransferObjectTask.java:488)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processTask(V2TransferObjectTask.java:426)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:211)
at org.dataone.cn.batch.synchronization.tasks.TransferObjectTaskTest.syncTheObject(TransferObjectTaskTest.java:984)
at org.dataone.cn.batch.synchronization.tasks.TransferObjectTaskTest.syncNewObject_unassigned_AuthNode_Should_fail(TransferObjectTaskTest.java:907)
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:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:123)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:104)
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.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:164)
at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:110)
at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:175)
at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcessWhenForked(SurefireStarter.java:107)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:68)
[ INFO] 2018-02-09 19:22:46,493 (ObjectFormatCache:refreshCache:196) refreshing objectFormatCache from cn: null
[ INFO] 2018-02-09 19:22:46,495 (RestClient:doRequestNoBody:268) RestClient.doRequestNoBody, thread(1) call Info: GET http://localhost/cn/v2/formats
[ INFO] 2018-02-09 19:22:46,495 (ExceptionHandler:filterErrors:93) response httpCode: 404
[ WARN] 2018-02-09 19:22:46,496 (ObjectFormatCache:refreshCache:203) Could not refresh ObjectFormat cache from CN: http://localhost/cn/
[ WARN] 2018-02-09 19:22:46,496 (ObjectFormatCache:refreshCache:206) Will temporarily use the locally cached list.
[ INFO] 2018-02-09 19:22:46,497 (ObjectFormatCache:refreshCache:229) refreshed cache from format list shipped with libclient_java.
[ INFO] 2018-02-09 19:22:46,502 (V2TransferObjectTask:call:234) Task-urn:node:authMN-SyncUnitTest-1518204161654 - Unlocked Pid.
[ERROR] 2018-02-09 19:22:46,502 (V2TransferObjectTask:call:259) Task-urn:node:authMN-SyncUnitTest-1518204161654 - SynchronizationFailed: Synchronization task of [PID::] SyncUnitTest-1518204161654 [::PID] failed. Cause: InvalidSystemMetadata: The following properties cannot be null: authoritativeMemberNode
org.dataone.service.exceptions.SynchronizationFailed: Synchronization task of [PID::] SyncUnitTest-1518204161654 [::PID] failed. Cause: InvalidSystemMetadata: The following properties cannot be null: authoritativeMemberNode
at org.dataone.cn.batch.synchronization.tasks.SyncFailedTask.createSynchronizationFailed(SyncFailedTask.java:99)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processNewObject(V2TransferObjectTask.java:495)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processTask(V2TransferObjectTask.java:426)
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:211)
at org.dataone.cn.batch.synchronization.tasks.TransferObjectTaskTest.syncTheObject(TransferObjectTaskTest.java:984)
at org.dataone.cn.batch.synchronization.tasks.TransferObjectTaskTest.syncNewObject_unassigned_AuthNode_Should_fail(TransferObjectTaskTest.java:907)
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:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:123)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:104)
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.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:164)
at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:110)
at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:175)
at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcessWhenForked(SurefireStarter.java:107)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:68)
[ WARN] 2018-02-09 19:22:46,502 (SyncFailedTask:submitSynchronizationFailed:116) Task-urn:node:authMN-SyncUnitTest-1518204161654 - SynchronizationFailed: detail code: 6001 id:SyncUnitTest-1518204161654 nodeId:null description:Synchronization task of [PID::] SyncUnitTest-1518204161654 [::PID] failed. Cause: InvalidSystemMetadata: The following properties cannot be null: authoritativeMemberNode
[ INFO] 2018-02-09 19:22:46,502 (V2TransferObjectTask:call:294) Task-urn:node:authMN-SyncUnitTest-1518204161654 - exiting with callState: FAILED
eventDate=Fri Feb 09 19:22:46 UTC 2018 : type=SYNCHRONIZATION_FAILED : id=SyncUnitTest-1518204161654 : nodeId=urn:node:authMN
Feb 09, 2018 7:22:46 PM com.hazelcast.impl.LifecycleServiceImpl
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Address[127.0.0.1]:5720 is SHUTTING_DOWN
Feb 09, 2018 7:22:47 PM com.hazelcast.nio.Connection
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Connection [Address[127.0.0.1]:37808] lost. Reason: Explicit close
Feb 09, 2018 7:22:47 PM com.hazelcast.client.ConnectionManager
WARNING: Connection to Connection [28] [localhost/127.0.0.1:5720 -> 127.0.0.1:5720] is lost
Feb 09, 2018 7:22:47 PM com.hazelcast.nio.Connection
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Connection [Address[127.0.0.1]:37807] lost. Reason: Explicit close
Feb 09, 2018 7:22:47 PM com.hazelcast.client.ConnectionManager
WARNING: Connection to Connection [28] [localhost/127.0.0.1:5720 -> 127.0.0.1:5720] is lost
Feb 09, 2018 7:22:47 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_LOST
Feb 09, 2018 7:22:47 PM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is CLIENT_CONNECTION_LOST
Feb 09, 2018 7:22:47 PM com.hazelcast.client.ConnectionManager
WARNING: got an exception on getConnection:Connection reset
java.net.SocketException: Connection reset
at java.net.SocketInputStream.read(SocketInputStream.java:209)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
at java.io.DataInputStream.readFully(DataInputStream.java:195)
at java.io.DataInputStream.readFully(DataInputStream.java:169)
at com.hazelcast.client.PacketReader.readPacket(PacketReader.java:32)
at com.hazelcast.client.DefaultClientBinder.writeAndRead(DefaultClientBinder.java:74)
at com.hazelcast.client.DefaultClientBinder.auth(DefaultClientBinder.java:61)
at com.hazelcast.client.DefaultClientBinder.bind(DefaultClientBinder.java:44)
at com.hazelcast.client.ConnectionManager.bindConnection(ConnectionManager.java:225)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:139)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:115)
at com.hazelcast.client.OutRunnable$1.run(OutRunnable.java:176)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:344)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:342)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Feb 09, 2018 7:22:47 PM com.hazelcast.client.ConnectionManager
WARNING: got an exception on getConnection:Connection reset
java.net.SocketException: Connection reset
at java.net.SocketInputStream.read(SocketInputStream.java:209)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
at java.io.DataInputStream.readFully(DataInputStream.java:195)
at java.io.DataInputStream.readFully(DataInputStream.java:169)
at com.hazelcast.client.PacketReader.readPacket(PacketReader.java:32)
at com.hazelcast.client.DefaultClientBinder.writeAndRead(DefaultClientBinder.java:74)
at com.hazelcast.client.DefaultClientBinder.auth(DefaultClientBinder.java:61)
at com.hazelcast.client.DefaultClientBinder.bind(DefaultClientBinder.java:44)
at com.hazelcast.client.ConnectionManager.bindConnection(ConnectionManager.java:225)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:139)
at com.hazelcast.client.ConnectionManager.lookForLiveConnection(ConnectionManager.java:115)
at com.hazelcast.client.OutRunnable$1.run(OutRunnable.java:176)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:344)
at com.hazelcast.client.HazelcastClient$1.call(HazelcastClient.java:342)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Feb 09, 2018 7:22:47 PM com.hazelcast.client.ConnectionManager
INFO: Unable to get alive cluster connection, try in 4,997 ms later, attempt 1 of 1.
Feb 09, 2018 7:22:47 PM com.hazelcast.client.ConnectionManager
INFO: Unable to get alive cluster connection, try in 4,996 ms later, attempt 1 of 1.
Feb 09, 2018 7:22:47 PM com.hazelcast.initializer
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Destroying node initializer.
Feb 09, 2018 7:22:47 PM com.hazelcast.impl.Node
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Hazelcast Shutdown is completed in 651 ms.
Feb 09, 2018 7:22:47 PM com.hazelcast.impl.LifecycleServiceImpl
INFO: [127.0.0.1]:5720 [DataONEBuildTest] Address[127.0.0.1]:5720 is SHUTDOWN
Tests run: 17, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 81.188 sec
Running org.dataone.cn.batch.synchronization.tasks.ObjectListHarvestTaskTest
[DEBUG] 2018-02-09 19:22:47,170 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:47.067+00:00 and endDate of 2018-02-09T19:22:37.166+00:00
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,0) returning 0 out of 2222 objectInfos
[ INFO] 2018-02-09 19:22:47,171 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 2222 pids to harvest.
[DEBUG] 2018-02-09 19:22:47,172 (ObjectListHarvestTask:adjustFilterWindow:369) 0. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jul 22 15:41:42 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jul 22 15:41:42 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,173 (ObjectListHarvestTask:adjustFilterWindow:369) 1. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Wed Apr 12 13:51:14 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Wed Apr 12 13:51:14 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,174 (ObjectListHarvestTask:adjustFilterWindow:369) 2. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Tue Feb 21 00:56:00 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Tue Feb 21 00:56:00 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,175 (ObjectListHarvestTask:adjustFilterWindow:369) 3. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Thu Jan 26 18:28:23 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Thu Jan 26 18:28:23 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,175 (ObjectListHarvestTask:adjustFilterWindow:369) 4. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jan 14 03:14:35 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jan 14 03:14:35 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,176 (ObjectListHarvestTask:adjustFilterWindow:369) 5. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jan 07 19:37:41 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jan 07 19:37:41 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,177 (ObjectListHarvestTask:adjustFilterWindow:369) 6. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Wed Jan 04 15:49:14 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Wed Jan 04 15:49:14 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,177 (ObjectListHarvestTask:adjustFilterWindow:369) 7. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Tue Jan 03 01:55:00 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Tue Jan 03 01:55:00 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2018-02-09 19:22:47,178 (ObjectListHarvestTask:adjustFilterWindow:369) 8. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Mon Jan 02 06:57:53 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,0,0) returning 0 out of 1138 objectInfos
[ INFO] 2018-02-09 19:22:47,179 (ObjectListHarvestTask:adjustFilterWindow:383) urn:node:HARVEST_TEST - final time window: total = 1138, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Mon Jan 02 06:57:53 UTC 2017]
[ INFO] 2018-02-09 19:22:47,179 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,0,500) returning 500 out of 1138 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,500,500) returning 500 out of 1138 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,1000,500) returning 138 out of 1138 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 12:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,184 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 12:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 13:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,185 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 13:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 14:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,185 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 14:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,186 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 16:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,186 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 16:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 17:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,186 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 17:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 18:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,187 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 18:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 19:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,187 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 19:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 20:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,188 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 20:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 21:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,188 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 21:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 22:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,188 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 22:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 23:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,189 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 23:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 00:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,189 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 00:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 01:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,190 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 01:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 02:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,190 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 02:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 03:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,190 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 03:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 04:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,191 (ObjectListHarvestTask:spoolToSynchronizationQueue:240) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 04:39:47 UTC 2017 ***end of harvest***
[DEBUG] 2018-02-09 19:22:47,191 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-02T04:39:47.167+00:00 and endDate of 2018-02-09T19:22:27.166+00:00
listObjects(Mon Jan 02 04:39:47 UTC 2017,Fri Feb 09 19:22:27 UTC 2018,0,0) returning 0 out of 1222 objectInfos
[ INFO] 2018-02-09 19:22:47,192 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 1222 pids to harvest.
[ INFO] 2018-02-09 19:22:47,192 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Mon Jan 02 04:39:47 UTC 2017,Fri Feb 09 19:22:27 UTC 2018,0,500) returning 500 out of 1222 objectInfos
listObjects(Mon Jan 02 04:39:47 UTC 2017,Fri Feb 09 19:22:27 UTC 2018,500,500) returning 500 out of 1222 objectInfos
listObjects(Mon Jan 02 04:39:47 UTC 2017,Fri Feb 09 19:22:27 UTC 2018,1000,500) returning 222 out of 1222 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 05:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,194 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 05:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 06:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,194 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 06:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 07:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,195 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 07:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 08:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,195 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 08:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 09:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,195 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 09:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 10:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,196 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 10:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 11:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,196 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 11:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 12:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,196 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 12:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 13:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,197 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 13:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 14:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,197 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 14:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 15:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,198 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 15:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 16:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,198 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 16:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 17:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,198 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 17:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 18:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,199 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 18:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 19:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,199 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 19:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 20:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,199 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 20:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 21:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,200 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 21:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 22:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,200 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 22:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 23:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,200 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 23:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 00:39:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,201 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 00:39:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 01:01:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,201 (ObjectListHarvestTask:spoolToSynchronizationQueue:240) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 01:01:47 UTC 2017 ***end of harvest***
[DEBUG] 2018-02-09 19:22:47,202 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:47.102+00:00 and endDate of 2018-02-09T19:22:37.201+00:00
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2018-02-09 19:22:47,203 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[ INFO] 2018-02-09 19:22:47,203 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,1000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,1500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,2000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,2500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,3000,500) returning 333 out of 3333 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 12:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,210 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 12:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 13:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,210 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 13:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 14:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,211 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 14:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,211 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 16:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,211 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 16:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 17:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,212 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 17:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 18:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,212 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 18:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 19:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,212 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 19:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 20:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,213 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 20:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 21:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,213 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 21:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 22:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,213 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 22:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 23:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,214 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 23:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 00:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,214 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 00:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 01:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,214 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 01:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 02:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,215 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 02:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 03:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,215 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 03:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 04:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,215 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 04:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 05:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,216 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 05:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 06:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,216 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 06:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 07:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,216 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 07:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 08:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,217 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 08:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 09:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,217 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 09:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 10:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,217 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 10:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 11:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,218 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 11:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 12:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,218 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 12:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 13:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,218 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 13:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 14:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,219 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 14:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 15:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,219 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 15:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 16:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,219 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 16:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 17:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,220 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 17:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 18:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,220 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 18:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 19:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,220 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 19:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 20:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,221 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 20:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 21:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,221 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 21:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 22:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,221 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 22:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 23:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,222 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 23:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 00:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,222 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 00:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 01:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,222 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 01:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 02:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,223 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 02:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 03:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,223 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 03:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 04:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,223 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 04:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 05:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,224 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 05:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 06:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,224 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 06:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 07:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,224 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 07:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 08:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,225 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 08:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 09:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,225 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 09:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 10:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,225 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 10:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 11:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,226 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 11:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 12:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,226 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 12:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 13:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,227 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 13:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 14:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,227 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 14:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 15:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,227 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 15:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 16:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,228 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 16:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 17:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,228 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 17:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 18:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,228 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 18:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 19:32:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,229 (ObjectListHarvestTask:spoolToSynchronizationQueue:240) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 19:32:47 UTC 2017 ***end of harvest***
[DEBUG] 2018-02-09 19:22:47,230 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:47.130+00:00 and endDate of 2018-02-09T19:22:37.229+00:00
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,0) returning 0 out of 3722 objectInfos
[ INFO] 2018-02-09 19:22:47,231 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 3722 pids to harvest.
[DEBUG] 2018-02-09 19:22:47,231 (ObjectListHarvestTask:adjustFilterWindow:369) 0. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jul 22 15:41:42 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jul 22 15:41:42 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,231 (ObjectListHarvestTask:adjustFilterWindow:369) 1. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Wed Apr 12 13:51:14 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Wed Apr 12 13:51:14 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,232 (ObjectListHarvestTask:adjustFilterWindow:369) 2. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Tue Feb 21 00:56:00 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Tue Feb 21 00:56:00 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,232 (ObjectListHarvestTask:adjustFilterWindow:369) 3. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Thu Jan 26 18:28:24 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Thu Jan 26 18:28:24 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,233 (ObjectListHarvestTask:adjustFilterWindow:369) 4. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jan 14 03:14:35 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jan 14 03:14:35 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,233 (ObjectListHarvestTask:adjustFilterWindow:369) 5. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jan 07 19:37:41 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jan 07 19:37:41 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,234 (ObjectListHarvestTask:adjustFilterWindow:369) 6. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Wed Jan 04 15:49:14 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Wed Jan 04 15:49:14 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,234 (ObjectListHarvestTask:adjustFilterWindow:369) 7. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Tue Jan 03 01:55:00 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Tue Jan 03 01:55:00 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2018-02-09 19:22:47,235 (ObjectListHarvestTask:adjustFilterWindow:369) 8. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Mon Jan 02 06:57:53 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,0,0) returning 0 out of 2638 objectInfos
[DEBUG] 2018-02-09 19:22:47,236 (ObjectListHarvestTask:adjustFilterWindow:369) 9. total = 2638, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sun Jan 01 21:29:20 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sun Jan 01 21:29:20 UTC 2017,0,0) returning 0 out of 2069 objectInfos
[DEBUG] 2018-02-09 19:22:47,236 (ObjectListHarvestTask:adjustFilterWindow:369) 10. total = 2069, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sun Jan 01 16:45:03 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sun Jan 01 16:45:03 UTC 2017,0,0) returning 0 out of 1785 objectInfos
[ INFO] 2018-02-09 19:22:47,236 (ObjectListHarvestTask:adjustFilterWindow:383) urn:node:HARVEST_TEST - final time window: total = 1785, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sun Jan 01 16:45:03 UTC 2017]
[ INFO] 2018-02-09 19:22:47,237 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sun Jan 01 16:45:03 UTC 2017,0,500) returning 500 out of 1785 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sun Jan 01 16:45:03 UTC 2017,500,500) returning 500 out of 1785 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sun Jan 01 16:45:03 UTC 2017,1000,500) returning 500 out of 1785 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sun Jan 01 16:45:03 UTC 2017,1500,500) returning 285 out of 1785 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 12:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,241 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 12:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 13:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,242 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 13:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 14:59:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,242 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 14:59:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:19:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,242 (ObjectListHarvestTask:spoolToSynchronizationQueue:240) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:19:47 UTC 2017 ***end of harvest***
[DEBUG] 2018-02-09 19:22:47,243 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T15:19:47.230+00:00 and endDate of 2018-02-09T19:22:27.229+00:00
listObjects(Sun Jan 01 15:19:47 UTC 2017,Fri Feb 09 19:22:27 UTC 2018,0,0) returning 0 out of 3522 objectInfos
[ INFO] 2018-02-09 19:22:47,243 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 3522 pids to harvest.
[DEBUG] 2018-02-09 19:22:47,243 (ObjectListHarvestTask:adjustFilterWindow:369) 0. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Sat Jul 22 17:21:07 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sat Jul 22 17:21:07 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,244 (ObjectListHarvestTask:adjustFilterWindow:369) 1. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Wed Apr 12 16:20:27 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Wed Apr 12 16:20:27 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,244 (ObjectListHarvestTask:adjustFilterWindow:369) 2. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Tue Feb 21 03:50:07 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Tue Feb 21 03:50:07 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,245 (ObjectListHarvestTask:adjustFilterWindow:369) 3. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Thu Jan 26 21:34:57 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Thu Jan 26 21:34:57 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,245 (ObjectListHarvestTask:adjustFilterWindow:369) 4. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Sat Jan 14 06:27:22 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sat Jan 14 06:27:22 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,245 (ObjectListHarvestTask:adjustFilterWindow:369) 5. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Sat Jan 07 22:53:34 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sat Jan 07 22:53:34 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,246 (ObjectListHarvestTask:adjustFilterWindow:369) 6. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Wed Jan 04 19:06:40 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Wed Jan 04 19:06:40 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,246 (ObjectListHarvestTask:adjustFilterWindow:369) 7. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Tue Jan 03 05:13:14 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Tue Jan 03 05:13:14 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2018-02-09 19:22:47,247 (ObjectListHarvestTask:adjustFilterWindow:369) 8. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Mon Jan 02 10:16:30 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Mon Jan 02 10:16:30 UTC 2017,0,0) returning 0 out of 2636 objectInfos
[DEBUG] 2018-02-09 19:22:47,247 (ObjectListHarvestTask:adjustFilterWindow:369) 9. total = 2636, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Mon Jan 02 00:48:08 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Mon Jan 02 00:48:08 UTC 2017,0,0) returning 0 out of 2068 objectInfos
[DEBUG] 2018-02-09 19:22:47,248 (ObjectListHarvestTask:adjustFilterWindow:369) 10. total = 2068, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Sun Jan 01 20:03:58 UTC 2017]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sun Jan 01 20:03:58 UTC 2017,0,0) returning 0 out of 1784 objectInfos
[ INFO] 2018-02-09 19:22:47,248 (ObjectListHarvestTask:adjustFilterWindow:383) urn:node:HARVEST_TEST - final time window: total = 1784, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:47 UTC 2017 to Sun Jan 01 20:03:58 UTC 2017]
[ INFO] 2018-02-09 19:22:47,248 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sun Jan 01 20:03:58 UTC 2017,0,500) returning 500 out of 1784 objectInfos
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sun Jan 01 20:03:58 UTC 2017,500,500) returning 500 out of 1784 objectInfos
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sun Jan 01 20:03:58 UTC 2017,1000,500) returning 500 out of 1784 objectInfos
listObjects(Sun Jan 01 15:19:47 UTC 2017,Sun Jan 01 20:03:58 UTC 2017,1500,500) returning 284 out of 1784 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,254 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,254 (ObjectListHarvestTask:spoolToSynchronizationQueue:240) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:20:47 UTC 2017 ***end of harvest***
[DEBUG] 2018-02-09 19:22:47,254 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T15:20:47.230+00:00 and endDate of 2018-02-09T19:22:17.229+00:00
listObjects(Sun Jan 01 15:20:47 UTC 2017,Fri Feb 09 19:22:17 UTC 2018,0,0) returning 0 out of 2021 objectInfos
[ INFO] 2018-02-09 19:22:47,255 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 2021 pids to harvest.
[ INFO] 2018-02-09 19:22:47,255 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 15:20:47 UTC 2017,Fri Feb 09 19:22:17 UTC 2018,0,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:47 UTC 2017,Fri Feb 09 19:22:17 UTC 2018,500,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:47 UTC 2017,Fri Feb 09 19:22:17 UTC 2018,1000,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:47 UTC 2017,Fri Feb 09 19:22:17 UTC 2018,1500,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:47 UTC 2017,Fri Feb 09 19:22:17 UTC 2018,2000,500) returning 21 out of 2021 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 16:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,259 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 16:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 17:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,259 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 17:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 18:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,259 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 18:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 19:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,260 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 19:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 20:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,260 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 20:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 21:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,260 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 21:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 22:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,261 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 22:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 23:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,261 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 23:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 00:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,261 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 00:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 01:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,262 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 01:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 02:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,262 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 02:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 03:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,262 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 03:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 04:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,262 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 04:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 05:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,263 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 05:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 06:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,263 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 06:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 07:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,263 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 07:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 08:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,264 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 08:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 09:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,264 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 09:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 10:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,264 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 10:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 11:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,265 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 11:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 12:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,265 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 12:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 13:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,265 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 13:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 14:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,266 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 14:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 15:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,266 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 15:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 16:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,266 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 16:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 17:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,266 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 17:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 18:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,267 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 18:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 19:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,267 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 19:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 20:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,267 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 20:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 21:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,268 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 21:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 22:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,268 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 22:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 23:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,268 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 23:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 00:20:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,269 (ObjectListHarvestTask:spoolToSynchronizationQueue:226) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 00:20:47 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 01:01:47 UTC 2017
[ INFO] 2018-02-09 19:22:47,269 (ObjectListHarvestTask:spoolToSynchronizationQueue:240) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 01:01:47 UTC 2017 ***end of harvest***
[DEBUG] 2018-02-09 19:22:47,270 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T11:44:07.270+00:00 and endDate of 2018-02-09T19:22:37.269+00:00
[ WARN] 2018-02-09 19:22:47,272 (ObjectListHarvestTask:getFullObjectList:317) urn:node:HARVEST_TEST- Node doesn't like slicing parameters, trying single-page harvest strategy: <?xml version="1.0" encoding="UTF-8"?>
<error detailCode="code" errorCode="400" name="InvalidRequest">
<description>listObjects does not support 'start' and 'count' parameters</description>
</error>
listObjects(Sun Jan 01 11:44:07 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,null) returning 3333 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,277 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T11:44:07.270+00:00 and endDate of 2018-02-09T19:22:27.269+00:00
[ WARN] 2018-02-09 19:22:47,278 (ObjectListHarvestTask:getFullObjectList:317) urn:node:HARVEST_TEST- Node doesn't like slicing parameters, trying single-page harvest strategy: <?xml version="1.0" encoding="UTF-8"?>
<error detailCode="code" errorCode="400" name="InvalidRequest">
<description>listObjects does not support 'start' and 'count' parameters</description>
</error>
listObjects(Sun Jan 01 11:44:07 UTC 2017,Fri Feb 09 19:22:27 UTC 2018,0,null) returning 3333 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,280 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T11:44:07.280+00:00 and endDate of 2018-02-09T19:22:37.279+00:00
[ WARN] 2018-02-09 19:22:47,281 (ObjectListHarvestTask:getFullObjectList:317) urn:node:HARVEST_TEST- Node doesn't like slicing parameters, trying single-page harvest strategy: <?xml version="1.0" encoding="UTF-8"?>
<error detailCode="code" errorCode="400" name="InvalidRequest">
<description>listObjects does not support 'start' and 'count' parameters</description>
</error>
listObjects(Sun Jan 01 11:44:07 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,null) returning 1000 out of 3333 objectInfos
[ERROR] 2018-02-09 19:22:47,282 (ObjectListHarvestTask:doAllInOneHarvest:477) urn:node:HARVEST_TEST - MemberNode does not support paging, but also doesn't return the total list within the harvest period. Cannot harvest!
[DEBUG] 2018-02-09 19:22:47,284 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:47.184+00:00 and endDate of 2018-02-09T19:22:37.283+00:00
[ WARN] 2018-02-09 19:22:47,285 (ObjectListHarvestTask:getFullObjectList:317) urn:node:HARVEST_TEST- Node doesn't like slicing parameters, trying single-page harvest strategy: <?xml version="1.0" encoding="UTF-8"?>
<error detailCode="code" errorCode="400" name="InvalidRequest">
<description>listObjects does not support 'start' and 'count' parameters</description>
</error>
[DEBUG] 2018-02-09 19:22:47,286 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-04T06:40:47.286+00:00 and endDate of 2018-02-09T19:22:37.285+00:00
[ WARN] 2018-02-09 19:22:47,287 (ObjectListHarvestTask:getFullObjectList:317) urn:node:HARVEST_TEST- Node doesn't like slicing parameters, trying single-page harvest strategy: <?xml version="1.0" encoding="UTF-8"?>
<error detailCode="code" errorCode="400" name="InvalidRequest">
<description>listObjects does not support 'start' and 'count' parameters</description>
</error>
listObjects(Wed Jan 04 06:40:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,null) returning 0 out of 0 objectInfos
[DEBUG] 2018-02-09 19:22:47,288 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:47.188+00:00 and endDate of 2018-02-09T19:22:37.287+00:00
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2018-02-09 19:22:47,289 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[ INFO] 2018-02-09 19:22:47,289 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,1000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,1500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,2000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,2500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,3000,500) returning 333 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,294 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:47.188+00:00 and endDate of 2018-02-09T19:22:27.287+00:00
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:27 UTC 2018,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2018-02-09 19:22:47,294 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[DEBUG] 2018-02-09 19:22:47,294 (ObjectListHarvestTask:adjustFilterWindow:369) 0. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jul 22 15:41:37 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jul 22 15:41:37 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,295 (ObjectListHarvestTask:adjustFilterWindow:369) 1. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Wed Apr 12 13:51:12 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Wed Apr 12 13:51:12 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,295 (ObjectListHarvestTask:adjustFilterWindow:369) 2. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Tue Feb 21 00:55:59 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Tue Feb 21 00:55:59 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,295 (ObjectListHarvestTask:adjustFilterWindow:369) 3. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Thu Jan 26 18:28:23 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Thu Jan 26 18:28:23 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,296 (ObjectListHarvestTask:adjustFilterWindow:369) 4. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jan 14 03:14:35 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jan 14 03:14:35 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,296 (ObjectListHarvestTask:adjustFilterWindow:369) 5. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Sat Jan 07 19:37:41 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Sat Jan 07 19:37:41 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,296 (ObjectListHarvestTask:adjustFilterWindow:369) 6. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Wed Jan 04 15:49:14 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Wed Jan 04 15:49:14 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,297 (ObjectListHarvestTask:adjustFilterWindow:369) 7. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Tue Jan 03 01:55:00 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Tue Jan 03 01:55:00 UTC 2017,0,0) returning 0 out of 2275 objectInfos
[DEBUG] 2018-02-09 19:22:47,297 (ObjectListHarvestTask:adjustFilterWindow:369) 8. total = 2275, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Mon Jan 02 06:57:53 UTC 2017]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,0,0) returning 0 out of 1138 objectInfos
[ INFO] 2018-02-09 19:22:47,297 (ObjectListHarvestTask:adjustFilterWindow:383) urn:node:HARVEST_TEST - final time window: total = 1138, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:47 UTC 2017 to Mon Jan 02 06:57:53 UTC 2017]
[ INFO] 2018-02-09 19:22:47,297 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,0,500) returning 500 out of 1138 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,500,500) returning 500 out of 1138 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Mon Jan 02 06:57:53 UTC 2017,1000,500) returning 138 out of 1138 objectInfos
[DEBUG] 2018-02-09 19:22:47,300 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:47.200+00:00 and endDate of 2018-02-09T19:22:37.299+00:00
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2018-02-09 19:22:47,300 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[ INFO] 2018-02-09 19:22:47,300 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,100) returning 100 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,100,100) returning 100 out of 3333 objectInfos
[DEBUG] 2018-02-09 19:22:47,302 (ObjectListHarvestTask:getFullObjectList:287) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-04T06:40:47.302+00:00 and endDate of 2018-02-09T19:22:37.301+00:00
listObjects(Wed Jan 04 06:40:47 UTC 2017,Fri Feb 09 19:22:37 UTC 2018,0,0) returning 0 out of 0 objectInfos
[ INFO] 2018-02-09 19:22:47,302 (ObjectListHarvestTask:getFullObjectList:307) urn:node:HARVEST_TEST- has 0 pids to harvest.
[ INFO] 2018-02-09 19:22:47,302 (ObjectListHarvestTask:getFullObjectList:310) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.146 sec
Running org.dataone.cn.batch.synchronization.tasks.SortedHarvestTimepointMapTest
==========================================
Fri Feb 09 19:22:46 UTC 2018 : foo.9,bar.9
Fri Feb 09 19:22:46 UTC 2018 : foo.8,bar.8
Fri Feb 09 19:22:46 UTC 2018 : foo.7,bar.7
Fri Feb 09 19:22:46 UTC 2018 : foo.6,bar.6
Fri Feb 09 19:22:46 UTC 2018 : foo.5,bar.5
Fri Feb 09 19:22:46 UTC 2018 : foo.4,bar.4
Fri Feb 09 19:22:47 UTC 2018 : foo.3,bar.3
Fri Feb 09 19:22:47 UTC 2018 : foo.2,bar.2
Fri Feb 09 19:22:47 UTC 2018 : foo.1,bar.1
Fri Feb 09 19:22:47 UTC 2018 : foo.0,bar.0
==========================================
Fri Feb 09 19:22:46 UTC 2018 : foo.6,bar.6
Fri Feb 09 19:22:46 UTC 2018 : foo.5,bar.5
Fri Feb 09 19:22:46 UTC 2018 : foo.4,bar.4
Fri Feb 09 19:22:47 UTC 2018 : foo.3,bar.3
Fri Feb 09 19:22:47 UTC 2018 : foo.2,bar.2
Fri Feb 09 19:22:47 UTC 2018 : foo.1,bar.1
Fri Feb 09 19:22:47 UTC 2018 : foo.0,bar.0
==========================================
1518204166404 : foo.9,bar.9
1518204166504 : foo.8,bar.8
1518204166604 : foo.7,bar.7
==========================================
1518204166504 : foo.8,bar.8
1518204166604 : foo.7,bar.7
1518204166704 : foo.6,bar.6
1518204166804 : foo.5,bar.5
1518204166904 : foo.4,bar.4
1518204167004 : foo.3,bar.3
1518204167104 : foo.2,bar.2
1518204167204 : foo.1,bar.1
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.dataone.cn.batch.synchronization.tasks.SyncMetricLogReportTest
[DEBUG] 2018-02-09 19:22:47,683 (SyncMetricLogReport:reportSyncMetrics:29) start
[DEBUG] 2018-02-09 19:22:47,695 (SyncMetricLogReport:reportSyncMetrics:59) end
[ INFO] 2018-02-09 19:22:47,695 (SyncMetricLogReportTest:testLargeRandomArray:81) Time to Execute in Milliseconds: 12
[ INFO] 2018-02-09 19:22:47,695 (SyncMetricLogReportTest:testLargeRandomArray:82) 8
[DEBUG] 2018-02-09 19:22:47,695 (SyncMetricLogReport:reportSyncMetrics:29) start
[DEBUG] 2018-02-09 19:22:47,695 (SyncMetricLogReport:reportSyncMetrics:59) end
{"event":"synchronization queued","message":"Total Sync Objects Queued: 10","threadName":"main","threadId":1,"dateLogged":"2018-02-09T19:22:47.695+00:00"}
{"event":"synchronization queued","nodeId":"urn:node:test1","message":"Sync Objects Queued: 1","threadName":"main","threadId":1,"dateLogged":"2018-02-09T19:22:47.695+00:00"}
{"event":"synchronization queued","nodeId":"urn:node:test2","message":"Sync Objects Queued: 2","threadName":"main","threadId":1,"dateLogged":"2018-02-09T19:22:47.695+00:00"}
{"event":"synchronization queued","nodeId":"urn:node:test3","message":"Sync Objects Queued: 3","threadName":"main","threadId":1,"dateLogged":"2018-02-09T19:22:47.695+00:00"}
{"event":"synchronization queued","nodeId":"urn:node:test4","message":"Sync Objects Queued: 4","threadName":"main","threadId":1,"dateLogged":"2018-02-09T19:22:47.695+00:00"}
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.382 sec
Running org.dataone.cn.batch.synchronization.listener.SyncMetricLogJobTriggerTest
[ INFO] 2018-02-09 19:22:48,122 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
[ INFO] 2018-02-09 19:22:48,548 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
[ INFO] 2018-02-09 19:22:48,973 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
[ INFO] 2018-02-09 19:22:49,399 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.002 sec
Running org.dataone.cn.batch.synchronization.type.SystemMetadataValidatorTest
[ INFO] 2018-02-09 19:22:49,704 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,713 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,718 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,722 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,727 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,731 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,736 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,741 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,745 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,749 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,754 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,757 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2018-02-09 19:22:49,762 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.065 sec
[ INFO] 2018-02-09 19:22:49,767 (AbstractApplicationContext:doClose:1032) Closing org.springframework.context.support.GenericApplicationContext@10aa41f2: startup date [Fri Feb 09 19:20:51 UTC 2018]; root of context hierarchy
[ INFO] 2018-02-09 19:22:49,767 (DefaultSingletonBeanRegistry:destroySingletons:434) Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@5824a83d: defining beans [log4jInitialization,mylog,rejectionExecutionHander,threadPoolTaskExecutor,tinyThreadPoolTaskExecutor,mockQueueProcessor,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor]; root of factory hierarchy
[ INFO] 2018-02-09 19:22:49,777 (ExecutorConfigurationSupport:shutdown:150) Shutting down ExecutorService 'tinyThreadPoolTaskExecutor'
[ INFO] 2018-02-09 19:22:49,778 (ExecutorConfigurationSupport:shutdown:150) Shutting down ExecutorService 'threadPoolTaskExecutor'
Results :
Tests run: 65, Failures: 0, Errors: 0, Skipped: 4
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.2:jar (default-jar) @ d1_synchronization ---
[INFO] Building jar: /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/d1_synchronization-2.3.5.jar
[INFO]
[INFO] --- maven-install-plugin:2.3:install (default-install) @ d1_synchronization ---
[INFO] Installing /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/d1_synchronization-2.3.5.jar to /var/lib/jenkins/.m2/repository/org/dataone/d1_synchronization/2.3.5/d1_synchronization-2.3.5.jar
[INFO] Installing /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/pom.xml to /var/lib/jenkins/.m2/repository/org/dataone/d1_synchronization/2.3.5/d1_synchronization-2.3.5.pom
[INFO]
[INFO] --- buildnumber-maven-plugin:1.4:create (default) @ d1_synchronization ---
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization
[INFO] Storing buildNumber: 19104 at timestamp: 1518204170580
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization
[INFO] Storing buildScmBranch: tags/D1_SYNCHRONIZATION_v2.3.5
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO]
[INFO] --- maven-javadoc-plugin:2.10.4:javadoc (default-cli) @ d1_synchronization ---
[INFO]
Loading source files for package org.dataone.cn.batch.exceptions...
Loading source files for package org.dataone.cn.batch.service.v2...
Loading source files for package org.dataone.cn.batch.service.v2.impl...
Loading source files for package org.dataone.cn.batch.synchronization...
Loading source files for package org.dataone.cn.batch.synchronization.tasks...
Loading source files for package org.dataone.cn.batch.synchronization.listener...
Loading source files for package org.dataone.cn.batch.synchronization.jobs...
Loading source files for package org.dataone.cn.batch.synchronization.type...
Loading source files for package org.dataone.cn.ldap...
Constructing Javadoc information...
Standard Doclet version 1.8.0_111
Building tree for all the packages and classes...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/NodeCommUnavailable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/RetryableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/UnrecoverableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/IdentifierReservationQueryService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/NodeRegistrySyncService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/NodeRegistrySyncServiceImpl.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/D1TypeUtils.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/HarvestSchedulingManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/NodeCommFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/NodeCommObjectListHarvestFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/NodeCommSyncObjectFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/SyncObjectTaskManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/ObjectListHarvestTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/QueueProcessorCallable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SortedHarvestTimepointMap.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SyncFailedTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SyncMetricLogReport.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SyncObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/V2TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/NodeTopicListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/SyncMetricLogJobTriggerListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/MemberNodeHarvestJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/SyncMetricLogJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/NodeComm.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/NodeCommState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/SyncObjectState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/NodeRegistrySyncFacade.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/overview-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/constant-values.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/serialized-form.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/class-use/UnrecoverableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/class-use/RetryableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/class-use/NodeCommUnavailable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/class-use/NodeRegistrySyncService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/class-use/IdentifierReservationQueryService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/class-use/NodeRegistrySyncServiceImpl.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/NodeCommSyncObjectFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/D1TypeUtils.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/NodeCommObjectListHarvestFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/SyncObjectTaskManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/HarvestSchedulingManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/NodeCommFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/QueueProcessorCallable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SortedHarvestTimepointMap.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SyncFailedTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/V2TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/ObjectListHarvestTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SyncMetricLogReport.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/DelayWrapper.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SyncObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/class-use/NodeTopicListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/class-use/SyncMetricLogJobTriggerListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/class-use/MemberNodeHarvestJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/class-use/SyncMetricLogJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/NodeCommState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/SystemMetadataValidator.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/NodeComm.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/SyncObjectState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/class-use/NodeRegistrySyncFacade.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/package-use.html...
Building index for all the packages and classes...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/overview-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/index-all.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/deprecated-list.html...
Building index for all classes...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/allclasses-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/allclasses-noframe.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/index.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/overview-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/target/site/apidocs/help-doc.html...
44 warnings
[WARNING] Javadoc Warnings
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:42: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:67: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:90: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:113: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:142: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:175: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:208: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:241: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:274: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:307: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "58" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "47" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "47" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "47" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "47" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "47" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "45" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "45" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "45" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see:illegal character: "45" in "http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html"
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.java:14: warning - Tag @see: reference not found: http://aredko.blogspot.com/2012/04/using-delayed-queues-in-practice.html
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/ObjectListHarvestTask.java:263: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/ObjectListHarvestTask.java:119: warning - @returns is an unknown tag.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/SyncFailedTask.java:84: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/SyncFailedTask.java:63: warning - Tag @author cannot be used in method documentation. It can only be used in the following types of documentation: overview, package, class/interface.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "58" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "45" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "45" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "37" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "37" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "45" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "37" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "45" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see:illegal character: "47" in "https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties."
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:51: warning - @ is an unknown tag.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization_stable/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.java:201: warning - Tag @see: reference not found: https://jenkins-ucsb-1.dataone.org/job/API%20Documentation%20-%20trunk/ws/api-documentation/build/htmldesign/SystemMetadata.html
[WARNING] for specification on ownership and editability of properties.
[JENKINS] Archiving javadoc
Notifying upstream projects of job completion
Join notifier requires a CauseAction
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 2:11.566s
[INFO] Finished at: Fri Feb 09 19:22:54 UTC 2018
[INFO] Final Memory: 50M/385M
[INFO] ------------------------------------------------------------------------
Waiting for Jenkins to finish collecting data
![[Jenkins]](/jenkins/static/b611b658/images/jenkins-header-logo-v2.svg){kind=logo}
Console Output