s:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:42,169 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:42,419 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:42,670 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:42,920 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:43,170 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:43,421 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:43,671 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:43,921 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:44,172 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:44,422 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:44,673 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:44,923 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:45,174 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:45,424 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[DEBUG] 2019-11-12 05:05:45,674 (QueueProcessorCallable:reapFutures:242) Polling empty queue
Nov 12, 2019 5:05:45 AM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is SHUTDOWN
Nov 12, 2019 5:05:45 AM com.hazelcast.client.LifecycleServiceClientImpl
INFO: HazelcastClient is SHUTDOWN
[DEBUG] 2019-11-12 05:05:45,925 (QueueProcessorCallable:reapFutures:242) Polling empty queue
Remaining on the queue: 0
java.lang.InterruptedExceptionitem3
: [2019-11-12 05:05:36.159]: task Prepared from: item3 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2088)
item3 at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:418) :
[2019-11-12 05:05:36.159]: task CleanedUp at org.dataone.cn.batch.synchronization.tasks.QueueProcessorCallable.getNextItem(QueueProcessorCallable.java:152)
at org.dataone.cn.batch.synchronization.tasks.QueueProcessorCallable.call(QueueProcessorCallable.java:92)
item1 at org.dataone.cn.batch.synchronization.tasks.QueueProcessorCallable.call(QueueProcessorCallable.java:40) :
at java.util.concurrent.FutureTask.run(FutureTask.java:266)[2019-11-12 05:05:36.158]: task Prepared from: item1
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)item1
: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)[2019-11-12 05:05:36.159]: task CleanedUp
at java.lang.Thread.run(Thread.java:748)item2
: [2019-11-12 05:05:36.159]: task Prepared from: item2
item2 : [2019-11-12 05:05:36.159]: task CleanedUp
item4 : [2019-11-12 05:05:36.159]: task Prepared from: item4
item4 : [2019-11-12 05:05:36.159]: task CleanedUp
item0 : [2019-11-12 05:05:36.150]: task Prepared from: item0
item0 : [2019-11-12 05:05:36.158]: task CleanedUp
[DEBUG] 2019-11-12 05:05:46,152 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[ INFO] 2019-11-12 05:05:46,153 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:05:46,403 (QueueProcessorCallable:reapFutures:240) waiting on 2 futures
Nov 12, 2019 5:05:46 AM com.hazelcast.client.InRunnable
INFO: InRunnable is finished ok.
Nov 12, 2019 5:05:46 AM com.hazelcast.client.InRunnable
INFO: InRunnable is finished.
Nov 12, 2019 5:05:46 AM com.hazelcast.client.InRunnable
INFO: InRunnable is finished ok.
Nov 12, 2019 5:05:46 AM com.hazelcast.client.InRunnable
INFO: InRunnable is finished.
[ INFO] 2019-11-12 05:05:46,904 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
cleaning up task: item5
[ INFO] 2019-11-12 05:05:47,405 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
[ INFO] 2019-11-12 05:05:48,156 (QueueProcessorCallable:reapFutures:240) waiting on 4 futures
cleaning up task: item6
[ WARN] 2019-11-12 05:05:49,152 (QueueProcessorCallable:setIsInactivated:220) Setting processor inactivation: true
[ INFO] 2019-11-12 05:05:49,157 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
item10
[ INFO] 2019-11-12 05:05:52,908 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
cleaning up task: item7
cleaning up task: item8
[ INFO] 2019-11-12 05:05:56,159 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
cleaning up task: item9
[ INFO] 2019-11-12 05:05:56,159 (QueueProcessorCallable:call:80) All Tasks are complete. Shutting down
Remaining on the queue: 1
item8 : [2019-11-12 05:05:47.405]: task Prepared from: item8
item8 : [2019-11-12 05:05:52.908]: task CleanedUp
item9 : [2019-11-12 05:05:48.156]: task Prepared from: item9
item9 : [2019-11-12 05:05:56.159]: task CleanedUp
item6 : [2019-11-12 05:05:46.403]: task Prepared from: item6
item6 : [2019-11-12 05:05:48.406]: task CleanedUp
item7 : [2019-11-12 05:05:46.904]: task Prepared from: item7
item7 : [2019-11-12 05:05:52.908]: task CleanedUp
item5 : [2019-11-12 05:05:46.153]: task Prepared from: item5
item5 : [2019-11-12 05:05:47.405]: task CleanedUp
[DEBUG] 2019-11-12 05:05:59,255 (QueueProcessorCallable:reapFutures:242) Polling empty queue
[ INFO] 2019-11-12 05:05:59,255 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:05:59,506 (QueueProcessorCallable:reapFutures:240) waiting on 2 futures
[ INFO] 2019-11-12 05:06:00,006 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
[ WARN] 2019-11-12 05:06:00,255 (QueueProcessorCallable:setIsInactivated:220) Setting processor inactivation: true
throwing exception: org.dataone.cn.batch.exceptions.RetryableException
throwing exception: org.dataone.cn.batch.exceptions.RetryableException
[ INFO] 2019-11-12 05:06:03,757 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
[DEBUG] 2019-11-12 05:06:03,757 (QueueProcessorCallable:reapFutures:268) Adding item to pendingQueue...
cleaning up task: item11
[DEBUG] 2019-11-12 05:06:03,759 (QueueProcessorCallable:reapFutures:268) Adding item to pendingQueue...
cleaning up task: item12
[ INFO] 2019-11-12 05:06:04,009 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:06:04,259 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:06:04,510 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:06:04,760 (QueueProcessorCallable:reapFutures:240) waiting on 2 futures
[ INFO] 2019-11-12 05:06:05,261 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
throwing exception: org.dataone.cn.batch.exceptions.RetryableException
throwing exception: org.dataone.cn.batch.exceptions.RetryableException
[ INFO] 2019-11-12 05:06:09,012 (QueueProcessorCallable:reapFutures:240) waiting on 3 futures
[DEBUG] 2019-11-12 05:06:09,262 (QueueProcessorCallable:reapFutures:268) Adding item to pendingQueue...
cleaning up task: item13
[DEBUG] 2019-11-12 05:06:09,262 (QueueProcessorCallable:reapFutures:268) Adding item to pendingQueue...
cleaning up task: item11
[ INFO] 2019-11-12 05:06:09,263 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:06:09,513 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:06:09,763 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
[ INFO] 2019-11-12 05:06:10,013 (QueueProcessorCallable:reapFutures:240) waiting on 1 futures
Remaining on the queue: 2
item12 : [2019-11-12 05:05:59.505]: task Prepared from: item12
item12 : [2019-11-12 05:06:03.759]: task CleanedUp
item12 : [2019-11-12 05:06:05.261]: task Prepared from: item12
item13 : [2019-11-12 05:06:00.006]: task Prepared from: item13
item13 : [2019-11-12 05:06:09.262]: task CleanedUp
item11 : [2019-11-12 05:05:59.255]: task Prepared from: item11
item11 : [2019-11-12 05:06:03.759]: task CleanedUp
item11 : [2019-11-12 05:06:04.760]: task Prepared from: item11
item11 : [2019-11-12 05:06:09.263]: task CleanedUp
java.lang.InterruptedException
at java.util.concurrent.FutureTask.awaitDone(FutureTask.java:404)
at java.util.concurrent.FutureTask.get(FutureTask.java:204)
at org.dataone.cn.batch.synchronization.tasks.QueueProcessorCallable.reapFutures(QueueProcessorCallable.java:252)
Tests run: 6, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 34.3 sec
Running org.dataone.cn.batch.synchronization.tasks.ObjectListHarvestTaskTest
at org.dataone.cn.batch.synchronization.tasks.QueueProcessorCallable.call(QueueProcessorCallable.java:75)
at org.dataone.cn.batch.synchronization.tasks.QueueProcessorCallable.call(QueueProcessorCallable.java:40)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
[ INFO] 2019-11-12 05:06:10,258 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@773dab28 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,258 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@773dab28 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,259 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@773dab28 added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,259 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@773dab28 added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,264 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:10.163+00:00 and endDate of 2019-11-12T05:06:00.262+00:00
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,0) returning 0 out of 3722 objectInfos
[ INFO] 2019-11-12 05:06:10,265 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 3722 pids to harvest.
[DEBUG] 2019-11-12 05:06:10,266 (ObjectListHarvestTask:adjustFilterWindow:370) 0. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Thu Jun 07 20:33:05 UTC 2018]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Thu Jun 07 20:33:05 UTC 2018,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,267 (ObjectListHarvestTask:adjustFilterWindow:370) 1. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Sep 19 16:16:37 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Sep 19 16:16:37 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,267 (ObjectListHarvestTask:adjustFilterWindow:370) 2. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Fri May 12 02:08:23 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Fri May 12 02:08:23 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,268 (ObjectListHarvestTask:adjustFilterWindow:370) 3. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Mar 07 19:04:17 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Mar 07 19:04:17 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,268 (ObjectListHarvestTask:adjustFilterWindow:370) 4. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Fri Feb 03 03:32:13 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Fri Feb 03 03:32:13 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,269 (ObjectListHarvestTask:adjustFilterWindow:370) 5. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Jan 17 19:46:11 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Jan 17 19:46:11 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,270 (ObjectListHarvestTask:adjustFilterWindow:370) 6. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 09 15:53:11 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 09 15:53:11 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,270 (ObjectListHarvestTask:adjustFilterWindow:370) 7. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Thu Jan 05 13:56:40 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Thu Jan 05 13:56:40 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,271 (ObjectListHarvestTask:adjustFilterWindow:370) 8. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Jan 03 12:58:25 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Jan 03 12:58:25 UTC 2017,0,0) returning 0 out of 3722 objectInfos
[DEBUG] 2019-11-12 05:06:10,271 (ObjectListHarvestTask:adjustFilterWindow:370) 9. total = 3722, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 02 12:29:17 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,0,0) returning 0 out of 2970 objectInfos
[DEBUG] 2019-11-12 05:06:10,272 (ObjectListHarvestTask:adjustFilterWindow:370) 10. total = 2970, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 02 00:14:43 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 00:14:43 UTC 2017,0,0) returning 0 out of 2235 objectInfos
[DEBUG] 2019-11-12 05:06:10,273 (ObjectListHarvestTask:adjustFilterWindow:370) 11. total = 2235, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Sun Jan 01 18:07:27 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Sun Jan 01 18:07:27 UTC 2017,0,0) returning 0 out of 1868 objectInfos
[ INFO] 2019-11-12 05:06:10,273 (ObjectListHarvestTask:adjustFilterWindow:384) urn:node:HARVEST_TEST - final time window: total = 1868, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Sun Jan 01 18:07:27 UTC 2017]
[ INFO] 2019-11-12 05:06:10,273 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Sun Jan 01 18:07:27 UTC 2017,0,500) returning 500 out of 1868 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Sun Jan 01 18:07:27 UTC 2017,500,500) returning 500 out of 1868 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Sun Jan 01 18:07:27 UTC 2017,1000,500) returning 500 out of 1868 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Sun Jan 01 18:07:27 UTC 2017,1500,500) returning 368 out of 1868 objectInfos
[ INFO] 2019-11-12 05:06:10,278 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@773dab28 added queue named 'urn:node:HARVEST_TEST' to the queue round robin
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 12:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,278 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 12:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 13:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,279 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 13:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 14:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,279 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 14:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:19:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,279 (ObjectListHarvestTask:spoolToSynchronizationQueue:241) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:19:10 UTC 2017 ***end of harvest***
[DEBUG] 2019-11-12 05:06:10,279 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T15:19:10.263+00:00 and endDate of 2019-11-12T05:05:50.262+00:00
listObjects(Sun Jan 01 15:19:10 UTC 2017,Tue Nov 12 05:05:50 UTC 2019,0,0) returning 0 out of 3522 objectInfos
[ INFO] 2019-11-12 05:06:10,280 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 3522 pids to harvest.
[DEBUG] 2019-11-12 05:06:10,280 (ObjectListHarvestTask:adjustFilterWindow:370) 0. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Thu Jun 07 22:12:30 UTC 2018]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Thu Jun 07 22:12:30 UTC 2018,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,280 (ObjectListHarvestTask:adjustFilterWindow:370) 1. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Tue Sep 19 18:45:50 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Tue Sep 19 18:45:50 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,281 (ObjectListHarvestTask:adjustFilterWindow:370) 2. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Fri May 12 05:02:30 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Fri May 12 05:02:30 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,281 (ObjectListHarvestTask:adjustFilterWindow:370) 3. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Tue Mar 07 22:10:50 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Tue Mar 07 22:10:50 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,281 (ObjectListHarvestTask:adjustFilterWindow:370) 4. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Fri Feb 03 06:45:00 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Fri Feb 03 06:45:00 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,282 (ObjectListHarvestTask:adjustFilterWindow:370) 5. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Tue Jan 17 23:02:05 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Tue Jan 17 23:02:05 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,282 (ObjectListHarvestTask:adjustFilterWindow:370) 6. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Mon Jan 09 19:10:37 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Mon Jan 09 19:10:37 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,282 (ObjectListHarvestTask:adjustFilterWindow:370) 7. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Thu Jan 05 17:14:54 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Thu Jan 05 17:14:54 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,283 (ObjectListHarvestTask:adjustFilterWindow:370) 8. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Tue Jan 03 16:17:02 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Tue Jan 03 16:17:02 UTC 2017,0,0) returning 0 out of 3522 objectInfos
[DEBUG] 2019-11-12 05:06:10,283 (ObjectListHarvestTask:adjustFilterWindow:370) 9. total = 3522, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Mon Jan 02 15:48:06 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Mon Jan 02 15:48:06 UTC 2017,0,0) returning 0 out of 2968 objectInfos
[DEBUG] 2019-11-12 05:06:10,283 (ObjectListHarvestTask:adjustFilterWindow:370) 10. total = 2968, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Mon Jan 02 03:33:38 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Mon Jan 02 03:33:38 UTC 2017,0,0) returning 0 out of 2234 objectInfos
[DEBUG] 2019-11-12 05:06:10,284 (ObjectListHarvestTask:adjustFilterWindow:370) 11. total = 2234, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Sun Jan 01 21:26:24 UTC 2017]
listObjects(Sun Jan 01 15:19:10 UTC 2017,Sun Jan 01 21:26:24 UTC 2017,0,0) returning 0 out of 1867 objectInfos
[ INFO] 2019-11-12 05:06:10,284 (ObjectListHarvestTask:adjustFilterWindow:384) urn:node:HARVEST_TEST - final time window: total = 1867, max = 1000: adjustingFilterWindow: [Sun Jan 01 15:19:10 UTC 2017 to Sun Jan 01 21:26:24 UTC 2017]
[ INFO] 2019-11-12 05:06:10,284 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Sun Jan 01 21:26:24 UTC 2017,0,500) returning 500 out of 1867 objectInfos
listObjects(Sun Jan 01 15:19:10 UTC 2017,Sun Jan 01 21:26:24 UTC 2017,500,500) returning 500 out of 1867 objectInfos
listObjects(Sun Jan 01 15:19:10 UTC 2017,Sun Jan 01 21:26:24 UTC 2017,1000,500) returning 500 out of 1867 objectInfos
listObjects(Sun Jan 01 15:19:10 UTC 2017,Sun Jan 01 21:26:24 UTC 2017,1500,500) returning 367 out of 1867 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,290 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,290 (ObjectListHarvestTask:spoolToSynchronizationQueue:241) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:20:10 UTC 2017 ***end of harvest***
[DEBUG] 2019-11-12 05:06:10,290 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T15:20:10.263+00:00 and endDate of 2019-11-12T05:05:40.262+00:00
listObjects(Sun Jan 01 15:20:10 UTC 2017,Tue Nov 12 05:05:40 UTC 2019,0,0) returning 0 out of 2021 objectInfos
[ INFO] 2019-11-12 05:06:10,290 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 2021 pids to harvest.
[ INFO] 2019-11-12 05:06:10,290 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Tue Nov 12 05:05:40 UTC 2019,0,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:10 UTC 2017,Tue Nov 12 05:05:40 UTC 2019,500,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:10 UTC 2017,Tue Nov 12 05:05:40 UTC 2019,1000,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:10 UTC 2017,Tue Nov 12 05:05:40 UTC 2019,1500,500) returning 500 out of 2021 objectInfos
listObjects(Sun Jan 01 15:20:10 UTC 2017,Tue Nov 12 05:05:40 UTC 2019,2000,500) returning 21 out of 2021 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 16:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,293 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 16:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 17:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,293 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 17:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 18:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,293 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 18:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 19:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,294 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 19:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 20:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,294 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 20:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 21:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,294 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 21:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 22:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,294 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 22:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 23:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,295 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 23:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 00:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,295 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 00:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 01:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,295 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 01:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 02:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,295 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 02:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 03:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,296 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 03:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 04:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,296 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 04:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 05:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,296 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 05:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 06:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,296 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 06:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 07:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,296 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 07:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 08:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,297 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 08:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 09:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,297 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 09:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 10:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,297 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 10:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 11:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,297 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 11:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 12:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,298 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 12:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 13:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,298 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 13:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 14:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,298 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 14:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 15:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,298 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 15:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 16:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,299 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 16:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 17:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,299 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 17:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 18:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,299 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 18:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 19:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,299 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 19:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 20:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,300 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 20:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 21:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,300 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 21:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 22:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,300 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 22:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 23:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,300 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 23:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 00:20:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,301 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 00:20:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 01:01:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,301 (ObjectListHarvestTask:spoolToSynchronizationQueue:241) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 01:01:10 UTC 2017 ***end of harvest***
[ INFO] 2019-11-12 05:06:10,301 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@226b143b Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,301 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@226b143b Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,302 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@226b143b added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,302 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@226b143b added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,303 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:10.203+00:00 and endDate of 2019-11-12T05:06:00.302+00:00
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,0) returning 0 out of 2222 objectInfos
[ INFO] 2019-11-12 05:06:10,303 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 2222 pids to harvest.
[DEBUG] 2019-11-12 05:06:10,303 (ObjectListHarvestTask:adjustFilterWindow:370) 0. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Thu Jun 07 20:33:05 UTC 2018]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Thu Jun 07 20:33:05 UTC 2018,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,304 (ObjectListHarvestTask:adjustFilterWindow:370) 1. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Sep 19 16:16:37 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Sep 19 16:16:37 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,304 (ObjectListHarvestTask:adjustFilterWindow:370) 2. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Fri May 12 02:08:23 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Fri May 12 02:08:23 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,304 (ObjectListHarvestTask:adjustFilterWindow:370) 3. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Mar 07 19:04:17 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Mar 07 19:04:17 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,304 (ObjectListHarvestTask:adjustFilterWindow:370) 4. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Fri Feb 03 03:32:13 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Fri Feb 03 03:32:13 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,305 (ObjectListHarvestTask:adjustFilterWindow:370) 5. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Jan 17 19:46:11 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Jan 17 19:46:11 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,305 (ObjectListHarvestTask:adjustFilterWindow:370) 6. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 09 15:53:11 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 09 15:53:11 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,305 (ObjectListHarvestTask:adjustFilterWindow:370) 7. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Thu Jan 05 13:56:40 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Thu Jan 05 13:56:40 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,305 (ObjectListHarvestTask:adjustFilterWindow:370) 8. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Jan 03 12:58:25 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Jan 03 12:58:25 UTC 2017,0,0) returning 0 out of 2222 objectInfos
[DEBUG] 2019-11-12 05:06:10,306 (ObjectListHarvestTask:adjustFilterWindow:370) 9. total = 2222, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 02 12:29:17 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,0,0) returning 0 out of 1470 objectInfos
[ INFO] 2019-11-12 05:06:10,306 (ObjectListHarvestTask:adjustFilterWindow:384) urn:node:HARVEST_TEST - final time window: total = 1470, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 02 12:29:17 UTC 2017]
[ INFO] 2019-11-12 05:06:10,306 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,0,500) returning 500 out of 1470 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,500,500) returning 500 out of 1470 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,1000,500) returning 470 out of 1470 objectInfos
[ INFO] 2019-11-12 05:06:10,307 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@226b143b added queue named 'urn:node:HARVEST_TEST' to the queue round robin
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 12:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,308 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 12:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 13:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,308 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 13:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 14:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,308 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 14:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,309 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 16:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,309 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 16:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 17:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,309 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 17:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 18:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,309 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 18:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 19:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,309 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 19:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 20:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,310 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 20:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 21:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,310 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 21:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 22:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,310 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 22:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 23:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,310 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 23:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 00:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,311 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 00:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 01:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,311 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 01:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 02:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,311 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 02:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 03:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,311 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 03:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 04:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,312 (ObjectListHarvestTask:spoolToSynchronizationQueue:241) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 04:39:10 UTC 2017 ***end of harvest***
[DEBUG] 2019-11-12 05:06:10,312 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-02T04:39:10.303+00:00 and endDate of 2019-11-12T05:05:50.302+00:00
listObjects(Mon Jan 02 04:39:10 UTC 2017,Tue Nov 12 05:05:50 UTC 2019,0,0) returning 0 out of 1222 objectInfos
[ INFO] 2019-11-12 05:06:10,312 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 1222 pids to harvest.
[ INFO] 2019-11-12 05:06:10,312 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Tue Nov 12 05:05:50 UTC 2019,0,500) returning 500 out of 1222 objectInfos
listObjects(Mon Jan 02 04:39:10 UTC 2017,Tue Nov 12 05:05:50 UTC 2019,500,500) returning 500 out of 1222 objectInfos
listObjects(Mon Jan 02 04:39:10 UTC 2017,Tue Nov 12 05:05:50 UTC 2019,1000,500) returning 222 out of 1222 objectInfos
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 05:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,313 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 05:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 06:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,314 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 06:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 07:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,314 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 07:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 08:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,314 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 08:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 09:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,314 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 09:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 10:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,315 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 10:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 11:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,315 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 11:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 12:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,315 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 12:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 13:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,315 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 13:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 14:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,316 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 14:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 15:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,316 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 15:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 16:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,316 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 16:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 17:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,316 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 17:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 18:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,317 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 18:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 19:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,317 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 19:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 20:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,317 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 20:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 21:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,317 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 21:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 22:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,318 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 22:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 23:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,318 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 23:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 00:39:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,318 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 00:39:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 01:01:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,318 (ObjectListHarvestTask:spoolToSynchronizationQueue:241) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 01:01:10 UTC 2017 ***end of harvest***
[ INFO] 2019-11-12 05:06:10,318 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@682bd3c4 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,319 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@682bd3c4 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,319 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@682bd3c4 added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,319 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@682bd3c4 added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,320 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:10.220+00:00 and endDate of 2019-11-12T05:06:00.319+00:00
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2019-11-12 05:06:10,321 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[ INFO] 2019-11-12 05:06:10,321 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,1000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,1500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,2000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,2500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,3000,500) returning 333 out of 3333 objectInfos
[ INFO] 2019-11-12 05:06:10,324 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@682bd3c4 added queue named 'urn:node:HARVEST_TEST' to the queue round robin
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 12:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,325 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 12:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 13:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,325 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 13:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 14:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,325 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 14:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 15:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,325 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 15:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 16:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,326 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 16:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 17:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,326 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 17:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 18:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,326 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 18:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 19:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,326 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 19:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 20:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,326 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 20:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 21:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,327 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 21:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 22:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,327 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 22:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Sun Jan 01 23:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,327 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Sun Jan 01 23:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 00:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,327 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 00:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 01:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,328 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 01:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 02:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,328 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 02:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 03:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,328 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 03:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 04:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,328 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 04:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 05:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,329 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 05:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 06:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,329 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 06:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 07:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,329 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 07:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 08:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,329 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 08:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 09:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,329 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 09:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 10:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,330 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 10:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 11:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,330 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 11:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 12:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,330 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 12:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 13:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,330 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 13:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 14:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,331 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 14:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 15:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,331 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 15:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 16:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,331 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 16:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 17:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,331 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 17:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 18:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,331 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 18:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 19:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,332 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 19:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 20:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,332 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 20:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 21:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,332 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 21:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 22:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,333 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 22:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Mon Jan 02 23:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,333 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Mon Jan 02 23:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 00:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,333 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 00:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 01:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,333 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 01:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 02:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,334 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 02:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 03:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,334 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 03:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 04:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,334 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 04:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 05:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,334 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 05:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 06:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,335 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 06:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 07:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,335 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 07:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 08:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,335 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 08:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 09:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,336 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 09:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 10:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,336 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 10:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 11:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,336 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 11:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 12:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,337 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 12:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 13:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,337 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 13:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 14:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,337 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 14:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 15:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,337 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 15:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 16:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,338 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 16:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 17:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,338 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 17:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 18:59:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,338 (ObjectListHarvestTask:spoolToSynchronizationQueue:227) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 18:59:10 UTC 2017
NodeRegistrySyncService setting dateLastHarvested: Tue Jan 03 19:32:10 UTC 2017
[ INFO] 2019-11-12 05:06:10,338 (ObjectListHarvestTask:spoolToSynchronizationQueue:241) urn:node:HARVEST_TEST - updated lastHarvestedDate to Tue Jan 03 19:32:10 UTC 2017 ***end of harvest***
[ INFO] 2019-11-12 05:06:10,339 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@f2e4acf Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,339 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@f2e4acf Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,339 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@f2e4acf added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,339 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@f2e4acf added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,340 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-04T06:40:10.340+00:00 and endDate of 2019-11-12T05:06:00.339+00:00
listObjects(Wed Jan 04 06:40:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,0) returning 0 out of 0 objectInfos
[ INFO] 2019-11-12 05:06:10,341 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 0 pids to harvest.
[ INFO] 2019-11-12 05:06:10,341 (ObjectListHarvestTask:getFullObjectList:311) urn:node:HARVEST_TEST- adjusting harvest toDate to limit the total for paged harvest [to between max and 2*max]
[ INFO] 2019-11-12 05:06:10,341 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24097e9b Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,341 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24097e9b Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,341 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24097e9b added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,342 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24097e9b added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,344 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:10.243+00:00 and endDate of 2019-11-12T05:06:00.342+00:00
[ WARN] 2019-11-12 05:06:10,345 (ObjectListHarvestTask:getFullObjectList:318) 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>
[ INFO] 2019-11-12 05:06:10,346 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@77a281fc Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,346 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@77a281fc Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,346 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@77a281fc added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,346 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@77a281fc added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,348 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T11:43:30.347+00:00 and endDate of 2019-11-12T05:06:00.346+00:00
[ WARN] 2019-11-12 05:06:10,348 (ObjectListHarvestTask:getFullObjectList:318) 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:43:30 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,null) returning 3333 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,353 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T11:43:30.347+00:00 and endDate of 2019-11-12T05:05:50.346+00:00
[ WARN] 2019-11-12 05:06:10,354 (ObjectListHarvestTask:getFullObjectList:318) 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:43:30 UTC 2017,Tue Nov 12 05:05:50 UTC 2019,0,null) returning 3333 out of 3333 objectInfos
[ INFO] 2019-11-12 05:06:10,356 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5ac7aa18 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,356 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5ac7aa18 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,356 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5ac7aa18 added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,356 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5ac7aa18 added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,357 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T11:43:30.357+00:00 and endDate of 2019-11-12T05:06:00.356+00:00
[ WARN] 2019-11-12 05:06:10,358 (ObjectListHarvestTask:getFullObjectList:318) 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:43:30 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,null) returning 1000 out of 3333 objectInfos
[ERROR] 2019-11-12 05:06:10,359 (ObjectListHarvestTask:doAllInOneHarvest:478) urn:node:HARVEST_TEST - MemberNode does not support paging, but also doesn't return the total list within the harvest period. Cannot harvest!
[ INFO] 2019-11-12 05:06:10,360 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5e39850 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,360 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5e39850 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,360 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5e39850 added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,360 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@5e39850 added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,362 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:10.261+00:00 and endDate of 2019-11-12T05:06:00.360+00:00
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2019-11-12 05:06:10,362 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[ INFO] 2019-11-12 05:06:10,363 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,100) returning 100 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,100,100) returning 100 out of 3333 objectInfos
[ INFO] 2019-11-12 05:06:10,363 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@70abf9b0 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,364 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@70abf9b0 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,364 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@70abf9b0 added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,364 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@70abf9b0 added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,365 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-04T06:40:10.365+00:00 and endDate of 2019-11-12T05:06:00.364+00:00
[ WARN] 2019-11-12 05:06:10,366 (ObjectListHarvestTask:getFullObjectList:318) 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:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,null) returning 0 out of 0 objectInfos
[ INFO] 2019-11-12 05:06:10,367 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@72fe8a4f Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,367 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@72fe8a4f Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,367 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@72fe8a4f added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,367 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@72fe8a4f added 'legacy' queue to its queue round-robin. size: 0
[DEBUG] 2019-11-12 05:06:10,368 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:10.268+00:00 and endDate of 2019-11-12T05:06:00.367+00:00
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2019-11-12 05:06:10,369 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[ INFO] 2019-11-12 05:06:10,369 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,0,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,1000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,1500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,2000,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,2500,500) returning 500 out of 3333 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:06:00 UTC 2019,3000,500) returning 333 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,373 (ObjectListHarvestTask:getFullObjectList:288) urn:node:HARVEST_TEST- starting retrieval theBaseUrl with startDate of 2017-01-01T12:00:10.268+00:00 and endDate of 2019-11-12T05:05:50.367+00:00
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Nov 12 05:05:50 UTC 2019,0,0) returning 0 out of 3333 objectInfos
[ INFO] 2019-11-12 05:06:10,373 (ObjectListHarvestTask:getFullObjectList:308) urn:node:HARVEST_TEST- has 3333 pids to harvest.
[DEBUG] 2019-11-12 05:06:10,373 (ObjectListHarvestTask:adjustFilterWindow:370) 0. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Thu Jun 07 20:33:00 UTC 2018]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Thu Jun 07 20:33:00 UTC 2018,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,374 (ObjectListHarvestTask:adjustFilterWindow:370) 1. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Sep 19 16:16:35 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Sep 19 16:16:35 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,374 (ObjectListHarvestTask:adjustFilterWindow:370) 2. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Fri May 12 02:08:22 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Fri May 12 02:08:22 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,374 (ObjectListHarvestTask:adjustFilterWindow:370) 3. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Mar 07 19:04:16 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Mar 07 19:04:16 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,375 (ObjectListHarvestTask:adjustFilterWindow:370) 4. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Fri Feb 03 03:32:13 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Fri Feb 03 03:32:13 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,375 (ObjectListHarvestTask:adjustFilterWindow:370) 5. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Jan 17 19:46:11 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Jan 17 19:46:11 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,375 (ObjectListHarvestTask:adjustFilterWindow:370) 6. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 09 15:53:11 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 09 15:53:11 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,375 (ObjectListHarvestTask:adjustFilterWindow:370) 7. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Thu Jan 05 13:56:40 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Thu Jan 05 13:56:40 UTC 2017,0,0) returning 0 out of 3333 objectInfos
[DEBUG] 2019-11-12 05:06:10,376 (ObjectListHarvestTask:adjustFilterWindow:370) 8. total = 3333, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Tue Jan 03 12:58:25 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Tue Jan 03 12:58:25 UTC 2017,0,0) returning 0 out of 2939 objectInfos
[DEBUG] 2019-11-12 05:06:10,376 (ObjectListHarvestTask:adjustFilterWindow:370) 9. total = 2939, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 02 12:29:17 UTC 2017]
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,0,0) returning 0 out of 1470 objectInfos
[ INFO] 2019-11-12 05:06:10,376 (ObjectListHarvestTask:adjustFilterWindow:384) urn:node:HARVEST_TEST - final time window: total = 1470, max = 1000: adjustingFilterWindow: [Sun Jan 01 12:00:10 UTC 2017 to Mon Jan 02 12:29:17 UTC 2017]
[ INFO] 2019-11-12 05:06:10,376 (ObjectListHarvestTask:getFullObjectList:311) 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:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,0,500) returning 500 out of 1470 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,500,500) returning 500 out of 1470 objectInfos
listObjects(Sun Jan 01 12:00:10 UTC 2017,Mon Jan 02 12:29:17 UTC 2017,1000,500) returning 470 out of 1470 objectInfos
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.12 sec
Running org.dataone.cn.batch.synchronization.D1TypeUtilsTest
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.016 sec
Running org.dataone.cn.batch.synchronization.type.SyncQueueFacadeTest
[ INFO] 2019-11-12 05:06:10,405 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24fabd0f Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,406 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24fabd0f Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:10,406 (SyncQueueFacade:<init>:143) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24fabd0f added 'urn:node:TestTest' to its queue round-robin. size: 30
[ INFO] 2019-11-12 05:06:10,406 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24fabd0f added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:10,406 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@24fabd0f added 'legacy' queue to its queue round-robin. size: 20
iiiiii 1
xxxxxx101
iiiiii 2
xxxxxx102
iiiiii 3
xxxxxx103
iiiiii 4
xxxxxx104
iiiiii 5
xxxxxx105
iiiiii 6
xxxxxx106
iiiiii 7
xxxxxx107
iiiiii 8
xxxxxx108
iiiiii 9
xxxxxx109
iiiiii10
xxxxxx110
iiiiii11
xxxxxx111
iiiiii12
xxxxxx112
iiiiii13
xxxxxx113
iiiiii14
xxxxxx114
iiiiii15
xxxxxx115
iiiiii16
xxxxxx116
iiiiii17
xxxxxx117
iiiiii18
xxxxxx118
iiiiii19
xxxxxx119
iiiiii20
xxxxxx120
iiiiii21
iiiiii22
iiiiii23
iiiiii24
iiiiii25
iiiiii26
iiiiii27
iiiiii28
iiiiii29
iiiiii30
throwing exception: org.dataone.cn.batch.exceptions.RetryableException
[ INFO] 2019-11-12 05:06:11,759 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@250b236d Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:11,759 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@250b236d Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:11,759 (SyncQueueFacade:<init>:143) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@250b236d added 'urn:node:TestTest' to its queue round-robin. size: 30
[ INFO] 2019-11-12 05:06:11,760 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@250b236d added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:11,760 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@250b236d added 'legacy' queue to its queue round-robin. size: 20
iiiiii 1
xxxxxx101
iiiiii 2
xxxxxx102
iiiiii 3
xxxxxx103
iiiiii 4
xxxxxx104
iiiiii 5
xxxxxx105
iiiiii 6
xxxxxx106
iiiiii 7
xxxxxx107
iiiiii 8
xxxxxx108
iiiiii 9
xxxxxx109
iiiiii10
xxxxxx110
iiiiii11
xxxxxx111
iiiiii12
xxxxxx112
iiiiii13
xxxxxx113
iiiiii14
xxxxxx114
iiiiii15
xxxxxx115
iiiiii16
xxxxxx116
iiiiii17
xxxxxx117
iiiiii18
xxxxxx118
iiiiii19
xxxxxx119
iiiiii20
xxxxxx120
iiiiii21
iiiiii22
iiiiii23
iiiiii24
iiiiii25
iiiiii26
iiiiii27
iiiiii28
iiiiii29
iiiiii30
[ INFO] 2019-11-12 05:06:11,853 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@61f3fbb8 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:11,853 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@61f3fbb8 Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:11,853 (SyncQueueFacade:<init>:143) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@61f3fbb8 added 'urn:node:TestTest' to its queue round-robin. size: 30
[ INFO] 2019-11-12 05:06:11,853 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@61f3fbb8 added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:11,853 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@61f3fbb8 added 'legacy' queue to its queue round-robin. size: 20
urn:node:TestTest iiiiii 1
default xxxxxx101
urn:node:TestTest iiiiii 2
default xxxxxx102
urn:node:TestTest iiiiii 3
default xxxxxx103
urn:node:TestTest iiiiii 4
default xxxxxx104
urn:node:TestTest iiiiii 5
default xxxxxx105
urn:node:TestTest iiiiii 6
default xxxxxx106
urn:node:TestTest iiiiii 7
default xxxxxx107
urn:node:TestTest iiiiii 8
default xxxxxx108
urn:node:TestTest iiiiii 9
default xxxxxx109
urn:node:TestTest iiiiii10
default xxxxxx110
[ INFO] 2019-11-12 05:06:11,854 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@61f3fbb8 added queue named 'urn:node:YAMN' to the queue round robin
========= New objects added, new total is: 50
urn:node:TestTest iiiiii11
default xxxxxx111
urn:node:YAMN yyyyyy301
urn:node:TestTest iiiiii12
default xxxxxx112
urn:node:YAMN yyyyyy302
urn:node:TestTest iiiiii13
default xxxxxx113
urn:node:YAMN yyyyyy303
urn:node:TestTest iiiiii14
default xxxxxx114
urn:node:YAMN yyyyyy304
urn:node:TestTest iiiiii15
default xxxxxx115
urn:node:YAMN yyyyyy305
urn:node:TestTest iiiiii16
default xxxxxx116
urn:node:YAMN yyyyyy306
urn:node:TestTest iiiiii17
default xxxxxx117
urn:node:YAMN yyyyyy307
urn:node:TestTest iiiiii18
default xxxxxx118
urn:node:YAMN yyyyyy308
urn:node:TestTest iiiiii19
default xxxxxx119
urn:node:YAMN yyyyyy309
urn:node:TestTest iiiiii20
default xxxxxx120
urn:node:YAMN yyyyyy310
urn:node:TestTest iiiiii21
urn:node:YAMN yyyyyy311
urn:node:TestTest iiiiii22
urn:node:YAMN yyyyyy312
urn:node:TestTest iiiiii23
urn:node:YAMN yyyyyy313
urn:node:TestTest iiiiii24
urn:node:YAMN yyyyyy314
urn:node:TestTest iiiiii25
urn:node:YAMN yyyyyy315
urn:node:TestTest iiiiii26
urn:node:YAMN yyyyyy316
urn:node:TestTest iiiiii27
urn:node:YAMN yyyyyy317
urn:node:TestTest iiiiii28
urn:node:YAMN yyyyyy318
urn:node:TestTest iiiiii29
urn:node:YAMN yyyyyy319
urn:node:TestTest iiiiii30
urn:node:YAMN yyyyyy320
[ INFO] 2019-11-12 05:06:11,956 (SyncQueueFacade:<init>:130) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@7551da2a Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:11,956 (SyncQueueFacade:<init>:135) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@7551da2a Added listener to 'dataone.synchronization.queueMap'
[ INFO] 2019-11-12 05:06:11,956 (SyncQueueFacade:<init>:143) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@7551da2a added 'urn:node:TestTest' to its queue round-robin. size: 30
[ INFO] 2019-11-12 05:06:11,956 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@7551da2a added queue named 'legacy' to the queue round robin
[ INFO] 2019-11-12 05:06:11,956 (SyncQueueFacade:<init>:152) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@7551da2a added 'legacy' queue to its queue round-robin. size: 20
58: urn:node:TestTest iiiiii 1
57: default xxxxxx101
[ INFO] 2019-11-12 05:06:11,957 (SyncQueueFacade:entryAdded:361) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@7551da2a the queue named 'urn:node:TestTest' is already in the queue round robin
56: Return to queue with priority: urn:node:TestTest / iiiiii 2
55: default xxxxxx102
54: urn:node:TestTest iiiiii 2
53: default xxxxxx103
52: urn:node:TestTest iiiiii 3
51: default xxxxxx104
50: urn:node:TestTest iiiiii 4
[ INFO] 2019-11-12 05:06:11,957 (SyncQueueFacade:entryAdded:358) org.dataone.cn.batch.synchronization.type.SyncQueueFacade@7551da2a added queue named 'default' to the queue round robin
49: Return to queue with priority: default / xxxxxx105
48: urn:node:TestTest iiiiii 5
47: default xxxxxx106
46: default xxxxxx105
45: urn:node:TestTest iiiiii 6
44: default xxxxxx107
43: urn:node:TestTest iiiiii 7
42: Return to queue with priority: default / xxxxxx108
41: default xxxxxx108
40: urn:node:TestTest iiiiii 8
39: default xxxxxx109
38: urn:node:TestTest iiiiii 9
37: default xxxxxx110
36: urn:node:TestTest iiiiii10
35: Return to queue with priority: default / xxxxxx111
34: default xxxxxx111
33: urn:node:TestTest iiiiii11
32: default xxxxxx112
31: urn:node:TestTest iiiiii12
30: default xxxxxx113
29: urn:node:TestTest iiiiii13
28: Return to queue with priority: default / xxxxxx114
27: default xxxxxx114
26: urn:node:TestTest iiiiii14
25: default xxxxxx115
24: urn:node:TestTest iiiiii15
23: default xxxxxx116
22: urn:node:TestTest iiiiii16
21: Return to queue with priority: default / xxxxxx117
20: default xxxxxx117
19: urn:node:TestTest iiiiii17
18: default xxxxxx118
17: urn:node:TestTest iiiiii18
16: default xxxxxx119
15: urn:node:TestTest iiiiii19
14: Return to queue with priority: default / xxxxxx120
13: default xxxxxx120
12: urn:node:TestTest iiiiii20
11: urn:node:TestTest iiiiii21
10: urn:node:TestTest iiiiii22
9: urn:node:TestTest iiiiii23
8: urn:node:TestTest iiiiii24
7: Return to queue with priority: urn:node:TestTest / iiiiii25
6: urn:node:TestTest iiiiii25
5: urn:node:TestTest iiiiii26
4: urn:node:TestTest iiiiii27
3: urn:node:TestTest iiiiii28
2: urn:node:TestTest iiiiii29
1: urn:node:TestTest iiiiii30
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.679 sec
Running org.dataone.cn.batch.synchronization.type.SystemMetadataValidatorTest
[ INFO] 2019-11-12 05:06:12,079 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,082 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,085 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,092 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,095 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,098 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,101 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,104 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,106 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,110 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,112 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,116 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
[ INFO] 2019-11-12 05:06:12,119 (SystemMetadataValidator:schemaValidateSystemMetadata:70) Entering schemaValidateSysMeta method...
Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.044 sec
Running org.dataone.cn.batch.synchronization.listener.SyncMetricLogJobTriggerTest
[ INFO] 2019-11-12 05:06:12,566 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
[ INFO] 2019-11-12 05:06:12,991 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
[ INFO] 2019-11-12 05:06:13,417 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
[ INFO] 2019-11-12 05:06:13,842 (SyncMetricLogJobTriggerTest:testLocking:45) java.util.concurrent.TimeoutException
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.02 sec
[ INFO] 2019-11-12 05:06:14,144 (AbstractApplicationContext:doClose:1032) Closing org.springframework.context.support.GenericApplicationContext@74a195a4: startup date [Tue Nov 12 05:05:36 UTC 2019]; root of context hierarchy
[ INFO] 2019-11-12 05:06:14,145 (DefaultSingletonBeanRegistry:destroySingletons:434) Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@6d367020: 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] 2019-11-12 05:06:14,152 (ExecutorConfigurationSupport:shutdown:150) Shutting down ExecutorService 'tinyThreadPoolTaskExecutor'
[ INFO] 2019-11-12 05:06:14,152 (ExecutorConfigurationSupport:shutdown:150) Shutting down ExecutorService 'threadPoolTaskExecutor'
Results :
Tests run: 70, Failures: 0, Errors: 0, Skipped: 4
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ d1_synchronization ---
[INFO] Building jar: /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/d1_synchronization-2.4.0-SNAPSHOT.jar
[INFO]
[INFO] --- buildnumber-maven-plugin:1.4:create (default) @ d1_synchronization ---
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization
[INFO] Storing buildNumber: null at timestamp: 1573535176346
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization
[INFO] Storing buildScmBranch: UNKNOWN_BRANCH
[INFO]
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ d1_synchronization ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 1 resource
[INFO] Copying 1 resource
[INFO]
[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ d1_synchronization ---
[INFO] Nothing to compile - all classes are up to date
[INFO]
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ d1_synchronization ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 18 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ d1_synchronization ---
[INFO] Nothing to compile - all classes are up to date
[INFO]
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ d1_synchronization ---
[INFO] Skipping execution of surefire because it has already been run for this configuration
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ d1_synchronization ---
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ d1_synchronization ---
[INFO] Installing /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/d1_synchronization-2.4.0-SNAPSHOT.jar to /var/lib/jenkins/.m2/repository/org/dataone/d1_synchronization/2.4.0-SNAPSHOT/d1_synchronization-2.4.0-SNAPSHOT.jar
[INFO] Installing /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/pom.xml to /var/lib/jenkins/.m2/repository/org/dataone/d1_synchronization/2.4.0-SNAPSHOT/d1_synchronization-2.4.0-SNAPSHOT.pom
[INFO]
[INFO] >>> maven-javadoc-plugin:2.10.4:javadoc (default-cli) > generate-sources @ d1_synchronization >>>
[INFO]
[INFO] --- buildnumber-maven-plugin:1.4:create (default) @ d1_synchronization ---
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization
[INFO] Storing buildNumber: null at timestamp: 1573535176909
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization
[INFO] Storing buildScmBranch: UNKNOWN_BRANCH
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO]
[INFO] <<< maven-javadoc-plugin:2.10.4:javadoc (default-cli) < generate-sources @ d1_synchronization <<<
[INFO]
[INFO]
[INFO] --- maven-javadoc-plugin:2.10.4:javadoc (default-cli) @ d1_synchronization ---
[INFO]
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.jobs...
Loading source files for package org.dataone.cn.batch.synchronization.type...
Loading source files for package org.dataone.cn.batch.synchronization.listener...
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.ldap...
Constructing Javadoc information...
Standard Doclet version 1.8.0_222
Building tree for all the packages and classes...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/D1TypeUtils.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/HarvestSchedulingManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/NodeCommFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/NodeCommObjectListHarvestFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/NodeCommSyncObjectFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/SyncObjectTaskManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/DelayWrapper.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/ObjectListHarvestTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/QueueProcessorCallable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SortedHarvestTimepointMap.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SyncFailedTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SyncMetricLogReport.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/SyncObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/V2TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/MemberNodeHarvestJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/SyncMetricLogJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/AbstractListenableMapAdapter.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/DistributedDataClient.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/DistributedDataClient.ListenableMap.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/NodeComm.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/NodeCommState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/SyncObjectState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/SyncQueueFacade.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/SystemMetadataValidator.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/NodeTopicListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/SyncMetricLogJobTriggerListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/NodeCommUnavailable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/RetryableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/UnrecoverableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/IdentifierReservationQueryService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/NodeRegistrySyncService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/NodeRegistrySyncServiceImpl.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/NodeRegistrySyncFacade.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/overview-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/constant-values.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/serialized-form.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/NodeCommFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/HarvestSchedulingManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/D1TypeUtils.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/NodeCommObjectListHarvestFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/SyncObjectTaskManager.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/class-use/NodeCommSyncObjectFactory.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/ObjectListHarvestTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/QueueProcessorCallable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SortedHarvestTimepointMap.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SyncFailedTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/DelayWrapper.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SyncObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/SyncMetricLogReport.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/class-use/V2TransferObjectTask.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/class-use/SyncMetricLogJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/class-use/MemberNodeHarvestJob.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/SyncObjectState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/NodeComm.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/NodeCommState.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/AbstractListenableMapAdapter.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/DistributedDataClient.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/DistributedDataClient.ListenableMap.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/SystemMetadataValidator.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/class-use/SyncQueueFacade.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/class-use/NodeTopicListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/class-use/SyncMetricLogJobTriggerListener.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/class-use/UnrecoverableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/class-use/NodeCommUnavailable.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/class-use/RetryableException.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/class-use/IdentifierReservationQueryService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/class-use/NodeRegistrySyncService.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/class-use/NodeRegistrySyncServiceImpl.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/ldap/class-use/NodeRegistrySyncFacade.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/exceptions/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/service/v2/impl/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/jobs/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/listener/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/tasks/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/org/dataone/cn/batch/synchronization/type/package-use.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/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/workspace/d1_synchronization/target/site/apidocs/overview-tree.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/index-all.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/deprecated-list.html...
Building index for all classes...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/allclasses-frame.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/allclasses-noframe.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/index.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/overview-summary.html...
Generating /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/target/site/apidocs/help-doc.html...
64 warnings
[WARNING] Javadoc Warnings
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:48: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:76: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:102: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:128: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:157: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:193: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:229: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:265: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:301: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:337: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:369: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:400: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:432: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:48: warning - Tag @see: reference not found: valueEquals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:76: warning - Tag @see: reference not found: valueEquals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:102: warning - Tag @see: reference not found: valueEquals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:128: warning - Tag @see: reference not found: valueEquals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:193: warning - Tag @see: reference not found: equals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:229: warning - Tag @see: reference not found: equals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:265: warning - Tag @see: reference not found: equals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:301: warning - Tag @see: reference not found: equals and emptyEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:337: warning - Tag @see: reference not found: equals and valueEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:369: warning - Tag @see: reference not found: equals and valueEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:400: warning - Tag @see: reference not found: equals and valueEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/D1TypeUtils.java:432: warning - Tag @see: reference not found: equals and valueEquals
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/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/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/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/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/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/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/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/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/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/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/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/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/ObjectListHarvestTask.java:264: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/tasks/ObjectListHarvestTask.java:120: warning - @returns is an unknown tag.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/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/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/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SyncQueueFacade.java:206: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SyncQueueFacade.java:249: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SyncQueueFacade.java:268: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SyncQueueFacade.java:297: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/workspace/d1_synchronization/src/main/java/org/dataone/cn/batch/synchronization/type/SyncQueueFacade.java:306: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_synchronization/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/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/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/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/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/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/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/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/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/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/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/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/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/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/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/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/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/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/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: 02:14 min
[INFO] Finished at: 2019-11-12T05:06:19Z
[INFO] ------------------------------------------------------------------------
Waiting for Jenkins to finish collecting data
![[Jenkins]](/jenkins/static/a3ee21c9/images/jenkins-header-logo-v2.svg){kind=logo}
Console Output