Project

General

Profile

improperly_escaped_PID_2017-06-01t.txt

Monica Ihli, 2017-06-06 17:18

Download (179 KB)

 
1
doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
2

    
3
TIME SEQUENCE DIGEST
4

    
5
generated: 2017-06-17 20:12:40
6
regex: doi:10.6067:XCV8WM1D1X_meta
7
generate walltime: 00:17:00
8
from: 2017-04-17 20:12:40
9
to: <unset>
10
total log files: 1669
11
used log files: 375
12
max records per type: 100
13
used records: 695
14
log types: 11
15

    
16

    
17
age="0d 1h 35m"  time="2017-06-17 18:37:15"  log="METACAT"  level="ERROR"
18

    
19
D1ResourceHandler: Serializing exception with code 404: No system metadata could be found for given PID:
20
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [edu.ucsb.nceas.metacat.restservice.D1ResourceHandler]
21
org.dataone.service.exceptions.NotFound: No system metadata could be found for given PID:
22
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
23
at edu.ucsb.nceas.metacat.dataone.D1NodeService.userHasPermission(D1NodeService.java:1214)
24
at edu.ucsb.nceas.metacat.dataone.D1NodeService.isAuthorized(D1NodeService.java:1153)
25
at edu.ucsb.nceas.metacat.dataone.D1NodeService.getSystemMetadata(D1NodeService.java:782)
26
at edu.ucsb.nceas.metacat.restservice.v2.CNResourceHandler.getSystemMetadataObject(CNResourceHandler.java:644)
27
at edu.ucsb.nceas.metacat.restservice.v2.CNResourceHandler.handle(CNResourceHandler.java:188)
28
at edu.ucsb.nceas.metacat.restservice.D1RestServlet.doGet(D1RestServlet.java:76)
29
at javax.servlet.http.HttpServlet.service(HttpServlet.java:620)
30
at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
31
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
32
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
33
at edu.ucsb.nceas.metacat.restservice.D1URLFilter.doFilter(D1URLFilter.java:48)
34
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
35
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
36
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
37
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
38
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
39
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
40
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
41
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
42
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
43
at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:193)
44
at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
45
at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:313)
46
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
47
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
48
at java.lang.Thread.run(Thread.java:745)
49

    
50
~~~~
51

    
52
age="0d 1h 35m"  time="2017-06-17 18:37:15"  log="METACAT"  level="WARN"
53

    
54
Could not check docid for revision: doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [edu.ucsb.nceas.metacat.EventLog]
55
edu.ucsb.nceas.metacat.McdbDocNotFoundException: the requested docid 'doi:10.6067:XCV8WM1D1X_meta$v=1356819351482' does
56
  not exist
57
at edu.ucsb.nceas.metacat.util.DocumentUtil.appendRev(DocumentUtil.java:328)
58
at edu.ucsb.nceas.metacat.EventLog.getReport(EventLog.java:288)
59
at edu.ucsb.nceas.metacat.EventLog.isDeleted(EventLog.java:395)
60
at edu.ucsb.nceas.metacat.dataone.D1NodeService.userHasPermission(D1NodeService.java:1211)
61
at edu.ucsb.nceas.metacat.dataone.D1NodeService.isAuthorized(D1NodeService.java:1153)
62
at edu.ucsb.nceas.metacat.dataone.D1NodeService.getSystemMetadata(D1NodeService.java:782)
63
at edu.ucsb.nceas.metacat.restservice.v2.CNResourceHandler.getSystemMetadataObject(CNResourceHandler.java:644)
64
at edu.ucsb.nceas.metacat.restservice.v2.CNResourceHandler.handle(CNResourceHandler.java:188)
65
at edu.ucsb.nceas.metacat.restservice.D1RestServlet.doGet(D1RestServlet.java:76)
66
at javax.servlet.http.HttpServlet.service(HttpServlet.java:620)
67
at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
68
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
69
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
70
at edu.ucsb.nceas.metacat.restservice.D1URLFilter.doFilter(D1URLFilter.java:48)
71
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
72
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
73
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
74
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
75
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
76
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
77
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
78
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
79
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
80
at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:193)
81
at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
82
at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:313)
83
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
84
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
85
at java.lang.Thread.run(Thread.java:745)
86

    
87
~~~~
88

    
89
age="0d 1h 35m"  time="2017-06-17 18:37:15"  log="METACAT"  level="WARN"
90

    
91
Couldn't find the local id for the pid doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
92
  [edu.ucsb.nceas.metacat.dataone.D1NodeService]
93

    
94
~~~~
95

    
96
age="0d 1h 35m"  time="2017-06-17 18:37:15"  log="METACAT"  level="WARN"
97

    
98
could not load system metadata for: doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
99
  [edu.ucsb.nceas.metacat.dataone.hazelcast.SystemMetadataMap]
100
edu.ucsb.nceas.metacat.McdbDocNotFoundException: Could not find doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
101
at edu.ucsb.nceas.metacat.IdentifierManager.getSystemMetadata(IdentifierManager.java:381)
102
at edu.ucsb.nceas.metacat.dataone.hazelcast.SystemMetadataMap.load(SystemMetadataMap.java:77)
103
at edu.ucsb.nceas.metacat.dataone.hazelcast.SystemMetadataMap.load(SystemMetadataMap.java:25)
104
at com.hazelcast.impl.concurrentmap.MapStoreWrapper.load(MapStoreWrapper.java:143)
105
at com.hazelcast.impl.ConcurrentMapManager$GetOperationHandler$GetLoader.doMapStoreOperation(ConcurrentMapManager.java:3
106
  785)
107
at com.hazelcast.impl.ConcurrentMapManager$AbstractMapStoreOperation.run(ConcurrentMapManager.java:3860)
108
at com.hazelcast.impl.executor.ParallelExecutorService$ParallelExecutorImpl$ExecutionSegment.run(ParallelExecutorService
109
  .java:212)
110
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
111
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
112
at java.lang.Thread.run(Thread.java:745)
113
at com.hazelcast.impl.ExecutorThreadFactory$1.run(ExecutorThreadFactory.java:38)
114

    
115
~~~~
116

    
117
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-PROCESS-DAEMON"  level="WARN"
118

    
119
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
120
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
121

    
122
~~~~
123

    
124
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-REPLICATION"  level="WARN"
125

    
126
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
127
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
128

    
129
~~~~
130

    
131
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="D1-PROCESSING-JSVC"  level="WARN"
132

    
133
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
134
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
135

    
136
~~~~
137

    
138
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-PROCESS-DAEMON"  level="INFO"
139

    
140
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
141
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
142

    
143
~~~~
144

    
145
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-REPLICATION"  level="INFO"
146

    
147
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
148
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
149

    
150
~~~~
151

    
152
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="D1-PROCESSING-JSVC"  level="INFO"
153

    
154
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
155
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
156

    
157
~~~~
158

    
159
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-PROCESS-DAEMON"  level="INFO"
160

    
161
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
162
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
163

    
164
~~~~
165

    
166
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-PROCESS-DAEMON"  level="DEBUG"
167

    
168
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
169
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
170

    
171
~~~~
172

    
173
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-PROCESS-DAEMON"  level="DEBUG"
174

    
175
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
176
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
177

    
178
~~~~
179

    
180
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-PROCESS-DAEMON"  level="DEBUG"
181

    
182
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
183

    
184
~~~~
185

    
186
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-REPLICATION"  level="INFO"
187

    
188
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
189
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
190

    
191
~~~~
192

    
193
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-REPLICATION"  level="DEBUG"
194

    
195
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
196
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
197

    
198
~~~~
199

    
200
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-REPLICATION"  level="DEBUG"
201

    
202
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
203
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
204

    
205
~~~~
206

    
207
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="CN-REPLICATION"  level="DEBUG"
208

    
209
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
210

    
211
~~~~
212

    
213
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="D1-PROCESSING-JSVC"  level="INFO"
214

    
215
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
216
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
217

    
218
~~~~
219

    
220
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="D1-PROCESSING-JSVC"  level="DEBUG"
221

    
222
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
223
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
224

    
225
~~~~
226

    
227
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="D1-PROCESSING-JSVC"  level="DEBUG"
228

    
229
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
230
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
231

    
232
~~~~
233

    
234
age="2d 9h 12m"  time="2017-05-17 11:00:33"  log="D1-PROCESSING-JSVC"  level="DEBUG"
235

    
236
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
237

    
238
~~~~
239

    
240
age="4d 3h 4m"  time="2017-05-17 17:08:24"  log="D1-PROCESSING-JSVC"  level="WARN"
241

    
242
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
243
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
244

    
245
~~~~
246

    
247
age="4d 3h 4m"  time="2017-05-17 17:08:24"  log="D1-PROCESSING-JSVC"  level="INFO"
248

    
249
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
250
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
251

    
252
~~~~
253

    
254
age="4d 3h 4m"  time="2017-05-17 17:08:24"  log="D1-PROCESSING-JSVC"  level="INFO"
255

    
256
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
257
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
258

    
259
~~~~
260

    
261
age="4d 3h 4m"  time="2017-05-17 17:08:24"  log="D1-PROCESSING-JSVC"  level="DEBUG"
262

    
263
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
264
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
265

    
266
~~~~
267

    
268
age="4d 3h 4m"  time="2017-05-17 17:08:24"  log="D1-PROCESSING-JSVC"  level="DEBUG"
269

    
270
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
271
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
272

    
273
~~~~
274

    
275
age="4d 3h 4m"  time="2017-05-17 17:08:24"  log="D1-PROCESSING-JSVC"  level="DEBUG"
276

    
277
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
278

    
279
~~~~
280

    
281
age="5d 20h 56m"  time="2017-05-17 23:16:30"  log="D1-PROCESSING-JSVC"  level="WARN"
282

    
283
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
284
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
285

    
286
~~~~
287

    
288
age="5d 20h 56m"  time="2017-05-17 23:16:30"  log="D1-PROCESSING-JSVC"  level="INFO"
289

    
290
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
291
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
292

    
293
~~~~
294

    
295
age="5d 20h 56m"  time="2017-05-17 23:16:30"  log="D1-PROCESSING-JSVC"  level="INFO"
296

    
297
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
298
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
299

    
300
~~~~
301

    
302
age="5d 20h 56m"  time="2017-05-17 23:16:30"  log="D1-PROCESSING-JSVC"  level="DEBUG"
303

    
304
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
305
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
306

    
307
~~~~
308

    
309
age="5d 20h 56m"  time="2017-05-17 23:16:30"  log="D1-PROCESSING-JSVC"  level="DEBUG"
310

    
311
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
312
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
313

    
314
~~~~
315

    
316
age="5d 20h 56m"  time="2017-05-17 23:16:30"  log="D1-PROCESSING-JSVC"  level="DEBUG"
317

    
318
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
319

    
320
~~~~
321

    
322
age="7d 13h 0m"  time="2017-05-17 07:12:24"  log="D1-PROCESSING-JSVC"  level="WARN"
323

    
324
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
325
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
326

    
327
~~~~
328

    
329
age="7d 13h 0m"  time="2017-05-17 07:12:24"  log="D1-PROCESSING-JSVC"  level="INFO"
330

    
331
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
332
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
333

    
334
~~~~
335

    
336
age="7d 13h 0m"  time="2017-05-17 07:12:24"  log="D1-PROCESSING-JSVC"  level="INFO"
337

    
338
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
339
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
340

    
341
~~~~
342

    
343
age="7d 13h 0m"  time="2017-05-17 07:12:24"  log="D1-PROCESSING-JSVC"  level="DEBUG"
344

    
345
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
346
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
347

    
348
~~~~
349

    
350
age="7d 13h 0m"  time="2017-05-17 07:12:24"  log="D1-PROCESSING-JSVC"  level="DEBUG"
351

    
352
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
353
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
354

    
355
~~~~
356

    
357
age="7d 13h 0m"  time="2017-05-17 07:12:24"  log="D1-PROCESSING-JSVC"  level="DEBUG"
358

    
359
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
360

    
361
~~~~
362

    
363
age="9d 2h 52m"  time="2017-05-17 17:20:13"  log="D1-PROCESSING-JSVC"  level="WARN"
364

    
365
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
366
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
367

    
368
~~~~
369

    
370
age="9d 2h 52m"  time="2017-05-17 17:20:13"  log="D1-PROCESSING-JSVC"  level="INFO"
371

    
372
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
373
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
374

    
375
~~~~
376

    
377
age="9d 2h 52m"  time="2017-05-17 17:20:13"  log="D1-PROCESSING-JSVC"  level="INFO"
378

    
379
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
380
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
381

    
382
~~~~
383

    
384
age="9d 2h 52m"  time="2017-05-17 17:20:13"  log="D1-PROCESSING-JSVC"  level="DEBUG"
385

    
386
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
387
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
388

    
389
~~~~
390

    
391
age="9d 2h 52m"  time="2017-05-17 17:20:13"  log="D1-PROCESSING-JSVC"  level="DEBUG"
392

    
393
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
394
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
395

    
396
~~~~
397

    
398
age="9d 2h 52m"  time="2017-05-17 17:20:13"  log="D1-PROCESSING-JSVC"  level="DEBUG"
399

    
400
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
401

    
402
~~~~
403

    
404
age="10d 16h 12m"  time="2017-05-17 04:00:16"  log="D1-PROCESSING-JSVC"  level="WARN"
405

    
406
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
407
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
408

    
409
~~~~
410

    
411
age="10d 16h 12m"  time="2017-05-17 04:00:16"  log="D1-PROCESSING-JSVC"  level="INFO"
412

    
413
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
414
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
415

    
416
~~~~
417

    
418
age="10d 16h 12m"  time="2017-05-17 04:00:16"  log="D1-PROCESSING-JSVC"  level="INFO"
419

    
420
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
421
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
422

    
423
~~~~
424

    
425
age="10d 16h 12m"  time="2017-05-17 04:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
426

    
427
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
428
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
429

    
430
~~~~
431

    
432
age="10d 16h 12m"  time="2017-05-17 04:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
433

    
434
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
435
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
436

    
437
~~~~
438

    
439
age="10d 16h 12m"  time="2017-05-17 04:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
440

    
441
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
442

    
443
~~~~
444

    
445
age="12d 5h 26m"  time="2017-05-17 14:46:06"  log="D1-PROCESSING-JSVC"  level="WARN"
446

    
447
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
448
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
449

    
450
~~~~
451

    
452
age="12d 5h 26m"  time="2017-05-17 14:46:06"  log="D1-PROCESSING-JSVC"  level="INFO"
453

    
454
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
455
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
456

    
457
~~~~
458

    
459
age="12d 5h 26m"  time="2017-05-17 14:46:06"  log="D1-PROCESSING-JSVC"  level="INFO"
460

    
461
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
462
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
463

    
464
~~~~
465

    
466
age="12d 5h 26m"  time="2017-05-17 14:46:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
467

    
468
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
469
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
470

    
471
~~~~
472

    
473
age="12d 5h 26m"  time="2017-05-17 14:46:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
474

    
475
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
476
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
477

    
478
~~~~
479

    
480
age="12d 5h 26m"  time="2017-05-17 14:46:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
481

    
482
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
483

    
484
~~~~
485

    
486
age="13d 18h 28m"  time="2017-05-17 01:44:22"  log="D1-PROCESSING-JSVC"  level="WARN"
487

    
488
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
489
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
490

    
491
~~~~
492

    
493
age="13d 18h 28m"  time="2017-05-17 01:44:22"  log="D1-PROCESSING-JSVC"  level="INFO"
494

    
495
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
496
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
497

    
498
~~~~
499

    
500
age="13d 18h 28m"  time="2017-05-17 01:44:21"  log="D1-PROCESSING-JSVC"  level="INFO"
501

    
502
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
503
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
504

    
505
~~~~
506

    
507
age="13d 18h 28m"  time="2017-05-17 01:44:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
508

    
509
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
510
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
511

    
512
~~~~
513

    
514
age="13d 18h 28m"  time="2017-05-17 01:44:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
515

    
516
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
517
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
518

    
519
~~~~
520

    
521
age="13d 18h 28m"  time="2017-05-17 01:44:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
522

    
523
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
524

    
525
~~~~
526

    
527
age="14d 6h 49m"  time="2017-05-17 13:23:11"  log="HAZELCAST-STORAGE"  level="DEBUG"
528

    
529
(FactoryImpl$4:process:421) [128.111.54.80]:5701 [DataONE] Instance created ProxyKey {name='lock',
530
  key=doi:10.6067:XCV8WM1D1X_meta$v=1493667645608}
531

    
532
~~~~
533

    
534
age="15d 5h 32m"  time="2017-05-17 14:40:06"  log="D1-PROCESSING-JSVC"  level="WARN"
535

    
536
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
537
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
538

    
539
~~~~
540

    
541
age="15d 5h 32m"  time="2017-05-17 14:40:06"  log="D1-PROCESSING-JSVC"  level="INFO"
542

    
543
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
544
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
545

    
546
~~~~
547

    
548
age="15d 5h 32m"  time="2017-05-17 14:40:06"  log="D1-PROCESSING-JSVC"  level="INFO"
549

    
550
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
551
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
552

    
553
~~~~
554

    
555
age="15d 5h 32m"  time="2017-05-17 14:40:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
556

    
557
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
558
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
559

    
560
~~~~
561

    
562
age="15d 5h 32m"  time="2017-05-17 14:40:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
563

    
564
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
565
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
566

    
567
~~~~
568

    
569
age="15d 5h 32m"  time="2017-05-17 14:40:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
570

    
571
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
572

    
573
~~~~
574

    
575
age="16d 13h 6m"  time="2017-05-17 07:06:11"  log="D1-PROCESSING-JSVC"  level="WARN"
576

    
577
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
578
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
579

    
580
~~~~
581

    
582
age="16d 13h 6m"  time="2017-05-17 07:06:11"  log="D1-PROCESSING-JSVC"  level="INFO"
583

    
584
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
585
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
586

    
587
~~~~
588

    
589
age="16d 13h 6m"  time="2017-05-17 07:06:11"  log="D1-PROCESSING-JSVC"  level="INFO"
590

    
591
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
592
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
593

    
594
~~~~
595

    
596
age="16d 13h 6m"  time="2017-05-17 07:06:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
597

    
598
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
599
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
600

    
601
~~~~
602

    
603
age="16d 13h 6m"  time="2017-05-17 07:06:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
604

    
605
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
606
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
607

    
608
~~~~
609

    
610
age="16d 13h 6m"  time="2017-05-17 07:06:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
611

    
612
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
613

    
614
~~~~
615

    
616
age="17d 10h 58m"  time="2017-05-17 09:13:58"  log="D1-PROCESSING-JSVC"  level="WARN"
617

    
618
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
619
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
620

    
621
~~~~
622

    
623
age="17d 10h 58m"  time="2017-05-17 09:13:58"  log="D1-PROCESSING-JSVC"  level="INFO"
624

    
625
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
626
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
627

    
628
~~~~
629

    
630
age="17d 10h 58m"  time="2017-05-17 09:13:58"  log="D1-PROCESSING-JSVC"  level="INFO"
631

    
632
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
633
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
634

    
635
~~~~
636

    
637
age="17d 10h 58m"  time="2017-05-17 09:13:58"  log="D1-PROCESSING-JSVC"  level="DEBUG"
638

    
639
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
640
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
641

    
642
~~~~
643

    
644
age="17d 10h 58m"  time="2017-05-17 09:13:58"  log="D1-PROCESSING-JSVC"  level="DEBUG"
645

    
646
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
647
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
648

    
649
~~~~
650

    
651
age="17d 10h 58m"  time="2017-05-17 09:13:58"  log="D1-PROCESSING-JSVC"  level="DEBUG"
652

    
653
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
654

    
655
~~~~
656

    
657
age="18d 8h 26m"  time="2017-05-17 11:46:07"  log="D1-PROCESSING-JSVC"  level="WARN"
658

    
659
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
660
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
661

    
662
~~~~
663

    
664
age="18d 8h 26m"  time="2017-05-17 11:46:07"  log="D1-PROCESSING-JSVC"  level="INFO"
665

    
666
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
667
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
668

    
669
~~~~
670

    
671
age="18d 8h 26m"  time="2017-05-17 11:46:07"  log="D1-PROCESSING-JSVC"  level="INFO"
672

    
673
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
674
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
675

    
676
~~~~
677

    
678
age="18d 8h 26m"  time="2017-05-17 11:46:07"  log="D1-PROCESSING-JSVC"  level="DEBUG"
679

    
680
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
681
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
682

    
683
~~~~
684

    
685
age="18d 8h 26m"  time="2017-05-17 11:46:07"  log="D1-PROCESSING-JSVC"  level="DEBUG"
686

    
687
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
688
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
689

    
690
~~~~
691

    
692
age="18d 8h 26m"  time="2017-05-17 11:46:07"  log="D1-PROCESSING-JSVC"  level="DEBUG"
693

    
694
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
695

    
696
~~~~
697

    
698
age="19d 5h 8m"  time="2017-05-17 15:04:22"  log="D1-PROCESSING-JSVC"  level="WARN"
699

    
700
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
701
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
702

    
703
~~~~
704

    
705
age="19d 5h 8m"  time="2017-05-17 15:04:22"  log="D1-PROCESSING-JSVC"  level="INFO"
706

    
707
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
708
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
709

    
710
~~~~
711

    
712
age="19d 5h 8m"  time="2017-05-17 15:04:22"  log="D1-PROCESSING-JSVC"  level="INFO"
713

    
714
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
715
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
716

    
717
~~~~
718

    
719
age="19d 5h 8m"  time="2017-05-17 15:04:22"  log="D1-PROCESSING-JSVC"  level="DEBUG"
720

    
721
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
722
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
723

    
724
~~~~
725

    
726
age="19d 5h 8m"  time="2017-05-17 15:04:22"  log="D1-PROCESSING-JSVC"  level="DEBUG"
727

    
728
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
729
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
730

    
731
~~~~
732

    
733
age="19d 5h 8m"  time="2017-05-17 15:04:22"  log="D1-PROCESSING-JSVC"  level="DEBUG"
734

    
735
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
736

    
737
~~~~
738

    
739
age="20d 1h 10m"  time="2017-05-17 19:01:48"  log="D1-PROCESSING-JSVC"  level="WARN"
740

    
741
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
742
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
743

    
744
~~~~
745

    
746
age="20d 1h 10m"  time="2017-05-17 19:01:48"  log="D1-PROCESSING-JSVC"  level="INFO"
747

    
748
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
749
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
750

    
751
~~~~
752

    
753
age="20d 1h 10m"  time="2017-05-17 19:01:48"  log="D1-PROCESSING-JSVC"  level="INFO"
754

    
755
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
756
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
757

    
758
~~~~
759

    
760
age="20d 1h 10m"  time="2017-05-17 19:01:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
761

    
762
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
763
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
764

    
765
~~~~
766

    
767
age="20d 1h 10m"  time="2017-05-17 19:01:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
768

    
769
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
770
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
771

    
772
~~~~
773

    
774
age="20d 1h 10m"  time="2017-05-17 19:01:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
775

    
776
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
777

    
778
~~~~
779

    
780
age="20d 7h 6m"  time="2017-05-17 13:05:41"  log="CN-SYNCHRONIZATION"  level="DEBUG"
781

    
782
(SyncObjectTask:reapFutures:303) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Returned from the
783
  Future :(1):
784

    
785
~~~~
786

    
787
age="20d 7h 6m"  time="2017-05-17 13:05:41"  log="CN-SYNCHRONIZATION"  level="INFO"
788

    
789
(SyncObjectTask:reapFutures:297) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SyncObjectState: FAILED
790

    
791
~~~~
792

    
793
age="20d 7h 6m"  time="2017-05-17 13:05:41"  log="CN-SYNCHRONIZATION"  level="DEBUG"
794

    
795
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
796

    
797
~~~~
798

    
799
age="20d 7h 6m"  time="2017-05-17 13:05:41"  log="D1-PROCESSING-JSVC"  level="DEBUG"
800

    
801
(SyncObjectTask:reapFutures:303) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Returned from the
802
  Future :(1):
803

    
804
~~~~
805

    
806
age="20d 7h 6m"  time="2017-05-17 13:05:41"  log="D1-PROCESSING-JSVC"  level="INFO"
807

    
808
(SyncObjectTask:reapFutures:297) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SyncObjectState: FAILED
809

    
810
~~~~
811

    
812
age="20d 7h 6m"  time="2017-05-17 13:05:41"  log="D1-PROCESSING-JSVC"  level="DEBUG"
813

    
814
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
815

    
816
~~~~
817

    
818
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="INFO"
819

    
820
(V2TransferObjectTask:call:294) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  exiting with
821
  callState: FAILED
822

    
823
~~~~
824

    
825
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="ERROR"
826

    
827
(SyncFailedTask:submitSynchronizationFailed:139) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 <?xml
828
  version="1.0" encoding="UTF-8"?>
829
<error detailCode="-1" errorCode="500" name="ServiceFailure">
830
<description>415: Unsupported Media Type: parser for deserializing HTML not written yet.  Providing message body:
831
&lt;!DOCTYPE html&gt;&lt;html&gt;&lt;head&gt;&lt;title&gt;Apache Tomcat/8.0.32 (Ubuntu) - Error
832
  report&lt;/title&gt;&lt;style type="text/css"&gt;H1 {font-family:Tahoma,Arial,sans-serif;color:white;background-
833
  color:#525D76;font-size:22px;} H2 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-
834
  size:16px;} H3 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:14px;} BODY {font-
835
  family:Tahoma,Arial,sans-serif;color:black;background-color:white;} B {font-family:Tahoma,Arial,sans-serif;color:white
836
  ;background-color:#525D76;} P {font-family:Tahoma,Arial,sans-serif;background:white;color:black;font-size:12px;}A
837
  {color : black;}A.name {color : black;}.line {height: 1px; background-color: #525D76; border: none;}&lt;/style&gt;
838
  &lt;/head&gt;&lt;body&gt;&lt;h1&gt;HTTP Status 415 - Unsupported Media Type&lt;/h1&gt;&lt;div
839
  class="line"&gt;&lt;/div&gt;&lt;p&gt;&lt;b&gt;type&lt;/b&gt; Status
840
  report&lt;/p&gt;&lt;p&gt;&lt;b&gt;message&lt;/b&gt; &lt;u&gt;Unsupported Media
841
  Type&lt;/u&gt;&lt;/p&gt;&lt;p&gt;&lt;b&gt;description&lt;/b&gt; &lt;u&gt;The server refused this request because the
842
  request entity is in a format not supported by the requested resource for the requested
843
  method.&lt;/u&gt;&lt;/p&gt;&lt;hr class="line"&gt;&lt;h3&gt;Apache Tomcat/8.0.32
844
  (Ubuntu)&lt;/h3&gt;&lt;/body&gt;&lt;/html&gt;</description>
845
</error>
846

    
847

    
848
~~~~
849

    
850
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="INFO"
851

    
852
(V2TransferObjectTask:call:294) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  exiting with
853
  callState: FAILED
854

    
855
~~~~
856

    
857
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="ERROR"
858

    
859
(SyncFailedTask:submitSynchronizationFailed:139) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 <?xml
860
  version="1.0" encoding="UTF-8"?>
861
<error detailCode="-1" errorCode="500" name="ServiceFailure">
862
<description>415: Unsupported Media Type: parser for deserializing HTML not written yet.  Providing message body:
863
&lt;!DOCTYPE html&gt;&lt;html&gt;&lt;head&gt;&lt;title&gt;Apache Tomcat/8.0.32 (Ubuntu) - Error
864
  report&lt;/title&gt;&lt;style type="text/css"&gt;H1 {font-family:Tahoma,Arial,sans-serif;color:white;background-
865
  color:#525D76;font-size:22px;} H2 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-
866
  size:16px;} H3 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:14px;} BODY {font-
867
  family:Tahoma,Arial,sans-serif;color:black;background-color:white;} B {font-family:Tahoma,Arial,sans-serif;color:white
868
  ;background-color:#525D76;} P {font-family:Tahoma,Arial,sans-serif;background:white;color:black;font-size:12px;}A
869
  {color : black;}A.name {color : black;}.line {height: 1px; background-color: #525D76; border: none;}&lt;/style&gt;
870
  &lt;/head&gt;&lt;body&gt;&lt;h1&gt;HTTP Status 415 - Unsupported Media Type&lt;/h1&gt;&lt;div
871
  class="line"&gt;&lt;/div&gt;&lt;p&gt;&lt;b&gt;type&lt;/b&gt; Status
872
  report&lt;/p&gt;&lt;p&gt;&lt;b&gt;message&lt;/b&gt; &lt;u&gt;Unsupported Media
873
  Type&lt;/u&gt;&lt;/p&gt;&lt;p&gt;&lt;b&gt;description&lt;/b&gt; &lt;u&gt;The server refused this request because the
874
  request entity is in a format not supported by the requested resource for the requested
875
  method.&lt;/u&gt;&lt;/p&gt;&lt;hr class="line"&gt;&lt;h3&gt;Apache Tomcat/8.0.32
876
  (Ubuntu)&lt;/h3&gt;&lt;/body&gt;&lt;/html&gt;</description>
877
</error>
878

    
879

    
880
~~~~
881

    
882
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="WARN"
883

    
884
(SyncFailedTask:submitSynchronizationFailed:116) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
885
  SynchronizationFailed: detail code: 6001 id:+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 nodeId:urn:node:CNUCSB1
886
  description:Synchronization task of [PID::] +doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause:
887
  NotFound: Not Found
888

    
889
~~~~
890

    
891
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="ERROR"
892

    
893
(V2TransferObjectTask:call:259) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - SynchronizationFailed:
894
  Synchronization task of [PID::] +doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause: NotFound: Not
895
  Found
896
org.dataone.service.exceptions.SynchronizationFailed: Synchronization task of [PID::]
897
  +doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause: NotFound: Not Found
898
at org.dataone.cn.batch.synchronization.tasks.SyncFailedTask.createSynchronizationFailed(SyncFailedTask.java:97)
899
at
900
  org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.retrieveMNSystemMetadata(V2TransferObjectTask.java:334
901
  )
902
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:208)
903
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:113)
904
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
905
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
906
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
907
at java.lang.Thread.run(Thread.java:745)
908

    
909
~~~~
910

    
911
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="INFO"
912

    
913
(V2TransferObjectTask:call:234) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Unlocked Pid.
914

    
915
~~~~
916

    
917
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="WARN"
918

    
919
(SyncFailedTask:submitSynchronizationFailed:116) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
920
  SynchronizationFailed: detail code: 6001 id:+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 nodeId:urn:node:CNUCSB1
921
  description:Synchronization task of [PID::] +doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause:
922
  NotFound: Not Found
923

    
924
~~~~
925

    
926
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="ERROR"
927

    
928
(V2TransferObjectTask:call:259) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - SynchronizationFailed:
929
  Synchronization task of [PID::] +doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause: NotFound: Not
930
  Found
931
org.dataone.service.exceptions.SynchronizationFailed: Synchronization task of [PID::]
932
  +doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause: NotFound: Not Found
933
at org.dataone.cn.batch.synchronization.tasks.SyncFailedTask.createSynchronizationFailed(SyncFailedTask.java:97)
934
at
935
  org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.retrieveMNSystemMetadata(V2TransferObjectTask.java:334
936
  )
937
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:208)
938
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:113)
939
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
940
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
941
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
942
at java.lang.Thread.run(Thread.java:745)
943

    
944
~~~~
945

    
946
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="INFO"
947

    
948
(V2TransferObjectTask:call:234) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Unlocked Pid.
949

    
950
~~~~
951

    
952
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="ERROR"
953

    
954
(V2TransferObjectTask:retrieveMNSystemMetadata:333) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
955
  Not Found - NotFound - Not Found
956
org.dataone.service.exceptions.NotFound: Not Found
957
at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
958
at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
959
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
960
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
961
at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
962
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
963
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
964
at org.dataone.client.v2.impl.MultipartD1Node.getSystemMetadata(MultipartD1Node.java:94)
965
at org.dataone.client.v2.impl.MultipartMNode.getSystemMetadata(MultipartMNode.java:337)
966
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.getSystemMetadataHandleRetry(V2TransferObjectTask.jav
967
  a:365)
968
at
969
  org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.retrieveMNSystemMetadata(V2TransferObjectTask.java:316
970
  )
971
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:208)
972
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:113)
973
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
974
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
975
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
976
at java.lang.Thread.run(Thread.java:745)
977

    
978
~~~~
979

    
980
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="ERROR"
981

    
982
(V2TransferObjectTask:retrieveMNSystemMetadata:333) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
983
  Not Found - NotFound - Not Found
984
org.dataone.service.exceptions.NotFound: Not Found
985
at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
986
at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
987
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
988
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
989
at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
990
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
991
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
992
at org.dataone.client.v2.impl.MultipartD1Node.getSystemMetadata(MultipartD1Node.java:94)
993
at org.dataone.client.v2.impl.MultipartMNode.getSystemMetadata(MultipartMNode.java:337)
994
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.getSystemMetadataHandleRetry(V2TransferObjectTask.jav
995
  a:365)
996
at
997
  org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.retrieveMNSystemMetadata(V2TransferObjectTask.java:316
998
  )
999
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:208)
1000
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:113)
1001
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1002
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
1003
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
1004
at java.lang.Thread.run(Thread.java:745)
1005

    
1006
~~~~
1007

    
1008
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="ERROR"
1009

    
1010
(StdOutErrLog$2:print:51) 152067061 [SynchronizeTask2885] INFO org.dataone.client.rest.RestClient -
1011
  RestClient.doRequestNoBody, thread(31890) call Info: GET
1012
  https://dataone.tdar.org/dataone/v2/meta/%2Bdoi:10.6067:XCV8WM1D1X_meta$v=1356819351482
1013

    
1014

    
1015
~~~~
1016

    
1017
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="DEBUG"
1018

    
1019
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Waiting for the future
1020
  :(1): since 2017-05-12T13:05:39.474+00:00
1021

    
1022
~~~~
1023

    
1024
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1025

    
1026
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Waiting for the future
1027
  :(1): since 2017-05-12T13:05:39.474+00:00
1028

    
1029
~~~~
1030

    
1031
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="CN-SYNCHRONIZATION"  level="DEBUG"
1032

    
1033
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
1034

    
1035
~~~~
1036

    
1037
age="20d 7h 7m"  time="2017-05-17 13:05:40"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1038

    
1039
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
1040

    
1041
~~~~
1042

    
1043
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="CN-SYNCHRONIZATION"  level="INFO"
1044

    
1045
(V2TransferObjectTask:call:207) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Processing SyncObject
1046

    
1047
~~~~
1048

    
1049
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="D1-PROCESSING-JSVC"  level="INFO"
1050

    
1051
(V2TransferObjectTask:call:207) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Processing SyncObject
1052

    
1053
~~~~
1054

    
1055
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="CN-SYNCHRONIZATION"  level="INFO"
1056

    
1057
(V2TransferObjectTask:call:202) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Locking task, attempt
1058
  1
1059

    
1060
~~~~
1061

    
1062
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="D1-PROCESSING-JSVC"  level="INFO"
1063

    
1064
(V2TransferObjectTask:call:202) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Locking task, attempt
1065
  1
1066

    
1067
~~~~
1068

    
1069
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="CN-SYNCHRONIZATION"  level="INFO"
1070

    
1071
(SyncObjectTask:executeTransferObjectTask:236) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 submitted
1072
  for execution
1073

    
1074
~~~~
1075

    
1076
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="D1-PROCESSING-JSVC"  level="INFO"
1077

    
1078
(SyncObjectTask:executeTransferObjectTask:236) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 submitted
1079
  for execution
1080

    
1081
~~~~
1082

    
1083
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="CN-SYNCHRONIZATION"  level="INFO"
1084

    
1085
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
1086

    
1087
~~~~
1088

    
1089
age="20d 7h 7m"  time="2017-05-17 13:05:39"  log="D1-PROCESSING-JSVC"  level="INFO"
1090

    
1091
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-+doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
1092

    
1093
~~~~
1094

    
1095
age="20d 18h 58m"  time="2017-05-17 01:14:03"  log="D1-PROCESSING-JSVC"  level="WARN"
1096

    
1097
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1098
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1099

    
1100
~~~~
1101

    
1102
age="20d 18h 58m"  time="2017-05-17 01:14:03"  log="D1-PROCESSING-JSVC"  level="INFO"
1103

    
1104
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1105
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1106

    
1107
~~~~
1108

    
1109
age="20d 18h 58m"  time="2017-05-17 01:14:03"  log="D1-PROCESSING-JSVC"  level="INFO"
1110

    
1111
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1112
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1113

    
1114
~~~~
1115

    
1116
age="20d 18h 58m"  time="2017-05-17 01:14:03"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1117

    
1118
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1119
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1120

    
1121
~~~~
1122

    
1123
age="20d 18h 58m"  time="2017-05-17 01:14:03"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1124

    
1125
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1126
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1127

    
1128
~~~~
1129

    
1130
age="20d 18h 58m"  time="2017-05-17 01:14:03"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1131

    
1132
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1133

    
1134
~~~~
1135

    
1136
age="21d 11h 22m"  time="2017-05-17 08:49:54"  log="D1-PROCESSING-JSVC"  level="WARN"
1137

    
1138
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1139
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1140

    
1141
~~~~
1142

    
1143
age="21d 11h 22m"  time="2017-05-17 08:49:54"  log="D1-PROCESSING-JSVC"  level="INFO"
1144

    
1145
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1146
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1147

    
1148
~~~~
1149

    
1150
age="21d 11h 22m"  time="2017-05-17 08:49:54"  log="D1-PROCESSING-JSVC"  level="INFO"
1151

    
1152
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1153
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1154

    
1155
~~~~
1156

    
1157
age="21d 11h 22m"  time="2017-05-17 08:49:54"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1158

    
1159
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1160
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1161

    
1162
~~~~
1163

    
1164
age="21d 11h 22m"  time="2017-05-17 08:49:54"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1165

    
1166
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1167
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1168

    
1169
~~~~
1170

    
1171
age="21d 11h 22m"  time="2017-05-17 08:49:54"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1172

    
1173
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1174

    
1175
~~~~
1176

    
1177
age="21d 20h 40m"  time="2017-05-17 23:32:09"  log="D1-PROCESSING-JSVC"  level="INFO"
1178

    
1179
(ReplicationEventListener:entryUpdated:126) Received entry updated event on the hzSystemMetadata map for pid:
1180
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1181

    
1182
~~~~
1183

    
1184
age="22d 4h 1m"  time="2017-05-17 16:11:12"  log="D1-PROCESSING-JSVC"  level="WARN"
1185

    
1186
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1187
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1188

    
1189
~~~~
1190

    
1191
age="22d 4h 1m"  time="2017-05-17 16:11:12"  log="D1-PROCESSING-JSVC"  level="INFO"
1192

    
1193
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1194
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1195

    
1196
~~~~
1197

    
1198
age="22d 4h 1m"  time="2017-05-17 16:11:11"  log="D1-PROCESSING-JSVC"  level="INFO"
1199

    
1200
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1201
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1202

    
1203
~~~~
1204

    
1205
age="22d 4h 1m"  time="2017-05-17 16:11:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1206

    
1207
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1208
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1209

    
1210
~~~~
1211

    
1212
age="22d 4h 1m"  time="2017-05-17 16:11:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1213

    
1214
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1215
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1216

    
1217
~~~~
1218

    
1219
age="22d 4h 1m"  time="2017-05-17 16:11:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1220

    
1221
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1222

    
1223
~~~~
1224

    
1225
age="22d 19h 16m"  time="2017-05-17 00:56:11"  log="D1-PROCESSING-JSVC"  level="WARN"
1226

    
1227
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1228
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1229

    
1230
~~~~
1231

    
1232
age="22d 19h 16m"  time="2017-05-17 00:56:11"  log="D1-PROCESSING-JSVC"  level="INFO"
1233

    
1234
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1235
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1236

    
1237
~~~~
1238

    
1239
age="22d 19h 16m"  time="2017-05-17 00:56:11"  log="D1-PROCESSING-JSVC"  level="INFO"
1240

    
1241
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1242
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1243

    
1244
~~~~
1245

    
1246
age="22d 19h 16m"  time="2017-05-17 00:56:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1247

    
1248
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1249
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1250

    
1251
~~~~
1252

    
1253
age="22d 19h 16m"  time="2017-05-17 00:56:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1254

    
1255
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1256
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1257

    
1258
~~~~
1259

    
1260
age="22d 19h 16m"  time="2017-05-17 00:56:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1261

    
1262
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1263

    
1264
~~~~
1265

    
1266
age="23d 10h 24m"  time="2017-05-17 09:47:49"  log="D1-PROCESSING-JSVC"  level="WARN"
1267

    
1268
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1269
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1270

    
1271
~~~~
1272

    
1273
age="23d 10h 24m"  time="2017-05-17 09:47:49"  log="D1-PROCESSING-JSVC"  level="INFO"
1274

    
1275
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1276
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1277

    
1278
~~~~
1279

    
1280
age="23d 10h 24m"  time="2017-05-17 09:47:49"  log="D1-PROCESSING-JSVC"  level="INFO"
1281

    
1282
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1283
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1284

    
1285
~~~~
1286

    
1287
age="23d 10h 24m"  time="2017-05-17 09:47:49"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1288

    
1289
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1290
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1291

    
1292
~~~~
1293

    
1294
age="23d 10h 24m"  time="2017-05-17 09:47:49"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1295

    
1296
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1297
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1298

    
1299
~~~~
1300

    
1301
age="23d 10h 24m"  time="2017-05-17 09:47:49"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1302

    
1303
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1304

    
1305
~~~~
1306

    
1307
age="24d 1h 27m"  time="2017-05-17 18:45:31"  log="D1-PROCESSING-JSVC"  level="WARN"
1308

    
1309
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1310
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1311

    
1312
~~~~
1313

    
1314
age="24d 1h 27m"  time="2017-05-17 18:45:31"  log="D1-PROCESSING-JSVC"  level="INFO"
1315

    
1316
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1317
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1318

    
1319
~~~~
1320

    
1321
age="24d 1h 27m"  time="2017-05-17 18:45:31"  log="D1-PROCESSING-JSVC"  level="INFO"
1322

    
1323
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1324
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1325

    
1326
~~~~
1327

    
1328
age="24d 1h 27m"  time="2017-05-17 18:45:31"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1329

    
1330
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1331
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1332

    
1333
~~~~
1334

    
1335
age="24d 1h 27m"  time="2017-05-17 18:45:31"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1336

    
1337
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1338
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1339

    
1340
~~~~
1341

    
1342
age="24d 1h 27m"  time="2017-05-17 18:45:31"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1343

    
1344
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1345

    
1346
~~~~
1347

    
1348
age="24d 16h 25m"  time="2017-05-17 03:47:27"  log="D1-PROCESSING-JSVC"  level="WARN"
1349

    
1350
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1351
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1352

    
1353
~~~~
1354

    
1355
age="24d 16h 25m"  time="2017-05-17 03:47:27"  log="D1-PROCESSING-JSVC"  level="INFO"
1356

    
1357
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1358
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1359

    
1360
~~~~
1361

    
1362
age="24d 16h 25m"  time="2017-05-17 03:47:27"  log="D1-PROCESSING-JSVC"  level="INFO"
1363

    
1364
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1365
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1366

    
1367
~~~~
1368

    
1369
age="24d 16h 25m"  time="2017-05-17 03:47:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1370

    
1371
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1372
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1373

    
1374
~~~~
1375

    
1376
age="24d 16h 25m"  time="2017-05-17 03:47:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1377

    
1378
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1379
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1380

    
1381
~~~~
1382

    
1383
age="24d 16h 25m"  time="2017-05-17 03:47:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1384

    
1385
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1386

    
1387
~~~~
1388

    
1389
age="25d 7h 24m"  time="2017-05-17 12:47:42"  log="D1-PROCESSING-JSVC"  level="WARN"
1390

    
1391
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1392
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1393

    
1394
~~~~
1395

    
1396
age="25d 7h 24m"  time="2017-05-17 12:47:42"  log="D1-PROCESSING-JSVC"  level="INFO"
1397

    
1398
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1399
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1400

    
1401
~~~~
1402

    
1403
age="25d 7h 24m"  time="2017-05-17 12:47:42"  log="D1-PROCESSING-JSVC"  level="INFO"
1404

    
1405
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1406
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1407

    
1408
~~~~
1409

    
1410
age="25d 7h 24m"  time="2017-05-17 12:47:42"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1411

    
1412
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1413
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1414

    
1415
~~~~
1416

    
1417
age="25d 7h 24m"  time="2017-05-17 12:47:42"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1418

    
1419
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1420
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1421

    
1422
~~~~
1423

    
1424
age="25d 7h 24m"  time="2017-05-17 12:47:42"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1425

    
1426
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1427

    
1428
~~~~
1429

    
1430
age="25d 22h 19m"  time="2017-05-17 21:53:17"  log="D1-PROCESSING-JSVC"  level="WARN"
1431

    
1432
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1433
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1434

    
1435
~~~~
1436

    
1437
age="25d 22h 19m"  time="2017-05-17 21:53:17"  log="D1-PROCESSING-JSVC"  level="INFO"
1438

    
1439
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1440
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1441

    
1442
~~~~
1443

    
1444
age="25d 22h 19m"  time="2017-05-17 21:53:17"  log="D1-PROCESSING-JSVC"  level="INFO"
1445

    
1446
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1447
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1448

    
1449
~~~~
1450

    
1451
age="25d 22h 19m"  time="2017-05-17 21:53:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1452

    
1453
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1454
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1455

    
1456
~~~~
1457

    
1458
age="25d 22h 19m"  time="2017-05-17 21:53:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1459

    
1460
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1461
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1462

    
1463
~~~~
1464

    
1465
age="25d 22h 19m"  time="2017-05-17 21:53:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1466

    
1467
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1468

    
1469
~~~~
1470

    
1471
age="26d 13h 12m"  time="2017-05-17 06:59:46"  log="D1-PROCESSING-JSVC"  level="WARN"
1472

    
1473
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1474
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1475

    
1476
~~~~
1477

    
1478
age="26d 13h 12m"  time="2017-05-17 06:59:46"  log="D1-PROCESSING-JSVC"  level="INFO"
1479

    
1480
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1481
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1482

    
1483
~~~~
1484

    
1485
age="26d 13h 12m"  time="2017-05-17 06:59:46"  log="D1-PROCESSING-JSVC"  level="INFO"
1486

    
1487
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1488
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1489

    
1490
~~~~
1491

    
1492
age="26d 13h 12m"  time="2017-05-17 06:59:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1493

    
1494
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1495
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1496

    
1497
~~~~
1498

    
1499
age="26d 13h 12m"  time="2017-05-17 06:59:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1500

    
1501
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1502
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1503

    
1504
~~~~
1505

    
1506
age="26d 13h 12m"  time="2017-05-17 06:59:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1507

    
1508
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1509

    
1510
~~~~
1511

    
1512
age="27d 1h 28m"  time="2017-05-17 18:43:48"  log="D1-PROCESSING-JSVC"  level="WARN"
1513

    
1514
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1515
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1516

    
1517
~~~~
1518

    
1519
age="27d 1h 28m"  time="2017-05-17 18:43:48"  log="D1-PROCESSING-JSVC"  level="INFO"
1520

    
1521
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1522
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1523

    
1524
~~~~
1525

    
1526
age="27d 1h 28m"  time="2017-05-17 18:43:48"  log="D1-PROCESSING-JSVC"  level="INFO"
1527

    
1528
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1529
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1530

    
1531
~~~~
1532

    
1533
age="27d 1h 28m"  time="2017-05-17 18:43:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1534

    
1535
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1536
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1537

    
1538
~~~~
1539

    
1540
age="27d 1h 28m"  time="2017-05-17 18:43:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1541

    
1542
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1543
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1544

    
1545
~~~~
1546

    
1547
age="27d 1h 28m"  time="2017-05-17 18:43:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1548

    
1549
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1550

    
1551
~~~~
1552

    
1553
age="27d 9h 25m"  time="2017-05-17 10:47:35"  log="D1-PROCESSING-JSVC"  level="WARN"
1554

    
1555
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1556
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1557

    
1558
~~~~
1559

    
1560
age="27d 9h 25m"  time="2017-05-17 10:47:35"  log="D1-PROCESSING-JSVC"  level="INFO"
1561

    
1562
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1563
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1564

    
1565
~~~~
1566

    
1567
age="27d 9h 25m"  time="2017-05-17 10:47:35"  log="D1-PROCESSING-JSVC"  level="INFO"
1568

    
1569
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1570
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1571

    
1572
~~~~
1573

    
1574
age="27d 9h 25m"  time="2017-05-17 10:47:35"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1575

    
1576
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1577
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1578

    
1579
~~~~
1580

    
1581
age="27d 9h 25m"  time="2017-05-17 10:47:35"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1582

    
1583
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1584
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1585

    
1586
~~~~
1587

    
1588
age="27d 9h 25m"  time="2017-05-17 10:47:35"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1589

    
1590
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1591

    
1592
~~~~
1593

    
1594
age="27d 14h 29m"  time="2017-05-17 05:43:37"  log="D1-PROCESSING-JSVC"  level="WARN"
1595

    
1596
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1597
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1598

    
1599
~~~~
1600

    
1601
age="27d 14h 29m"  time="2017-05-17 05:43:37"  log="D1-PROCESSING-JSVC"  level="INFO"
1602

    
1603
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1604
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1605

    
1606
~~~~
1607

    
1608
age="27d 14h 29m"  time="2017-05-17 05:43:37"  log="D1-PROCESSING-JSVC"  level="INFO"
1609

    
1610
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1611
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1612

    
1613
~~~~
1614

    
1615
age="27d 14h 29m"  time="2017-05-17 05:43:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1616

    
1617
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1618
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1619

    
1620
~~~~
1621

    
1622
age="27d 14h 29m"  time="2017-05-17 05:43:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1623

    
1624
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1625
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1626

    
1627
~~~~
1628

    
1629
age="27d 14h 29m"  time="2017-05-17 05:43:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1630

    
1631
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1632

    
1633
~~~~
1634

    
1635
age="27d 17h 13m"  time="2017-05-17 02:59:34"  log="D1-PROCESSING-JSVC"  level="WARN"
1636

    
1637
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1638
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1639

    
1640
~~~~
1641

    
1642
age="27d 17h 13m"  time="2017-05-17 02:59:34"  log="D1-PROCESSING-JSVC"  level="INFO"
1643

    
1644
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1645
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1646

    
1647
~~~~
1648

    
1649
age="27d 17h 13m"  time="2017-05-17 02:59:34"  log="D1-PROCESSING-JSVC"  level="INFO"
1650

    
1651
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1652
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1653

    
1654
~~~~
1655

    
1656
age="27d 17h 13m"  time="2017-05-17 02:59:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1657

    
1658
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1659
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1660

    
1661
~~~~
1662

    
1663
age="27d 17h 13m"  time="2017-05-17 02:59:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1664

    
1665
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1666
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1667

    
1668
~~~~
1669

    
1670
age="27d 17h 13m"  time="2017-05-17 02:59:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1671

    
1672
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1673

    
1674
~~~~
1675

    
1676
age="27d 18h 52m"  time="2017-05-17 01:19:46"  log="D1-PROCESSING-JSVC"  level="WARN"
1677

    
1678
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1679
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1680

    
1681
~~~~
1682

    
1683
age="27d 18h 52m"  time="2017-05-17 01:19:46"  log="D1-PROCESSING-JSVC"  level="INFO"
1684

    
1685
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1686
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1687

    
1688
~~~~
1689

    
1690
age="27d 18h 52m"  time="2017-05-17 01:19:46"  log="D1-PROCESSING-JSVC"  level="INFO"
1691

    
1692
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1693
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1694

    
1695
~~~~
1696

    
1697
age="27d 18h 52m"  time="2017-05-17 01:19:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1698

    
1699
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1700
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1701

    
1702
~~~~
1703

    
1704
age="27d 18h 52m"  time="2017-05-17 01:19:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1705

    
1706
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1707
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1708

    
1709
~~~~
1710

    
1711
age="27d 18h 52m"  time="2017-05-17 01:19:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1712

    
1713
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1714

    
1715
~~~~
1716

    
1717
age="27d 20h 31m"  time="2017-05-17 23:41:21"  log="D1-PROCESSING-JSVC"  level="WARN"
1718

    
1719
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1720
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1721

    
1722
~~~~
1723

    
1724
age="27d 20h 31m"  time="2017-05-17 23:41:21"  log="D1-PROCESSING-JSVC"  level="INFO"
1725

    
1726
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1727
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1728

    
1729
~~~~
1730

    
1731
age="27d 20h 31m"  time="2017-05-17 23:41:21"  log="D1-PROCESSING-JSVC"  level="INFO"
1732

    
1733
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1734
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1735

    
1736
~~~~
1737

    
1738
age="27d 20h 31m"  time="2017-05-17 23:41:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1739

    
1740
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1741
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1742

    
1743
~~~~
1744

    
1745
age="27d 20h 31m"  time="2017-05-17 23:41:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1746

    
1747
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1748
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1749

    
1750
~~~~
1751

    
1752
age="27d 20h 31m"  time="2017-05-17 23:41:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1753

    
1754
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1755

    
1756
~~~~
1757

    
1758
age="27d 22h 10m"  time="2017-05-17 22:02:18"  log="D1-PROCESSING-JSVC"  level="WARN"
1759

    
1760
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1761
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1762

    
1763
~~~~
1764

    
1765
age="27d 22h 10m"  time="2017-05-17 22:02:18"  log="D1-PROCESSING-JSVC"  level="INFO"
1766

    
1767
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1768
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1769

    
1770
~~~~
1771

    
1772
age="27d 22h 10m"  time="2017-05-17 22:02:18"  log="D1-PROCESSING-JSVC"  level="INFO"
1773

    
1774
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1775
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1776

    
1777
~~~~
1778

    
1779
age="27d 22h 10m"  time="2017-05-17 22:02:18"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1780

    
1781
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1782
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1783

    
1784
~~~~
1785

    
1786
age="27d 22h 10m"  time="2017-05-17 22:02:18"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1787

    
1788
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1789
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1790

    
1791
~~~~
1792

    
1793
age="27d 22h 10m"  time="2017-05-17 22:02:18"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1794

    
1795
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1796

    
1797
~~~~
1798

    
1799
age="27d 23h 48m"  time="2017-05-17 20:23:45"  log="D1-PROCESSING-JSVC"  level="WARN"
1800

    
1801
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1802
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1803

    
1804
~~~~
1805

    
1806
age="27d 23h 48m"  time="2017-05-17 20:23:45"  log="D1-PROCESSING-JSVC"  level="INFO"
1807

    
1808
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1809
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1810

    
1811
~~~~
1812

    
1813
age="27d 23h 48m"  time="2017-05-17 20:23:45"  log="D1-PROCESSING-JSVC"  level="INFO"
1814

    
1815
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1816
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1817

    
1818
~~~~
1819

    
1820
age="27d 23h 48m"  time="2017-05-17 20:23:45"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1821

    
1822
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1823
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1824

    
1825
~~~~
1826

    
1827
age="27d 23h 48m"  time="2017-05-17 20:23:45"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1828

    
1829
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1830
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1831

    
1832
~~~~
1833

    
1834
age="27d 23h 48m"  time="2017-05-17 20:23:45"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1835

    
1836
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1837

    
1838
~~~~
1839

    
1840
age="28d 1h 26m"  time="2017-05-17 18:45:54"  log="D1-PROCESSING-JSVC"  level="WARN"
1841

    
1842
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1843
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1844

    
1845
~~~~
1846

    
1847
age="28d 1h 26m"  time="2017-05-17 18:45:54"  log="D1-PROCESSING-JSVC"  level="INFO"
1848

    
1849
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1850
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1851

    
1852
~~~~
1853

    
1854
age="28d 1h 26m"  time="2017-05-17 18:45:54"  log="D1-PROCESSING-JSVC"  level="INFO"
1855

    
1856
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1857
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1858

    
1859
~~~~
1860

    
1861
age="28d 1h 26m"  time="2017-05-17 18:45:54"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1862

    
1863
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1864
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1865

    
1866
~~~~
1867

    
1868
age="28d 1h 26m"  time="2017-05-17 18:45:54"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1869

    
1870
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1871
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1872

    
1873
~~~~
1874

    
1875
age="28d 1h 26m"  time="2017-05-17 18:45:54"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1876

    
1877
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1878

    
1879
~~~~
1880

    
1881
age="28d 3h 3m"  time="2017-05-17 17:09:23"  log="D1-PROCESSING-JSVC"  level="WARN"
1882

    
1883
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1884
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1885

    
1886
~~~~
1887

    
1888
age="28d 3h 3m"  time="2017-05-17 17:09:23"  log="D1-PROCESSING-JSVC"  level="INFO"
1889

    
1890
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1891
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1892

    
1893
~~~~
1894

    
1895
age="28d 3h 3m"  time="2017-05-17 17:09:23"  log="D1-PROCESSING-JSVC"  level="INFO"
1896

    
1897
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1898
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1899

    
1900
~~~~
1901

    
1902
age="28d 3h 3m"  time="2017-05-17 17:09:23"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1903

    
1904
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1905
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1906

    
1907
~~~~
1908

    
1909
age="28d 3h 3m"  time="2017-05-17 17:09:23"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1910

    
1911
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1912
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1913

    
1914
~~~~
1915

    
1916
age="28d 3h 3m"  time="2017-05-17 17:09:23"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1917

    
1918
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1919

    
1920
~~~~
1921

    
1922
age="28d 4h 40m"  time="2017-05-17 15:31:57"  log="D1-PROCESSING-JSVC"  level="WARN"
1923

    
1924
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1925
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1926

    
1927
~~~~
1928

    
1929
age="28d 4h 40m"  time="2017-05-17 15:31:57"  log="D1-PROCESSING-JSVC"  level="INFO"
1930

    
1931
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1932
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1933

    
1934
~~~~
1935

    
1936
age="28d 4h 40m"  time="2017-05-17 15:31:57"  log="D1-PROCESSING-JSVC"  level="INFO"
1937

    
1938
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1939
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1940

    
1941
~~~~
1942

    
1943
age="28d 4h 40m"  time="2017-05-17 15:31:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1944

    
1945
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1946
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1947

    
1948
~~~~
1949

    
1950
age="28d 4h 40m"  time="2017-05-17 15:31:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1951

    
1952
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1953
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1954

    
1955
~~~~
1956

    
1957
age="28d 4h 40m"  time="2017-05-17 15:31:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1958

    
1959
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1960

    
1961
~~~~
1962

    
1963
age="28d 6h 17m"  time="2017-05-17 13:55:34"  log="D1-PROCESSING-JSVC"  level="WARN"
1964

    
1965
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
1966
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1967

    
1968
~~~~
1969

    
1970
age="28d 6h 17m"  time="2017-05-17 13:55:34"  log="D1-PROCESSING-JSVC"  level="INFO"
1971

    
1972
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
1973
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1974

    
1975
~~~~
1976

    
1977
age="28d 6h 17m"  time="2017-05-17 13:55:34"  log="D1-PROCESSING-JSVC"  level="INFO"
1978

    
1979
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
1980
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1981

    
1982
~~~~
1983

    
1984
age="28d 6h 17m"  time="2017-05-17 13:55:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1985

    
1986
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
1987
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1988

    
1989
~~~~
1990

    
1991
age="28d 6h 17m"  time="2017-05-17 13:55:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1992

    
1993
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
1994
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
1995

    
1996
~~~~
1997

    
1998
age="28d 6h 17m"  time="2017-05-17 13:55:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
1999

    
2000
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2001

    
2002
~~~~
2003

    
2004
age="28d 7h 52m"  time="2017-05-17 12:19:42"  log="D1-PROCESSING-JSVC"  level="WARN"
2005

    
2006
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2007
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2008

    
2009
~~~~
2010

    
2011
age="28d 7h 52m"  time="2017-05-17 12:19:42"  log="D1-PROCESSING-JSVC"  level="INFO"
2012

    
2013
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2014
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2015

    
2016
~~~~
2017

    
2018
age="28d 7h 52m"  time="2017-05-17 12:19:42"  log="D1-PROCESSING-JSVC"  level="INFO"
2019

    
2020
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2021
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2022

    
2023
~~~~
2024

    
2025
age="28d 7h 52m"  time="2017-05-17 12:19:42"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2026

    
2027
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2028
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2029

    
2030
~~~~
2031

    
2032
age="28d 7h 52m"  time="2017-05-17 12:19:42"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2033

    
2034
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2035
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2036

    
2037
~~~~
2038

    
2039
age="28d 7h 52m"  time="2017-05-17 12:19:42"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2040

    
2041
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2042

    
2043
~~~~
2044

    
2045
age="28d 9h 27m"  time="2017-05-17 10:45:20"  log="D1-PROCESSING-JSVC"  level="WARN"
2046

    
2047
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2048
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2049

    
2050
~~~~
2051

    
2052
age="28d 9h 27m"  time="2017-05-17 10:45:20"  log="D1-PROCESSING-JSVC"  level="INFO"
2053

    
2054
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2055
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2056

    
2057
~~~~
2058

    
2059
age="28d 9h 27m"  time="2017-05-17 10:45:20"  log="D1-PROCESSING-JSVC"  level="INFO"
2060

    
2061
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2062
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2063

    
2064
~~~~
2065

    
2066
age="28d 9h 27m"  time="2017-05-17 10:45:20"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2067

    
2068
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2069
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2070

    
2071
~~~~
2072

    
2073
age="28d 9h 27m"  time="2017-05-17 10:45:20"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2074

    
2075
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2076
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2077

    
2078
~~~~
2079

    
2080
age="28d 9h 27m"  time="2017-05-17 10:45:20"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2081

    
2082
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2083

    
2084
~~~~
2085

    
2086
age="28d 11h 2m"  time="2017-05-17 09:09:51"  log="D1-PROCESSING-JSVC"  level="WARN"
2087

    
2088
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2089
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2090

    
2091
~~~~
2092

    
2093
age="28d 11h 2m"  time="2017-05-17 09:09:51"  log="D1-PROCESSING-JSVC"  level="INFO"
2094

    
2095
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2096
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2097

    
2098
~~~~
2099

    
2100
age="28d 11h 2m"  time="2017-05-17 09:09:51"  log="D1-PROCESSING-JSVC"  level="INFO"
2101

    
2102
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2103
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2104

    
2105
~~~~
2106

    
2107
age="28d 11h 2m"  time="2017-05-17 09:09:51"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2108

    
2109
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2110
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2111

    
2112
~~~~
2113

    
2114
age="28d 11h 2m"  time="2017-05-17 09:09:51"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2115

    
2116
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2117
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2118

    
2119
~~~~
2120

    
2121
age="28d 11h 2m"  time="2017-05-17 09:09:51"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2122

    
2123
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2124

    
2125
~~~~
2126

    
2127
age="28d 12h 37m"  time="2017-05-17 07:35:39"  log="D1-PROCESSING-JSVC"  level="WARN"
2128

    
2129
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2130
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2131

    
2132
~~~~
2133

    
2134
age="28d 12h 37m"  time="2017-05-17 07:35:39"  log="D1-PROCESSING-JSVC"  level="INFO"
2135

    
2136
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2137
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2138

    
2139
~~~~
2140

    
2141
age="28d 12h 37m"  time="2017-05-17 07:35:39"  log="D1-PROCESSING-JSVC"  level="INFO"
2142

    
2143
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2144
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2145

    
2146
~~~~
2147

    
2148
age="28d 12h 37m"  time="2017-05-17 07:35:39"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2149

    
2150
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2151
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2152

    
2153
~~~~
2154

    
2155
age="28d 12h 37m"  time="2017-05-17 07:35:39"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2156

    
2157
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2158
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2159

    
2160
~~~~
2161

    
2162
age="28d 12h 37m"  time="2017-05-17 07:35:39"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2163

    
2164
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2165

    
2166
~~~~
2167

    
2168
age="28d 14h 11m"  time="2017-05-17 06:01:33"  log="D1-PROCESSING-JSVC"  level="INFO"
2169

    
2170
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2171
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2172

    
2173
~~~~
2174

    
2175
age="28d 14h 11m"  time="2017-05-17 06:01:33"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2176

    
2177
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2178
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2179

    
2180
~~~~
2181

    
2182
age="28d 14h 11m"  time="2017-05-17 06:01:33"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2183

    
2184
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2185
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2186

    
2187
~~~~
2188

    
2189
age="28d 14h 11m"  time="2017-05-17 06:01:33"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2190

    
2191
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2192

    
2193
~~~~
2194

    
2195
age="28d 15h 40m"  time="2017-05-17 04:31:53"  log="D1-PROCESSING-JSVC"  level="WARN"
2196

    
2197
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2198
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2199

    
2200
~~~~
2201

    
2202
age="28d 15h 40m"  time="2017-05-17 04:31:53"  log="D1-PROCESSING-JSVC"  level="INFO"
2203

    
2204
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2205
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2206

    
2207
~~~~
2208

    
2209
age="28d 15h 40m"  time="2017-05-17 04:31:52"  log="D1-PROCESSING-JSVC"  level="INFO"
2210

    
2211
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2212
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2213

    
2214
~~~~
2215

    
2216
age="28d 15h 40m"  time="2017-05-17 04:31:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2217

    
2218
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2219
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2220

    
2221
~~~~
2222

    
2223
age="28d 15h 40m"  time="2017-05-17 04:31:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2224

    
2225
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2226
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2227

    
2228
~~~~
2229

    
2230
age="28d 15h 40m"  time="2017-05-17 04:31:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2231

    
2232
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2233

    
2234
~~~~
2235

    
2236
age="28d 17h 9m"  time="2017-05-17 03:03:37"  log="D1-PROCESSING-JSVC"  level="WARN"
2237

    
2238
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2239
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2240

    
2241
~~~~
2242

    
2243
age="28d 17h 9m"  time="2017-05-17 03:03:37"  log="D1-PROCESSING-JSVC"  level="INFO"
2244

    
2245
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2246
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2247

    
2248
~~~~
2249

    
2250
age="28d 17h 9m"  time="2017-05-17 03:03:37"  log="D1-PROCESSING-JSVC"  level="INFO"
2251

    
2252
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2253
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2254

    
2255
~~~~
2256

    
2257
age="28d 17h 9m"  time="2017-05-17 03:03:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2258

    
2259
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2260
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2261

    
2262
~~~~
2263

    
2264
age="28d 17h 9m"  time="2017-05-17 03:03:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2265

    
2266
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2267
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2268

    
2269
~~~~
2270

    
2271
age="28d 17h 9m"  time="2017-05-17 03:03:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2272

    
2273
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2274

    
2275
~~~~
2276

    
2277
age="28d 18h 37m"  time="2017-05-17 01:35:26"  log="D1-PROCESSING-JSVC"  level="WARN"
2278

    
2279
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2280
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2281

    
2282
~~~~
2283

    
2284
age="28d 18h 37m"  time="2017-05-17 01:35:26"  log="D1-PROCESSING-JSVC"  level="INFO"
2285

    
2286
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2287
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2288

    
2289
~~~~
2290

    
2291
age="28d 18h 37m"  time="2017-05-17 01:35:26"  log="D1-PROCESSING-JSVC"  level="INFO"
2292

    
2293
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2294
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2295

    
2296
~~~~
2297

    
2298
age="28d 18h 37m"  time="2017-05-17 01:35:26"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2299

    
2300
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2301
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2302

    
2303
~~~~
2304

    
2305
age="28d 18h 37m"  time="2017-05-17 01:35:26"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2306

    
2307
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2308
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2309

    
2310
~~~~
2311

    
2312
age="28d 18h 37m"  time="2017-05-17 01:35:26"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2313

    
2314
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2315

    
2316
~~~~
2317

    
2318
age="28d 20h 5m"  time="2017-05-17 00:07:17"  log="D1-PROCESSING-JSVC"  level="WARN"
2319

    
2320
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2321
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2322

    
2323
~~~~
2324

    
2325
age="28d 20h 5m"  time="2017-05-17 00:07:17"  log="D1-PROCESSING-JSVC"  level="INFO"
2326

    
2327
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2328
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2329

    
2330
~~~~
2331

    
2332
age="28d 20h 5m"  time="2017-05-17 00:07:17"  log="D1-PROCESSING-JSVC"  level="INFO"
2333

    
2334
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2335
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2336

    
2337
~~~~
2338

    
2339
age="28d 20h 5m"  time="2017-05-17 00:07:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2340

    
2341
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2342
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2343

    
2344
~~~~
2345

    
2346
age="28d 20h 5m"  time="2017-05-17 00:07:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2347

    
2348
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2349
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2350

    
2351
~~~~
2352

    
2353
age="28d 20h 5m"  time="2017-05-17 00:07:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2354

    
2355
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2356

    
2357
~~~~
2358

    
2359
age="28d 21h 33m"  time="2017-05-17 22:39:17"  log="D1-PROCESSING-JSVC"  level="WARN"
2360

    
2361
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2362
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2363

    
2364
~~~~
2365

    
2366
age="28d 21h 33m"  time="2017-05-17 22:39:17"  log="D1-PROCESSING-JSVC"  level="INFO"
2367

    
2368
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2369
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2370

    
2371
~~~~
2372

    
2373
age="28d 21h 33m"  time="2017-05-17 22:39:17"  log="D1-PROCESSING-JSVC"  level="INFO"
2374

    
2375
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2376
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2377

    
2378
~~~~
2379

    
2380
age="28d 21h 33m"  time="2017-05-17 22:39:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2381

    
2382
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2383
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2384

    
2385
~~~~
2386

    
2387
age="28d 21h 33m"  time="2017-05-17 22:39:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2388

    
2389
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2390
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2391

    
2392
~~~~
2393

    
2394
age="28d 21h 33m"  time="2017-05-17 22:39:17"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2395

    
2396
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2397

    
2398
~~~~
2399

    
2400
age="28d 23h 1m"  time="2017-05-17 21:11:28"  log="D1-PROCESSING-JSVC"  level="WARN"
2401

    
2402
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2403
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2404

    
2405
~~~~
2406

    
2407
age="28d 23h 1m"  time="2017-05-17 21:11:28"  log="D1-PROCESSING-JSVC"  level="INFO"
2408

    
2409
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2410
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2411

    
2412
~~~~
2413

    
2414
age="28d 23h 1m"  time="2017-05-17 21:11:28"  log="D1-PROCESSING-JSVC"  level="INFO"
2415

    
2416
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2417
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2418

    
2419
~~~~
2420

    
2421
age="28d 23h 1m"  time="2017-05-17 21:11:28"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2422

    
2423
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2424
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2425

    
2426
~~~~
2427

    
2428
age="28d 23h 1m"  time="2017-05-17 21:11:28"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2429

    
2430
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2431
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2432

    
2433
~~~~
2434

    
2435
age="28d 23h 1m"  time="2017-05-17 21:11:28"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2436

    
2437
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2438

    
2439
~~~~
2440

    
2441
age="29d 0h 28m"  time="2017-05-17 19:43:48"  log="D1-PROCESSING-JSVC"  level="WARN"
2442

    
2443
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2444
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2445

    
2446
~~~~
2447

    
2448
age="29d 0h 28m"  time="2017-05-17 19:43:48"  log="D1-PROCESSING-JSVC"  level="INFO"
2449

    
2450
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2451
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2452

    
2453
~~~~
2454

    
2455
age="29d 0h 28m"  time="2017-05-17 19:43:48"  log="D1-PROCESSING-JSVC"  level="INFO"
2456

    
2457
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2458
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2459

    
2460
~~~~
2461

    
2462
age="29d 0h 28m"  time="2017-05-17 19:43:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2463

    
2464
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2465
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2466

    
2467
~~~~
2468

    
2469
age="29d 0h 28m"  time="2017-05-17 19:43:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2470

    
2471
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2472
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2473

    
2474
~~~~
2475

    
2476
age="29d 0h 28m"  time="2017-05-17 19:43:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2477

    
2478
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2479

    
2480
~~~~
2481

    
2482
age="29d 1h 55m"  time="2017-05-17 18:17:25"  log="D1-PROCESSING-JSVC"  level="WARN"
2483

    
2484
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2485
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2486

    
2487
~~~~
2488

    
2489
age="29d 1h 55m"  time="2017-05-17 18:17:25"  log="D1-PROCESSING-JSVC"  level="INFO"
2490

    
2491
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2492
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2493

    
2494
~~~~
2495

    
2496
age="29d 1h 55m"  time="2017-05-17 18:17:25"  log="D1-PROCESSING-JSVC"  level="INFO"
2497

    
2498
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2499
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2500

    
2501
~~~~
2502

    
2503
age="29d 1h 55m"  time="2017-05-17 18:17:25"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2504

    
2505
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2506
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2507

    
2508
~~~~
2509

    
2510
age="29d 1h 55m"  time="2017-05-17 18:17:25"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2511

    
2512
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2513
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2514

    
2515
~~~~
2516

    
2517
age="29d 1h 55m"  time="2017-05-17 18:17:25"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2518

    
2519
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2520

    
2521
~~~~
2522

    
2523
age="29d 3h 21m"  time="2017-05-17 16:51:15"  log="D1-PROCESSING-JSVC"  level="WARN"
2524

    
2525
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2526
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2527

    
2528
~~~~
2529

    
2530
age="29d 3h 21m"  time="2017-05-17 16:51:15"  log="D1-PROCESSING-JSVC"  level="INFO"
2531

    
2532
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2533
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2534

    
2535
~~~~
2536

    
2537
age="29d 3h 21m"  time="2017-05-17 16:51:15"  log="D1-PROCESSING-JSVC"  level="INFO"
2538

    
2539
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2540
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2541

    
2542
~~~~
2543

    
2544
age="29d 3h 21m"  time="2017-05-17 16:51:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2545

    
2546
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2547
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2548

    
2549
~~~~
2550

    
2551
age="29d 3h 21m"  time="2017-05-17 16:51:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2552

    
2553
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2554
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2555

    
2556
~~~~
2557

    
2558
age="29d 3h 21m"  time="2017-05-17 16:51:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2559

    
2560
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2561

    
2562
~~~~
2563

    
2564
age="29d 4h 47m"  time="2017-05-17 15:25:21"  log="D1-PROCESSING-JSVC"  level="WARN"
2565

    
2566
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2567
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2568

    
2569
~~~~
2570

    
2571
age="29d 4h 47m"  time="2017-05-17 15:25:21"  log="D1-PROCESSING-JSVC"  level="INFO"
2572

    
2573
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2574
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2575

    
2576
~~~~
2577

    
2578
age="29d 4h 47m"  time="2017-05-17 15:25:21"  log="D1-PROCESSING-JSVC"  level="INFO"
2579

    
2580
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2581
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2582

    
2583
~~~~
2584

    
2585
age="29d 4h 47m"  time="2017-05-17 15:25:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2586

    
2587
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2588
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2589

    
2590
~~~~
2591

    
2592
age="29d 4h 47m"  time="2017-05-17 15:25:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2593

    
2594
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2595
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2596

    
2597
~~~~
2598

    
2599
age="29d 4h 47m"  time="2017-05-17 15:25:21"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2600

    
2601
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2602

    
2603
~~~~
2604

    
2605
age="29d 6h 13m"  time="2017-05-17 13:59:37"  log="D1-PROCESSING-JSVC"  level="WARN"
2606

    
2607
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2608
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2609

    
2610
~~~~
2611

    
2612
age="29d 6h 13m"  time="2017-05-17 13:59:37"  log="D1-PROCESSING-JSVC"  level="INFO"
2613

    
2614
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2615
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2616

    
2617
~~~~
2618

    
2619
age="29d 6h 13m"  time="2017-05-17 13:59:37"  log="D1-PROCESSING-JSVC"  level="INFO"
2620

    
2621
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2622
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2623

    
2624
~~~~
2625

    
2626
age="29d 6h 13m"  time="2017-05-17 13:59:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2627

    
2628
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2629
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2630

    
2631
~~~~
2632

    
2633
age="29d 6h 13m"  time="2017-05-17 13:59:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2634

    
2635
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2636
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2637

    
2638
~~~~
2639

    
2640
age="29d 6h 13m"  time="2017-05-17 13:59:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2641

    
2642
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2643

    
2644
~~~~
2645

    
2646
age="29d 7h 38m"  time="2017-05-17 12:33:55"  log="D1-PROCESSING-JSVC"  level="WARN"
2647

    
2648
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2649
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2650

    
2651
~~~~
2652

    
2653
age="29d 7h 38m"  time="2017-05-17 12:33:55"  log="D1-PROCESSING-JSVC"  level="INFO"
2654

    
2655
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2656
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2657

    
2658
~~~~
2659

    
2660
age="29d 7h 38m"  time="2017-05-17 12:33:55"  log="D1-PROCESSING-JSVC"  level="INFO"
2661

    
2662
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2663
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2664

    
2665
~~~~
2666

    
2667
age="29d 7h 38m"  time="2017-05-17 12:33:55"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2668

    
2669
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2670
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2671

    
2672
~~~~
2673

    
2674
age="29d 7h 38m"  time="2017-05-17 12:33:55"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2675

    
2676
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2677
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2678

    
2679
~~~~
2680

    
2681
age="29d 7h 38m"  time="2017-05-17 12:33:55"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2682

    
2683
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2684

    
2685
~~~~
2686

    
2687
age="29d 9h 1m"  time="2017-05-17 11:11:10"  log="D1-PROCESSING-JSVC"  level="WARN"
2688

    
2689
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2690
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2691

    
2692
~~~~
2693

    
2694
age="29d 9h 1m"  time="2017-05-17 11:11:10"  log="D1-PROCESSING-JSVC"  level="INFO"
2695

    
2696
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2697
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2698

    
2699
~~~~
2700

    
2701
age="29d 9h 1m"  time="2017-05-17 11:11:10"  log="D1-PROCESSING-JSVC"  level="INFO"
2702

    
2703
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2704
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2705

    
2706
~~~~
2707

    
2708
age="29d 9h 1m"  time="2017-05-17 11:11:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2709

    
2710
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2711
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2712

    
2713
~~~~
2714

    
2715
age="29d 9h 1m"  time="2017-05-17 11:11:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2716

    
2717
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2718
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2719

    
2720
~~~~
2721

    
2722
age="29d 9h 1m"  time="2017-05-17 11:11:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2723

    
2724
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2725

    
2726
~~~~
2727

    
2728
age="29d 10h 32m"  time="2017-05-17 09:40:26"  log="D1-PROCESSING-JSVC"  level="WARN"
2729

    
2730
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2731
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2732

    
2733
~~~~
2734

    
2735
age="29d 10h 32m"  time="2017-05-17 09:40:26"  log="D1-PROCESSING-JSVC"  level="INFO"
2736

    
2737
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2738
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2739

    
2740
~~~~
2741

    
2742
age="29d 10h 32m"  time="2017-05-17 09:40:26"  log="D1-PROCESSING-JSVC"  level="INFO"
2743

    
2744
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2745
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2746

    
2747
~~~~
2748

    
2749
age="29d 10h 32m"  time="2017-05-17 09:40:26"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2750

    
2751
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2752
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2753

    
2754
~~~~
2755

    
2756
age="29d 10h 32m"  time="2017-05-17 09:40:26"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2757

    
2758
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2759
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2760

    
2761
~~~~
2762

    
2763
age="29d 10h 32m"  time="2017-05-17 09:40:26"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2764

    
2765
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2766

    
2767
~~~~
2768

    
2769
age="29d 11h 56m"  time="2017-05-17 08:16:37"  log="D1-PROCESSING-JSVC"  level="WARN"
2770

    
2771
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2772
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2773

    
2774
~~~~
2775

    
2776
age="29d 11h 56m"  time="2017-05-17 08:16:37"  log="D1-PROCESSING-JSVC"  level="INFO"
2777

    
2778
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2779
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2780

    
2781
~~~~
2782

    
2783
age="29d 11h 56m"  time="2017-05-17 08:16:37"  log="D1-PROCESSING-JSVC"  level="INFO"
2784

    
2785
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2786
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2787

    
2788
~~~~
2789

    
2790
age="29d 11h 56m"  time="2017-05-17 08:16:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2791

    
2792
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2793
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2794

    
2795
~~~~
2796

    
2797
age="29d 11h 56m"  time="2017-05-17 08:16:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2798

    
2799
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2800
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2801

    
2802
~~~~
2803

    
2804
age="29d 11h 56m"  time="2017-05-17 08:16:37"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2805

    
2806
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2807

    
2808
~~~~
2809

    
2810
age="29d 13h 19m"  time="2017-05-17 06:52:57"  log="D1-PROCESSING-JSVC"  level="WARN"
2811

    
2812
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2813
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2814

    
2815
~~~~
2816

    
2817
age="29d 13h 19m"  time="2017-05-17 06:52:57"  log="D1-PROCESSING-JSVC"  level="INFO"
2818

    
2819
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2820
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2821

    
2822
~~~~
2823

    
2824
age="29d 13h 19m"  time="2017-05-17 06:52:57"  log="D1-PROCESSING-JSVC"  level="INFO"
2825

    
2826
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2827
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2828

    
2829
~~~~
2830

    
2831
age="29d 13h 19m"  time="2017-05-17 06:52:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2832

    
2833
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2834
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2835

    
2836
~~~~
2837

    
2838
age="29d 13h 19m"  time="2017-05-17 06:52:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2839

    
2840
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2841
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2842

    
2843
~~~~
2844

    
2845
age="29d 13h 19m"  time="2017-05-17 06:52:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2846

    
2847
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2848

    
2849
~~~~
2850

    
2851
age="29d 16h 6m"  time="2017-05-17 04:06:34"  log="D1-PROCESSING-JSVC"  level="INFO"
2852

    
2853
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2854
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2855

    
2856
~~~~
2857

    
2858
age="29d 16h 6m"  time="2017-05-17 04:06:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2859

    
2860
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2861
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2862

    
2863
~~~~
2864

    
2865
age="29d 16h 6m"  time="2017-05-17 04:06:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2866

    
2867
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2868
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2869

    
2870
~~~~
2871

    
2872
age="29d 16h 6m"  time="2017-05-17 04:06:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2873

    
2874
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2875

    
2876
~~~~
2877

    
2878
age="29d 17h 29m"  time="2017-05-17 02:42:57"  log="D1-PROCESSING-JSVC"  level="WARN"
2879

    
2880
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2881
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2882

    
2883
~~~~
2884

    
2885
age="29d 17h 29m"  time="2017-05-17 02:42:57"  log="D1-PROCESSING-JSVC"  level="INFO"
2886

    
2887
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2888
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2889

    
2890
~~~~
2891

    
2892
age="29d 17h 29m"  time="2017-05-17 02:42:57"  log="D1-PROCESSING-JSVC"  level="INFO"
2893

    
2894
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2895
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2896

    
2897
~~~~
2898

    
2899
age="29d 17h 29m"  time="2017-05-17 02:42:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2900

    
2901
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2902
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2903

    
2904
~~~~
2905

    
2906
age="29d 17h 29m"  time="2017-05-17 02:42:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2907

    
2908
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2909
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2910

    
2911
~~~~
2912

    
2913
age="29d 17h 29m"  time="2017-05-17 02:42:57"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2914

    
2915
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2916

    
2917
~~~~
2918

    
2919
age="29d 18h 51m"  time="2017-05-17 01:20:48"  log="D1-PROCESSING-JSVC"  level="WARN"
2920

    
2921
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2922
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2923

    
2924
~~~~
2925

    
2926
age="29d 18h 51m"  time="2017-05-17 01:20:48"  log="D1-PROCESSING-JSVC"  level="INFO"
2927

    
2928
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2929
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2930

    
2931
~~~~
2932

    
2933
age="29d 18h 51m"  time="2017-05-17 01:20:48"  log="D1-PROCESSING-JSVC"  level="INFO"
2934

    
2935
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2936
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2937

    
2938
~~~~
2939

    
2940
age="29d 18h 51m"  time="2017-05-17 01:20:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2941

    
2942
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2943
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2944

    
2945
~~~~
2946

    
2947
age="29d 18h 51m"  time="2017-05-17 01:20:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2948

    
2949
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2950
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2951

    
2952
~~~~
2953

    
2954
age="29d 18h 51m"  time="2017-05-17 01:20:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2955

    
2956
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2957

    
2958
~~~~
2959

    
2960
age="29d 20h 15m"  time="2017-05-17 23:57:18"  log="D1-PROCESSING-JSVC"  level="WARN"
2961

    
2962
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
2963
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2964

    
2965
~~~~
2966

    
2967
age="29d 20h 15m"  time="2017-05-17 23:57:18"  log="D1-PROCESSING-JSVC"  level="INFO"
2968

    
2969
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
2970
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2971

    
2972
~~~~
2973

    
2974
age="29d 20h 15m"  time="2017-05-17 23:57:18"  log="D1-PROCESSING-JSVC"  level="INFO"
2975

    
2976
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
2977
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2978

    
2979
~~~~
2980

    
2981
age="29d 20h 15m"  time="2017-05-17 23:57:18"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2982

    
2983
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
2984
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2985

    
2986
~~~~
2987

    
2988
age="29d 20h 15m"  time="2017-05-17 23:57:18"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2989

    
2990
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
2991
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2992

    
2993
~~~~
2994

    
2995
age="29d 20h 15m"  time="2017-05-17 23:57:18"  log="D1-PROCESSING-JSVC"  level="DEBUG"
2996

    
2997
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
2998

    
2999
~~~~
3000

    
3001
age="29d 21h 38m"  time="2017-05-17 22:34:19"  log="D1-PROCESSING-JSVC"  level="WARN"
3002

    
3003
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3004
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3005

    
3006
~~~~
3007

    
3008
age="29d 21h 38m"  time="2017-05-17 22:34:19"  log="D1-PROCESSING-JSVC"  level="INFO"
3009

    
3010
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3011
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3012

    
3013
~~~~
3014

    
3015
age="29d 21h 38m"  time="2017-05-17 22:34:19"  log="D1-PROCESSING-JSVC"  level="INFO"
3016

    
3017
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3018
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3019

    
3020
~~~~
3021

    
3022
age="29d 21h 38m"  time="2017-05-17 22:34:19"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3023

    
3024
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3025
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3026

    
3027
~~~~
3028

    
3029
age="29d 21h 38m"  time="2017-05-17 22:34:19"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3030

    
3031
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3032
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3033

    
3034
~~~~
3035

    
3036
age="29d 21h 38m"  time="2017-05-17 22:34:19"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3037

    
3038
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3039

    
3040
~~~~
3041

    
3042
age="29d 23h 1m"  time="2017-05-17 21:10:52"  log="D1-PROCESSING-JSVC"  level="WARN"
3043

    
3044
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3045
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3046

    
3047
~~~~
3048

    
3049
age="29d 23h 1m"  time="2017-05-17 21:10:52"  log="D1-PROCESSING-JSVC"  level="INFO"
3050

    
3051
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3052
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3053

    
3054
~~~~
3055

    
3056
age="29d 23h 1m"  time="2017-05-17 21:10:52"  log="D1-PROCESSING-JSVC"  level="INFO"
3057

    
3058
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3059
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3060

    
3061
~~~~
3062

    
3063
age="29d 23h 1m"  time="2017-05-17 21:10:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3064

    
3065
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3066
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3067

    
3068
~~~~
3069

    
3070
age="29d 23h 1m"  time="2017-05-17 21:10:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3071

    
3072
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3073
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3074

    
3075
~~~~
3076

    
3077
age="29d 23h 1m"  time="2017-05-17 21:10:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3078

    
3079
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3080

    
3081
~~~~
3082

    
3083
age="30d 0h 23m"  time="2017-05-17 19:48:41"  log="D1-PROCESSING-JSVC"  level="WARN"
3084

    
3085
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3086
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3087

    
3088
~~~~
3089

    
3090
age="30d 0h 23m"  time="2017-05-17 19:48:41"  log="D1-PROCESSING-JSVC"  level="INFO"
3091

    
3092
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3093
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3094

    
3095
~~~~
3096

    
3097
age="30d 0h 23m"  time="2017-05-17 19:48:41"  log="D1-PROCESSING-JSVC"  level="INFO"
3098

    
3099
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3100
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3101

    
3102
~~~~
3103

    
3104
age="30d 0h 23m"  time="2017-05-17 19:48:41"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3105

    
3106
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3107
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3108

    
3109
~~~~
3110

    
3111
age="30d 0h 23m"  time="2017-05-17 19:48:41"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3112

    
3113
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3114
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3115

    
3116
~~~~
3117

    
3118
age="30d 0h 23m"  time="2017-05-17 19:48:41"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3119

    
3120
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3121

    
3122
~~~~
3123

    
3124
age="30d 1h 44m"  time="2017-05-17 18:28:27"  log="D1-PROCESSING-JSVC"  level="WARN"
3125

    
3126
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3127
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3128

    
3129
~~~~
3130

    
3131
age="30d 1h 44m"  time="2017-05-17 18:28:27"  log="D1-PROCESSING-JSVC"  level="INFO"
3132

    
3133
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3134
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3135

    
3136
~~~~
3137

    
3138
age="30d 1h 44m"  time="2017-05-17 18:28:27"  log="D1-PROCESSING-JSVC"  level="INFO"
3139

    
3140
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3141
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3142

    
3143
~~~~
3144

    
3145
age="30d 1h 44m"  time="2017-05-17 18:28:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3146

    
3147
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3148
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3149

    
3150
~~~~
3151

    
3152
age="30d 1h 44m"  time="2017-05-17 18:28:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3153

    
3154
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3155
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3156

    
3157
~~~~
3158

    
3159
age="30d 1h 44m"  time="2017-05-17 18:28:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3160

    
3161
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3162

    
3163
~~~~
3164

    
3165
age="30d 3h 3m"  time="2017-05-17 17:08:43"  log="D1-PROCESSING-JSVC"  level="WARN"
3166

    
3167
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3168
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3169

    
3170
~~~~
3171

    
3172
age="30d 3h 3m"  time="2017-05-17 17:08:43"  log="D1-PROCESSING-JSVC"  level="INFO"
3173

    
3174
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3175
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3176

    
3177
~~~~
3178

    
3179
age="30d 3h 3m"  time="2017-05-17 17:08:43"  log="D1-PROCESSING-JSVC"  level="INFO"
3180

    
3181
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3182
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3183

    
3184
~~~~
3185

    
3186
age="30d 3h 3m"  time="2017-05-17 17:08:43"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3187

    
3188
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3189
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3190

    
3191
~~~~
3192

    
3193
age="30d 3h 3m"  time="2017-05-17 17:08:43"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3194

    
3195
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3196
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3197

    
3198
~~~~
3199

    
3200
age="30d 3h 3m"  time="2017-05-17 17:08:43"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3201

    
3202
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3203

    
3204
~~~~
3205

    
3206
age="30d 4h 21m"  time="2017-05-17 15:50:52"  log="D1-PROCESSING-JSVC"  level="WARN"
3207

    
3208
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3209
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3210

    
3211
~~~~
3212

    
3213
age="30d 4h 21m"  time="2017-05-17 15:50:52"  log="D1-PROCESSING-JSVC"  level="INFO"
3214

    
3215
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3216
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3217

    
3218
~~~~
3219

    
3220
age="30d 4h 21m"  time="2017-05-17 15:50:52"  log="D1-PROCESSING-JSVC"  level="INFO"
3221

    
3222
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3223
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3224

    
3225
~~~~
3226

    
3227
age="30d 4h 21m"  time="2017-05-17 15:50:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3228

    
3229
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3230
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3231

    
3232
~~~~
3233

    
3234
age="30d 4h 21m"  time="2017-05-17 15:50:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3235

    
3236
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3237
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3238

    
3239
~~~~
3240

    
3241
age="30d 4h 21m"  time="2017-05-17 15:50:52"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3242

    
3243
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3244

    
3245
~~~~
3246

    
3247
age="30d 5h 38m"  time="2017-05-17 14:34:35"  log="D1-PROCESSING-JSVC"  level="WARN"
3248

    
3249
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3250
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3251

    
3252
~~~~
3253

    
3254
age="30d 5h 38m"  time="2017-05-17 14:34:35"  log="D1-PROCESSING-JSVC"  level="INFO"
3255

    
3256
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3257
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3258

    
3259
~~~~
3260

    
3261
age="30d 5h 38m"  time="2017-05-17 14:34:35"  log="D1-PROCESSING-JSVC"  level="INFO"
3262

    
3263
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3264
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3265

    
3266
~~~~
3267

    
3268
age="30d 5h 38m"  time="2017-05-17 14:34:35"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3269

    
3270
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3271
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3272

    
3273
~~~~
3274

    
3275
age="30d 5h 38m"  time="2017-05-17 14:34:35"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3276

    
3277
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3278
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3279

    
3280
~~~~
3281

    
3282
age="30d 5h 38m"  time="2017-05-17 14:34:35"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3283

    
3284
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3285

    
3286
~~~~
3287

    
3288
age="30d 6h 54m"  time="2017-05-17 13:18:29"  log="D1-PROCESSING-JSVC"  level="WARN"
3289

    
3290
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3291
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3292

    
3293
~~~~
3294

    
3295
age="30d 6h 54m"  time="2017-05-17 13:18:29"  log="D1-PROCESSING-JSVC"  level="INFO"
3296

    
3297
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3298
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3299

    
3300
~~~~
3301

    
3302
age="30d 6h 54m"  time="2017-05-17 13:18:29"  log="D1-PROCESSING-JSVC"  level="INFO"
3303

    
3304
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3305
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3306

    
3307
~~~~
3308

    
3309
age="30d 6h 54m"  time="2017-05-17 13:18:29"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3310

    
3311
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3312
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3313

    
3314
~~~~
3315

    
3316
age="30d 6h 54m"  time="2017-05-17 13:18:29"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3317

    
3318
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3319
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3320

    
3321
~~~~
3322

    
3323
age="30d 6h 54m"  time="2017-05-17 13:18:29"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3324

    
3325
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3326

    
3327
~~~~
3328

    
3329
age="30d 8h 10m"  time="2017-05-17 12:02:34"  log="D1-PROCESSING-JSVC"  level="WARN"
3330

    
3331
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3332
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3333

    
3334
~~~~
3335

    
3336
age="30d 8h 10m"  time="2017-05-17 12:02:34"  log="D1-PROCESSING-JSVC"  level="INFO"
3337

    
3338
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3339
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3340

    
3341
~~~~
3342

    
3343
age="30d 8h 10m"  time="2017-05-17 12:02:34"  log="D1-PROCESSING-JSVC"  level="INFO"
3344

    
3345
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3346
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3347

    
3348
~~~~
3349

    
3350
age="30d 8h 10m"  time="2017-05-17 12:02:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3351

    
3352
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3353
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3354

    
3355
~~~~
3356

    
3357
age="30d 8h 10m"  time="2017-05-17 12:02:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3358

    
3359
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3360
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3361

    
3362
~~~~
3363

    
3364
age="30d 8h 10m"  time="2017-05-17 12:02:34"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3365

    
3366
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3367

    
3368
~~~~
3369

    
3370
age="30d 9h 25m"  time="2017-05-17 10:46:46"  log="D1-PROCESSING-JSVC"  level="WARN"
3371

    
3372
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3373
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3374

    
3375
~~~~
3376

    
3377
age="30d 9h 25m"  time="2017-05-17 10:46:46"  log="D1-PROCESSING-JSVC"  level="INFO"
3378

    
3379
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3380
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3381

    
3382
~~~~
3383

    
3384
age="30d 9h 25m"  time="2017-05-17 10:46:46"  log="D1-PROCESSING-JSVC"  level="INFO"
3385

    
3386
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3387
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3388

    
3389
~~~~
3390

    
3391
age="30d 9h 25m"  time="2017-05-17 10:46:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3392

    
3393
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3394
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3395

    
3396
~~~~
3397

    
3398
age="30d 9h 25m"  time="2017-05-17 10:46:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3399

    
3400
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3401
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3402

    
3403
~~~~
3404

    
3405
age="30d 9h 25m"  time="2017-05-17 10:46:46"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3406

    
3407
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3408

    
3409
~~~~
3410

    
3411
age="30d 10h 41m"  time="2017-05-17 09:30:56"  log="D1-PROCESSING-JSVC"  level="WARN"
3412

    
3413
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3414
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3415

    
3416
~~~~
3417

    
3418
age="30d 10h 41m"  time="2017-05-17 09:30:56"  log="D1-PROCESSING-JSVC"  level="INFO"
3419

    
3420
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3421
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3422

    
3423
~~~~
3424

    
3425
age="30d 10h 41m"  time="2017-05-17 09:30:56"  log="D1-PROCESSING-JSVC"  level="INFO"
3426

    
3427
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3428
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3429

    
3430
~~~~
3431

    
3432
age="30d 10h 41m"  time="2017-05-17 09:30:56"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3433

    
3434
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3435
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3436

    
3437
~~~~
3438

    
3439
age="30d 10h 41m"  time="2017-05-17 09:30:56"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3440

    
3441
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3442
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3443

    
3444
~~~~
3445

    
3446
age="30d 10h 41m"  time="2017-05-17 09:30:56"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3447

    
3448
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3449

    
3450
~~~~
3451

    
3452
age="30d 11h 56m"  time="2017-05-17 08:16:27"  log="D1-PROCESSING-JSVC"  level="WARN"
3453

    
3454
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3455
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3456

    
3457
~~~~
3458

    
3459
age="30d 11h 56m"  time="2017-05-17 08:16:27"  log="D1-PROCESSING-JSVC"  level="INFO"
3460

    
3461
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3462
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3463

    
3464
~~~~
3465

    
3466
age="30d 11h 56m"  time="2017-05-17 08:16:27"  log="D1-PROCESSING-JSVC"  level="INFO"
3467

    
3468
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3469
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3470

    
3471
~~~~
3472

    
3473
age="30d 11h 56m"  time="2017-05-17 08:16:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3474

    
3475
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3476
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3477

    
3478
~~~~
3479

    
3480
age="30d 11h 56m"  time="2017-05-17 08:16:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3481

    
3482
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3483
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3484

    
3485
~~~~
3486

    
3487
age="30d 11h 56m"  time="2017-05-17 08:16:27"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3488

    
3489
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3490

    
3491
~~~~
3492

    
3493
age="30d 13h 11m"  time="2017-05-17 07:00:48"  log="D1-PROCESSING-JSVC"  level="WARN"
3494

    
3495
(ReplicationManager:requeueReplicationTask:787) In Replication Manager, task that should exist 'in process' does not
3496
  exist.  Creating new task for pid: doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3497

    
3498
~~~~
3499

    
3500
age="30d 13h 11m"  time="2017-05-17 07:00:48"  log="D1-PROCESSING-JSVC"  level="INFO"
3501

    
3502
(ReplicationManager:createAndQueueTasks:390) Added 0 MNReplicationTasks to the queue for
3503
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3504

    
3505
~~~~
3506

    
3507
age="30d 13h 11m"  time="2017-05-17 07:00:48"  log="D1-PROCESSING-JSVC"  level="INFO"
3508

    
3509
(ReplicationManager:removeReplicationTasksForPid:772) removing replication tasks for pid:
3510
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3511

    
3512
~~~~
3513

    
3514
age="30d 13h 11m"  time="2017-05-17 07:00:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3515

    
3516
(ReplicationManager:processPid:413) Replication is not allowed for the object identified by
3517
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3518

    
3519
~~~~
3520

    
3521
age="30d 13h 11m"  time="2017-05-17 07:00:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3522

    
3523
(ReplicationManager:isAllowed:824) ReplicationManager.isAllowed() = false for
3524
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3525

    
3526
~~~~
3527

    
3528
age="30d 13h 11m"  time="2017-05-17 07:00:48"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3529

    
3530
(ReplicationManager:isAllowed:807) ReplicationManager.isAllowed() called for doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
3531

    
3532
~~~~
3533

    
3534
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3535

    
3536
(SyncObjectTask:reapFutures:303) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Returned from the Future
3537
  :(3):
3538

    
3539
~~~~
3540

    
3541
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="INFO"
3542

    
3543
(SyncObjectTask:reapFutures:297) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SyncObjectState: FAILED
3544

    
3545
~~~~
3546

    
3547
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3548

    
3549
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3550

    
3551
~~~~
3552

    
3553
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3554

    
3555
(SyncObjectTask:reapFutures:303) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Returned from the Future
3556
  :(3):
3557

    
3558
~~~~
3559

    
3560
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="INFO"
3561

    
3562
(SyncObjectTask:reapFutures:297) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SyncObjectState: FAILED
3563

    
3564
~~~~
3565

    
3566
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3567

    
3568
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3569

    
3570
~~~~
3571

    
3572
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="INFO"
3573

    
3574
(V2TransferObjectTask:call:294) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  exiting with
3575
  callState: FAILED
3576

    
3577
~~~~
3578

    
3579
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="ERROR"
3580

    
3581
(SyncFailedTask:submitSynchronizationFailed:139) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 <?xml
3582
  version="1.0" encoding="UTF-8"?>
3583
<error detailCode="-1" errorCode="500" name="ServiceFailure">
3584
<description>415: Unsupported Media Type: parser for deserializing HTML not written yet.  Providing message body:
3585
&lt;!DOCTYPE html&gt;&lt;html&gt;&lt;head&gt;&lt;title&gt;Apache Tomcat/8.0.32 (Ubuntu) - Error
3586
  report&lt;/title&gt;&lt;style type="text/css"&gt;H1 {font-family:Tahoma,Arial,sans-serif;color:white;background-
3587
  color:#525D76;font-size:22px;} H2 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-
3588
  size:16px;} H3 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:14px;} BODY {font-
3589
  family:Tahoma,Arial,sans-serif;color:black;background-color:white;} B {font-family:Tahoma,Arial,sans-serif;color:white
3590
  ;background-color:#525D76;} P {font-family:Tahoma,Arial,sans-serif;background:white;color:black;font-size:12px;}A
3591
  {color : black;}A.name {color : black;}.line {height: 1px; background-color: #525D76; border: none;}&lt;/style&gt;
3592
  &lt;/head&gt;&lt;body&gt;&lt;h1&gt;HTTP Status 415 - Unsupported Media Type&lt;/h1&gt;&lt;div
3593
  class="line"&gt;&lt;/div&gt;&lt;p&gt;&lt;b&gt;type&lt;/b&gt; Status
3594
  report&lt;/p&gt;&lt;p&gt;&lt;b&gt;message&lt;/b&gt; &lt;u&gt;Unsupported Media
3595
  Type&lt;/u&gt;&lt;/p&gt;&lt;p&gt;&lt;b&gt;description&lt;/b&gt; &lt;u&gt;The server refused this request because the
3596
  request entity is in a format not supported by the requested resource for the requested
3597
  method.&lt;/u&gt;&lt;/p&gt;&lt;hr class="line"&gt;&lt;h3&gt;Apache Tomcat/8.0.32
3598
  (Ubuntu)&lt;/h3&gt;&lt;/body&gt;&lt;/html&gt;</description>
3599
</error>
3600

    
3601

    
3602
~~~~
3603

    
3604
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="INFO"
3605

    
3606
(V2TransferObjectTask:call:294) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  exiting with
3607
  callState: FAILED
3608

    
3609
~~~~
3610

    
3611
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="ERROR"
3612

    
3613
(SyncFailedTask:submitSynchronizationFailed:139) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 <?xml
3614
  version="1.0" encoding="UTF-8"?>
3615
<error detailCode="-1" errorCode="500" name="ServiceFailure">
3616
<description>415: Unsupported Media Type: parser for deserializing HTML not written yet.  Providing message body:
3617
&lt;!DOCTYPE html&gt;&lt;html&gt;&lt;head&gt;&lt;title&gt;Apache Tomcat/8.0.32 (Ubuntu) - Error
3618
  report&lt;/title&gt;&lt;style type="text/css"&gt;H1 {font-family:Tahoma,Arial,sans-serif;color:white;background-
3619
  color:#525D76;font-size:22px;} H2 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-
3620
  size:16px;} H3 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:14px;} BODY {font-
3621
  family:Tahoma,Arial,sans-serif;color:black;background-color:white;} B {font-family:Tahoma,Arial,sans-serif;color:white
3622
  ;background-color:#525D76;} P {font-family:Tahoma,Arial,sans-serif;background:white;color:black;font-size:12px;}A
3623
  {color : black;}A.name {color : black;}.line {height: 1px; background-color: #525D76; border: none;}&lt;/style&gt;
3624
  &lt;/head&gt;&lt;body&gt;&lt;h1&gt;HTTP Status 415 - Unsupported Media Type&lt;/h1&gt;&lt;div
3625
  class="line"&gt;&lt;/div&gt;&lt;p&gt;&lt;b&gt;type&lt;/b&gt; Status
3626
  report&lt;/p&gt;&lt;p&gt;&lt;b&gt;message&lt;/b&gt; &lt;u&gt;Unsupported Media
3627
  Type&lt;/u&gt;&lt;/p&gt;&lt;p&gt;&lt;b&gt;description&lt;/b&gt; &lt;u&gt;The server refused this request because the
3628
  request entity is in a format not supported by the requested resource for the requested
3629
  method.&lt;/u&gt;&lt;/p&gt;&lt;hr class="line"&gt;&lt;h3&gt;Apache Tomcat/8.0.32
3630
  (Ubuntu)&lt;/h3&gt;&lt;/body&gt;&lt;/html&gt;</description>
3631
</error>
3632

    
3633

    
3634
~~~~
3635

    
3636
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="WARN"
3637

    
3638
(SyncFailedTask:submitSynchronizationFailed:116) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
3639
  SynchronizationFailed: detail code: 6001 id:doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 nodeId:urn:node:CNUCSB1
3640
  description:Synchronization task of [PID::] doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause:
3641
  NotFound: Not Found
3642

    
3643
~~~~
3644

    
3645
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="ERROR"
3646

    
3647
(V2TransferObjectTask:call:269) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - UnrecoverableException:
3648
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 cn.createObject failed. - NotFound - Not Found
3649
org.dataone.cn.batch.exceptions.UnrecoverableException: doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 cn.createObject
3650
  failed.
3651
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.createObject(V2TransferObjectTask.java:815)
3652
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processNewObject(V2TransferObjectTask.java:493)
3653
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processTask(V2TransferObjectTask.java:426)
3654
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:211)
3655
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:113)
3656
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
3657
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
3658
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
3659
at java.lang.Thread.run(Thread.java:745)
3660
Caused by: org.dataone.service.exceptions.NotFound: Not Found
3661
at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
3662
at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
3663
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
3664
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
3665
at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
3666
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
3667
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
3668
at org.dataone.client.rest.MultipartD1Node.get(MultipartD1Node.java:366)
3669
at org.dataone.client.v2.impl.MultipartMNode.get(MultipartMNode.java:279)
3670
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.createObject(V2TransferObjectTask.java:756)
3671
... 8 more
3672

    
3673
~~~~
3674

    
3675
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="WARN"
3676

    
3677
(SyncFailedTask:submitSynchronizationFailed:116) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
3678
  SynchronizationFailed: detail code: 6001 id:doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 nodeId:urn:node:CNUCSB1
3679
  description:Synchronization task of [PID::] doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 [::PID] failed. Cause:
3680
  NotFound: Not Found
3681

    
3682
~~~~
3683

    
3684
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="ERROR"
3685

    
3686
(V2TransferObjectTask:call:269) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - UnrecoverableException:
3687
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 cn.createObject failed. - NotFound - Not Found
3688
org.dataone.cn.batch.exceptions.UnrecoverableException: doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 cn.createObject
3689
  failed.
3690
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.createObject(V2TransferObjectTask.java:815)
3691
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processNewObject(V2TransferObjectTask.java:493)
3692
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.processTask(V2TransferObjectTask.java:426)
3693
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:211)
3694
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.call(V2TransferObjectTask.java:113)
3695
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
3696
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
3697
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
3698
at java.lang.Thread.run(Thread.java:745)
3699
Caused by: org.dataone.service.exceptions.NotFound: Not Found
3700
at org.dataone.service.util.ExceptionHandler.deserializeXml(ExceptionHandler.java:629)
3701
at org.dataone.service.util.ExceptionHandler.deserializeXmlAndThrowException(ExceptionHandler.java:517)
3702
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:363)
3703
at org.dataone.service.util.ExceptionHandler.deserializeAndThrowException(ExceptionHandler.java:313)
3704
at org.dataone.service.util.ExceptionHandler.filterErrors(ExceptionHandler.java:107)
3705
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:343)
3706
at org.dataone.client.rest.HttpMultipartRestClient.doGetRequest(HttpMultipartRestClient.java:328)
3707
at org.dataone.client.rest.MultipartD1Node.get(MultipartD1Node.java:366)
3708
at org.dataone.client.v2.impl.MultipartMNode.get(MultipartMNode.java:279)
3709
at org.dataone.cn.batch.synchronization.tasks.V2TransferObjectTask.createObject(V2TransferObjectTask.java:756)
3710
... 8 more
3711

    
3712
~~~~
3713

    
3714
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="INFO"
3715

    
3716
(V2TransferObjectTask:call:234) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Unlocked Pid.
3717

    
3718
~~~~
3719

    
3720
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="INFO"
3721

    
3722
(V2TransferObjectTask:call:234) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Unlocked Pid.
3723

    
3724
~~~~
3725

    
3726
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="ERROR"
3727

    
3728
(StdOutErrLog$2:print:51) 440886659 [SynchronizeTask7384] INFO org.dataone.client.rest.RestClient -
3729
  RestClient.doRequestNoBody, thread(102757) call Info: GET
3730
  https://dataone.tdar.org/dataone/v2/object/doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3731

    
3732

    
3733
~~~~
3734

    
3735
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3736

    
3737
(V2TransferObjectTask:createObject:753) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 getting
3738
  ScienceMetadata
3739

    
3740
~~~~
3741

    
3742
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3743

    
3744
(V2TransferObjectTask:createObject:753) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 getting
3745
  ScienceMetadata
3746

    
3747
~~~~
3748

    
3749
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="ERROR"
3750

    
3751
(StdOutErrLog$2:print:51) 440886258 [SynchronizeTask7384] INFO org.dataone.client.rest.RestClient -
3752
  RestClient.doRequestNoBody, thread(102757) call Info: GET
3753
  https://dataone.tdar.org/dataone/v2/checksum/doi:10.6067:XCV8WM1D1X_meta$v=1356819351482?checksumAlgorithm=MD5
3754

    
3755

    
3756
~~~~
3757

    
3758
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3759

    
3760
(V2TransferObjectTask:validateChecksum:830) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
3761
  validateChecksum...
3762

    
3763
~~~~
3764

    
3765
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3766

    
3767
(V2TransferObjectTask:validateChecksum:830) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
3768
  validateChecksum...
3769

    
3770
~~~~
3771

    
3772
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="INFO"
3773

    
3774
(V2TransferObjectTask:createObject:722) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - Start
3775
  CreateObject
3776

    
3777
~~~~
3778

    
3779
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3780

    
3781
(V2TransferObjectTask:populateInitialReplicaList:543) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3782
  Added CN as replica because formatType METADATA is not DATA
3783

    
3784
~~~~
3785

    
3786
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="INFO"
3787

    
3788
(V2TransferObjectTask:createObject:722) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - Start
3789
  CreateObject
3790

    
3791
~~~~
3792

    
3793
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3794

    
3795
(V2TransferObjectTask:populateInitialReplicaList:543) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3796
  Added CN as replica because formatType METADATA is not DATA
3797

    
3798
~~~~
3799

    
3800
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3801

    
3802
(V2TransferObjectTask:populateInitialReplicaList:532) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Get
3803
  Object Format
3804

    
3805
~~~~
3806

    
3807
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3808

    
3809
(V2TransferObjectTask:populateInitialReplicaList:528) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3810
  Included replica for original MN
3811

    
3812
~~~~
3813

    
3814
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3815

    
3816
(V2TransferObjectTask:populateInitialReplicaList:513) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3817
  entering populateInitialReplicaList
3818

    
3819
~~~~
3820

    
3821
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3822

    
3823
(V2TransferObjectTask:processNewObject:477) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Pid is not
3824
  reserved by anyone.
3825

    
3826
~~~~
3827

    
3828
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3829

    
3830
(V2TransferObjectTask:populateInitialReplicaList:532) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Get
3831
  Object Format
3832

    
3833
~~~~
3834

    
3835
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3836

    
3837
(V2TransferObjectTask:populateInitialReplicaList:528) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3838
  Included replica for original MN
3839

    
3840
~~~~
3841

    
3842
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3843

    
3844
(V2TransferObjectTask:populateInitialReplicaList:513) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3845
  entering populateInitialReplicaList
3846

    
3847
~~~~
3848

    
3849
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3850

    
3851
(V2TransferObjectTask:processNewObject:477) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Pid is not
3852
  reserved by anyone.
3853

    
3854
~~~~
3855

    
3856
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3857

    
3858
(V2TransferObjectTask:processNewObject:464) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
3859
  processNewObject...
3860

    
3861
~~~~
3862

    
3863
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3864

    
3865
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 PID
3866
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 does not exist on the CN.
3867

    
3868
~~~~
3869

    
3870
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3871

    
3872
(V2TransferObjectTask:processNewObject:464) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
3873
  processNewObject...
3874

    
3875
~~~~
3876

    
3877
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3878

    
3879
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 PID
3880
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 does not exist on the CN.
3881

    
3882
~~~~
3883

    
3884
age="31d 0h 12m"  time="2017-05-17 20:00:16"  log="D1-PROCESSING-JSVC"  level="ERROR"
3885

    
3886
(StdOutErrLog$2:print:51) 440886054 [SynchronizeTask7384] INFO org.dataone.client.rest.RestClient -
3887
  RestClient.doRequestNoBody, thread(102757) call Info: GET https://cn-
3888
  ucsb-1.dataone.org/cn/v2/resolve/doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3889

    
3890

    
3891
~~~~
3892

    
3893
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3894

    
3895
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Waiting for the future
3896
  :(3): since 2017-05-01T20:00:13.509+00:00
3897

    
3898
~~~~
3899

    
3900
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3901

    
3902
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Waiting for the future
3903
  :(3): since 2017-05-01T20:00:13.509+00:00
3904

    
3905
~~~~
3906

    
3907
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3908

    
3909
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3910

    
3911
~~~~
3912

    
3913
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3914

    
3915
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3916

    
3917
~~~~
3918

    
3919
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3920

    
3921
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering resolve...
3922

    
3923
~~~~
3924

    
3925
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3926

    
3927
(V2TransferObjectTask:validateSeriesId:638) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 OK. SeriesId
3928
  is not reserved.
3929

    
3930
~~~~
3931

    
3932
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3933

    
3934
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering resolve...
3935

    
3936
~~~~
3937

    
3938
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3939

    
3940
(V2TransferObjectTask:validateSeriesId:638) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 OK. SeriesId
3941
  is not reserved.
3942

    
3943
~~~~
3944

    
3945
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3946

    
3947
(V2TransferObjectTask:validateSeriesId:624) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SeriesId
3948
  (377505_meta) doesn't exist for any object on the CN, checking reservation service...
3949

    
3950
~~~~
3951

    
3952
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3953

    
3954
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SID
3955
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 does not exist on the CN.
3956

    
3957
~~~~
3958

    
3959
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3960

    
3961
(V2TransferObjectTask:validateSeriesId:624) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SeriesId
3962
  (377505_meta) doesn't exist for any object on the CN, checking reservation service...
3963

    
3964
~~~~
3965

    
3966
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3967

    
3968
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 SID
3969
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 does not exist on the CN.
3970

    
3971
~~~~
3972

    
3973
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="ERROR"
3974

    
3975
(StdOutErrLog$2:print:51) 440885429 [SynchronizeTask7384] INFO org.dataone.client.rest.RestClient -
3976
  RestClient.doRequestNoBody, thread(102757) call Info: GET https://cn-
3977
  ucsb-1.dataone.org/cn/v2/resolve/doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
3978

    
3979

    
3980
~~~~
3981

    
3982
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
3983

    
3984
(SyncObjectTask:reapFutures:303) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Returned from the Future
3985
  :(5):
3986

    
3987
~~~~
3988

    
3989
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
3990

    
3991
(SyncObjectTask:reapFutures:303) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Returned from the Future
3992
  :(5):
3993

    
3994
~~~~
3995

    
3996
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="INFO"
3997

    
3998
(SyncObjectTask:reapFutures:297) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 SyncObjectState: SUCCESS
3999

    
4000
~~~~
4001

    
4002
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4003

    
4004
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4005

    
4006
~~~~
4007

    
4008
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="INFO"
4009

    
4010
(SyncObjectTask:reapFutures:297) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 SyncObjectState: SUCCESS
4011

    
4012
~~~~
4013

    
4014
age="31d 0h 12m"  time="2017-05-17 20:00:15"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4015

    
4016
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4017

    
4018
~~~~
4019

    
4020
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="INFO"
4021

    
4022
(V2TransferObjectTask:call:294) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  exiting with
4023
  callState: SUCCESS
4024

    
4025
~~~~
4026

    
4027
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="INFO"
4028

    
4029
(V2TransferObjectTask:call:294) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  exiting with
4030
  callState: SUCCESS
4031

    
4032
~~~~
4033

    
4034
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="INFO"
4035

    
4036
(V2TransferObjectTask:call:234) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  Unlocked Pid.
4037

    
4038
~~~~
4039

    
4040
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="INFO"
4041

    
4042
(V2TransferObjectTask:call:234) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  Unlocked Pid.
4043

    
4044
~~~~
4045

    
4046
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4047

    
4048
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering resolve...
4049

    
4050
~~~~
4051

    
4052
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4053

    
4054
(V2TransferObjectTask:validateSeriesId:580) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
4055
  validateSeriesId...
4056

    
4057
~~~~
4058

    
4059
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4060

    
4061
(V2TransferObjectTask:processTask:412) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
4062
  processTask...
4063

    
4064
~~~~
4065

    
4066
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4067

    
4068
(V2TransferObjectTask:call:209) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 MN system metadata
4069
  retrieved...
4070

    
4071
~~~~
4072

    
4073
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="INFO"
4074

    
4075
(V2TransferObjectTask:retrieveMNSystemMetadata:317) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
4076
  Retrieved SystemMetadata Identifier:doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 from node urn:node:TDAR for ObjectInfo
4077
  Identifier doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
4078

    
4079
~~~~
4080

    
4081
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4082

    
4083
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering resolve...
4084

    
4085
~~~~
4086

    
4087
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4088

    
4089
(V2TransferObjectTask:validateSeriesId:580) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
4090
  validateSeriesId...
4091

    
4092
~~~~
4093

    
4094
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4095

    
4096
(V2TransferObjectTask:processTask:412) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 entering
4097
  processTask...
4098

    
4099
~~~~
4100

    
4101
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4102

    
4103
(V2TransferObjectTask:call:209) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 MN system metadata
4104
  retrieved...
4105

    
4106
~~~~
4107

    
4108
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="INFO"
4109

    
4110
(V2TransferObjectTask:retrieveMNSystemMetadata:317) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -
4111
  Retrieved SystemMetadata Identifier:doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 from node urn:node:TDAR for ObjectInfo
4112
  Identifier doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
4113

    
4114
~~~~
4115

    
4116
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="ERROR"
4117

    
4118
(StdOutErrLog$2:print:51) 440884772 [SynchronizeTask7384] INFO org.dataone.client.rest.RestClient -
4119
  RestClient.doRequestNoBody, thread(102757) call Info: GET
4120
  https://dataone.tdar.org/dataone/v2/meta/doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
4121

    
4122

    
4123
~~~~
4124

    
4125
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="INFO"
4126

    
4127
(V2TransferObjectTask:createObject:805) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 - Completed
4128
  CreateObject
4129

    
4130
~~~~
4131

    
4132
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4133

    
4134
(V2TransferObjectTask:createObject:794) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 ... CNCreate
4135
  finished
4136

    
4137
~~~~
4138

    
4139
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="INFO"
4140

    
4141
(V2TransferObjectTask:createObject:805) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 - Completed
4142
  CreateObject
4143

    
4144
~~~~
4145

    
4146
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4147

    
4148
(V2TransferObjectTask:createObject:794) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 ... CNCreate
4149
  finished
4150

    
4151
~~~~
4152

    
4153
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="ERROR"
4154

    
4155
(StdOutErrLog$2:print:51) 440884758 [SynchronizeTask7381] INFO org.dataone.client.rest.RestClient -
4156
  RestClient.doRequestMMPBody, thread(102738) call Info: POST https://cn-ucsb-1.dataone.org/cn/v2/object; MMP message
4157
  has: ParamPart:pid = doi:10.6067:XCV8WM1D1X_meta$v=1493667645608; FilePart:object =
4158
  /tmp/mmp.output.6511241267508104030.tmp (2485 bytes); FilePart:sysmeta = /tmp/mmp.output.458160030191918558.tmp (1604
4159
  bytes);
4160

    
4161

    
4162
~~~~
4163

    
4164
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4165

    
4166
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Waiting for the future
4167
  :(3): since 2017-05-01T20:00:13.509+00:00
4168

    
4169
~~~~
4170

    
4171
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4172

    
4173
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 Waiting for the future
4174
  :(3): since 2017-05-01T20:00:13.509+00:00
4175

    
4176
~~~~
4177

    
4178
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4179

    
4180
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
4181

    
4182
~~~~
4183

    
4184
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4185

    
4186
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4187
  :(5): since 2017-05-01T20:00:04.027+00:00
4188

    
4189
~~~~
4190

    
4191
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4192

    
4193
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
4194

    
4195
~~~~
4196

    
4197
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4198

    
4199
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4200
  :(5): since 2017-05-01T20:00:04.027+00:00
4201

    
4202
~~~~
4203

    
4204
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4205

    
4206
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4207

    
4208
~~~~
4209

    
4210
age="31d 0h 12m"  time="2017-05-17 20:00:14"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4211

    
4212
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4213

    
4214
~~~~
4215

    
4216
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="D1-PROCESSING-JSVC"  level="INFO"
4217

    
4218
(ReplicationEventListener:entryAdded:104) Received entry added event on the hzSystemMetadata map for pid:
4219
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4220

    
4221
~~~~
4222

    
4223
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="CN-SYNCHRONIZATION"  level="INFO"
4224

    
4225
(V2TransferObjectTask:call:207) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Processing SyncObject
4226

    
4227
~~~~
4228

    
4229
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="D1-PROCESSING-JSVC"  level="INFO"
4230

    
4231
(V2TransferObjectTask:call:207) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Processing SyncObject
4232

    
4233
~~~~
4234

    
4235
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="CN-SYNCHRONIZATION"  level="INFO"
4236

    
4237
(V2TransferObjectTask:call:202) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Locking task, attempt
4238
  1
4239

    
4240
~~~~
4241

    
4242
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="D1-PROCESSING-JSVC"  level="INFO"
4243

    
4244
(V2TransferObjectTask:call:202) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 -  Locking task, attempt
4245
  1
4246

    
4247
~~~~
4248

    
4249
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="CN-SYNCHRONIZATION"  level="INFO"
4250

    
4251
(SyncObjectTask:executeTransferObjectTask:236) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 submitted
4252
  for execution
4253

    
4254
~~~~
4255

    
4256
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="CN-SYNCHRONIZATION"  level="INFO"
4257

    
4258
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
4259

    
4260
~~~~
4261

    
4262
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4263

    
4264
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4265
  :(5): since 2017-05-01T20:00:04.027+00:00
4266

    
4267
~~~~
4268

    
4269
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="D1-PROCESSING-JSVC"  level="INFO"
4270

    
4271
(SyncObjectTask:executeTransferObjectTask:236) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 submitted
4272
  for execution
4273

    
4274
~~~~
4275

    
4276
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="D1-PROCESSING-JSVC"  level="INFO"
4277

    
4278
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
4279

    
4280
~~~~
4281

    
4282
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4283

    
4284
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4285
  :(5): since 2017-05-01T20:00:04.027+00:00
4286

    
4287
~~~~
4288

    
4289
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4290

    
4291
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4292

    
4293
~~~~
4294

    
4295
age="31d 0h 12m"  time="2017-05-17 20:00:13"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4296

    
4297
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4298

    
4299
~~~~
4300

    
4301
age="31d 0h 12m"  time="2017-05-17 20:00:11"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4302

    
4303
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4304
  :(5): since 2017-05-01T20:00:04.027+00:00
4305

    
4306
~~~~
4307

    
4308
age="31d 0h 12m"  time="2017-05-17 20:00:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4309

    
4310
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4311
  :(5): since 2017-05-01T20:00:04.027+00:00
4312

    
4313
~~~~
4314

    
4315
age="31d 0h 12m"  time="2017-05-17 20:00:11"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4316

    
4317
(V2TransferObjectTask:createObject:790) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Calling
4318
  CNCreate...
4319

    
4320
~~~~
4321

    
4322
age="31d 0h 12m"  time="2017-05-17 20:00:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4323

    
4324
(V2TransferObjectTask:createObject:790) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Calling
4325
  CNCreate...
4326

    
4327
~~~~
4328

    
4329
age="31d 0h 12m"  time="2017-05-17 20:00:11"  log="D1-PROCESSING-JSVC"  level="ERROR"
4330

    
4331
(StdOutErrLog$2:print:51) 440881830 [SynchronizeTask7381] INFO org.dataone.client.rest.RestClient -
4332
  RestClient.doRequestNoBody, thread(102738) call Info: GET
4333
  https://dataone.tdar.org/dataone/v2/object/doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4334

    
4335

    
4336
~~~~
4337

    
4338
age="31d 0h 12m"  time="2017-05-17 20:00:11"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4339

    
4340
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4341

    
4342
~~~~
4343

    
4344
age="31d 0h 12m"  time="2017-05-17 20:00:11"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4345

    
4346
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4347

    
4348
~~~~
4349

    
4350
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4351

    
4352
(V2TransferObjectTask:createObject:753) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 getting
4353
  ScienceMetadata
4354

    
4355
~~~~
4356

    
4357
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4358

    
4359
(V2TransferObjectTask:createObject:753) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 getting
4360
  ScienceMetadata
4361

    
4362
~~~~
4363

    
4364
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="ERROR"
4365

    
4366
(StdOutErrLog$2:print:51) 440880808 [SynchronizeTask7381] INFO org.dataone.client.rest.RestClient -
4367
  RestClient.doRequestNoBody, thread(102738) call Info: GET
4368
  https://dataone.tdar.org/dataone/v2/checksum/doi:10.6067:XCV8WM1D1X_meta$v=1493667645608?checksumAlgorithm=MD5
4369

    
4370

    
4371
~~~~
4372

    
4373
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4374

    
4375
(V2TransferObjectTask:validateChecksum:830) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4376
  validateChecksum...
4377

    
4378
~~~~
4379

    
4380
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4381

    
4382
(V2TransferObjectTask:validateChecksum:830) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4383
  validateChecksum...
4384

    
4385
~~~~
4386

    
4387
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="INFO"
4388

    
4389
(V2TransferObjectTask:createObject:722) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 - Start
4390
  CreateObject
4391

    
4392
~~~~
4393

    
4394
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4395

    
4396
(V2TransferObjectTask:populateInitialReplicaList:543) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4397
  Added CN as replica because formatType METADATA is not DATA
4398

    
4399
~~~~
4400

    
4401
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="INFO"
4402

    
4403
(V2TransferObjectTask:createObject:722) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 - Start
4404
  CreateObject
4405

    
4406
~~~~
4407

    
4408
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4409

    
4410
(V2TransferObjectTask:populateInitialReplicaList:543) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4411
  Added CN as replica because formatType METADATA is not DATA
4412

    
4413
~~~~
4414

    
4415
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4416

    
4417
(V2TransferObjectTask:populateInitialReplicaList:532) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Get
4418
  Object Format
4419

    
4420
~~~~
4421

    
4422
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4423

    
4424
(V2TransferObjectTask:populateInitialReplicaList:528) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4425
  Included replica for original MN
4426

    
4427
~~~~
4428

    
4429
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4430

    
4431
(V2TransferObjectTask:populateInitialReplicaList:513) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4432
  entering populateInitialReplicaList
4433

    
4434
~~~~
4435

    
4436
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4437

    
4438
(V2TransferObjectTask:processNewObject:477) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Pid is not
4439
  reserved by anyone.
4440

    
4441
~~~~
4442

    
4443
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4444

    
4445
(V2TransferObjectTask:populateInitialReplicaList:532) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Get
4446
  Object Format
4447

    
4448
~~~~
4449

    
4450
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4451

    
4452
(V2TransferObjectTask:populateInitialReplicaList:528) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4453
  Included replica for original MN
4454

    
4455
~~~~
4456

    
4457
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4458

    
4459
(V2TransferObjectTask:populateInitialReplicaList:513) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4460
  entering populateInitialReplicaList
4461

    
4462
~~~~
4463

    
4464
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4465

    
4466
(V2TransferObjectTask:processNewObject:477) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Pid is not
4467
  reserved by anyone.
4468

    
4469
~~~~
4470

    
4471
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4472

    
4473
(V2TransferObjectTask:processNewObject:464) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4474
  processNewObject...
4475

    
4476
~~~~
4477

    
4478
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4479

    
4480
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 PID
4481
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 does not exist on the CN.
4482

    
4483
~~~~
4484

    
4485
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4486

    
4487
(V2TransferObjectTask:processNewObject:464) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4488
  processNewObject...
4489

    
4490
~~~~
4491

    
4492
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4493

    
4494
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 PID
4495
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 does not exist on the CN.
4496

    
4497
~~~~
4498

    
4499
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="ERROR"
4500

    
4501
(StdOutErrLog$2:print:51) 440880628 [SynchronizeTask7381] INFO org.dataone.client.rest.RestClient -
4502
  RestClient.doRequestNoBody, thread(102738) call Info: GET https://cn-
4503
  ucsb-1.dataone.org/cn/v2/resolve/doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4504

    
4505

    
4506
~~~~
4507

    
4508
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4509

    
4510
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4511
  :(5): since 2017-05-01T20:00:04.027+00:00
4512

    
4513
~~~~
4514

    
4515
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4516

    
4517
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4518
  :(5): since 2017-05-01T20:00:04.027+00:00
4519

    
4520
~~~~
4521

    
4522
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4523

    
4524
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4525

    
4526
~~~~
4527

    
4528
age="31d 0h 12m"  time="2017-05-17 20:00:10"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4529

    
4530
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4531

    
4532
~~~~
4533

    
4534
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4535

    
4536
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering resolve...
4537

    
4538
~~~~
4539

    
4540
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4541

    
4542
(V2TransferObjectTask:validateSeriesId:638) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 OK. SeriesId
4543
  is not reserved.
4544

    
4545
~~~~
4546

    
4547
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4548

    
4549
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering resolve...
4550

    
4551
~~~~
4552

    
4553
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4554

    
4555
(V2TransferObjectTask:validateSeriesId:638) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 OK. SeriesId
4556
  is not reserved.
4557

    
4558
~~~~
4559

    
4560
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4561

    
4562
(V2TransferObjectTask:validateSeriesId:624) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 SeriesId
4563
  (377505_meta) doesn't exist for any object on the CN, checking reservation service...
4564

    
4565
~~~~
4566

    
4567
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4568

    
4569
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 SID
4570
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 does not exist on the CN.
4571

    
4572
~~~~
4573

    
4574
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4575

    
4576
(V2TransferObjectTask:validateSeriesId:624) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 SeriesId
4577
  (377505_meta) doesn't exist for any object on the CN, checking reservation service...
4578

    
4579
~~~~
4580

    
4581
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4582

    
4583
(V2TransferObjectTask:resolve:690) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 SID
4584
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 does not exist on the CN.
4585

    
4586
~~~~
4587

    
4588
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="ERROR"
4589

    
4590
(StdOutErrLog$2:print:51) 440879848 [SynchronizeTask7381] INFO org.dataone.client.rest.RestClient -
4591
  RestClient.doRequestNoBody, thread(102738) call Info: GET https://cn-
4592
  ucsb-1.dataone.org/cn/v2/resolve/doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4593

    
4594

    
4595
~~~~
4596

    
4597
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="INFO"
4598

    
4599
(SyncObjectTask:call:154) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - requeueing task.
4600

    
4601
~~~~
4602

    
4603
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="INFO"
4604

    
4605
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
4606

    
4607
~~~~
4608

    
4609
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="INFO"
4610

    
4611
(SyncObjectTask:call:154) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - requeueing task.
4612

    
4613
~~~~
4614

    
4615
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="INFO"
4616

    
4617
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
4618

    
4619
~~~~
4620

    
4621
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4622

    
4623
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4624
  :(5): since 2017-05-01T20:00:04.027+00:00
4625

    
4626
~~~~
4627

    
4628
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4629

    
4630
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4631
  :(5): since 2017-05-01T20:00:04.027+00:00
4632

    
4633
~~~~
4634

    
4635
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4636

    
4637
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering resolve...
4638

    
4639
~~~~
4640

    
4641
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4642

    
4643
(V2TransferObjectTask:validateSeriesId:580) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4644
  validateSeriesId...
4645

    
4646
~~~~
4647

    
4648
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4649

    
4650
(V2TransferObjectTask:processTask:412) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4651
  processTask...
4652

    
4653
~~~~
4654

    
4655
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4656

    
4657
(V2TransferObjectTask:call:209) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 MN system metadata
4658
  retrieved...
4659

    
4660
~~~~
4661

    
4662
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="INFO"
4663

    
4664
(V2TransferObjectTask:retrieveMNSystemMetadata:317) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -
4665
  Retrieved SystemMetadata Identifier:doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 from node urn:node:TDAR for ObjectInfo
4666
  Identifier doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4667

    
4668
~~~~
4669

    
4670
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4671

    
4672
(V2TransferObjectTask:resolve:682) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering resolve...
4673

    
4674
~~~~
4675

    
4676
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4677

    
4678
(V2TransferObjectTask:validateSeriesId:580) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4679
  validateSeriesId...
4680

    
4681
~~~~
4682

    
4683
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4684

    
4685
(V2TransferObjectTask:processTask:412) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 entering
4686
  processTask...
4687

    
4688
~~~~
4689

    
4690
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4691

    
4692
(V2TransferObjectTask:call:209) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 MN system metadata
4693
  retrieved...
4694

    
4695
~~~~
4696

    
4697
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="INFO"
4698

    
4699
(V2TransferObjectTask:retrieveMNSystemMetadata:317) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -
4700
  Retrieved SystemMetadata Identifier:doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 from node urn:node:TDAR for ObjectInfo
4701
  Identifier doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4702

    
4703
~~~~
4704

    
4705
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="ERROR"
4706

    
4707
(StdOutErrLog$2:print:51) 440879096 [SynchronizeTask7381] INFO org.dataone.client.rest.RestClient -
4708
  RestClient.doRequestNoBody, thread(102738) call Info: GET
4709
  https://dataone.tdar.org/dataone/v2/meta/doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4710

    
4711

    
4712
~~~~
4713

    
4714
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4715

    
4716
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4717

    
4718
~~~~
4719

    
4720
age="31d 0h 12m"  time="2017-05-17 20:00:09"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4721

    
4722
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4723

    
4724
~~~~
4725

    
4726
age="31d 0h 12m"  time="2017-05-17 20:00:07"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4727

    
4728
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4729
  :(5): since 2017-05-01T20:00:04.027+00:00
4730

    
4731
~~~~
4732

    
4733
age="31d 0h 12m"  time="2017-05-17 20:00:07"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4734

    
4735
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4736
  :(5): since 2017-05-01T20:00:04.027+00:00
4737

    
4738
~~~~
4739

    
4740
age="31d 0h 12m"  time="2017-05-17 20:00:07"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4741

    
4742
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4743

    
4744
~~~~
4745

    
4746
age="31d 0h 12m"  time="2017-05-17 20:00:07"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4747

    
4748
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4749

    
4750
~~~~
4751

    
4752
age="31d 0h 12m"  time="2017-05-17 20:00:06"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4753

    
4754
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4755
  :(5): since 2017-05-01T20:00:04.027+00:00
4756

    
4757
~~~~
4758

    
4759
age="31d 0h 12m"  time="2017-05-17 20:00:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4760

    
4761
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4762
  :(5): since 2017-05-01T20:00:04.027+00:00
4763

    
4764
~~~~
4765

    
4766
age="31d 0h 12m"  time="2017-05-17 20:00:06"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4767

    
4768
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4769

    
4770
~~~~
4771

    
4772
age="31d 0h 12m"  time="2017-05-17 20:00:06"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4773

    
4774
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4775

    
4776
~~~~
4777

    
4778
age="31d 0h 12m"  time="2017-05-17 20:00:05"  log="CN-SYNCHRONIZATION"  level="INFO"
4779

    
4780
(SyncObjectTask:call:154) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - requeueing task.
4781

    
4782
~~~~
4783

    
4784
age="31d 0h 12m"  time="2017-05-17 20:00:05"  log="CN-SYNCHRONIZATION"  level="INFO"
4785

    
4786
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
4787

    
4788
~~~~
4789

    
4790
age="31d 0h 12m"  time="2017-05-17 20:00:05"  log="D1-PROCESSING-JSVC"  level="INFO"
4791

    
4792
(SyncObjectTask:call:154) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 - requeueing task.
4793

    
4794
~~~~
4795

    
4796
age="31d 0h 12m"  time="2017-05-17 20:00:05"  log="D1-PROCESSING-JSVC"  level="INFO"
4797

    
4798
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1356819351482 received
4799

    
4800
~~~~
4801

    
4802
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4803

    
4804
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4805
  :(5): since 2017-05-01T20:00:04.027+00:00
4806

    
4807
~~~~
4808

    
4809
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4810

    
4811
(SyncObjectTask:reapFutures:334) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 Waiting for the future
4812
  :(5): since 2017-05-01T20:00:04.027+00:00
4813

    
4814
~~~~
4815

    
4816
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4817

    
4818
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4819

    
4820
~~~~
4821

    
4822
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4823

    
4824
(SyncObjectTask:reapFutures:293) trying future Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4825

    
4826
~~~~
4827

    
4828
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="CN-SYNCHRONIZATION"  level="INFO"
4829

    
4830
(V2TransferObjectTask:call:207) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  Processing SyncObject
4831

    
4832
~~~~
4833

    
4834
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="D1-PROCESSING-JSVC"  level="INFO"
4835

    
4836
(V2TransferObjectTask:call:207) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  Processing SyncObject
4837

    
4838
~~~~
4839

    
4840
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="CN-SYNCHRONIZATION"  level="INFO"
4841

    
4842
(V2TransferObjectTask:call:202) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  Locking task, attempt
4843
  1
4844

    
4845
~~~~
4846

    
4847
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="D1-PROCESSING-JSVC"  level="INFO"
4848

    
4849
(V2TransferObjectTask:call:202) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 -  Locking task, attempt
4850
  1
4851

    
4852
~~~~
4853

    
4854
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="CN-SYNCHRONIZATION"  level="INFO"
4855

    
4856
(SyncObjectTask:executeTransferObjectTask:236) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 submitted
4857
  for execution
4858

    
4859
~~~~
4860

    
4861
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="D1-PROCESSING-JSVC"  level="INFO"
4862

    
4863
(SyncObjectTask:executeTransferObjectTask:236) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 submitted
4864
  for execution
4865

    
4866
~~~~
4867

    
4868
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="CN-SYNCHRONIZATION"  level="INFO"
4869

    
4870
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 received
4871

    
4872
~~~~
4873

    
4874
age="31d 0h 12m"  time="2017-05-17 20:00:04"  log="D1-PROCESSING-JSVC"  level="INFO"
4875

    
4876
(SyncObjectTask:executeTransferObjectTask:219) Task-urn:node:TDAR-doi:10.6067:XCV8WM1D1X_meta$v=1493667645608 received
4877

    
4878
~~~~
4879

    
4880
age="31d 0h 12m"  time="2017-05-17 20:00:01"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4881

    
4882
(ObjectListHarvestTask:call:221) placed on hzSyncObjectQueue- Task-urn:node:TDAR-
4883
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
4884

    
4885
~~~~
4886

    
4887
age="31d 0h 12m"  time="2017-05-17 20:00:01"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4888

    
4889
(ObjectListHarvestTask:call:221) placed on hzSyncObjectQueue- Task-urn:node:TDAR-
4890
  doi:10.6067:XCV8WM1D1X_meta$v=1356819351482
4891

    
4892
~~~~
4893

    
4894
age="31d 0h 12m"  time="2017-05-17 20:00:01"  log="CN-SYNCHRONIZATION"  level="DEBUG"
4895

    
4896
(ObjectListHarvestTask:call:221) placed on hzSyncObjectQueue- Task-urn:node:TDAR-
4897
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4898

    
4899
~~~~
4900

    
4901
age="31d 0h 12m"  time="2017-05-17 20:00:01"  log="D1-PROCESSING-JSVC"  level="DEBUG"
4902

    
4903
(ObjectListHarvestTask:call:221) placed on hzSyncObjectQueue- Task-urn:node:TDAR-
4904
  doi:10.6067:XCV8WM1D1X_meta$v=1493667645608
4905

    
Add picture from clipboard (Maximum size: 14.8 MB)