[ on nov 28 08:00:59 CET 2007 ],INFO ,[Thread-5],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateArchive,1801,Sync Archive for 1 devices - Sync Archive [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-5],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateArchive,1815,Number of devices in fetch Q = 0 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-5],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,addToDeviceIdToReqIdMap,2163,.Device 489 added to updateResults List. [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-5],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,compareDeviceWithDevicesinRunningThreads,57,inside compareDeviceWithDevicesinRunningThreads method [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-5],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateArchiveIfRequired,1915,Compared the device with running thread devices.Adding to Fetch Q [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-5],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,triggerConfigFetch,41,Config fetch request received [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-5],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,triggerConfigFetch,51,#### Start of Sweep Wed Nov 28 08:01:00 CET 2007 #### [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,85,++++ Starting Thread 0 ++++ [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,85,++++ Starting Thread 1 ++++ [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,85,++++ Starting Thread 2 ++++ [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getTransport,127,Getting DeviceContext for DeviceId: 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,85,++++ Starting Thread 3 ++++ [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,85,++++ Starting Thread 4 ++++ [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,91,---- Stopped Thread 0 ---- [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getDeviceContext,161,Getting the DeviceContext for DeviceID: 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select DCR_ID from DM_Dev_State where NetworkElementID = '489' [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getDCRId,1082,dev id: 489 dcr id : 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,,437,Parent DC set to Null... for DeviceID : 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCommunicationAddress,1051,device type is 0 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCommunicationAddress,1323,Parent DC is null [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCommunicationAddress,1339,IP of device: 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDCFactory,createDC,247,RME Repository for DC of dev: 489 is going to be set [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 9 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAllDeviceAttributes,697,SNMP timeout : 2 retries : 2 Telnet timeout : 36 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,fillRepository,486,Repository cache filled for : 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDCFactory,createDC,251,RME Repository for DC of dev with device id: 489 is SET [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,setSysObjectId,843,syObjectId from DCR is NOT null, not UNKNOWN [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,setSysObjectId,847,SysOID set to : 1.3.6.1.4.1.9.1.495 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,setAttr,191,Setting attr for: 10.69.77.1|SYSOBJECTID With val: *** (masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDCFactory,createDC,255,Device Context for: 489 successfully created [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,setAttr,191,Setting attr for: 10.69.77.1|RCP_USER With val: *** (masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,setMaxRefreshTimeout,107,Max refresh timeout for repository : -1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getDeviceContext,169,Got DeviceContext for DeviceID: 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getTransport,183,Requesting ConfigAG for 10.69.77.1.. [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getDevRepr,250,Getting CIDS DeviceRepr for 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getAnalyzer,144,Getting DeviceContext for DeviceId: 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getDeviceContext,161,Getting the DeviceContext for DeviceID: 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select DCR_ID from DM_Dev_State where NetworkElementID = '489' [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getDCRId,1082,dev id: 489 dcr id : 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,,437,Parent DC set to Null... for DeviceID : 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCommunicationAddress,1051,device type is 0 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCommunicationAddress,1323,Parent DC is null [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCommunicationAddress,1339,IP of device: 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDCFactory,createDC,247,RME Repository for DC of dev: 489 is going to be set [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 9 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAllDeviceAttributes,697,SNMP timeout : 2 retries : 2 Telnet timeout : 36 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,fillRepository,486,Repository cache filled for : 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDCFactory,createDC,251,RME Repository for DC of dev with device id: 489 is SET [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,setSysObjectId,843,syObjectId from DCR is NOT null, not UNKNOWN [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,setSysObjectId,847,SysOID set to : 1.3.6.1.4.1.9.1.495 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,setAttr,191,Setting attr for: 10.69.77.1|SYSOBJECTID With val: *** (masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDCFactory,createDC,255,Device Context for: 489 successfully created [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,setAttr,191,Setting attr for: 10.69.77.1|RCP_USER With val: *** (masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,setMaxRefreshTimeout,107,Max refresh timeout for repository : -1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getDeviceContext,169,Got DeviceContext for DeviceID: 489 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getAnalyzer,217,Requesting ConfigAnalyzerAG for 10.69.77.1.. [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.cats.CATS,getDevRepr,250,Getting CIDS DeviceRepr for 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,95,Getting the ConfigOperator for Protocol:SSH for device 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_USERNAME Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_PASSWORD Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,148, Cache for: PRIMARY_ENABLE_USERNAME not available. Getting from System [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 9 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAllDeviceAttributes,697,SNMP timeout : 2 retries : 2 Telnet timeout : 36 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,fillRepository,486,Repository cache filled for : 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,160, Error getting value for: 10.69.77.1|PRIMARY_ENABLE_USERNAME from system. Returning null [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_ENABLE_PASSWORD Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,134,Time out : 36 [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1525,Inside RMEDeviceContext's getCmdSvc [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1535,Protocol and Platforms passed = SSH , RMEIOS [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1561,Iam inside ssh .... [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1563,Initial time_out : 0 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1573,Computed time_out : 36 [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getSshCmdSvc,1819,inside getSshCmdSvc with timeout : 36 [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getSshProtocols,1925,Inside getsshprotocols with time out : 36 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,SSH is running on /10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,trace,23,getOutput(Socket[addr=/10.69.77.1,port=22,localport=39783]) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Reached the end of output [ on nov 28 08:01:00 CET 2007 ],FATAL,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,148,Failed to get CmdSvc from DeviceContext.... [ on nov 28 08:01:00 CET 2007 ],FATAL,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,149,Could not detect protocols running on the devicejava.lang.Exception: Could not detect protocols running on the device at com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext.getSshProtocols(RMEDeviceContext.java:1937) at com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext.getSshCmdSvc(RMEDeviceContext.java:1825) at com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext.getCmdSvc(RMEDeviceContext.java:1591) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator.(CliOperator.java:145) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSCliOperator.(IOSCliOperator.java:100) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.getOperator(IOSConfigOperator.java:225) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOS_OCSM_ConfigOperator.getOperator(IOS_OCSM_ConfigOperator.java:42) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.OperatorCacheManager.getOperatorForDevice(OperatorCacheManager.java:50) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation.doConfigOperation(ConfigOperation.java:99) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.fetchConfig(IOSConfigOperator.java:71) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.updateArchiveForDevice(ConfigManager.java:1313) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.performCollection(ConfigManager.java:3129) at com.cisco.nm.rmeng.dcma.configmanager.CfgUpdateThread.run(CfgUpdateThread.java:29) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,101,Failed to get ConfigOperator for Protocol:SSH for device 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,103,Could not detect protocols running on the device [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,95,Getting the ConfigOperator for Protocol:TELNET for device 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_USERNAME Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_PASSWORD Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,148, Cache for: PRIMARY_ENABLE_USERNAME not available. Getting from System [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 2 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 3 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 5 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 9 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAllDeviceAttributes,697,SNMP timeout : 2 retries : 2 Telnet timeout : 36 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,fillRepository,486,Repository cache filled for : 10.69.77.1 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,160, Error getting value for: 10.69.77.1|PRIMARY_ENABLE_USERNAME from system. Returning null [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_ENABLE_PASSWORD Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,134,Time out : 36 [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1525,Inside RMEDeviceContext's getCmdSvc [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1535,Protocol and Platforms passed = TELNET , RMEIOS [ on nov 28 08:01:00 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1543,trying for Telnet [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,181,Using Telnet : Remove device reachability check from RME as cmdsvc takes care of it [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,186,After calling Detect. isReachable=true 1196233260308 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,191,Using block writer instead of char writer [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,195,before calling connect [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,196,Getting the Cli Socket timeout..... [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,198,DAAType.TELNET_TIMEOUT=com.cisco.nm.xms.xdi.daa.DAA@1fe2a0 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,201,Setting the Socket timeout to 36 seconds [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31, > In LocalProxy.process( OpConnect ) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Creating a new SessionContext(1) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,SessionContext(1) found [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_USERNAME Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_PASSWORD Value : ***(masked) [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibCommon.platform.RMEIOSPlatform,getTuneSleepMillis,40,*************************** [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibCommon.platform.RMEIOSPlatform,getTuneSleepMillis,41,Called getTuneSleepMillis(). [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibCommon.platform.RMEIOSPlatform,getTuneSleepMillis,42,*************************** [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Setting newline from Platform implementation [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,creating a new Socket( ip==/10.69.77.1,port==23 ),timeout==36000 [ on nov 28 08:01:00 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Connecting to /10.69.77.1:23 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,printStackTrace,51,stacktracejava.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:305) at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:171) at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:158) at java.net.Socket.connect(Socket.java:452) at com.cisco.nm.lib.cmdsvc.Session.createSocket(Session.java:1054) at com.cisco.nm.lib.cmdsvc.TelnetSession.connect(TelnetSession.java:56) at com.cisco.nm.lib.cmdsvc.Session.connect(Session.java:113) at com.cisco.nm.lib.cmdsvc.AuthHandler.connect(AuthHandler.java:168) at com.cisco.nm.lib.cmdsvc.OpConnect.invoke(OpConnect.java:56) at com.cisco.nm.lib.cmdsvc.SessionContext.invoke(SessionContext.java:296) at com.cisco.nm.lib.cmdsvc.Engine.process(Engine.java:57) at com.cisco.nm.lib.cmdsvc.LocalProxy.process(LocalProxy.java:22) at com.cisco.nm.lib.cmdsvc.CmdSvc.connect(CmdSvc.java:190) at com.cisco.nm.lib.cmdsvc.CmdSvc.connect(CmdSvc.java:166) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator.(CliOperator.java:206) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSCliOperator.(IOSCliOperator.java:100) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.getOperator(IOSConfigOperator.java:225) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOS_OCSM_ConfigOperator.getOperator(IOS_OCSM_ConfigOperator.java:42) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.OperatorCacheManager.getOperatorForDevice(OperatorCacheManager.java:50) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation.doConfigOperation(ConfigOperation.java:99) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.fetchConfig(IOSConfigOperator.java:71) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.updateArchiveForDevice(ConfigManager.java:1313) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.performCollection(ConfigManager.java:3129) at com.cisco.nm.rmeng.dcma.configmanager.CfgUpdateThread.run(CfgUpdateThread.java:29) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,216,Failed to connect to 10.69.77.1 with TELNET [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,219,message java.net.ConnectException: Connection refused: connect [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,229,message= Connection refused [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,101,Failed to get ConfigOperator for Protocol:TELNET for device 10.69.77.1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,103,TELNET: Failed to establish TELNET connection to 10.69.77.1 - Cause: Connection refused. [ on nov 28 08:01:01 CET 2007 ],ERROR,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateArchiveForDevice,1335,PRIMARY STARTUP Config fetch Failed for slagavpn [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.DBInterface,updateStatusTable,574,Select * from DCMA_Archive_Status where NetworkElementId = '489' and Config_Type = 1 and Config_File_Type = 1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.DBInterface,updateStatusTable,602,Update DCMA_Archive_Status set Status = 2, UpdatedAt = '2007-11-28 08:01:01.527', Comments = 'Could not detect protocols running on the device TELNET: Failed to establish TELNET connection to 10.69.77.1 - Cause: Connection refused. ' where NetworkElementId = '489' and Config_Type = 1 and Config_File_Type = 1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,95,Getting the ConfigOperator for Protocol:SSH for device 10.69.77.1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_USERNAME Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_PASSWORD Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,148, Cache for: PRIMARY_ENABLE_USERNAME not available. Getting from System [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 2 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 3 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 5 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 2 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 3 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 5 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 9 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAllDeviceAttributes,697,SNMP timeout : 2 retries : 2 Telnet timeout : 36 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,fillRepository,486,Repository cache filled for : 10.69.77.1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,160, Error getting value for: 10.69.77.1|PRIMARY_ENABLE_USERNAME from system. Returning null [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_ENABLE_PASSWORD Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,134,Time out : 36 [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1525,Inside RMEDeviceContext's getCmdSvc [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1535,Protocol and Platforms passed = SSH , RMEIOS [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1561,Iam inside ssh .... [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1563,Initial time_out : 0 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1573,Computed time_out : 36 [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getSshCmdSvc,1819,inside getSshCmdSvc with timeout : 36 [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getSshProtocols,1925,Inside getsshprotocols with time out : 36 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,SSH is running on /10.69.77.1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,trace,23,getOutput(Socket[addr=/10.69.77.1,port=22,localport=39785]) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Reached the end of output [ on nov 28 08:01:01 CET 2007 ],FATAL,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,148,Failed to get CmdSvc from DeviceContext.... [ on nov 28 08:01:01 CET 2007 ],FATAL,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,149,Could not detect protocols running on the devicejava.lang.Exception: Could not detect protocols running on the device at com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext.getSshProtocols(RMEDeviceContext.java:1937) at com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext.getSshCmdSvc(RMEDeviceContext.java:1825) at com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext.getCmdSvc(RMEDeviceContext.java:1591) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator.(CliOperator.java:145) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSCliOperator.(IOSCliOperator.java:100) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.getOperator(IOSConfigOperator.java:225) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOS_OCSM_ConfigOperator.getOperator(IOS_OCSM_ConfigOperator.java:42) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.OperatorCacheManager.getOperatorForDevice(OperatorCacheManager.java:50) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation.doConfigOperation(ConfigOperation.java:99) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.fetchConfig(IOSConfigOperator.java:71) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.updateArchiveForDevice(ConfigManager.java:1313) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.performCollection(ConfigManager.java:3129) at com.cisco.nm.rmeng.dcma.configmanager.CfgUpdateThread.run(CfgUpdateThread.java:29) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,101,Failed to get ConfigOperator for Protocol:SSH for device 10.69.77.1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,103,Could not detect protocols running on the device [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,95,Getting the ConfigOperator for Protocol:TELNET for device 10.69.77.1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_USERNAME Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_PASSWORD Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,148, Cache for: PRIMARY_ENABLE_USERNAME not available. Getting from System [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 2 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 3 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Val from DM_Dev_Attribute_Value where NetworkElementID = 489 AND Attribute_Id = 5 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 2 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 3 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 5 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.DCRWrapperAPIs,getResultFromQuery,2924,Query: select Attribute_Default from DM_Dev_Attribute_Type where Attribute_Id = 9 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAllDeviceAttributes,697,SNMP timeout : 2 retries : 2 Telnet timeout : 36 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,fillRepository,486,Repository cache filled for : 10.69.77.1 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,160, Error getting value for: 10.69.77.1|PRIMARY_ENABLE_USERNAME from system. Returning null [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_ENABLE_PASSWORD Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,134,Time out : 36 [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1525,Inside RMEDeviceContext's getCmdSvc [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1535,Protocol and Platforms passed = TELNET , RMEIOS [ on nov 28 08:01:01 CET 2007 ],INFO ,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMEDeviceContext,getCmdSvc,1543,trying for Telnet [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,181,Using Telnet : Remove device reachability check from RME as cmdsvc takes care of it [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,186,After calling Detect. isReachable=true 1196233261715 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,191,Using block writer instead of char writer [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,195,before calling connect [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,196,Getting the Cli Socket timeout..... [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|TELNET_TIMEOUT Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,198,DAAType.TELNET_TIMEOUT=com.cisco.nm.xms.xdi.daa.DAA@1fe2a0 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,201,Setting the Socket timeout to 36 seconds [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31, > In LocalProxy.process( OpConnect ) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Creating a new SessionContext(1) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,SessionContext(1) found [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_USERNAME Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.rmedaa.RMERepository,getAttr,116, Got value for 10.69.77.1|PRIMARY_PASSWORD Value : ***(masked) [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibCommon.platform.RMEIOSPlatform,getTuneSleepMillis,40,*************************** [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibCommon.platform.RMEIOSPlatform,getTuneSleepMillis,41,Called getTuneSleepMillis(). [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibCommon.platform.RMEIOSPlatform,getTuneSleepMillis,42,*************************** [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Setting newline from Platform implementation [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,creating a new Socket( ip==/10.69.77.1,port==23 ),timeout==36000 [ on nov 28 08:01:01 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,debug,31,Connecting to /10.69.77.1:23 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.transport.cmdsvc.LogAdapter,printStackTrace,51,stacktracejava.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:305) at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:171) at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:158) at java.net.Socket.connect(Socket.java:452) at com.cisco.nm.lib.cmdsvc.Session.createSocket(Session.java:1054) at com.cisco.nm.lib.cmdsvc.TelnetSession.connect(TelnetSession.java:56) at com.cisco.nm.lib.cmdsvc.Session.connect(Session.java:113) at com.cisco.nm.lib.cmdsvc.AuthHandler.connect(AuthHandler.java:168) at com.cisco.nm.lib.cmdsvc.OpConnect.invoke(OpConnect.java:56) at com.cisco.nm.lib.cmdsvc.SessionContext.invoke(SessionContext.java:296) at com.cisco.nm.lib.cmdsvc.Engine.process(Engine.java:57) at com.cisco.nm.lib.cmdsvc.LocalProxy.process(LocalProxy.java:22) at com.cisco.nm.lib.cmdsvc.CmdSvc.connect(CmdSvc.java:190) at com.cisco.nm.lib.cmdsvc.CmdSvc.connect(CmdSvc.java:166) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator.(CliOperator.java:206) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSCliOperator.(IOSCliOperator.java:100) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.getOperator(IOSConfigOperator.java:225) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOS_OCSM_ConfigOperator.getOperator(IOS_OCSM_ConfigOperator.java:42) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.OperatorCacheManager.getOperatorForDevice(OperatorCacheManager.java:50) at com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation.doConfigOperation(ConfigOperation.java:99) at com.cisco.nm.xms.xdi.pkgs.SharedDcmaIOS.transport.IOSConfigOperator.fetchConfig(IOSConfigOperator.java:71) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.updateArchiveForDevice(ConfigManager.java:1313) at com.cisco.nm.rmeng.dcma.configmanager.ConfigManager.performCollection(ConfigManager.java:3129) at com.cisco.nm.rmeng.dcma.configmanager.CfgUpdateThread.run(CfgUpdateThread.java:29) [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,216,Failed to connect to 10.69.77.1 with TELNET [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,219,message java.net.ConnectException: Connection refused: connect [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.CliOperator,,229,message= Connection refused [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,101,Failed to get ConfigOperator for Protocol:TELNET for device 10.69.77.1 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.xms.xdi.pkgs.LibDcma.persistor.ConfigOperation,doConfigOperation,103,TELNET: Failed to establish TELNET connection to 10.69.77.1 - Cause: Connection refused. [ on nov 28 08:01:03 CET 2007 ],ERROR,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateArchiveForDevice,1335,PRIMARY RUNNING Config fetch Failed for slagavpn [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.DBInterface,updateStatusTable,574,Select * from DCMA_Archive_Status where NetworkElementId = '489' and Config_Type = 1 and Config_File_Type = 2 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.DBInterface,updateStatusTable,602,Update DCMA_Archive_Status set Status = 2, UpdatedAt = '2007-11-28 08:01:03.027', Comments = 'Could not detect protocols running on the device TELNET: Failed to establish TELNET connection to 10.69.77.1 - Cause: Connection refused. ' where NetworkElementId = '489' and Config_Type = 1 and Config_File_Type = 2 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,storeResults,2431,Device status = 2 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateFetchResultsForDevice,529,Updating fetch results for 1196233260027 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateDeviceCount,1985,Total devices left = 0 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateDeviceCount,1997,All devices processed for requestId 1196233260027 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,cleanUpQueueEntries,1783,Removed results for device 489 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,updateFetchResultsForDevice,571,Completed device 489 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.dcma.utils.ArchiveUtils,getDeviceReachabilityStatus,403,Checking if device is reachable.. [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.inventory.InvAPIs,getAllIPAddrs,401,Querry statment is as follows [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.inventory.InvAPIs,getAllIPAddrs,403,select IPAddress from IPProtocolEndpoint where NetworkElementID = '489' [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.inventory.InvAPIs,getAllIPAddrs,415,The following are the obtained IP addresses [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.inventory.InvAPIs,getAllIPAddrs,423,10.69.77.1 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.inventory.InvAPIs,getAllIPAddrs,423,81.192.114.52 [ on nov 28 08:01:03 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.ServerAddress,getAddress,67,For Device 10.69.77.1 23 [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.util.ServerAddress,getAddress,67,For Device 10.69.77.1 22 [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.inventory.dm.DeviceAccessStatusInterpreter,updateDeviceAccessResult,189,Entering DeviceAccessStatusInterpreter's updateDeviceAccessResult Method [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-850],com.cisco.nm.rmeng.inventory.dm.DeviceAccessStatusInterpreter,updateDeviceAccessResult,207,Leaving DeviceAccessStatusInterpreter's updateDeviceAccessResult Method [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-18],com.cisco.nm.rmeng.inventory.dm.DeviceAccessStatusUpdater,updateDeviceAccessResult,56,Entering DeviceAccessStatusUpdater's updateDeviceAccessResult Method [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,91,---- Stopped Thread 1 ---- [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,91,---- Stopped Thread 2 ---- [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,91,---- Stopped Thread 3 ---- [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,91,---- Stopped Thread 4 ---- [ on nov 28 08:01:04 CET 2007 ],INFO ,[Thread-1],com.cisco.nm.rmeng.dcma.configmanager.CfgThreadManager,run,94,#### End of Sweep Wed Nov 28 08:01:04 CET 2007 #### [ on nov 28 08:01:04 CET 2007 ],DEBUG,[Thread-18],com.cisco.nm.rmeng.inventory.dm.DeviceAccessStatusUpdater,updateDeviceAccessResult,69,Leaving DeviceAccessStatusUpdater's updateDeviceAccessResult Method [ on nov 28 08:01:05 CET 2007 ],DEBUG,[Thread-4],com.cisco.nm.rmeng.dcma.configmanager.ConfigManager,getStatusOfRequest,913,Total devices due for reqId = 1196233260027 is 0