November 25 18:23:20 INFO: Max DO size: 335544320 bytes November 25 18:23:20 INFO [0000000001489370]: set entire process max log size to 10000000 November 25 18:23:20 INFO [0000000001489370]: set entire process max log size to 10000000 November 25 18:23:20 INFO [0000000001489370]: Startup: occurred at Wed Nov 25 18:23:20 2015 November 25 18:23:20 INFO [0000000001489370]: Successfully loaded configuration from z:\DEDUP\etc\puredisk\spa.cfg November 25 18:23:20 INFO [0000000001489370]: Startup: Symantec PureDisk Storage Pool Authority Version 8.0102.0015.0430. November 25 18:23:20 INFO [0000000001489370]: Startup: using Symantec: libdct 6.0.0.0, July 7, 2004 November 25 18:23:20 INFO [0000000001489370]: Startup: using Symantec PureDisk: libcr 6.1.0.0, December 13, 2006 November 25 18:23:20 INFO [0000000001489370]: Startup: upgrade of spa DB skipped November 25 18:23:20 INFO [0000000001489370]: Memory Manager: initializing November 25 18:23:20 INFO [0000000001489370]: Memory Manager: initialization complete November 25 18:23:20 INFO [0000000001489370]: Sched Manager: initializing November 25 18:23:20 INFO [0000000001489370]: schedule string for QueueProcess: [20 */12 * * *] November 25 18:23:20 INFO [0000000001489370]: alert time for QueueProcess is 21600 November 25 18:23:20 INFO [0000000001489370]: lifecycle for QueueProcess is 32400 November 25 18:23:20 INFO [0000000001489370]: schedule string for RepOrphan: [*/10 * * * *] November 25 18:23:20 INFO [0000000001489370]: alert time for RepOrphan is 300 November 25 18:23:20 INFO [0000000001489370]: lifecycle for RepOrphan is 600 November 25 18:23:20 INFO [0000000001489370]: schedule string for CatalogBackup: [40 3 * * *] November 25 18:23:20 INFO [0000000001489370]: alert time for CatalogBackup is 21600 November 25 18:23:20 INFO [0000000001489370]: lifecycle for CatalogBackup is 32400 November 25 18:23:20 INFO [0000000001489370]: Sched Manager: initialization complete November 25 18:23:20 INFO [0000000001489370]: PD_Replication: initializing November 25 18:23:20 INFO [0000000001489370]: PD_Replication: initialization complete November 25 18:23:20 INFO [0000000001489370]: Task Manager: initializing November 25 18:23:20 INFO [0000000001489370]: Task Manager: seeding random number generator November 25 18:23:20 INFO [0000000001489370]: Task Manager: initializing DCT Content Routing (TM) November 25 18:23:20 INFO [0000000001489370]: Task Manager: initialization complete November 25 18:23:20 INFO [0000000001489370]: Connection Manager: initializing November 25 18:23:20 INFO [0000000001489370]: Connection Manager: initialization complete November 25 18:23:20 INFO [0000000001489370]: Catalog auto recovery is enabled. November 25 18:23:20 INFO [0000000001489370]: Catalog backup version count: 5, shadow pool size: 64 November 25 18:23:20 INFO [0000000001489370]: Catalog backup shadow path: z:\DEDUP\databases\catalogshadow November 25 18:23:20 INFO [0000000001489370]: Startup: completed at Wed Nov 25 18:23:20 2015 November 25 18:23:20 INFO [0000000001489370]: set entire process max log size to 10000000 November 25 18:23:20 INFO [0000000001489370]: set entire process max log size to 10000000 November 25 18:23:20 INFO [0000000001489370]: Increasing maximum number of open files from 512 to 2048 November 25 18:23:20 INFO [0000000001489370]: History Manager: start [thread 0000000001F83590] November 25 18:23:20 INFO [0000000001489370]: Sched Manager: started [thread 000000000292CAC0] November 25 18:23:20 INFO [0000000001489370]: DataCheck Manager: started [thread 000000000292CC80] November 25 18:23:20 INFO [0000000001489370]: Task Manager: started [thread 0000000002AC9D10] November 25 18:23:20 INFO [0000000001489370]: EventSystem::SpaDBEventStorage::checkConsistency Starting consistency check November 25 18:23:20 INFO [0000000001489370]: EventSystem::SpaDBEventStorage::checkConsistency Ending consistency check November 25 18:23:20 INFO [0000000001489370]: EventSystem::EventManager::newEvent Adding new event. type:4 November 25 18:23:20 INFO [0000000001489370]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 25 18:23:20 INFO [0000000001489370]: NetBindAndListen: bound myself to :10102 using IPv6 November 25 18:23:20 INFO [0000000001489370]: NetBindAndListen: bound myself to :10102 using IPv4 November 25 18:23:20 INFO [0000000001489370]: Connection Manager: started November 25 18:23:20 INFO [0000000001489370]: Setting service status to running if service start November 26 09:27:36 INFO [0000000002AC9ED0]: Server entered shutdown mode on Thu Nov 26 09:27:36 2015 November 26 09:27:36 WARNING [0000000001489370]: 25212: NetWaitForRemote: interrupted November 26 09:27:36 INFO [0000000001489370]: === Symantec PureDisk Storage Pool Authority === November 26 09:27:36 INFO [0000000001489370]: Software Version : 8.0102.0015.0430 November 26 09:27:36 INFO [0000000001489370]: Protocol Version : 6.6.1.1 November 26 09:27:36 INFO [0000000001489370]: Router ID : 0 November 26 09:27:36 INFO [0000000001489370]: Storage Pool ID : 0 November 26 09:27:36 INFO [0000000001489370]: Operational mode : Shutting down November 26 09:27:36 INFO [0000000001489370]: Reroute mode : Disabled November 26 09:27:36 INFO [0000000001489370]: Maintenance applications : (none) November 26 09:27:36 INFO [0000000001489370]: Started On : Wed Nov 25 18:23:20 2015 November 26 09:27:36 INFO [0000000001489370]: Uptime : 0 days, 15:04:16 November 26 09:27:36 INFO [0000000001489370]: Session timeout interval : 3600 seconds November 26 09:27:36 INFO [0000000001489370]: Configuration file : z:\DEDUP\etc\puredisk\spa.cfg November 26 09:27:36 INFO [0000000001489370]: Main Log File : z:\DEDUP\log\spad\spad.log November 26 09:27:36 INFO [0000000001489370]: Spool Location : November 26 09:27:36 INFO [0000000001489370]: Memory Usage : 26.71 MB November 26 09:27:36 INFO [0000000001489370]: Manager stack size : 0 November 26 09:27:36 INFO [0000000001489370]: Task stack size : 0 November 26 09:27:36 INFO [0000000001489370]: Max DO size : 335544320 November 26 09:27:36 INFO [0000000001489370]: === Memory Manager === November 26 09:27:36 INFO [0000000001489370]: Chunks in pool : 1 November 26 09:27:36 INFO [0000000001489370]: Bytes in pool : 4096 November 26 09:27:36 INFO [0000000001489370]: Bytes malloc requests : 30081024 November 26 09:27:36 INFO [0000000001489370]: Actual bytes malloced : 3170304 November 26 09:27:36 INFO [0000000001489370]: Bytes free requests : 30081024 November 26 09:27:36 INFO [0000000001489370]: Actual bytes freed : 3166208 November 26 09:27:36 INFO [0000000001489370]: === PD_Replication === November 26 09:27:36 INFO [0000000001489370]: === Network Manager === November 26 09:27:36 INFO [0000000001489370]: Using route table : (default) November 26 09:27:36 INFO [0000000001489370]: Listening on port : 10102 November 26 09:27:36 INFO [0000000001489370]: Listening on : (all interfaces) November 26 09:27:36 INFO [0000000001489370]: IP range for backup restore : (all interfaces) November 26 09:27:36 INFO [0000000001489370]: Allowed networks : (any) November 26 09:27:36 INFO [0000000001489370]: === Task Manager === November 26 09:27:36 INFO [0000000001489370]: Task Manager: Control task 1 [thread 000000000292CE40] for rf0020500095523.rfoc.srf:57587: running November 26 09:27:36 INFO [0000000001489370]: Task Manager: Control task 2 [thread 000000000292CE40] for rf0020500095560.rfoc.srf:55206: running November 26 09:27:36 INFO [0000000001489370]: Task Manager: Control task 3 [thread 000000000292CE40] for rf0020500095523.rfoc.srf:56341: running November 26 09:27:36 INFO [0000000001489370]: Task Manager: Control task 4 [thread 000000000292CE40] for rf0020500095560.rfoc.srf:55207: running November 26 09:27:36 INFO [0000000001489370]: Task Manager: Control task 5 [thread 000000000292CE40] for rf0020500095523.rfoc.srf:60141: running November 26 09:27:36 INFO [0000000001489370]: Task Manager: Control task 6 [thread 000000000292CE40] for rf0020500095523.rfoc.srf:60474: running November 26 09:27:36 INFO [0000000001489370]: Task Manager: Control task 7 [thread 000000000292D000] for rf0020500095523.rfoc.srf:60203: running November 26 09:27:36 INFO [0000000001489370]: Store mode actual : normal November 26 09:27:36 INFO [0000000001489370]: Receive mode actual : normal November 26 09:27:36 INFO [0000000001489370]: Dereference mode actual : normal November 26 09:27:36 INFO [0000000001489370]: System mode actual : normal November 26 09:27:36 INFO [0000000001489370]: Store mode requested : normal November 26 09:27:36 INFO [0000000001489370]: Receive mode requested : normal November 26 09:27:36 INFO [0000000001489370]: Dereference mode requested : normal November 26 09:27:36 INFO [0000000001489370]: System mode requested : normal November 26 09:27:36 INFO [0000000001489370]: Total tasks serviced : 2775 November 26 09:27:36 INFO [0000000001489370]: Total Capture tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Active Capture tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Successful Capture tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Failed Capture tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Total Receive tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Active Receive tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Successful Receive tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Failed Receive tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Total Control tasks : 2768 November 26 09:27:36 INFO [0000000001489370]: Active Control tasks : 7 November 26 09:27:36 INFO [0000000001489370]: Successful Control tasks : 2768 November 26 09:27:36 INFO [0000000001489370]: Failed Control tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Active Deref tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Successful Deref tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Failed Deref tasks : 0 November 26 09:27:36 INFO [0000000001489370]: Unknown task requests : 0 November 26 09:27:36 INFO [0000000001489370]: Total Bytes Sent : 2395072 November 26 09:27:36 INFO [0000000001489370]: Total Bytes Received : 1570600 November 26 09:27:36 INFO [0000000001489370]: Total Segments Received : 0 November 26 09:27:36 INFO [0000000001489370]: Total Segments Sent : 0 November 26 09:27:36 INFO [0000000001489370]: Total DO Received : 0 November 26 09:27:36 INFO [0000000001489370]: Total DO Sent : 0 November 26 09:27:36 INFO [0000000001489370]: === Authorization Manager Report === November 26 09:27:36 INFO [0000000001489370]: Status : disabled November 26 09:27:36 INFO [0000000001489370]: === Sched Manager Report === November 26 09:27:36 INFO [0000000001489370]: sync is called every 60 seconds November 26 09:27:36 INFO [0000000001489370]: === Symantec PureDisk Storage Pool Authority === November 26 09:27:36 INFO [0000000001489370]: Shutdown: entered at Thu Nov 26 09:27:36 2015 November 26 09:27:38 INFO [0000000001489370]: Task Manager: terminating routing November 26 09:27:38 INFO [0000000001489370]: Connection Manager: shutdown November 26 09:27:38 INFO [0000000001489370]: Sched Manager: shutdown November 26 09:27:41 INFO [0000000001489370]: DataCheck Manager: shutdown November 26 09:27:42 INFO [0000000001489370]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 09:27:42 INFO [0000000001489370]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 09:27:42 ERR [0000000001489370]: 25054: _crBinaryMessageSend2: Error sending data: software caused connection abort November 26 09:27:42 ERR [0000000001489370]: 25054: Synchronization to RF0020500095523 failed (software caused connection abort). November 26 09:27:42 ERR [0000000001489370]: 25054: _crBinaryMessageSend2: Error sending data: software caused connection abort November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016362.rfoc.srf\VM_WSUS\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016362.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016339.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016299.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016275.rf06.srf\DFS_RF06_05\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016275.rf06.srf\DFS_RF06_01\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016275.rf06.srf\DFS_RF06\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016275.rf06.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016260.rfoc.srf\FileServer_RF08\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016260.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016258.rf10.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016252.rf01.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016247.rf08.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016206.rf06.srf\Windows_AD\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016206.rf06.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016196.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016194.rfoc.srf\TESTE_VM\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500016194.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500015634.rfoc.srf\Notes_Inet\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500015634.rfoc.srf\Lotus_Notes_Inet\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\rf0020500015634.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\REPCONFIG\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\notes22.rfoc.srf\Domino_SCD\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\notes22.rfoc.srf\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\notes14rfoc.rfoc.srf\DominoC\po.db November 26 09:27:42 INFO [0000000001489370]: Close SQLite database z:\DEDUP\databases\catalog\2\po.db November 26 09:27:42 INFO [0000000001489370]: History Manager: shutdown November 26 09:27:42 ERR [0000000001489370]: 25054: _crBinaryMessageSend2: Error sending data: software caused connection abort November 26 09:27:42 INFO [0000000001489370]: Shutdown: completed at Thu Nov 26 09:27:36 2015 November 26 09:31:25 INFO: set entire process max log size to 0 November 26 09:31:25 INFO: set entire process max log size to 0 November 26 09:31:25 INFO: CR mode in spa db is normal ! November 26 09:31:25 INFO: Startup: loading configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 09:31:25 INFO: : extended attribute support enabled November 26 09:31:26 INFO: Manager thread stack size: 0 bytes November 26 09:31:26 INFO: Task thread stack size: 0 bytes November 26 09:31:26 INFO: Max DO size: 335544320 bytes November 26 09:31:26 INFO [00000000014E9370]: set entire process max log size to 10000000 November 26 09:31:26 INFO [00000000014E9370]: set entire process max log size to 10000000 November 26 09:31:26 INFO [00000000014E9370]: Startup: occurred at Thu Nov 26 09:31:25 2015 November 26 09:31:26 INFO [00000000014E9370]: Successfully loaded configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 09:31:26 INFO [00000000014E9370]: Startup: Symantec PureDisk Storage Pool Authority Version 8.0102.0015.0430. November 26 09:31:26 INFO [00000000014E9370]: Startup: using Symantec: libdct 6.0.0.0, July 7, 2004 November 26 09:31:26 INFO [00000000014E9370]: Startup: using Symantec PureDisk: libcr 6.1.0.0, December 13, 2006 November 26 09:31:26 INFO [00000000014E9370]: Startup: upgrade of spa DB skipped November 26 09:31:26 INFO [00000000014E9370]: Memory Manager: initializing November 26 09:31:26 INFO [00000000014E9370]: Memory Manager: initialization complete November 26 09:31:26 INFO [00000000014E9370]: Sched Manager: initializing November 26 09:31:26 INFO [00000000014E9370]: schedule string for QueueProcess: [20 */12 * * *] November 26 09:31:26 INFO [00000000014E9370]: alert time for QueueProcess is 21600 November 26 09:31:26 INFO [00000000014E9370]: lifecycle for QueueProcess is 32400 November 26 09:31:26 INFO [00000000014E9370]: schedule string for RepOrphan: [*/10 * * * *] November 26 09:31:26 INFO [00000000014E9370]: alert time for RepOrphan is 300 November 26 09:31:26 INFO [00000000014E9370]: lifecycle for RepOrphan is 600 November 26 09:31:26 INFO [00000000014E9370]: schedule string for CatalogBackup: [40 3 * * *] November 26 09:31:26 INFO [00000000014E9370]: alert time for CatalogBackup is 21600 November 26 09:31:26 INFO [00000000014E9370]: lifecycle for CatalogBackup is 32400 November 26 09:31:26 INFO [00000000014E9370]: Sched Manager: initialization complete November 26 09:31:26 INFO [00000000014E9370]: PD_Replication: initializing November 26 09:31:26 INFO [00000000014E9370]: PD_Replication: initialization complete November 26 09:31:26 INFO [00000000014E9370]: Task Manager: initializing November 26 09:31:26 INFO [00000000014E9370]: Task Manager: seeding random number generator November 26 09:31:26 INFO [00000000014E9370]: Task Manager: initializing DCT Content Routing (TM) November 26 09:31:26 INFO [00000000014E9370]: Task Manager: initialization complete November 26 09:31:26 INFO [00000000014E9370]: Connection Manager: initializing November 26 09:31:26 INFO [00000000014E9370]: Connection Manager: initialization complete November 26 09:31:26 INFO [00000000014E9370]: Catalog auto recovery is enabled. November 26 09:31:26 INFO [00000000014E9370]: Catalog backup version count: 5, shadow pool size: 64 November 26 09:31:26 INFO [00000000014E9370]: Catalog backup shadow path: z:\DEDUP\databases\catalogshadow November 26 09:31:26 INFO [00000000014E9370]: Startup: completed at Thu Nov 26 09:31:26 2015 November 26 09:31:26 INFO [00000000014E9370]: set entire process max log size to 10000000 November 26 09:31:26 INFO [00000000014E9370]: set entire process max log size to 10000000 November 26 09:31:26 INFO [00000000014E9370]: Increasing maximum number of open files from 512 to 2048 November 26 09:31:26 INFO [00000000014E9370]: History Manager: start [thread 0000000001F13590] November 26 09:31:26 INFO [00000000014E9370]: Sched Manager: started [thread 00000000028BCB60] November 26 09:31:26 INFO [00000000014E9370]: DataCheck Manager: started [thread 00000000028BCD20] November 26 09:31:26 INFO [00000000014E9370]: Task Manager: started [thread 0000000002A59DB0] November 26 09:31:26 INFO [00000000014E9370]: EventSystem::SpaDBEventStorage::checkConsistency Starting consistency check November 26 09:31:26 INFO [00000000014E9370]: EventSystem::SpaDBEventStorage::checkConsistency Ending consistency check November 26 09:31:26 INFO [00000000014E9370]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 09:31:26 INFO [00000000014E9370]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 09:31:26 INFO [00000000014E9370]: NetBindAndListen: bound myself to :10102 using IPv6 November 26 09:31:26 INFO [00000000014E9370]: NetBindAndListen: bound myself to :10102 using IPv4 November 26 09:31:26 INFO [00000000014E9370]: Connection Manager: started November 26 09:31:26 INFO [00000000014E9370]: Setting service status to running if service start November 26 09:56:37 INFO [0000000002A59F70]: Server entered shutdown mode on Thu Nov 26 09:56:37 2015 November 26 09:56:37 WARNING [00000000014E9370]: 25212: NetWaitForRemote: interrupted November 26 09:56:37 INFO [00000000014E9370]: === Symantec PureDisk Storage Pool Authority === November 26 09:56:37 INFO [00000000014E9370]: Software Version : 8.0102.0015.0430 November 26 09:56:37 INFO [00000000014E9370]: Protocol Version : 6.6.1.1 November 26 09:56:37 INFO [00000000014E9370]: Router ID : 0 November 26 09:56:37 INFO [00000000014E9370]: Storage Pool ID : 0 November 26 09:56:37 INFO [00000000014E9370]: Operational mode : Shutting down November 26 09:56:37 INFO [00000000014E9370]: Reroute mode : Disabled November 26 09:56:37 INFO [00000000014E9370]: Maintenance applications : (none) November 26 09:56:37 INFO [00000000014E9370]: Started On : Thu Nov 26 09:31:25 2015 November 26 09:56:37 INFO [00000000014E9370]: Uptime : 0 days, 00:25:12 November 26 09:56:37 INFO [00000000014E9370]: Session timeout interval : 3600 seconds November 26 09:56:37 INFO [00000000014E9370]: Configuration file : z:\DEDUP\etc\puredisk\spa.cfg November 26 09:56:37 INFO [00000000014E9370]: Main Log File : z:\DEDUP\log\spad\spad.log November 26 09:56:37 INFO [00000000014E9370]: Spool Location : November 26 09:56:37 INFO [00000000014E9370]: Memory Usage : 22.32 MB November 26 09:56:37 INFO [00000000014E9370]: Manager stack size : 0 November 26 09:56:37 INFO [00000000014E9370]: Task stack size : 0 November 26 09:56:37 INFO [00000000014E9370]: Max DO size : 335544320 November 26 09:56:37 INFO [00000000014E9370]: === Memory Manager === November 26 09:56:37 INFO [00000000014E9370]: Chunks in pool : 1 November 26 09:56:37 INFO [00000000014E9370]: Bytes in pool : 4096 November 26 09:56:37 INFO [00000000014E9370]: Bytes malloc requests : 999424 November 26 09:56:37 INFO [00000000014E9370]: Actual bytes malloced : 94208 November 26 09:56:37 INFO [00000000014E9370]: Bytes free requests : 999424 November 26 09:56:37 INFO [00000000014E9370]: Actual bytes freed : 90112 November 26 09:56:37 INFO [00000000014E9370]: === PD_Replication === November 26 09:56:37 INFO [00000000014E9370]: === Network Manager === November 26 09:56:37 INFO [00000000014E9370]: Using route table : (default) November 26 09:56:37 INFO [00000000014E9370]: Listening on port : 10102 November 26 09:56:37 INFO [00000000014E9370]: Listening on : (all interfaces) November 26 09:56:37 INFO [00000000014E9370]: IP range for backup restore : (all interfaces) November 26 09:56:37 INFO [00000000014E9370]: Allowed networks : (any) November 26 09:56:37 INFO [00000000014E9370]: === Task Manager === November 26 09:56:37 INFO [00000000014E9370]: Task Manager: Control task 0 [thread 00000000028BCEE0] for rf0020500095560.rfoc.srf:55991: running November 26 09:56:37 INFO [00000000014E9370]: Task Manager: Control task 1 [thread 00000000028BCEE0] for rf0020500095560.rfoc.srf:55996: running November 26 09:56:37 INFO [00000000014E9370]: Task Manager: Control task 2 [thread 00000000028BCEE0] for rf0020500095523.rfoc.srf:50049: running November 26 09:56:37 INFO [00000000014E9370]: Task Manager: Control task 3 [thread 00000000028BCEE0] for rf0020500095523.rfoc.srf:50131: running November 26 09:56:37 INFO [00000000014E9370]: Task Manager: Control task 4 [thread 00000000028BCEE0] for rf0020500095523.rfoc.srf:50304: running November 26 09:56:37 INFO [00000000014E9370]: Store mode actual : normal November 26 09:56:37 INFO [00000000014E9370]: Receive mode actual : normal November 26 09:56:37 INFO [00000000014E9370]: Dereference mode actual : normal November 26 09:56:37 INFO [00000000014E9370]: System mode actual : normal November 26 09:56:37 INFO [00000000014E9370]: Store mode requested : normal November 26 09:56:37 INFO [00000000014E9370]: Receive mode requested : normal November 26 09:56:37 INFO [00000000014E9370]: Dereference mode requested : normal November 26 09:56:37 INFO [00000000014E9370]: System mode requested : normal November 26 09:56:37 INFO [00000000014E9370]: Total tasks serviced : 96 November 26 09:56:37 INFO [00000000014E9370]: Total Capture tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Active Capture tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Successful Capture tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Failed Capture tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Total Receive tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Active Receive tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Successful Receive tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Failed Receive tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Total Control tasks : 91 November 26 09:56:37 INFO [00000000014E9370]: Active Control tasks : 5 November 26 09:56:37 INFO [00000000014E9370]: Successful Control tasks : 91 November 26 09:56:37 INFO [00000000014E9370]: Failed Control tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Active Deref tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Successful Deref tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Failed Deref tasks : 0 November 26 09:56:37 INFO [00000000014E9370]: Unknown task requests : 0 November 26 09:56:37 INFO [00000000014E9370]: Total Bytes Sent : 217060 November 26 09:56:37 INFO [00000000014E9370]: Total Bytes Received : 52648 November 26 09:56:37 INFO [00000000014E9370]: Total Segments Received : 0 November 26 09:56:37 INFO [00000000014E9370]: Total Segments Sent : 0 November 26 09:56:37 INFO [00000000014E9370]: Total DO Received : 0 November 26 09:56:37 INFO [00000000014E9370]: Total DO Sent : 0 November 26 09:56:37 INFO [00000000014E9370]: === Authorization Manager Report === November 26 09:56:37 INFO [00000000014E9370]: Status : disabled November 26 09:56:37 INFO [00000000014E9370]: === Sched Manager Report === November 26 09:56:37 INFO [00000000014E9370]: sync is called every 60 seconds November 26 09:56:37 INFO [00000000014E9370]: === Symantec PureDisk Storage Pool Authority === November 26 09:56:37 INFO [00000000014E9370]: Shutdown: entered at Thu Nov 26 09:56:37 2015 November 26 09:56:37 INFO [00000000014E9370]: Task Manager: terminating routing November 26 09:56:37 INFO [00000000014E9370]: Connection Manager: shutdown November 26 09:56:37 INFO [00000000014E9370]: Sched Manager: shutdown November 26 09:56:41 INFO [00000000014E9370]: DataCheck Manager: shutdown November 26 09:56:42 INFO [00000000014E9370]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 09:56:42 INFO [00000000014E9370]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 09:56:43 INFO [00000000014E9370]: Close SQLite database z:\DEDUP\databases\catalog\2\REPCONFIG\po.db November 26 09:56:43 INFO [00000000014E9370]: Close SQLite database z:\DEDUP\databases\catalog\2\po.db November 26 09:56:43 INFO [00000000014E9370]: History Manager: shutdown November 26 09:56:43 INFO [00000000014E9370]: Shutdown: completed at Thu Nov 26 09:56:37 2015 November 26 09:56:43 INFO: set entire process max log size to 0 November 26 09:56:43 INFO: set entire process max log size to 0 November 26 09:56:43 INFO: CR mode in spa db is normal ! November 26 09:56:43 INFO: Startup: loading configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 09:56:43 INFO: : extended attribute support enabled November 26 09:56:43 INFO: Manager thread stack size: 0 bytes November 26 09:56:43 INFO: Task thread stack size: 0 bytes November 26 09:56:43 INFO: Max DO size: 335544320 bytes November 26 09:56:43 INFO [0000000000FD91D0]: set entire process max log size to 10000000 November 26 09:56:43 INFO [0000000000FD91D0]: set entire process max log size to 10000000 November 26 09:56:43 INFO [0000000000FD91D0]: Startup: occurred at Thu Nov 26 09:56:43 2015 November 26 09:56:43 INFO [0000000000FD91D0]: Successfully loaded configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 09:56:43 INFO [0000000000FD91D0]: Startup: Symantec PureDisk Storage Pool Authority Version 8.0102.0015.0430. November 26 09:56:43 INFO [0000000000FD91D0]: Startup: using Symantec: libdct 6.0.0.0, July 7, 2004 November 26 09:56:43 INFO [0000000000FD91D0]: Startup: using Symantec PureDisk: libcr 6.1.0.0, December 13, 2006 November 26 09:56:43 INFO [0000000000FD91D0]: Startup: upgrade of spa DB skipped November 26 09:56:43 INFO [0000000000FD91D0]: Memory Manager: initializing November 26 09:56:43 INFO [0000000000FD91D0]: Memory Manager: initialization complete November 26 09:56:43 INFO [0000000000FD91D0]: Sched Manager: initializing November 26 09:56:43 INFO [0000000000FD91D0]: schedule string for QueueProcess: [20 */12 * * *] November 26 09:56:43 INFO [0000000000FD91D0]: alert time for QueueProcess is 21600 November 26 09:56:43 INFO [0000000000FD91D0]: lifecycle for QueueProcess is 32400 November 26 09:56:43 INFO [0000000000FD91D0]: schedule string for RepOrphan: [*/10 * * * *] November 26 09:56:43 INFO [0000000000FD91D0]: alert time for RepOrphan is 300 November 26 09:56:43 INFO [0000000000FD91D0]: lifecycle for RepOrphan is 600 November 26 09:56:43 INFO [0000000000FD91D0]: schedule string for CatalogBackup: [40 3 * * *] November 26 09:56:43 INFO [0000000000FD91D0]: alert time for CatalogBackup is 21600 November 26 09:56:43 INFO [0000000000FD91D0]: lifecycle for CatalogBackup is 32400 November 26 09:56:43 INFO [0000000000FD91D0]: Sched Manager: initialization complete November 26 09:56:43 INFO [0000000000FD91D0]: PD_Replication: initializing November 26 09:56:43 INFO [0000000000FD91D0]: PD_Replication: initialization complete November 26 09:56:43 INFO [0000000000FD91D0]: Task Manager: initializing November 26 09:56:43 INFO [0000000000FD91D0]: Task Manager: seeding random number generator November 26 09:56:43 INFO [0000000000FD91D0]: Task Manager: initializing DCT Content Routing (TM) November 26 09:56:43 INFO [0000000000FD91D0]: Task Manager: initialization complete November 26 09:56:43 INFO [0000000000FD91D0]: Connection Manager: initializing November 26 09:56:43 INFO [0000000000FD91D0]: Connection Manager: initialization complete November 26 09:56:43 INFO [0000000000FD91D0]: Catalog auto recovery is enabled. November 26 09:56:43 INFO [0000000000FD91D0]: Catalog backup version count: 5, shadow pool size: 64 November 26 09:56:43 INFO [0000000000FD91D0]: Catalog backup shadow path: z:\DEDUP\databases\catalogshadow November 26 09:56:43 INFO [0000000000FD91D0]: Startup: completed at Thu Nov 26 09:56:43 2015 November 26 09:56:43 INFO [0000000000FD91D0]: set entire process max log size to 10000000 November 26 09:56:43 INFO [0000000000FD91D0]: set entire process max log size to 10000000 November 26 09:56:43 INFO [0000000000FD91D0]: Increasing maximum number of open files from 512 to 2048 November 26 09:56:43 INFO [0000000000FD91D0]: History Manager: start [thread 0000000001FC7F00] November 26 09:56:43 INFO [0000000000FD91D0]: Sched Manager: started [thread 000000000217E2A0] November 26 09:56:43 INFO [0000000000FD91D0]: DataCheck Manager: started [thread 000000000217E460] November 26 09:56:43 INFO [0000000000FD91D0]: Task Manager: started [thread 0000000002A69550] November 26 09:56:43 INFO [0000000000FD91D0]: EventSystem::SpaDBEventStorage::checkConsistency Starting consistency check November 26 09:56:43 INFO [0000000000FD91D0]: EventSystem::SpaDBEventStorage::checkConsistency Ending consistency check November 26 09:56:43 INFO [0000000000FD91D0]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 09:56:43 INFO [0000000000FD91D0]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 09:56:44 INFO [0000000000FD91D0]: NetBindAndListen: bound myself to :10102 using IPv6 November 26 09:56:44 INFO [0000000000FD91D0]: NetBindAndListen: bound myself to :10102 using IPv4 November 26 09:56:44 INFO [0000000000FD91D0]: Connection Manager: started November 26 09:56:44 INFO [0000000000FD91D0]: Setting service status to running if service start November 26 10:12:03 INFO [0000000002A69A90]: Server entered shutdown mode on Thu Nov 26 10:12:03 2015 November 26 10:12:03 WARNING [0000000000FD91D0]: 25212: NetWaitForRemote: interrupted November 26 10:12:03 INFO [0000000000FD91D0]: === Symantec PureDisk Storage Pool Authority === November 26 10:12:03 INFO [0000000000FD91D0]: Software Version : 8.0102.0015.0430 November 26 10:12:03 INFO [0000000000FD91D0]: Protocol Version : 6.6.1.1 November 26 10:12:03 INFO [0000000000FD91D0]: Router ID : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Storage Pool ID : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Operational mode : Shutting down November 26 10:12:03 INFO [0000000000FD91D0]: Reroute mode : Disabled November 26 10:12:03 INFO [0000000000FD91D0]: Maintenance applications : (none) November 26 10:12:03 INFO [0000000000FD91D0]: Started On : Thu Nov 26 09:56:43 2015 November 26 10:12:03 INFO [0000000000FD91D0]: Uptime : 0 days, 00:15:20 November 26 10:12:03 INFO [0000000000FD91D0]: Session timeout interval : 3600 seconds November 26 10:12:03 INFO [0000000000FD91D0]: Configuration file : z:\DEDUP\etc\puredisk\spa.cfg November 26 10:12:03 INFO [0000000000FD91D0]: Main Log File : z:\DEDUP\log\spad\spad.log November 26 10:12:03 INFO [0000000000FD91D0]: Spool Location : November 26 10:12:03 INFO [0000000000FD91D0]: Memory Usage : 22.27 MB November 26 10:12:03 INFO [0000000000FD91D0]: Manager stack size : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Task stack size : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Max DO size : 335544320 November 26 10:12:03 INFO [0000000000FD91D0]: === Memory Manager === November 26 10:12:03 INFO [0000000000FD91D0]: Chunks in pool : 1 November 26 10:12:03 INFO [0000000000FD91D0]: Bytes in pool : 4096 November 26 10:12:03 INFO [0000000000FD91D0]: Bytes malloc requests : 1110016 November 26 10:12:03 INFO [0000000000FD91D0]: Actual bytes malloced : 4096 November 26 10:12:03 INFO [0000000000FD91D0]: Bytes free requests : 1110016 November 26 10:12:03 INFO [0000000000FD91D0]: Actual bytes freed : 0 November 26 10:12:03 INFO [0000000000FD91D0]: === PD_Replication === November 26 10:12:03 INFO [0000000000FD91D0]: === Network Manager === November 26 10:12:03 INFO [0000000000FD91D0]: Using route table : (default) November 26 10:12:03 INFO [0000000000FD91D0]: Listening on port : 10102 November 26 10:12:03 INFO [0000000000FD91D0]: Listening on : (all interfaces) November 26 10:12:03 INFO [0000000000FD91D0]: IP range for backup restore : (all interfaces) November 26 10:12:03 INFO [0000000000FD91D0]: Allowed networks : (any) November 26 10:12:03 INFO [0000000000FD91D0]: === Task Manager === November 26 10:12:03 INFO [0000000000FD91D0]: Task Manager: Control task 1 [thread 000000000217E620] for rf0020500095560.rfoc.srf:56396: running November 26 10:12:03 INFO [0000000000FD91D0]: Task Manager: Control task 2 [thread 000000000217E620] for rf0020500095560.rfoc.srf:56398: running November 26 10:12:03 INFO [0000000000FD91D0]: Store mode actual : normal November 26 10:12:03 INFO [0000000000FD91D0]: Receive mode actual : normal November 26 10:12:03 INFO [0000000000FD91D0]: Dereference mode actual : normal November 26 10:12:03 INFO [0000000000FD91D0]: System mode actual : normal November 26 10:12:03 INFO [0000000000FD91D0]: Store mode requested : normal November 26 10:12:03 INFO [0000000000FD91D0]: Receive mode requested : normal November 26 10:12:03 INFO [0000000000FD91D0]: Dereference mode requested : normal November 26 10:12:03 INFO [0000000000FD91D0]: System mode requested : normal November 26 10:12:03 INFO [0000000000FD91D0]: Total tasks serviced : 115 November 26 10:12:03 INFO [0000000000FD91D0]: Total Capture tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Active Capture tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Successful Capture tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Failed Capture tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Total Receive tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Active Receive tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Successful Receive tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Failed Receive tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Total Control tasks : 113 November 26 10:12:03 INFO [0000000000FD91D0]: Active Control tasks : 2 November 26 10:12:03 INFO [0000000000FD91D0]: Successful Control tasks : 113 November 26 10:12:03 INFO [0000000000FD91D0]: Failed Control tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Active Deref tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Successful Deref tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Failed Deref tasks : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Unknown task requests : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Total Bytes Sent : 614484 November 26 10:12:03 INFO [0000000000FD91D0]: Total Bytes Received : 78840 November 26 10:12:03 INFO [0000000000FD91D0]: Total Segments Received : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Total Segments Sent : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Total DO Received : 0 November 26 10:12:03 INFO [0000000000FD91D0]: Total DO Sent : 0 November 26 10:12:03 INFO [0000000000FD91D0]: === Authorization Manager Report === November 26 10:12:03 INFO [0000000000FD91D0]: Status : disabled November 26 10:12:03 INFO [0000000000FD91D0]: === Sched Manager Report === November 26 10:12:03 INFO [0000000000FD91D0]: sync is called every 60 seconds November 26 10:12:03 INFO [0000000000FD91D0]: === Symantec PureDisk Storage Pool Authority === November 26 10:12:03 INFO [0000000000FD91D0]: Shutdown: entered at Thu Nov 26 10:12:03 2015 November 26 10:12:04 INFO [0000000000FD91D0]: Task Manager: terminating routing November 26 10:12:04 INFO [0000000000FD91D0]: Connection Manager: shutdown November 26 10:12:04 INFO [0000000000FD91D0]: Sched Manager: shutdown November 26 10:12:11 INFO [0000000000FD91D0]: DataCheck Manager: shutdown November 26 10:12:12 INFO [0000000000FD91D0]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 10:12:12 INFO [0000000000FD91D0]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 10:12:12 INFO [0000000000FD91D0]: Close SQLite database z:\DEDUP\databases\catalog\2\REPCONFIG\po.db November 26 10:12:12 INFO [0000000000FD91D0]: Close SQLite database z:\DEDUP\databases\catalog\2\po.db November 26 10:12:12 INFO [0000000000FD91D0]: History Manager: shutdown November 26 10:12:12 ERR [0000000000FD91D0]: 25054: binaryMessageRead:3437, Could not receive binary message: expected 4 bytes got -1 bytes. Cause: software caused connection abort November 26 10:12:12 INFO [0000000000FD91D0]: Shutdown: completed at Thu Nov 26 10:12:03 2015 November 26 10:12:55 INFO: set entire process max log size to 0 November 26 10:12:55 INFO: set entire process max log size to 0 November 26 10:12:55 INFO: CR mode in spa db is normal ! November 26 10:12:55 INFO: Startup: loading configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 10:12:55 INFO: : extended attribute support enabled November 26 10:12:55 INFO: Manager thread stack size: 0 bytes November 26 10:12:55 INFO: Task thread stack size: 0 bytes November 26 10:12:55 INFO: Max DO size: 335544320 bytes November 26 10:12:55 INFO [00000000010191D0]: set entire process max log size to 10000000 November 26 10:12:55 INFO [00000000010191D0]: set entire process max log size to 10000000 November 26 10:12:55 INFO [00000000010191D0]: Startup: occurred at Thu Nov 26 10:12:55 2015 November 26 10:12:55 INFO [00000000010191D0]: Successfully loaded configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 10:12:55 INFO [00000000010191D0]: Startup: Symantec PureDisk Storage Pool Authority Version 8.0102.0015.0430. November 26 10:12:55 INFO [00000000010191D0]: Startup: using Symantec: libdct 6.0.0.0, July 7, 2004 November 26 10:12:55 INFO [00000000010191D0]: Startup: using Symantec PureDisk: libcr 6.1.0.0, December 13, 2006 November 26 10:12:55 INFO [00000000010191D0]: Startup: upgrade of spa DB skipped November 26 10:12:55 INFO [00000000010191D0]: Memory Manager: initializing November 26 10:12:55 INFO [00000000010191D0]: Memory Manager: initialization complete November 26 10:12:55 INFO [00000000010191D0]: Sched Manager: initializing November 26 10:12:55 INFO [00000000010191D0]: schedule string for QueueProcess: [20 */12 * * *] November 26 10:12:55 INFO [00000000010191D0]: alert time for QueueProcess is 21600 November 26 10:12:55 INFO [00000000010191D0]: lifecycle for QueueProcess is 32400 November 26 10:12:55 INFO [00000000010191D0]: schedule string for RepOrphan: [*/10 * * * *] November 26 10:12:55 INFO [00000000010191D0]: alert time for RepOrphan is 300 November 26 10:12:55 INFO [00000000010191D0]: lifecycle for RepOrphan is 600 November 26 10:12:55 INFO [00000000010191D0]: schedule string for CatalogBackup: [40 3 * * *] November 26 10:12:55 INFO [00000000010191D0]: alert time for CatalogBackup is 21600 November 26 10:12:55 INFO [00000000010191D0]: lifecycle for CatalogBackup is 32400 November 26 10:12:55 INFO [00000000010191D0]: Sched Manager: initialization complete November 26 10:12:55 INFO [00000000010191D0]: PD_Replication: initializing November 26 10:12:55 INFO [00000000010191D0]: PD_Replication: initialization complete November 26 10:12:55 INFO [00000000010191D0]: Task Manager: initializing November 26 10:12:55 INFO [00000000010191D0]: Task Manager: seeding random number generator November 26 10:12:55 INFO [00000000010191D0]: Task Manager: initializing DCT Content Routing (TM) November 26 10:12:55 INFO [00000000010191D0]: Task Manager: initialization complete November 26 10:12:55 INFO [00000000010191D0]: Connection Manager: initializing November 26 10:12:55 INFO [00000000010191D0]: Connection Manager: initialization complete November 26 10:12:55 INFO [00000000010191D0]: Catalog auto recovery is enabled. November 26 10:12:55 INFO [00000000010191D0]: Catalog backup version count: 5, shadow pool size: 64 November 26 10:12:55 INFO [00000000010191D0]: Catalog backup shadow path: z:\DEDUP\databases\catalogshadow November 26 10:12:55 INFO [00000000010191D0]: Startup: completed at Thu Nov 26 10:12:55 2015 November 26 10:12:55 INFO [00000000010191D0]: set entire process max log size to 10000000 November 26 10:12:55 INFO [00000000010191D0]: set entire process max log size to 10000000 November 26 10:12:55 INFO [00000000010191D0]: Increasing maximum number of open files from 512 to 2048 November 26 10:12:55 INFO [00000000010191D0]: History Manager: start [thread 0000000001FA7F00] November 26 10:12:56 INFO [00000000010191D0]: Sched Manager: started [thread 000000000295CAC0] November 26 10:12:56 INFO [00000000010191D0]: DataCheck Manager: started [thread 000000000295CC80] November 26 10:12:56 INFO [00000000010191D0]: Task Manager: started [thread 0000000002AF9D10] November 26 10:12:56 INFO [00000000010191D0]: EventSystem::SpaDBEventStorage::checkConsistency Starting consistency check November 26 10:12:56 INFO [00000000010191D0]: EventSystem::SpaDBEventStorage::checkConsistency Ending consistency check November 26 10:12:56 INFO [00000000010191D0]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 10:12:56 INFO [00000000010191D0]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 10:12:56 INFO [00000000010191D0]: NetBindAndListen: bound myself to :10102 using IPv6 November 26 10:12:56 INFO [00000000010191D0]: NetBindAndListen: bound myself to :10102 using IPv4 November 26 10:12:56 INFO [00000000010191D0]: Connection Manager: started November 26 10:12:56 INFO [00000000010191D0]: Setting service status to running if service start November 26 10:50:08 INFO [0000000002AF9ED0]: Server entered shutdown mode on Thu Nov 26 10:50:08 2015 November 26 10:50:08 WARNING [00000000010191D0]: 25212: NetWaitForRemote: interrupted November 26 10:50:08 INFO [00000000010191D0]: === Symantec PureDisk Storage Pool Authority === November 26 10:50:08 INFO [00000000010191D0]: Software Version : 8.0102.0015.0430 November 26 10:50:08 INFO [00000000010191D0]: Protocol Version : 6.6.1.1 November 26 10:50:08 INFO [00000000010191D0]: Router ID : 0 November 26 10:50:08 INFO [00000000010191D0]: Storage Pool ID : 0 November 26 10:50:08 INFO [00000000010191D0]: Operational mode : Shutting down November 26 10:50:08 INFO [00000000010191D0]: Reroute mode : Disabled November 26 10:50:08 INFO [00000000010191D0]: Maintenance applications : (none) November 26 10:50:08 INFO [00000000010191D0]: Started On : Thu Nov 26 10:12:55 2015 November 26 10:50:08 INFO [00000000010191D0]: Uptime : 0 days, 00:37:13 November 26 10:50:08 INFO [00000000010191D0]: Session timeout interval : 3600 seconds November 26 10:50:08 INFO [00000000010191D0]: Configuration file : z:\DEDUP\etc\puredisk\spa.cfg November 26 10:50:08 INFO [00000000010191D0]: Main Log File : z:\DEDUP\log\spad\spad.log November 26 10:50:08 INFO [00000000010191D0]: Spool Location : November 26 10:50:08 INFO [00000000010191D0]: Memory Usage : 22.05 MB November 26 10:50:08 INFO [00000000010191D0]: Manager stack size : 0 November 26 10:50:08 INFO [00000000010191D0]: Task stack size : 0 November 26 10:50:08 INFO [00000000010191D0]: Max DO size : 335544320 November 26 10:50:08 INFO [00000000010191D0]: === Memory Manager === November 26 10:50:08 INFO [00000000010191D0]: Chunks in pool : 1 November 26 10:50:08 INFO [00000000010191D0]: Bytes in pool : 4096 November 26 10:50:08 INFO [00000000010191D0]: Bytes malloc requests : 2695168 November 26 10:50:08 INFO [00000000010191D0]: Actual bytes malloced : 49152 November 26 10:50:08 INFO [00000000010191D0]: Bytes free requests : 2695168 November 26 10:50:08 INFO [00000000010191D0]: Actual bytes freed : 45056 November 26 10:50:08 INFO [00000000010191D0]: === PD_Replication === November 26 10:50:08 INFO [00000000010191D0]: === Network Manager === November 26 10:50:08 INFO [00000000010191D0]: Using route table : (default) November 26 10:50:08 INFO [00000000010191D0]: Listening on port : 10102 November 26 10:50:08 INFO [00000000010191D0]: Listening on : (all interfaces) November 26 10:50:08 INFO [00000000010191D0]: IP range for backup restore : (all interfaces) November 26 10:50:08 INFO [00000000010191D0]: Allowed networks : (any) November 26 10:50:08 INFO [00000000010191D0]: === Task Manager === November 26 10:50:08 INFO [00000000010191D0]: Task Manager: Control task 0 [thread 000000000295CE40] for rf0020500095560.rfoc.srf:57344: running November 26 10:50:08 INFO [00000000010191D0]: Task Manager: Control task 1 [thread 000000000295CE40] for rf0020500095560.rfoc.srf:57348: running November 26 10:50:08 INFO [00000000010191D0]: Store mode actual : normal November 26 10:50:08 INFO [00000000010191D0]: Receive mode actual : normal November 26 10:50:08 INFO [00000000010191D0]: Dereference mode actual : normal November 26 10:50:08 INFO [00000000010191D0]: System mode actual : normal November 26 10:50:08 INFO [00000000010191D0]: Store mode requested : normal November 26 10:50:08 INFO [00000000010191D0]: Receive mode requested : normal November 26 10:50:08 INFO [00000000010191D0]: Dereference mode requested : normal November 26 10:50:08 INFO [00000000010191D0]: System mode requested : normal November 26 10:50:08 INFO [00000000010191D0]: Total tasks serviced : 268 November 26 10:50:08 INFO [00000000010191D0]: Total Capture tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Active Capture tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Successful Capture tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Failed Capture tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Total Receive tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Active Receive tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Successful Receive tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Failed Receive tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Total Control tasks : 266 November 26 10:50:08 INFO [00000000010191D0]: Active Control tasks : 2 November 26 10:50:08 INFO [00000000010191D0]: Successful Control tasks : 266 November 26 10:50:08 INFO [00000000010191D0]: Failed Control tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Active Deref tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Successful Deref tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Failed Deref tasks : 0 November 26 10:50:08 INFO [00000000010191D0]: Unknown task requests : 0 November 26 10:50:08 INFO [00000000010191D0]: Total Bytes Sent : 1495312 November 26 10:50:08 INFO [00000000010191D0]: Total Bytes Received : 181272 November 26 10:50:08 INFO [00000000010191D0]: Total Segments Received : 0 November 26 10:50:08 INFO [00000000010191D0]: Total Segments Sent : 0 November 26 10:50:08 INFO [00000000010191D0]: Total DO Received : 0 November 26 10:50:08 INFO [00000000010191D0]: Total DO Sent : 0 November 26 10:50:08 INFO [00000000010191D0]: === Authorization Manager Report === November 26 10:50:08 INFO [00000000010191D0]: Status : disabled November 26 10:50:08 INFO [00000000010191D0]: === Sched Manager Report === November 26 10:50:08 INFO [00000000010191D0]: sync is called every 60 seconds November 26 10:50:08 INFO [00000000010191D0]: === Symantec PureDisk Storage Pool Authority === November 26 10:50:08 INFO [00000000010191D0]: Shutdown: entered at Thu Nov 26 10:50:08 2015 November 26 10:50:09 INFO [00000000010191D0]: Task Manager: terminating routing November 26 10:50:09 INFO [00000000010191D0]: Connection Manager: shutdown November 26 10:50:09 INFO [00000000010191D0]: Sched Manager: shutdown November 26 10:50:11 INFO [00000000010191D0]: DataCheck Manager: shutdown November 26 10:50:12 INFO [00000000010191D0]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 10:50:12 INFO [00000000010191D0]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 10:50:13 INFO [00000000010191D0]: Close SQLite database z:\DEDUP\databases\catalog\2\REPCONFIG\po.db November 26 10:50:13 INFO [00000000010191D0]: Close SQLite database z:\DEDUP\databases\catalog\2\po.db November 26 10:50:13 INFO [00000000010191D0]: History Manager: shutdown November 26 10:50:13 ERR [00000000010191D0]: 25054: binaryMessageRead:3437, Could not receive binary message: expected 4 bytes got -1 bytes. Cause: software caused connection abort November 26 10:50:13 INFO [00000000010191D0]: Shutdown: completed at Thu Nov 26 10:50:08 2015 November 26 10:51:07 INFO: set entire process max log size to 0 November 26 10:51:07 INFO: set entire process max log size to 0 November 26 10:51:07 INFO: CR mode in spa db is normal ! November 26 10:51:07 INFO: Startup: loading configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 10:51:07 INFO: : extended attribute support enabled November 26 10:51:07 INFO: Manager thread stack size: 0 bytes November 26 10:51:07 INFO: Task thread stack size: 0 bytes November 26 10:51:07 INFO: Max DO size: 335544320 bytes November 26 10:51:07 INFO [0000000000E291D0]: set entire process max log size to 10000000 November 26 10:51:07 INFO [0000000000E291D0]: set entire process max log size to 10000000 November 26 10:51:07 INFO [0000000000E291D0]: Startup: occurred at Thu Nov 26 10:51:07 2015 November 26 10:51:07 INFO [0000000000E291D0]: Successfully loaded configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 10:51:07 INFO [0000000000E291D0]: Startup: Symantec PureDisk Storage Pool Authority Version 8.0102.0015.0430. November 26 10:51:07 INFO [0000000000E291D0]: Startup: using Symantec: libdct 6.0.0.0, July 7, 2004 November 26 10:51:07 INFO [0000000000E291D0]: Startup: using Symantec PureDisk: libcr 6.1.0.0, December 13, 2006 November 26 10:51:07 INFO [0000000000E291D0]: Startup: upgrade of spa DB skipped November 26 10:51:07 INFO [0000000000E291D0]: Memory Manager: initializing November 26 10:51:07 INFO [0000000000E291D0]: Memory Manager: initialization complete November 26 10:51:07 INFO [0000000000E291D0]: Sched Manager: initializing November 26 10:51:07 INFO [0000000000E291D0]: schedule string for QueueProcess: [20 */12 * * *] November 26 10:51:07 INFO [0000000000E291D0]: alert time for QueueProcess is 21600 November 26 10:51:07 INFO [0000000000E291D0]: lifecycle for QueueProcess is 32400 November 26 10:51:07 INFO [0000000000E291D0]: schedule string for RepOrphan: [*/10 * * * *] November 26 10:51:07 INFO [0000000000E291D0]: alert time for RepOrphan is 300 November 26 10:51:07 INFO [0000000000E291D0]: lifecycle for RepOrphan is 600 November 26 10:51:07 INFO [0000000000E291D0]: schedule string for CatalogBackup: [40 3 * * *] November 26 10:51:07 INFO [0000000000E291D0]: alert time for CatalogBackup is 21600 November 26 10:51:07 INFO [0000000000E291D0]: lifecycle for CatalogBackup is 32400 November 26 10:51:07 INFO [0000000000E291D0]: Sched Manager: initialization complete November 26 10:51:07 INFO [0000000000E291D0]: PD_Replication: initializing November 26 10:51:07 INFO [0000000000E291D0]: PD_Replication: initialization complete November 26 10:51:07 INFO [0000000000E291D0]: Task Manager: initializing November 26 10:51:07 INFO [0000000000E291D0]: Task Manager: seeding random number generator November 26 10:51:07 INFO [0000000000E291D0]: Task Manager: initializing DCT Content Routing (TM) November 26 10:51:07 INFO [0000000000E291D0]: Task Manager: initialization complete November 26 10:51:07 INFO [0000000000E291D0]: Connection Manager: initializing November 26 10:51:07 INFO [0000000000E291D0]: Connection Manager: initialization complete November 26 10:51:07 INFO [0000000000E291D0]: Catalog auto recovery is enabled. November 26 10:51:07 INFO [0000000000E291D0]: Catalog backup version count: 5, shadow pool size: 64 November 26 10:51:07 INFO [0000000000E291D0]: Catalog backup shadow path: z:\DEDUP\databases\catalogshadow November 26 10:51:07 INFO [0000000000E291D0]: Startup: completed at Thu Nov 26 10:51:07 2015 November 26 10:51:07 INFO [0000000000E291D0]: set entire process max log size to 10000000 November 26 10:51:07 INFO [0000000000E291D0]: set entire process max log size to 10000000 November 26 10:51:07 INFO [0000000000E291D0]: Increasing maximum number of open files from 512 to 2048 November 26 10:51:07 INFO [0000000000E291D0]: History Manager: start [thread 0000000001F17F00] November 26 10:51:07 INFO [0000000000E291D0]: Sched Manager: started [thread 00000000028BCAC0] November 26 10:51:07 INFO [0000000000E291D0]: DataCheck Manager: started [thread 00000000028BCC80] November 26 10:51:07 INFO [0000000000E291D0]: Task Manager: started [thread 0000000002A59D10] November 26 10:51:07 INFO [0000000000E291D0]: EventSystem::SpaDBEventStorage::checkConsistency Starting consistency check November 26 10:51:07 INFO [0000000000E291D0]: EventSystem::SpaDBEventStorage::checkConsistency Ending consistency check November 26 10:51:07 INFO [0000000000E291D0]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 10:51:07 INFO [0000000000E291D0]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 10:51:07 INFO [0000000000E291D0]: NetBindAndListen: bound myself to :10102 using IPv6 November 26 10:51:07 INFO [0000000000E291D0]: NetBindAndListen: bound myself to :10102 using IPv4 November 26 10:51:07 INFO [0000000000E291D0]: Connection Manager: started November 26 10:51:07 INFO [0000000000E291D0]: Setting service status to running if service start November 26 11:00:17 INFO [0000000002A59ED0]: Server entered shutdown mode on Thu Nov 26 11:00:17 2015 November 26 11:00:17 WARNING [0000000000E291D0]: 25212: NetWaitForRemote: interrupted November 26 11:00:17 INFO [0000000000E291D0]: === Symantec PureDisk Storage Pool Authority === November 26 11:00:17 INFO [0000000000E291D0]: Software Version : 8.0102.0015.0430 November 26 11:00:17 INFO [0000000000E291D0]: Protocol Version : 6.6.1.1 November 26 11:00:17 INFO [0000000000E291D0]: Router ID : 0 November 26 11:00:17 INFO [0000000000E291D0]: Storage Pool ID : 0 November 26 11:00:17 INFO [0000000000E291D0]: Operational mode : Shutting down November 26 11:00:17 INFO [0000000000E291D0]: Reroute mode : Disabled November 26 11:00:17 INFO [0000000000E291D0]: Maintenance applications : (none) November 26 11:00:17 INFO [0000000000E291D0]: Started On : Thu Nov 26 10:51:07 2015 November 26 11:00:17 INFO [0000000000E291D0]: Uptime : 0 days, 00:09:10 November 26 11:00:17 INFO [0000000000E291D0]: Session timeout interval : 3600 seconds November 26 11:00:17 INFO [0000000000E291D0]: Configuration file : z:\DEDUP\etc\puredisk\spa.cfg November 26 11:00:17 INFO [0000000000E291D0]: Main Log File : z:\DEDUP\log\spad\spad.log November 26 11:00:17 INFO [0000000000E291D0]: Spool Location : November 26 11:00:17 INFO [0000000000E291D0]: Memory Usage : 22.44 MB November 26 11:00:17 INFO [0000000000E291D0]: Manager stack size : 0 November 26 11:00:17 INFO [0000000000E291D0]: Task stack size : 0 November 26 11:00:17 INFO [0000000000E291D0]: Max DO size : 335544320 November 26 11:00:17 INFO [0000000000E291D0]: === Memory Manager === November 26 11:00:17 INFO [0000000000E291D0]: Chunks in pool : 1 November 26 11:00:17 INFO [0000000000E291D0]: Bytes in pool : 4096 November 26 11:00:17 INFO [0000000000E291D0]: Bytes malloc requests : 692224 November 26 11:00:17 INFO [0000000000E291D0]: Actual bytes malloced : 12288 November 26 11:00:17 INFO [0000000000E291D0]: Bytes free requests : 692224 November 26 11:00:17 INFO [0000000000E291D0]: Actual bytes freed : 8192 November 26 11:00:17 INFO [0000000000E291D0]: === PD_Replication === November 26 11:00:17 INFO [0000000000E291D0]: === Network Manager === November 26 11:00:17 INFO [0000000000E291D0]: Using route table : (default) November 26 11:00:17 INFO [0000000000E291D0]: Listening on port : 10102 November 26 11:00:17 INFO [0000000000E291D0]: Listening on : (all interfaces) November 26 11:00:17 INFO [0000000000E291D0]: IP range for backup restore : (all interfaces) November 26 11:00:17 INFO [0000000000E291D0]: Allowed networks : (any) November 26 11:00:17 INFO [0000000000E291D0]: === Task Manager === November 26 11:00:17 INFO [0000000000E291D0]: Task Manager: Control task 0 [thread 00000000028BCE40] for rf0020500095560.rfoc.srf:57594: running November 26 11:00:17 INFO [0000000000E291D0]: Task Manager: Control task 1 [thread 00000000028BCE40] for rf0020500095560.rfoc.srf:57596: running November 26 11:00:17 INFO [0000000000E291D0]: Task Manager: Control task 2 [thread 00000000028BCE40] for rf0020500095523.rfoc.srf:52879: running November 26 11:00:17 INFO [0000000000E291D0]: Store mode actual : normal November 26 11:00:17 INFO [0000000000E291D0]: Receive mode actual : normal November 26 11:00:17 INFO [0000000000E291D0]: Dereference mode actual : normal November 26 11:00:17 INFO [0000000000E291D0]: System mode actual : normal November 26 11:00:17 INFO [0000000000E291D0]: Store mode requested : normal November 26 11:00:17 INFO [0000000000E291D0]: Receive mode requested : normal November 26 11:00:17 INFO [0000000000E291D0]: Dereference mode requested : normal November 26 11:00:17 INFO [0000000000E291D0]: System mode requested : normal November 26 11:00:17 INFO [0000000000E291D0]: Total tasks serviced : 70 November 26 11:00:17 INFO [0000000000E291D0]: Total Capture tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Active Capture tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Successful Capture tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Failed Capture tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Total Receive tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Active Receive tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Successful Receive tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Failed Receive tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Total Control tasks : 67 November 26 11:00:17 INFO [0000000000E291D0]: Active Control tasks : 3 November 26 11:00:17 INFO [0000000000E291D0]: Successful Control tasks : 67 November 26 11:00:17 INFO [0000000000E291D0]: Failed Control tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Active Deref tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Successful Deref tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Failed Deref tasks : 0 November 26 11:00:17 INFO [0000000000E291D0]: Unknown task requests : 0 November 26 11:00:17 INFO [0000000000E291D0]: Total Bytes Sent : 385476 November 26 11:00:17 INFO [0000000000E291D0]: Total Bytes Received : 44300 November 26 11:00:17 INFO [0000000000E291D0]: Total Segments Received : 0 November 26 11:00:17 INFO [0000000000E291D0]: Total Segments Sent : 0 November 26 11:00:17 INFO [0000000000E291D0]: Total DO Received : 0 November 26 11:00:17 INFO [0000000000E291D0]: Total DO Sent : 0 November 26 11:00:17 INFO [0000000000E291D0]: === Authorization Manager Report === November 26 11:00:17 INFO [0000000000E291D0]: Status : disabled November 26 11:00:17 INFO [0000000000E291D0]: === Sched Manager Report === November 26 11:00:17 INFO [0000000000E291D0]: sync is called every 60 seconds November 26 11:00:17 INFO [0000000000E291D0]: === Symantec PureDisk Storage Pool Authority === November 26 11:00:17 INFO [0000000000E291D0]: Shutdown: entered at Thu Nov 26 11:00:17 2015 November 26 11:00:18 INFO [0000000000E291D0]: Task Manager: terminating routing November 26 11:00:18 INFO [0000000000E291D0]: Connection Manager: shutdown November 26 11:00:18 INFO [0000000000E291D0]: Sched Manager: shutdown November 26 11:00:21 INFO [0000000000E291D0]: DataCheck Manager: shutdown November 26 11:00:22 INFO [0000000000E291D0]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 11:00:24 INFO [0000000000E291D0]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 11:00:25 INFO [0000000000E291D0]: Close SQLite database z:\DEDUP\databases\catalog\2\REPCONFIG\po.db November 26 11:00:25 INFO [0000000000E291D0]: Close SQLite database z:\DEDUP\databases\catalog\2\po.db November 26 11:00:25 INFO [0000000000E291D0]: History Manager: shutdown November 26 11:00:25 ERR [0000000000E291D0]: 25054: binaryMessageRead:3437, Could not receive binary message: expected 4 bytes got -1 bytes. Cause: software caused connection abort November 26 11:00:25 INFO [0000000000E291D0]: Shutdown: completed at Thu Nov 26 11:00:17 2015 November 26 11:05:36 INFO: set entire process max log size to 0 November 26 11:05:36 INFO: set entire process max log size to 0 November 26 11:05:36 INFO: CR mode in spa db is normal ! November 26 11:05:36 INFO: Startup: loading configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 11:05:36 INFO: : extended attribute support enabled November 26 11:05:36 INFO: Manager thread stack size: 0 bytes November 26 11:05:36 INFO: Task thread stack size: 0 bytes November 26 11:05:36 INFO: Max DO size: 335544320 bytes November 26 11:05:36 INFO [00000000015291D0]: set entire process max log size to 10000000 November 26 11:05:36 INFO [00000000015291D0]: set entire process max log size to 10000000 November 26 11:05:36 INFO [00000000015291D0]: Startup: occurred at Thu Nov 26 11:05:36 2015 November 26 11:05:36 INFO [00000000015291D0]: Successfully loaded configuration from z:\DEDUP\etc\puredisk\spa.cfg November 26 11:05:36 INFO [00000000015291D0]: Startup: Symantec PureDisk Storage Pool Authority Version 8.0102.0015.0430. November 26 11:05:36 INFO [00000000015291D0]: Startup: using Symantec: libdct 6.0.0.0, July 7, 2004 November 26 11:05:36 INFO [00000000015291D0]: Startup: using Symantec PureDisk: libcr 6.1.0.0, December 13, 2006 November 26 11:05:36 INFO [00000000015291D0]: Startup: upgrade of spa DB skipped November 26 11:05:36 INFO [00000000015291D0]: Memory Manager: initializing November 26 11:05:36 INFO [00000000015291D0]: Memory Manager: initialization complete November 26 11:05:36 INFO [00000000015291D0]: Sched Manager: initializing November 26 11:05:36 INFO [00000000015291D0]: schedule string for QueueProcess: [20 */12 * * *] November 26 11:05:36 INFO [00000000015291D0]: alert time for QueueProcess is 21600 November 26 11:05:36 INFO [00000000015291D0]: lifecycle for QueueProcess is 32400 November 26 11:05:36 INFO [00000000015291D0]: schedule string for RepOrphan: [*/10 * * * *] November 26 11:05:36 INFO [00000000015291D0]: alert time for RepOrphan is 300 November 26 11:05:36 INFO [00000000015291D0]: lifecycle for RepOrphan is 600 November 26 11:05:36 INFO [00000000015291D0]: schedule string for CatalogBackup: [40 3 * * *] November 26 11:05:36 INFO [00000000015291D0]: alert time for CatalogBackup is 21600 November 26 11:05:36 INFO [00000000015291D0]: lifecycle for CatalogBackup is 32400 November 26 11:05:36 INFO [00000000015291D0]: Sched Manager: initialization complete November 26 11:05:36 INFO [00000000015291D0]: PD_Replication: initializing November 26 11:05:36 INFO [00000000015291D0]: PD_Replication: initialization complete November 26 11:05:36 INFO [00000000015291D0]: Task Manager: initializing November 26 11:05:36 INFO [00000000015291D0]: Task Manager: seeding random number generator November 26 11:05:36 INFO [00000000015291D0]: Task Manager: initializing DCT Content Routing (TM) November 26 11:05:36 INFO [00000000015291D0]: Task Manager: initialization complete November 26 11:05:36 INFO [00000000015291D0]: Connection Manager: initializing November 26 11:05:36 INFO [00000000015291D0]: Connection Manager: initialization complete November 26 11:05:36 INFO [00000000015291D0]: Catalog auto recovery is enabled. November 26 11:05:36 INFO [00000000015291D0]: Catalog backup version count: 5, shadow pool size: 64 November 26 11:05:36 INFO [00000000015291D0]: Catalog backup shadow path: z:\DEDUP\databases\catalogshadow November 26 11:05:36 INFO [00000000015291D0]: Startup: completed at Thu Nov 26 11:05:36 2015 November 26 11:05:36 INFO [00000000015291D0]: set entire process max log size to 10000000 November 26 11:05:36 INFO [00000000015291D0]: set entire process max log size to 10000000 November 26 11:05:36 INFO [00000000015291D0]: Increasing maximum number of open files from 512 to 2048 November 26 11:05:36 INFO [00000000015291D0]: History Manager: start [thread 0000000001F07F00] November 26 11:05:36 INFO [00000000015291D0]: Sched Manager: started [thread 000000000286CAC0] November 26 11:05:36 INFO [00000000015291D0]: DataCheck Manager: started [thread 000000000286CC80] November 26 11:05:37 INFO [00000000015291D0]: Task Manager: started [thread 0000000002A09510] November 26 11:05:38 INFO [00000000015291D0]: EventSystem::SpaDBEventStorage::checkConsistency Starting consistency check November 26 11:05:38 INFO [00000000015291D0]: EventSystem::SpaDBEventStorage::checkConsistency Ending consistency check November 26 11:05:38 INFO [00000000015291D0]: EventSystem::EventManager::newEvent Adding new event. type:4 November 26 11:05:38 INFO [00000000015291D0]: remove old record file z:\DEDUP\databases\spa\database\eventproperties\1. November 26 11:05:38 INFO [00000000015291D0]: NetBindAndListen: bound myself to :10102 using IPv6 November 26 11:05:38 INFO [00000000015291D0]: NetBindAndListen: bound myself to :10102 using IPv4 November 26 11:05:38 INFO [00000000015291D0]: Connection Manager: started November 26 11:05:38 INFO [00000000015291D0]: Setting service status to running if service start