00151336.001 |09:49:49.155 |AppInfo |CCDServiceManager::processCCDAdvertisingServiceProfileUpdate()- update
00151336.002 |09:49:49.155 |AppInfo |CCDServiceManager::subscribeRegistration()- key=eed50027-e419-56e0-6260-3d92188c4262.
00151337.000 |09:49:49.156 |SdlSig |AdvertisingServiceDBChangeNotification |wait |CCDAdvertisingService(1,100,227,5) |CCDServiceManager(1,100,228,1) |1,100,37,1.83^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151337.001 |09:49:49.156 |AppInfo |CCDAdvertisingService::ccdProfileUpdate() - CCD Advertising Service Service is reset!!!
00151337.002 |09:49:49.156 |AppInfo |CCDAdvertisingService::unpublishService(), trunk=2.5.1.10:5060, prot=11, version=0, published=0
00151337.003 |09:49:49.156 |AppInfo |CCDAdvertisingService::trunksAreAvailable() true!!!
00151337.004 |09:49:49.156 |Stopping | | |CCDAdvertisingService(1,100,227,5) |CCDAdvertisingService(1,100,227,5) | |NumOfCurrentInstances: 1
00151338.000 |09:49:49.156 |SdlSig |SNFUnSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.83^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|11, bulkUnSubscribeFlag = ,F subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151338.001 |09:49:49.156 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribe - subId = 1|0|11, subscriberPid=(1,228,1), bulkUnSub=0
00151338.002 |09:49:49.156 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList - InSubId = 1|0|11, ougoing sub id=1|0|11, type = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151338.003 |09:49:49.156 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList for destinationPid =(1,74,6)
00151338.004 |09:49:49.156 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribeForSubId - done for subId = 1|0|11
00151339.000 |09:49:49.156 |SdlSig |SAFUnregisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,5) |1,100,37,1.83^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,5), networkClientHandle= 0, clientHandle= FUJQXYDIFQGUKCKNUWXD,
00151339.001 |09:49:49.156 |AppInfo |Begin SAFClientControl::wait_SAFUnregisterReq()
00151339.002 |09:49:49.156 |AppInfo |SAFClientControl - updatePerfmonCounts unregistered = 1, registered = 0 clients = 1
00151339.003 |09:49:49.156 |AppInfo |SAFClientControl::wait_SAFUnregisterReq() App not registered - removing
00151340.000 |09:49:49.156 |SdlSig |SNFSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.83^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|15, SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =
00151340.001 |09:49:49.156 |AppInfo |SMDMSharedData::findSubscriptionIdExistsInSubInfo - subId=1|0|15 not found
00151340.002 |09:49:49.156 |AppInfo |SMDMSharedData::insertSubscriptionInfo - subId=1|0|15 inserted in mSubscriptionInfo hash map, bulkUnSub = 0 for this subscription, Subscription Type= REGISTRATION
00151340.003 |09:49:49.156 |AppInfo |SMDMSharedData::updateDestinationCepnInSubInfo - subId=1|0|15, destinationCepn=eed50027-e419-56e0-6260-3d92188c4262
00151340.004 |09:49:49.156 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - for subId = 1|0|15, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262
00151340.005 |09:49:49.156 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151340.006 |09:49:49.156 |AppInfo |SMDMSharedData::updateDestinationPidInSubMsg - subId=1|0|15, destinationPid=(1,74,6)
00151340.007 |09:49:49.156 |AppInfo |SMDMSharedData::updateAuthStateInSubInfo - subId=1|0|15, authorized=1
00151340.008 |09:49:49.156 |AppInfo |SMDMSharedData::insertPendingSubscription - Cepn=eed50027-e419-56e0-6260-3d92188c4262, SubscriptionId=1|0|15 inserted
00151340.009 |09:49:49.156 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo -Sub Info for subid=1|0|15 of Subscription Type= REGISTRATION
00151340.010 |09:49:49.156 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo - subId=1|0|15, NextHopPid=(1,74,6)
00151340.011 |09:49:49.156 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - calling notifyRegisterAndForwardSubscription for subId = 1|0|15, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262, nextHopPid = (1,74,6)
00151340.012 |09:49:49.156 |AppInfo |SMDMSharedData::notifyRegisterAndForwardSubscription - subId = 1|0|15, subMsg=SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (1,74,6), destinationCepn = eed50027-e419-56e0-6260-3d92188c4262, pretransformTagsList = , pretransformPositionalMatchList = , NextHopPid=(1,74,6)
00151340.013 |09:49:49.156 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151340.014 |09:49:49.156 |AppInfo |SMDMSharedData::sendRegistrationStatus - subId = 1|0|15, outgoing sub id = None, subscribeeCepn =eed50027-e419-56e0-6260-3d92188c4262 subscribeePid=(1,74,6) to subscriberPid=(1,228,1)
00151340.015 |09:49:49.156 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify subId not found adding - subid=1|0|15
00151340.016 |09:49:49.156 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify - found state entry for outSubId = 1|0|11, inSubId = 1|0|15, subscribee pid=(1,74,6), subType = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151341.000 |09:49:49.157 |SdlSig |SNFNotifyReq |wait |CCDServiceManager(1,100,228,1) |SubscriptionManager(1,100,55,1) |1,100,37,1.83^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|15, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151341.001 |09:49:49.158 |AppInfo |CCDServiceManager::wait_SNFNotifyReq, childPkid=69dd389c-f712-d54c-5b4f-c13eb3be26db, trunkCepn = eed50027-e419-56e0-6260-3d92188c4262
00151341.002 |09:49:49.158 |Created | | |CCDAdvertisingService(1,100,227,6) |CCDServiceManager(1,100,228,1) | |NumOfCurrentInstances: 1
00151342.000 |09:49:49.158 |SdlSig |SNFNotifyReq |wait |CCDAdvertisingService(1,100,227,6) |CCDServiceManager(1,100,228,1) |1,100,37,1.83^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|15, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151342.001 |09:49:49.159 |AppInfo |CCDAdvertisingService::updateCCDAdvertisingTrunkAfterReset()- SIP devRowSIP.pkid_m=eed50027-e419-56e0-6260-3d92188c4262.
00151342.002 |09:49:49.159 |AppInfo |CCDAdvertisingService::wait_SNFNotifyReq() reg SIP, remoteAddr = 2.5.1.10, 5060, subId = 1|0|15, safins=0
00151343.000 |09:49:49.159 |SdlSig |SAFRegisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,6) |1,100,37,1.83^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,6), networkClientHandle= 0
00151343.001 |09:49:49.159 |AppInfo |Begin SAFClientControl::wait_SAFRegisterReq()
00151343.002 |09:49:49.159 |AppInfo |End SAFClientControl::wait_SAFRegisterReq() - DUMPPPPP
ClientEntry
SafForwarderInfoCache
SAFConnectionInfoCacheDetails
SAFConnectionBlockDetails
subStatus ................ UnSubscribed
SAFConnectionInfoDetails
ipAddr.................... 10.1.5.100
port ..................... 5050
connStatus ............... SafEstablished
clientHandle ............. 0
sdlTCPPid ................ (0, 0, 0, 0)
safClientSettingPkid ..... 9e84e508-a07b-564e-3a6e-6ea058574c80
numConnetionReAttempts ... 1
timerReconnectInterval ... 20
numSafPacketsSent ........ 2
numOfRegisteredApps ...... 0
forwarderName ............ HQ-SAF-Forwarder
clientName ............... UCM/CM_HQPUB/NodeId=1/9.1.2.10000-28
clientLabel .............. CUCM-HQ
keepAliveInterval ........ 0
isKATimerRunning ........ 0
designation .............. SafNoForwarderDesignation
isRegistered ............. 1
enableTcpKeepalive ....... 1
safNotificationsWindowSize 7
Security Profile Details
password............... **************
userName .............. ciscociscocisco
authenticationMode .... 2
mServiceAttributes
ServiceIdentity .
serviceId.................. 0
subServiceId .............. 0
instanceId[0] ............. 0
instanceId[1] ............. 0
instanceId[2] ............. 0
instanceId[3] ............. 0
mApplClientHandle ....... UQNDOKLWEREDYWYJIYZJ
mCurrentState ........... App_AwaitRegister
mReceivingProcess ....... (1, 100, 227, 6)
mSubInstructions ........ SafSubscribeToNone
00151344.000 |09:49:49.159 |SdlSig |SAFRegisterRes |registeringSAFClient |CCDAdvertisingService(1,100,227,6) |SAFClientControl(1,100,79,1) |1,100,37,1.83^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(0,0,0), networkClientHandle= 0, clientHandle= UQNDOKLWEREDYWYJIYZJ,
00151344.001 |09:49:49.159 |AppInfo |CCDAdvertisingService::buildAdvertisedPatterns() est=655, max=2000.
00151344.002 |09:49:49.159 |AppInfo |CCDAdvertisingService::buildAndStorePatterns(), hostedDNList = 1, est len = 655, nextPatternInfoIndex = 1
00151344.003 |09:49:49.159 |AppInfo |CCDAdvertisingService::buildCCDHostedDnService() completed, len=655.
00151344.004 |09:49:49.159 |AppInfo |CCDAdvertisingService::encodeSafXmlOpaqueData() success, lene=668.
00151344.005 |09:49:49.159 |AppInfo |CCDAdvertisingService::buildServiceIdInstanceForTrunk() trunk=2.5.1.10:5060, rc=1!!, xml=
UCM9.1.2.10000-28StandAloneClusterCM_HQPUB
sip:eed50027-e419-56e0-6260-3d92188c4262@HQPUB
2XXX
00151344.006 |09:49:49.159 |AppInfo |CCDAdvertisingService::buildCcdAdvertisedService-advertised SIP, find SIP trunk=true
00151345.000 |09:49:49.925 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151346.000 |09:49:50.662 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151347.000 |09:49:50.946 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151348.000 |09:49:51.970 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151349.000 |09:49:51.970 |SdlStat |Period: 6s #Lines: 97 #Bytes: 21681 Total Number of Buffers: 10000 Free LWM: 9961 Free LWM(total): 9509
00151350.000 |09:49:52.990 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151351.000 |09:49:54.004 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151352.000 |09:49:54.494 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151352.001 |09:49:54.494 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151353.000 |09:49:54.494 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151354.000 |09:49:55.010 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151355.000 |09:49:55.670 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151356.000 |09:49:56.018 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151357.000 |09:49:57.037 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151358.000 |09:49:57.037 |SdlStat |Period: 6s #Lines: 9 #Bytes: 2401 Total Number of Buffers: 10000 Free LWM: 9997 Free LWM(total): 9509
00151359.000 |09:49:57.141 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.84^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151360.000 |09:49:57.141 |SdlSig |AdvertisingServiceDBChangeNotification |wait |CCDServiceManager(1,100,228,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.84^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151360.001 |09:49:57.141 |AppInfo |CCDServiceManager::processCCDAdvertisingServiceProfileUpdate()- update
00151360.002 |09:49:57.141 |AppInfo |CCDServiceManager::subscribeRegistration()- key=eed50027-e419-56e0-6260-3d92188c4262.
00151361.000 |09:49:57.141 |SdlSig |AdvertisingServiceDBChangeNotification |wait |CCDAdvertisingService(1,100,227,6) |CCDServiceManager(1,100,228,1) |1,100,37,1.84^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151361.001 |09:49:57.141 |AppInfo |CCDAdvertisingService::ccdProfileUpdate() - CCD Advertising Service Service is reset!!!
00151361.002 |09:49:57.141 |AppInfo |CCDAdvertisingService::unpublishService(), trunk=2.5.1.10:5060, prot=11, version=0, published=0
00151361.003 |09:49:57.141 |AppInfo |CCDAdvertisingService::trunksAreAvailable() true!!!
00151361.004 |09:49:57.141 |Stopping | | |CCDAdvertisingService(1,100,227,6) |CCDAdvertisingService(1,100,227,6) | |NumOfCurrentInstances: 1
00151362.000 |09:49:57.141 |SdlSig |SNFUnSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.84^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|15, bulkUnSubscribeFlag = ,F subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151362.001 |09:49:57.141 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribe - subId = 1|0|15, subscriberPid=(1,228,1), bulkUnSub=0
00151362.002 |09:49:57.141 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList - InSubId = 1|0|15, ougoing sub id=1|0|11, type = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151362.003 |09:49:57.141 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList for destinationPid =(1,74,6)
00151362.004 |09:49:57.141 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribeForSubId - done for subId = 1|0|15
00151363.000 |09:49:57.141 |SdlSig |SAFUnregisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,6) |1,100,37,1.84^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,6), networkClientHandle= 0, clientHandle= UQNDOKLWEREDYWYJIYZJ,
00151363.001 |09:49:57.141 |AppInfo |Begin SAFClientControl::wait_SAFUnregisterReq()
00151363.002 |09:49:57.141 |AppInfo |SAFClientControl - updatePerfmonCounts unregistered = 1, registered = 0 clients = 1
00151363.003 |09:49:57.141 |AppInfo |SAFClientControl::wait_SAFUnregisterReq() App not registered - removing
00151364.000 |09:49:57.142 |SdlSig |SNFSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.84^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|16, SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =
00151364.001 |09:49:57.142 |AppInfo |SMDMSharedData::findSubscriptionIdExistsInSubInfo - subId=1|0|16 not found
00151364.002 |09:49:57.142 |AppInfo |SMDMSharedData::insertSubscriptionInfo - subId=1|0|16 inserted in mSubscriptionInfo hash map, bulkUnSub = 0 for this subscription, Subscription Type= REGISTRATION
00151364.003 |09:49:57.142 |AppInfo |SMDMSharedData::updateDestinationCepnInSubInfo - subId=1|0|16, destinationCepn=eed50027-e419-56e0-6260-3d92188c4262
00151364.004 |09:49:57.142 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - for subId = 1|0|16, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262
00151364.005 |09:49:57.142 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151364.006 |09:49:57.142 |AppInfo |SMDMSharedData::updateDestinationPidInSubMsg - subId=1|0|16, destinationPid=(1,74,6)
00151364.007 |09:49:57.142 |AppInfo |SMDMSharedData::updateAuthStateInSubInfo - subId=1|0|16, authorized=1
00151364.008 |09:49:57.142 |AppInfo |SMDMSharedData::insertPendingSubscription - Cepn=eed50027-e419-56e0-6260-3d92188c4262, SubscriptionId=1|0|16 inserted
00151364.009 |09:49:57.142 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo -Sub Info for subid=1|0|16 of Subscription Type= REGISTRATION
00151364.010 |09:49:57.142 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo - subId=1|0|16, NextHopPid=(1,74,6)
00151364.011 |09:49:57.142 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - calling notifyRegisterAndForwardSubscription for subId = 1|0|16, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262, nextHopPid = (1,74,6)
00151364.012 |09:49:57.142 |AppInfo |SMDMSharedData::notifyRegisterAndForwardSubscription - subId = 1|0|16, subMsg=SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (1,74,6), destinationCepn = eed50027-e419-56e0-6260-3d92188c4262, pretransformTagsList = , pretransformPositionalMatchList = , NextHopPid=(1,74,6)
00151364.013 |09:49:57.142 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151364.014 |09:49:57.142 |AppInfo |SMDMSharedData::sendRegistrationStatus - subId = 1|0|16, outgoing sub id = None, subscribeeCepn =eed50027-e419-56e0-6260-3d92188c4262 subscribeePid=(1,74,6) to subscriberPid=(1,228,1)
00151364.015 |09:49:57.142 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify subId not found adding - subid=1|0|16
00151364.016 |09:49:57.142 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify - found state entry for outSubId = 1|0|11, inSubId = 1|0|16, subscribee pid=(1,74,6), subType = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151365.000 |09:49:57.142 |SdlSig |SNFNotifyReq |wait |CCDServiceManager(1,100,228,1) |SubscriptionManager(1,100,55,1) |1,100,37,1.84^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|16, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151365.001 |09:49:57.142 |AppInfo |CCDServiceManager::wait_SNFNotifyReq, childPkid=69dd389c-f712-d54c-5b4f-c13eb3be26db, trunkCepn = eed50027-e419-56e0-6260-3d92188c4262
00151365.002 |09:49:57.142 |Created | | |CCDAdvertisingService(1,100,227,7) |CCDServiceManager(1,100,228,1) | |NumOfCurrentInstances: 1
00151366.000 |09:49:57.142 |SdlSig |SNFNotifyReq |wait |CCDAdvertisingService(1,100,227,7) |CCDServiceManager(1,100,228,1) |1,100,37,1.84^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|16, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151366.001 |09:49:57.142 |AppInfo |CCDAdvertisingService::updateCCDAdvertisingTrunkAfterReset()- SIP devRowSIP.pkid_m=eed50027-e419-56e0-6260-3d92188c4262.
00151366.002 |09:49:57.142 |AppInfo |CCDAdvertisingService::wait_SNFNotifyReq() reg SIP, remoteAddr = 2.5.1.10, 5060, subId = 1|0|16, safins=0
00151367.000 |09:49:57.142 |SdlSig |SAFRegisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,7) |1,100,37,1.84^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,7), networkClientHandle= 0
00151367.001 |09:49:57.142 |AppInfo |Begin SAFClientControl::wait_SAFRegisterReq()
00151367.002 |09:49:57.142 |AppInfo |End SAFClientControl::wait_SAFRegisterReq() - DUMPPPPP
ClientEntry
SafForwarderInfoCache
SAFConnectionInfoCacheDetails
SAFConnectionBlockDetails
subStatus ................ UnSubscribed
SAFConnectionInfoDetails
ipAddr.................... 10.1.5.100
port ..................... 5050
connStatus ............... SafEstablished
clientHandle ............. 0
sdlTCPPid ................ (0, 0, 0, 0)
safClientSettingPkid ..... 9e84e508-a07b-564e-3a6e-6ea058574c80
numConnetionReAttempts ... 1
timerReconnectInterval ... 20
numSafPacketsSent ........ 2
numOfRegisteredApps ...... 0
forwarderName ............ HQ-SAF-Forwarder
clientName ............... UCM/CM_HQPUB/NodeId=1/9.1.2.10000-28
clientLabel .............. CUCM-HQ
keepAliveInterval ........ 0
isKATimerRunning ........ 0
designation .............. SafNoForwarderDesignation
isRegistered ............. 1
enableTcpKeepalive ....... 1
safNotificationsWindowSize 7
Security Profile Details
password............... **************
userName .............. ciscociscocisco
authenticationMode .... 2
mServiceAttributes
ServiceIdentity .
serviceId.................. 0
subServiceId .............. 0
instanceId[0] ............. 0
instanceId[1] ............. 0
instanceId[2] ............. 0
instanceId[3] ............. 0
mApplClientHandle ....... VOAOZHXXCANIPSLHSZBM
mCurrentState ........... App_AwaitRegister
mReceivingProcess ....... (1, 100, 227, 7)
mSubInstructions ........ SafSubscribeToNone
00151368.000 |09:49:57.142 |SdlSig |SAFRegisterRes |registeringSAFClient |CCDAdvertisingService(1,100,227,7) |SAFClientControl(1,100,79,1) |1,100,37,1.84^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(0,0,0), networkClientHandle= 0, clientHandle= VOAOZHXXCANIPSLHSZBM,
00151368.001 |09:49:57.142 |AppInfo |CCDAdvertisingService::buildAdvertisedPatterns() est=655, max=2000.
00151368.002 |09:49:57.142 |AppInfo |CCDAdvertisingService::buildAndStorePatterns(), hostedDNList = 1, est len = 655, nextPatternInfoIndex = 1
00151368.003 |09:49:57.142 |AppInfo |CCDAdvertisingService::buildCCDHostedDnService() completed, len=655.
00151368.004 |09:49:57.142 |AppInfo |CCDAdvertisingService::encodeSafXmlOpaqueData() success, lene=668.
00151368.005 |09:49:57.142 |AppInfo |CCDAdvertisingService::buildServiceIdInstanceForTrunk() trunk=2.5.1.10:5060, rc=1!!, xml=
UCM9.1.2.10000-28StandAloneClusterCM_HQPUB
sip:eed50027-e419-56e0-6260-3d92188c4262@HQPUB
2XXX
00151368.006 |09:49:57.142 |AppInfo |CCDAdvertisingService::buildCcdAdvertisedService-advertised SIP, find SIP trunk=true
00151369.000 |09:49:58.045 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151370.000 |09:49:59.058 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151371.000 |09:50:00.074 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151372.000 |09:50:00.689 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151373.000 |09:50:01.085 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151374.000 |09:50:01.314 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.85^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151375.000 |09:50:01.314 |SdlSig |AdvertisingServiceDBChangeNotification |wait |CCDServiceManager(1,100,228,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.85^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151375.001 |09:50:01.314 |AppInfo |CCDServiceManager::processCCDAdvertisingServiceProfileUpdate()- update
00151375.002 |09:50:01.314 |AppInfo |CCDServiceManager::subscribeRegistration()- key=eed50027-e419-56e0-6260-3d92188c4262.
00151376.000 |09:50:01.314 |SdlSig |AdvertisingServiceDBChangeNotification |wait |CCDAdvertisingService(1,100,227,7) |CCDServiceManager(1,100,228,1) |1,100,37,1.85^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151376.001 |09:50:01.314 |AppInfo |CCDAdvertisingService::ccdProfileUpdate() - CCD Advertising Service Service is reset!!!
00151376.002 |09:50:01.314 |AppInfo |CCDAdvertisingService::unpublishService(), trunk=2.5.1.10:5060, prot=11, version=0, published=0
00151376.003 |09:50:01.314 |AppInfo |CCDAdvertisingService::trunksAreAvailable() true!!!
00151376.004 |09:50:01.314 |Stopping | | |CCDAdvertisingService(1,100,227,7) |CCDAdvertisingService(1,100,227,7) | |NumOfCurrentInstances: 1
00151377.000 |09:50:01.314 |SdlSig |SNFUnSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.85^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|16, bulkUnSubscribeFlag = ,F subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151377.001 |09:50:01.314 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribe - subId = 1|0|16, subscriberPid=(1,228,1), bulkUnSub=0
00151377.002 |09:50:01.314 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList - InSubId = 1|0|16, ougoing sub id=1|0|11, type = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151377.003 |09:50:01.314 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList for destinationPid =(1,74,6)
00151377.004 |09:50:01.314 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribeForSubId - done for subId = 1|0|16
00151378.000 |09:50:01.314 |SdlSig |SAFUnregisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,7) |1,100,37,1.85^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,7), networkClientHandle= 0, clientHandle= VOAOZHXXCANIPSLHSZBM,
00151378.001 |09:50:01.314 |AppInfo |Begin SAFClientControl::wait_SAFUnregisterReq()
00151378.002 |09:50:01.314 |AppInfo |SAFClientControl - updatePerfmonCounts unregistered = 1, registered = 0 clients = 1
00151378.003 |09:50:01.314 |AppInfo |SAFClientControl::wait_SAFUnregisterReq() App not registered - removing
00151379.000 |09:50:01.314 |SdlSig |SNFSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.85^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|17, SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =
00151379.001 |09:50:01.314 |AppInfo |SMDMSharedData::findSubscriptionIdExistsInSubInfo - subId=1|0|17 not found
00151379.002 |09:50:01.314 |AppInfo |SMDMSharedData::insertSubscriptionInfo - subId=1|0|17 inserted in mSubscriptionInfo hash map, bulkUnSub = 0 for this subscription, Subscription Type= REGISTRATION
00151379.003 |09:50:01.314 |AppInfo |SMDMSharedData::updateDestinationCepnInSubInfo - subId=1|0|17, destinationCepn=eed50027-e419-56e0-6260-3d92188c4262
00151379.004 |09:50:01.314 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - for subId = 1|0|17, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262
00151379.005 |09:50:01.314 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151379.006 |09:50:01.314 |AppInfo |SMDMSharedData::updateDestinationPidInSubMsg - subId=1|0|17, destinationPid=(1,74,6)
00151379.007 |09:50:01.314 |AppInfo |SMDMSharedData::updateAuthStateInSubInfo - subId=1|0|17, authorized=1
00151379.008 |09:50:01.314 |AppInfo |SMDMSharedData::insertPendingSubscription - Cepn=eed50027-e419-56e0-6260-3d92188c4262, SubscriptionId=1|0|17 inserted
00151379.009 |09:50:01.314 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo -Sub Info for subid=1|0|17 of Subscription Type= REGISTRATION
00151379.010 |09:50:01.314 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo - subId=1|0|17, NextHopPid=(1,74,6)
00151379.011 |09:50:01.314 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - calling notifyRegisterAndForwardSubscription for subId = 1|0|17, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262, nextHopPid = (1,74,6)
00151379.012 |09:50:01.314 |AppInfo |SMDMSharedData::notifyRegisterAndForwardSubscription - subId = 1|0|17, subMsg=SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (1,74,6), destinationCepn = eed50027-e419-56e0-6260-3d92188c4262, pretransformTagsList = , pretransformPositionalMatchList = , NextHopPid=(1,74,6)
00151379.013 |09:50:01.314 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151379.014 |09:50:01.314 |AppInfo |SMDMSharedData::sendRegistrationStatus - subId = 1|0|17, outgoing sub id = None, subscribeeCepn =eed50027-e419-56e0-6260-3d92188c4262 subscribeePid=(1,74,6) to subscriberPid=(1,228,1)
00151379.015 |09:50:01.314 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify subId not found adding - subid=1|0|17
00151379.016 |09:50:01.314 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify - found state entry for outSubId = 1|0|11, inSubId = 1|0|17, subscribee pid=(1,74,6), subType = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151380.000 |09:50:01.314 |SdlSig |SNFNotifyReq |wait |CCDServiceManager(1,100,228,1) |SubscriptionManager(1,100,55,1) |1,100,37,1.85^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|17, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151380.001 |09:50:01.314 |AppInfo |CCDServiceManager::wait_SNFNotifyReq, childPkid=69dd389c-f712-d54c-5b4f-c13eb3be26db, trunkCepn = eed50027-e419-56e0-6260-3d92188c4262
00151380.002 |09:50:01.315 |Created | | |CCDAdvertisingService(1,100,227,8) |CCDServiceManager(1,100,228,1) | |NumOfCurrentInstances: 1
00151381.000 |09:50:01.315 |SdlSig |SNFNotifyReq |wait |CCDAdvertisingService(1,100,227,8) |CCDServiceManager(1,100,228,1) |1,100,37,1.85^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|17, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151381.001 |09:50:01.315 |AppInfo |CCDAdvertisingService::updateCCDAdvertisingTrunkAfterReset()- SIP devRowSIP.pkid_m=eed50027-e419-56e0-6260-3d92188c4262.
00151381.002 |09:50:01.315 |AppInfo |CCDAdvertisingService::wait_SNFNotifyReq() reg SIP, remoteAddr = 2.5.1.10, 5060, subId = 1|0|17, safins=0
00151382.000 |09:50:01.315 |SdlSig |SAFRegisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,8) |1,100,37,1.85^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,8), networkClientHandle= 0
00151382.001 |09:50:01.315 |AppInfo |Begin SAFClientControl::wait_SAFRegisterReq()
00151382.002 |09:50:01.315 |AppInfo |End SAFClientControl::wait_SAFRegisterReq() - DUMPPPPP
ClientEntry
SafForwarderInfoCache
SAFConnectionInfoCacheDetails
SAFConnectionBlockDetails
subStatus ................ UnSubscribed
SAFConnectionInfoDetails
ipAddr.................... 10.1.5.100
port ..................... 5050
connStatus ............... SafEstablished
clientHandle ............. 0
sdlTCPPid ................ (0, 0, 0, 0)
safClientSettingPkid ..... 9e84e508-a07b-564e-3a6e-6ea058574c80
numConnetionReAttempts ... 1
timerReconnectInterval ... 20
numSafPacketsSent ........ 2
numOfRegisteredApps ...... 0
forwarderName ............ HQ-SAF-Forwarder
clientName ............... UCM/CM_HQPUB/NodeId=1/9.1.2.10000-28
clientLabel .............. CUCM-HQ
keepAliveInterval ........ 0
isKATimerRunning ........ 0
designation .............. SafNoForwarderDesignation
isRegistered ............. 1
enableTcpKeepalive ....... 1
safNotificationsWindowSize 7
Security Profile Details
password............... **************
userName .............. ciscociscocisco
authenticationMode .... 2
mServiceAttributes
ServiceIdentity .
serviceId.................. 0
subServiceId .............. 0
instanceId[0] ............. 0
instanceId[1] ............. 0
instanceId[2] ............. 0
instanceId[3] ............. 0
mApplClientHandle ....... JKXDIVSPGBQSPXAZPPZJ
mCurrentState ........... App_AwaitRegister
mReceivingProcess ....... (1, 100, 227, 8)
mSubInstructions ........ SafSubscribeToNone
00151383.000 |09:50:01.315 |SdlSig |SAFRegisterRes |registeringSAFClient |CCDAdvertisingService(1,100,227,8) |SAFClientControl(1,100,79,1) |1,100,37,1.85^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(0,0,0), networkClientHandle= 0, clientHandle= JKXDIVSPGBQSPXAZPPZJ,
00151383.001 |09:50:01.315 |AppInfo |CCDAdvertisingService::buildAdvertisedPatterns() est=655, max=2000.
00151383.002 |09:50:01.315 |AppInfo |CCDAdvertisingService::buildAndStorePatterns(), hostedDNList = 1, est len = 655, nextPatternInfoIndex = 1
00151383.003 |09:50:01.315 |AppInfo |CCDAdvertisingService::buildCCDHostedDnService() completed, len=655.
00151383.004 |09:50:01.315 |AppInfo |CCDAdvertisingService::encodeSafXmlOpaqueData() success, lene=668.
00151383.005 |09:50:01.315 |AppInfo |CCDAdvertisingService::buildServiceIdInstanceForTrunk() trunk=2.5.1.10:5060, rc=1!!, xml=
UCM9.1.2.10000-28StandAloneClusterCM_HQPUB
sip:eed50027-e419-56e0-6260-3d92188c4262@HQPUB
2XXX
00151383.006 |09:50:01.315 |AppInfo |CCDAdvertisingService::buildCcdAdvertisedService-advertised SIP, find SIP trunk=true
00151384.000 |09:50:02.089 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151385.000 |09:50:03.098 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151386.000 |09:50:03.098 |SdlStat |Period: 6s #Lines: 109 #Bytes: 27576 Total Number of Buffers: 10000 Free LWM: 9949 Free LWM(total): 9509
00151387.000 |09:50:04.118 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151388.000 |09:50:04.688 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151388.001 |09:50:04.688 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151389.000 |09:50:04.689 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151390.000 |09:50:05.133 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151391.000 |09:50:05.698 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151392.000 |09:50:06.137 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151393.000 |09:50:07.150 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151394.000 |09:50:08.158 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151395.000 |09:50:09.272 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151396.000 |09:50:09.272 |SdlStat |Period: 6s #Lines: 10 #Bytes: 2664 Total Number of Buffers: 10000 Free LWM: 9997 Free LWM(total): 9509
00151397.000 |09:50:09.901 |SdlSig |PublishRetryAfterTimer |restart0 |PublishEPA(1,100,106,5) |SdlTimerService(1,100,3,1) |1,100,12,3338.1^*^* |*TraceFlagOverrode
00151397.001 |09:50:09.901 |AppInfo |sendPublishOut starts.
00151398.000 |09:50:09.901 |SdlSig |SIPPublishReq |wait |SIPHandler(1,100,72,1) |PublishEPA(1,100,106,5) |1,100,12,3338.1^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4129 event=presence duration=3600 sip_if_match= callingNumber=hqph1 calledNumber=hqph1 TransType=1 --TransSecurity=0 PeerAddr = 10.1.5.5:5060 addrList: |ipAddrType=0 (0)10.1.5.5:5060|
00151398.001 |09:50:09.902 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4129/ccsip_publish_request: Got DeviceName By SipAddr, Device is CIMP-TR
00151398.002 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xf2fb278) with key=[4124] to table
00151398.003 |09:50:09.902 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4129/ccsip_platform_init_scb: scb mgmt added 0xf2fb278 (context_id=4124)
00151398.004 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0xf2fb278/ccsip_new_scb: Created new scb: 0xf2fb278 with id: 4129
00151398.005 |09:50:09.902 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=5060, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0
00151398.006 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 207 (UNDEFINED)
00151398.007 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_PUBLISH] received in State [SUBSCRIBE_STATE_IDLE]
00151398.008 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_tcb: Created TCB: 0xf2f77f8
00151398.009 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/act_idle_publish_event: TCB 0xf2f77f8's parent is 0xf2fb278
00151398.010 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateTCBFromTargetInfo: Dialog Transaction Address 10.1.5.5,Port 5060, Port Present TRUE, Transpo
00151398.011 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/sipSPICreateSubscribeKey: Generated Subscribe Key : 1658ec00-33413aa1-e15-205010a@10.1.5.22086138697
00151398.012 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0xf2fb278/sipSPIPutSCBInClientTable: Inserting SCB with key 1658ec00-33413aa1-e15-205010a@10.1.5.22086138697 into ClientTable
00151398.013 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: TCB 0xf2f77f8's method is 113
00151398.014 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBClientMarker: Client TCB's Marker Updated to: z9hG4bKe14631c8e27
00151398.015 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_edb: Created new edb(0xf3002b8)
00151398.016 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateEDBEvent: EDB updated with Event Name: presence Event ID:
00151398.017 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0xf2fb278/sipSPICreateNewEDB: Q'd EDB(0xf3002b8) on SCB(0xf2fb278)
00151398.018 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/sipSPIQueTcb: Q'd TCB(0xf2f77f8) on EDB(0xf3002b8)
00151398.019 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0xf2fb278/sipSPITCBSendPackage: Sending Package on tcb: 0xf2f77f8 and parent: 0xf2fb278
00151398.020 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0xf2fb278/sipSPITransportSendMessage: msg=0xb0e89720, addr=10.1.5.5, port=5060, sentBy_port=0, is_req=1, transpo
00151398.021 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostRequestConnection: Posting TCP conn create request for addr=10.1.5.5, port=5060, conte
00151398.022 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer: Wait timer set for connection=0xe080880,addr=10.1.5.5, port=5060
00151398.023 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer:
Wait Conn Timer started for 5000 msec
00151398.024 |09:50:09.902 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151398.025 |09:50:09.902 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151398.026 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipCreateConnInstance: Created new initiated conn=0xe080880, connid=-1, addr=10.1.5.5, port=5060, transport=
00151398.027 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: Registering gcb=0xf2fb278 with connection=0xe080880
00151398.028 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0xf2fb278/sipSPITransportSendMessage: Deferred sending msg=0xb0e89720
00151398.029 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_DIALOG_PENDING
00151399.000 |09:50:09.902 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3338.1^*^* |*TraceFlagOverrode
00151399.001 |09:50:09.902 |AppInfo |SIPTcp - SIPSPI_EV_CREATE_CONNECTION: Addr=10.1.5.5, Port=5060, globalTCPInst=0xe07fb88
00151400.000 |09:50:09.902 |SdlSig |SdlConnectReq |started |SdlTCPService(1,100,10,1) |SIPTcp(1,100,64,1) |1,100,12,3338.1^*^* |*TraceFlagOverrode
00151400.001 |09:50:09.902 |Created | | |SdlTCPConnector(1,100,12,3340) |SdlTCPService(1,100,10,1) | |NumOfCurrentInstances: 1
00151401.000 |09:50:09.902 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3338.1^*^* |*TraceFlagOverrode
00151401.001 |09:50:09.902 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 58 (SIPSPI_EV_CONNECTION_CREATED)
00151401.002 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnCreated: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, connid=3327, transport=TCP
00151401.003 |09:50:09.902 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceHandleConnectionCreated: Moving connection=0xe080880, connid=3327state to pending
00151402.000 |09:50:10.282 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151403.000 |09:50:10.714 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151404.000 |09:50:11.294 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151405.000 |09:50:12.512 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151406.000 |09:50:13.509 |NetError | | |SdlTCPConnector(1,100,12,3340) | |1,100,12,3338.1^*^* |Cannot get remote address for connector socket.
00151407.000 |09:50:13.509 |NetError | | |SdlTCPConnector(1,100,12,3340) | |1,100,12,3338.1^*^* |SdlConnectorBase::connectionError
00151408.000 |09:50:13.509 |Stopping | | |SdlTCPConnector(1,100,12,3340) |SdlTCPConnector(1,100,12,3340) | |NumOfCurrentInstances: 1
00151409.000 |09:50:13.510 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151409.001 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 59 (SIPSPI_EV_CONNECTION_CREATE_FAILED)
00151409.002 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWConnectionFailed: context=0xe07fb88
00151409.003 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnFailed: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, transport=TCP
00151409.004 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0xe080880,addr=10.1.5.5, port=5060
00151409.005 |09:50:13.510 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151409.006 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0xf2fb278/sipTransportPostInternalMsg: Posting Internal Msg type=1
00151409.007 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostCloseConnection: Posting TCP conn close for addr=10.1.5.5, port=5060, connid=3327
00151409.008 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0x0/sipDeleteConnInstance: Deleted conn=0xe080880, connid=3327, addr=10.1.5.5, port=5060, transport=TCP
00151410.000 |09:50:13.510 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151410.001 |09:50:13.510 |AppInfo |SIPTcp - Did not find table entry in Close Msg,Index = 3327
00151411.000 |09:50:13.510 |SdlSig |SIPConnControlInd |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3340.1^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3327
00151411.001 |09:50:13.510 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getRemoteAddrInfo: RemoteAddrInfo = 10.1.5.5:5060
00151412.000 |09:50:13.510 |SdlSig |SIPConnControlInd |restart0 |SIPD(1,100,74,1) |SIPHandler(1,100,72,1) |1,100,12,3340.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3327
00151412.001 |09:50:13.510 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Rcvd ConnControl with operation = 1, connIdx = 3327, X.509 Name =
00151412.002 |09:50:13.510 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Cannot find TLSConnTbl entry, connIdx = 3327, X.509 Name =
00151413.000 |09:50:13.510 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151413.001 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 63 (SIPSPI_EV_INTERNAL_MSG)
00151413.002 |09:50:13.510 |AppInfo |//SIP/Stack/Error/0xf2fb278/sipTransportPostSendFailure: Posting send failure msg with tcb:0xf2f77f8 reason=4
00151414.000 |09:50:13.510 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151414.001 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 54 (SIPSPI_EV_SEND_FAILURE_MSG)
00151414.002 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0xf2fb278/ccsip_spi_process_event: Send Error for event(0xf2a1a90) on TCB(0xf2f77f8)
00151414.003 |09:50:13.510 |AppInfo |//SIP/Stack/Error/0xf2fb278/sipSPIHandleTCBSendError: Failed to send tcb=0xf2f77f8
00151414.004 |09:50:13.510 |AppInfo |//SIP/Stack/Error/0x0/sipAppGetHeader: Corrupted/Freed Container=(nil)
00151414.005 |09:50:13.510 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/fillInSipPeerAddrByContact: Contact header missing in the message.
00151414.006 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 210 (UNDEFINED)
00151414.007 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_SUBSCRIPTION_TERMINATED] received in State [SUBSCRIBE_STATE_DIALOG_PENDING]
00151414.008 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0xf2fb278/sipSPITerminateEvent: Freeing SCB(0xf2fb278), 0 active events present
00151414.009 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerUnregisterCtxtInCon: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, unregistering context=
00151414.010 |09:50:13.510 |AppInfo |//SIP/Stack/Error/0x0/sipConnectionManagerUnregisterCtxtInCon: Connection not found for addr=10.1.5.5, port=5060
00151414.011 |09:50:13.510 |AppInfo |//SIP/Stack/Transport/0xf2fb278/sipSPITransportContextCleanup: Could not purge context gcb=0xf2fb278 from the connection; gcb m
00151414.012 |09:50:13.510 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4129/ccsip_platform_delete_scb: scb mgmt deleted 0xf2fb278 (context_id=4124)
00151414.013 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0xf2fb278/sipSPIFreeOneSCB: EnQing SCB(0xf2fb278) for reuse
00151414.014 |09:50:13.510 |AppInfo |//SIP/Stack/Info/0xf2fb278/sipSPIReportRequestSendStatus: ccsip_api_publish_result returned: SIP_SUCCESS
00151415.000 |09:50:13.510 |SdlSig |SIPPublishResult |waitForResponse |PublishEPA(1,100,106,5) |SIPHandler(1,100,72,1) |1,100,12,3340.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4129 respCode=4 event=presence
00151415.001 |09:50:13.510 |AppInfo |Received 408 response. localEpaCount=2, retryInterval=30.191683.
00151416.000 |09:50:13.529 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151417.000 |09:50:13.719 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.86^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151418.000 |09:50:13.720 |SdlSig |RequestingServiceDBChangeNotification |wait |CCDServiceManager(1,100,228,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.86^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151418.001 |09:50:13.720 |AppInfo |CCDServiceManager::wait_RequestingServiceDBChangeNotification, action = 4
00151418.002 |09:50:13.720 |AppInfo |CCDServiceManager::processCcdRequestingServiceProfileUpdate - update
00151418.003 |09:50:13.720 |AppInfo |CCDServiceManager::subscribeRegistration()- key=eed50027-e419-56e0-6260-3d92188c4262.
00151419.000 |09:50:13.720 |SdlSig |SNFUnSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.86^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|14, bulkUnSubscribeFlag = ,F subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151420.000 |09:50:13.720 |SdlSig |RequestingServiceDBChangeNotification |wait |CCDRequestingService(1,100,225,7) |CCDServiceManager(1,100,228,1) |1,100,37,1.86^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151419.001 |09:50:13.720 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribe - subId = 1|0|14, subscriberPid=(1,228,1), bulkUnSub=0
00151419.002 |09:50:13.720 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList - InSubId = 1|0|14, ougoing sub id=1|0|11, type = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151419.003 |09:50:13.720 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList for destinationPid =(1,74,6)
00151420.001 |09:50:13.720 |AppInfo |CCDRequestingService::wait_RequestingServiceDBChangeNotification, action = 4
00151419.004 |09:50:13.720 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribeForSubId - done for subId = 1|0|14
00151420.002 |09:50:13.720 |AppInfo |CCDRequestingService::ccdRequestingServiceProfileUpdate() - CCD Service is reset!!!
00151421.000 |09:50:13.720 |SdlSig |SNFSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.86^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|18, SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =
00151421.001 |09:50:13.720 |AppInfo |SMDMSharedData::findSubscriptionIdExistsInSubInfo - subId=1|0|18 not found
00151421.002 |09:50:13.720 |AppInfo |SMDMSharedData::insertSubscriptionInfo - subId=1|0|18 inserted in mSubscriptionInfo hash map, bulkUnSub = 0 for this subscription, Subscription Type= REGISTRATION
00151421.003 |09:50:13.720 |AppInfo |SMDMSharedData::updateDestinationCepnInSubInfo - subId=1|0|18, destinationCepn=eed50027-e419-56e0-6260-3d92188c4262
00151421.004 |09:50:13.720 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - for subId = 1|0|18, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262
00151421.005 |09:50:13.720 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151421.006 |09:50:13.720 |AppInfo |SMDMSharedData::updateDestinationPidInSubMsg - subId=1|0|18, destinationPid=(1,74,6)
00151421.007 |09:50:13.720 |AppInfo |SMDMSharedData::updateAuthStateInSubInfo - subId=1|0|18, authorized=1
00151421.008 |09:50:13.720 |AppInfo |SMDMSharedData::insertPendingSubscription - Cepn=eed50027-e419-56e0-6260-3d92188c4262, SubscriptionId=1|0|18 inserted
00151421.009 |09:50:13.720 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo -Sub Info for subid=1|0|18 of Subscription Type= REGISTRATION
00151421.010 |09:50:13.720 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo - subId=1|0|18, NextHopPid=(1,74,6)
00151421.011 |09:50:13.720 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - calling notifyRegisterAndForwardSubscription for subId = 1|0|18, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262, nextHopPid = (1,74,6)
00151421.012 |09:50:13.720 |AppInfo |SMDMSharedData::notifyRegisterAndForwardSubscription - subId = 1|0|18, subMsg=SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (1,74,6), destinationCepn = eed50027-e419-56e0-6260-3d92188c4262, pretransformTagsList = , pretransformPositionalMatchList = , NextHopPid=(1,74,6)
00151421.013 |09:50:13.720 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151421.014 |09:50:13.720 |AppInfo |SMDMSharedData::sendRegistrationStatus - subId = 1|0|18, outgoing sub id = None, subscribeeCepn =eed50027-e419-56e0-6260-3d92188c4262 subscribeePid=(1,74,6) to subscriberPid=(1,228,1)
00151421.015 |09:50:13.720 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify subId not found adding - subid=1|0|18
00151421.016 |09:50:13.720 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify - found state entry for outSubId = 1|0|11, inSubId = 1|0|18, subscribee pid=(1,74,6), subType = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151420.003 |09:50:13.720 |Stopping | | |CCDRequestingService(1,100,225,7) |CCDRequestingService(1,100,225,7) | |NumOfCurrentInstances: 1
00151422.000 |09:50:13.721 |SdlSig |SNFNotifyReq |wait |CCDServiceManager(1,100,228,1) |SubscriptionManager(1,100,55,1) |1,100,37,1.86^*^* |[R:N-H:0,N:5,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|18, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151422.001 |09:50:13.721 |AppInfo |CCDServiceManager::wait_SNFNotifyReq, childPkid=287aa875-3a2e-372b-adc5-97433b2bbc96, trunkCepn = eed50027-e419-56e0-6260-3d92188c4262
00151422.002 |09:50:13.721 |Created | | |CCDRequestingService(1,100,225,8) |CCDServiceManager(1,100,228,1) | |NumOfCurrentInstances: 1
00151423.000 |09:50:13.721 |SdlSig |DaUnregisterDn |wait |Da(1,100,204,1) |CCDRequestingService(1,100,225,7) |1,100,37,1.86^*^* |[R:N-H:0,N:6,L:0,V:0,Z:0,D:0] CCDRequestingService(1,225,7) SsType=16777261 Partition=8499f27c-a6ff-829c-2ffb-9ad9e9ce6c05 Number= DialPlan= StatIndex=0
00151424.000 |09:50:13.721 |SdlSig |SsUnregisterManagerReq |wait |Cc(1,100,213,1) |CCDRequestingService(1,100,225,7) |1,100,37,1.86^*^* |[R:N-H:0,N:5,L:0,V:0,Z:0,D:0] Type=16777261 Key=0 NodeId=1 UnregisterDaAct=1
00151425.000 |09:50:13.721 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,7) |1,100,37,1.86^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 1 replyId= 0 destination= pkid= 825e63e0-c061-4ec8-aa84-1a919f220d37
00151425.001 |09:50:13.721 |AppInfo | updateRTMTReportingDynamic
00151426.000 |09:50:13.721 |SdlSig |SAFUnregisterReq |wait |SAFClientControl(1,100,79,1) |CCDRequestingService(1,100,225,7) |1,100,37,1.86^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,225,7), networkClientHandle= 0, clientHandle= PBBVBJHZXZZBVEYWGRGV,
00151426.001 |09:50:13.721 |AppInfo |Begin SAFClientControl::wait_SAFUnregisterReq()
00151426.002 |09:50:13.721 |AppInfo |SAFClientControl - updatePerfmonCounts unregistered = 1, registered = 0 clients = 1
00151426.003 |09:50:13.721 |AppInfo |SAFClientControl::wait_SAFUnregisterReq() App not registered - removing
00151425.002 |09:50:13.722 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151425.003 |09:50:13.722 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=1 WHERE pkid='825e63e0-c061-4ec8-aa84-1a919f220d37']
00151427.000 |09:50:13.733 |SdlSig |SNFNotifyReq |registeringManager |CCDRequestingService(1,100,225,8) |CCDServiceManager(1,100,228,1) |1,100,37,1.86^*^* |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|18, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151428.000 |09:50:13.733 |SdlSig-S |SNFNotifyReq |registeringManager |CCDRequestingService(1,100,225,8) |CCDServiceManager(1,100,228,1) |1,100,37,1.86^*^* |
00151429.000 |09:50:13.733 |SdlSig |SsRegisterManagerReq |wait |Cc(1,100,213,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.86^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] Type=0 UpdateSsType=F Key=0 Manager=(1,225,8) Name=CCDRequestingService
00151430.000 |09:50:13.733 |SdlSig |SsRegisterManagerRes |registeringManager |CCDRequestingService(1,100,225,8) |Cc(1,100,213,1) |1,100,37,1.86^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] Type=16777262 Key=0 NodeId=1
00151431.000 |09:50:13.733 |SdlSig-Q |SNFNotifyReq |wait |CCDRequestingService(1,100,225,8) |CCDServiceManager(1,100,228,1) |1,100,37,1.86^*^* |
00151431.001 |09:50:13.733 |AppInfo |CCDRequestingService::wait_SNFNotifyReq, subId = 1|0|18, safins=0, trunkFound=1
00151432.000 |09:50:13.734 |SdlSig |SAFRegisterReq |wait |SAFClientControl(1,100,79,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.86^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,225,8), networkClientHandle= 0
00151432.001 |09:50:13.734 |AppInfo |Begin SAFClientControl::wait_SAFRegisterReq()
00151432.002 |09:50:13.734 |AppInfo |End SAFClientControl::wait_SAFRegisterReq() - DUMPPPPP
ClientEntry
SafForwarderInfoCache
SAFConnectionInfoCacheDetails
SAFConnectionBlockDetails
subStatus ................ UnSubscribed
SAFConnectionInfoDetails
ipAddr.................... 10.1.5.100
port ..................... 5050
connStatus ............... SafEstablished
clientHandle ............. 0
sdlTCPPid ................ (0, 0, 0, 0)
safClientSettingPkid ..... 9e84e508-a07b-564e-3a6e-6ea058574c80
numConnetionReAttempts ... 1
timerReconnectInterval ... 20
numSafPacketsSent ........ 2
numOfRegisteredApps ...... 0
forwarderName ............ HQ-SAF-Forwarder
clientName ............... UCM/CM_HQPUB/NodeId=1/9.1.2.10000-28
clientLabel .............. CUCM-HQ
keepAliveInterval ........ 0
isKATimerRunning ........ 0
designation .............. SafNoForwarderDesignation
isRegistered ............. 1
enableTcpKeepalive ....... 1
safNotificationsWindowSize 7
Security Profile Details
password............... **************
userName .............. ciscociscocisco
authenticationMode .... 2
mServiceAttributes
ServiceIdentity .
serviceId.................. 0
subServiceId .............. 0
instanceId[0] ............. 0
instanceId[1] ............. 0
instanceId[2] ............. 0
instanceId[3] ............. 0
mApplClientHandle ....... QZCKEJVMRXQFNTCPQKWV
mCurrentState ........... App_AwaitRegister
mReceivingProcess ....... (1, 100, 225, 8)
mSubInstructions ........ SafSubscribeToNone
00151433.000 |09:50:13.734 |SdlSig |SAFRegisterRes |registeringSAFClient |CCDRequestingService(1,100,225,8) |SAFClientControl(1,100,79,1) |1,100,37,1.86^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(0,0,0), networkClientHandle= 0, clientHandle= QZCKEJVMRXQFNTCPQKWV,
00151425.004 |09:50:13.793 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151434.000 |09:50:13.793 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,7) |1,100,37,1.86^*^* |[T:N-H:0,N:2,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 1 replyId= 0 destination= pkid= 002ed778-7d38-41c0-8b7e-52d8e0aa46b9
00151434.001 |09:50:13.793 |AppInfo | updateRTMTReportingDynamic
00151434.002 |09:50:13.794 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151434.003 |09:50:13.794 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=1 WHERE pkid='002ed778-7d38-41c0-8b7e-52d8e0aa46b9']
00151434.004 |09:50:13.809 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151435.000 |09:50:13.809 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.86^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 2 replyId= 0 destination= pkid= 825e63e0-c061-4ec8-aa84-1a919f220d37
00151435.001 |09:50:13.809 |AppInfo | updateRTMTReportingDynamic
00151435.002 |09:50:13.809 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151435.003 |09:50:13.809 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=2 WHERE pkid='825e63e0-c061-4ec8-aa84-1a919f220d37']
00151435.004 |09:50:13.817 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151436.000 |09:50:13.817 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.86^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 2 replyId= 0 destination= pkid= 002ed778-7d38-41c0-8b7e-52d8e0aa46b9
00151436.001 |09:50:13.817 |AppInfo | updateRTMTReportingDynamic
00151436.002 |09:50:13.817 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151436.003 |09:50:13.817 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=2 WHERE pkid='002ed778-7d38-41c0-8b7e-52d8e0aa46b9']
00151436.004 |09:50:13.841 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151437.000 |09:50:13.931 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.87^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151437.001 |09:50:13.931 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151437.002 |09:50:13.931 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151437.003 |09:50:13.931 |AppInfo |SAFClientControl::processRTMTReportingTable
00151438.000 |09:50:13.931 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.88^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151438.001 |09:50:13.931 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151438.002 |09:50:13.931 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151438.003 |09:50:13.931 |AppInfo |SAFClientControl::processRTMTReportingTable
00151439.000 |09:50:13.938 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.89^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0]
00151439.001 |09:50:13.938 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151439.002 |09:50:13.938 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151439.003 |09:50:13.938 |AppInfo |SAFClientControl::processRTMTReportingTable
00151439.004 |09:50:13.938 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151439.005 |09:50:13.938 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151439.006 |09:50:13.938 |AppInfo |SAFClientControl::processRTMTReportingTable
00151440.000 |09:50:13.938 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.90^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151441.000 |09:50:14.545 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151442.000 |09:50:14.702 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151442.001 |09:50:14.702 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151443.000 |09:50:14.702 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151444.000 |09:50:15.554 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151445.000 |09:50:15.554 |SdlStat |Period: 6s #Lines: 175 #Bytes: 34295 Total Number of Buffers: 10000 Free LWM: 9960 Free LWM(total): 9509
00151446.000 |09:50:15.722 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151447.000 |09:50:16.562 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151448.000 |09:50:16.713 |SdlSig |CdrFileTimer |wait |EnvProcessCdr(1,100,239,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151448.001 |09:50:16.713 |AppInfo |EnvProcessCdr::wait_CdrFileTimer
00151448.002 |09:50:16.713 |AppInfo |EnvProcessCdr::closeCdrFile
00151448.003 |09:50:16.713 |AppInfo |EnvProcessCdr::closeCmrFile
00151449.000 |09:50:17.573 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151450.000 |09:50:17.921 |SdlSig |PublishRetryAfterTimer |restart0 |PublishEPA(1,100,106,2) |SdlTimerService(1,100,3,1) |1,100,12,3339.1^*^* |*TraceFlagOverrode
00151450.001 |09:50:17.921 |AppInfo |sendPublishOut starts.
00151451.000 |09:50:17.921 |SdlSig |SIPPublishReq |wait |SIPHandler(1,100,72,1) |PublishEPA(1,100,106,2) |1,100,12,3339.1^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4130 event=presence duration=3600 sip_if_match= callingNumber=hqph3 calledNumber=hqph3 TransType=1 --TransSecurity=0 PeerAddr = 10.1.5.5:5060 addrList: |ipAddrType=0 (0)10.1.5.5:5060|
00151451.001 |09:50:17.922 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4130/ccsip_publish_request: Got DeviceName By SipAddr, Device is CIMP-TR
00151451.002 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xf2fbee8) with key=[4125] to table
00151451.003 |09:50:17.922 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4130/ccsip_platform_init_scb: scb mgmt added 0xf2fbee8 (context_id=4125)
00151451.004 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0xf2fbee8/ccsip_new_scb: Created new scb: 0xf2fbee8 with id: 4130
00151451.005 |09:50:17.922 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=5060, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0
00151451.006 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 207 (UNDEFINED)
00151451.007 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_PUBLISH] received in State [SUBSCRIBE_STATE_IDLE]
00151451.008 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_tcb: Created TCB: 0xf2f7968
00151451.009 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/act_idle_publish_event: TCB 0xf2f7968's parent is 0xf2fbee8
00151451.010 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateTCBFromTargetInfo: Dialog Transaction Address 10.1.5.5,Port 5060, Port Present TRUE, Transpo
00151451.011 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/sipSPICreateSubscribeKey: Generated Subscribe Key : 1b1da000-33413aa9-e16-205010a@10.1.5.244806114
00151451.012 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0xf2fbee8/sipSPIPutSCBInClientTable: Inserting SCB with key 1b1da000-33413aa9-e16-205010a@10.1.5.244806114 into ClientTable
00151451.013 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: TCB 0xf2f7968's method is 113
00151451.014 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBClientMarker: Client TCB's Marker Updated to: z9hG4bKe15630c356e
00151451.015 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_edb: Created new edb(0xf300418)
00151451.016 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateEDBEvent: EDB updated with Event Name: presence Event ID:
00151451.017 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0xf2fbee8/sipSPICreateNewEDB: Q'd EDB(0xf300418) on SCB(0xf2fbee8)
00151451.018 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/sipSPIQueTcb: Q'd TCB(0xf2f7968) on EDB(0xf300418)
00151451.019 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0xf2fbee8/sipSPITCBSendPackage: Sending Package on tcb: 0xf2f7968 and parent: 0xf2fbee8
00151451.020 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0xf2fbee8/sipSPITransportSendMessage: msg=0xb0e87550, addr=10.1.5.5, port=5060, sentBy_port=0, is_req=1, transpo
00151451.021 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostRequestConnection: Posting TCP conn create request for addr=10.1.5.5, port=5060, conte
00151451.022 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer: Wait timer set for connection=0xe0808f8,addr=10.1.5.5, port=5060
00151451.023 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer:
Wait Conn Timer started for 5000 msec
00151451.024 |09:50:17.922 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151451.025 |09:50:17.922 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151451.026 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipCreateConnInstance: Created new initiated conn=0xe0808f8, connid=-1, addr=10.1.5.5, port=5060, transport=
00151451.027 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: Registering gcb=0xf2fbee8 with connection=0xe0808f8
00151451.028 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0xf2fbee8/sipSPITransportSendMessage: Deferred sending msg=0xb0e87550
00151452.000 |09:50:17.922 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3339.1^*^* |*TraceFlagOverrode
00151451.029 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_DIALOG_PENDING
00151452.001 |09:50:17.922 |AppInfo |SIPTcp - SIPSPI_EV_CREATE_CONNECTION: Addr=10.1.5.5, Port=5060, globalTCPInst=0xe07fb88
00151453.000 |09:50:17.922 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3339.1^*^* |*TraceFlagOverrode
00151453.001 |09:50:17.922 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 58 (SIPSPI_EV_CONNECTION_CREATED)
00151454.000 |09:50:17.922 |SdlSig |SdlConnectReq |started |SdlTCPService(1,100,10,1) |SIPTcp(1,100,64,1) |1,100,12,3339.1^*^* |*TraceFlagOverrode
00151454.001 |09:50:17.922 |Created | | |SdlTCPConnector(1,100,12,3341) |SdlTCPService(1,100,10,1) | |NumOfCurrentInstances: 1
00151453.002 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnCreated: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, connid=3328, transport=TCP
00151453.003 |09:50:17.922 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceHandleConnectionCreated: Moving connection=0xe0808f8, connid=3328state to pending
00151455.000 |09:50:18.586 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151456.000 |09:50:19.590 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151457.000 |09:50:20.310 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.91^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151458.000 |09:50:20.310 |SdlSig |RequestingServiceDBChangeNotification |wait |CCDServiceManager(1,100,228,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.91^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151458.001 |09:50:20.310 |AppInfo |CCDServiceManager::wait_RequestingServiceDBChangeNotification, action = 4
00151458.002 |09:50:20.310 |AppInfo |CCDServiceManager::processCcdRequestingServiceProfileUpdate - update
00151458.003 |09:50:20.310 |AppInfo |CCDServiceManager::subscribeRegistration()- key=eed50027-e419-56e0-6260-3d92188c4262.
00151459.000 |09:50:20.310 |SdlSig |RequestingServiceDBChangeNotification |wait |CCDRequestingService(1,100,225,8) |CCDServiceManager(1,100,228,1) |1,100,37,1.91^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151459.001 |09:50:20.310 |AppInfo |CCDRequestingService::wait_RequestingServiceDBChangeNotification, action = 4
00151459.002 |09:50:20.310 |AppInfo |CCDRequestingService::ccdRequestingServiceProfileUpdate() - CCD Service is reset!!!
00151459.003 |09:50:20.310 |Stopping | | |CCDRequestingService(1,100,225,8) |CCDRequestingService(1,100,225,8) | |NumOfCurrentInstances: 1
00151460.000 |09:50:20.310 |SdlSig |DaUnregisterDn |wait |Da(1,100,204,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.91^*^* |[R:N-H:0,N:4,L:0,V:0,Z:0,D:0] CCDRequestingService(1,225,8) SsType=16777262 Partition=8499f27c-a6ff-829c-2ffb-9ad9e9ce6c05 Number= DialPlan= StatIndex=0
00151461.000 |09:50:20.310 |SdlSig |SsUnregisterManagerReq |wait |Cc(1,100,213,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.91^*^* |[R:N-H:0,N:3,L:0,V:0,Z:0,D:0] Type=16777262 Key=0 NodeId=1 UnregisterDaAct=1
00151462.000 |09:50:20.310 |SdlSig |SNFUnSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.91^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|18, bulkUnSubscribeFlag = ,F subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151462.001 |09:50:20.310 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribe - subId = 1|0|18, subscriberPid=(1,228,1), bulkUnSub=0
00151462.002 |09:50:20.310 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList - InSubId = 1|0|18, ougoing sub id=1|0|11, type = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151462.003 |09:50:20.310 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList for destinationPid =(1,74,6)
00151462.004 |09:50:20.310 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribeForSubId - done for subId = 1|0|18
00151463.000 |09:50:20.310 |SdlSig |SAFUnregisterReq |wait |SAFClientControl(1,100,79,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.91^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,225,8), networkClientHandle= 0, clientHandle= QZCKEJVMRXQFNTCPQKWV,
00151463.001 |09:50:20.310 |AppInfo |Begin SAFClientControl::wait_SAFUnregisterReq()
00151463.002 |09:50:20.310 |AppInfo |SAFClientControl - updatePerfmonCounts unregistered = 1, registered = 0 clients = 1
00151463.003 |09:50:20.310 |AppInfo |SAFClientControl::wait_SAFUnregisterReq() App not registered - removing
00151464.000 |09:50:20.310 |SdlSig |SNFSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.91^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|19, SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =
00151464.001 |09:50:20.310 |AppInfo |SMDMSharedData::findSubscriptionIdExistsInSubInfo - subId=1|0|19 not found
00151464.002 |09:50:20.310 |AppInfo |SMDMSharedData::insertSubscriptionInfo - subId=1|0|19 inserted in mSubscriptionInfo hash map, bulkUnSub = 0 for this subscription, Subscription Type= REGISTRATION
00151464.003 |09:50:20.310 |AppInfo |SMDMSharedData::updateDestinationCepnInSubInfo - subId=1|0|19, destinationCepn=eed50027-e419-56e0-6260-3d92188c4262
00151464.004 |09:50:20.310 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - for subId = 1|0|19, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262
00151464.005 |09:50:20.310 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151464.006 |09:50:20.310 |AppInfo |SMDMSharedData::updateDestinationPidInSubMsg - subId=1|0|19, destinationPid=(1,74,6)
00151464.007 |09:50:20.310 |AppInfo |SMDMSharedData::updateAuthStateInSubInfo - subId=1|0|19, authorized=1
00151464.008 |09:50:20.310 |AppInfo |SMDMSharedData::insertPendingSubscription - Cepn=eed50027-e419-56e0-6260-3d92188c4262, SubscriptionId=1|0|19 inserted
00151464.009 |09:50:20.310 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo -Sub Info for subid=1|0|19 of Subscription Type= REGISTRATION
00151464.010 |09:50:20.310 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo - subId=1|0|19, NextHopPid=(1,74,6)
00151464.011 |09:50:20.310 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - calling notifyRegisterAndForwardSubscription for subId = 1|0|19, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262, nextHopPid = (1,74,6)
00151464.012 |09:50:20.310 |AppInfo |SMDMSharedData::notifyRegisterAndForwardSubscription - subId = 1|0|19, subMsg=SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (1,74,6), destinationCepn = eed50027-e419-56e0-6260-3d92188c4262, pretransformTagsList = , pretransformPositionalMatchList = , NextHopPid=(1,74,6)
00151464.013 |09:50:20.310 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151464.014 |09:50:20.310 |AppInfo |SMDMSharedData::sendRegistrationStatus - subId = 1|0|19, outgoing sub id = None, subscribeeCepn =eed50027-e419-56e0-6260-3d92188c4262 subscribeePid=(1,74,6) to subscriberPid=(1,228,1)
00151464.015 |09:50:20.310 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify subId not found adding - subid=1|0|19
00151464.016 |09:50:20.310 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify - found state entry for outSubId = 1|0|11, inSubId = 1|0|19, subscribee pid=(1,74,6), subType = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151465.000 |09:50:20.310 |SdlSig |SNFNotifyReq |wait |CCDServiceManager(1,100,228,1) |SubscriptionManager(1,100,55,1) |1,100,37,1.91^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|19, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151465.001 |09:50:20.310 |AppInfo |CCDServiceManager::wait_SNFNotifyReq, childPkid=287aa875-3a2e-372b-adc5-97433b2bbc96, trunkCepn = eed50027-e419-56e0-6260-3d92188c4262
00151465.002 |09:50:20.310 |Created | | |CCDRequestingService(1,100,225,9) |CCDServiceManager(1,100,228,1) | |NumOfCurrentInstances: 1
00151466.000 |09:50:20.310 |SdlSig |SNFNotifyReq |registeringManager |CCDRequestingService(1,100,225,9) |CCDServiceManager(1,100,228,1) |1,100,37,1.91^*^* |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|19, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151467.000 |09:50:20.310 |SdlSig-S |SNFNotifyReq |registeringManager |CCDRequestingService(1,100,225,9) |CCDServiceManager(1,100,228,1) |1,100,37,1.91^*^* |
00151468.000 |09:50:20.310 |SdlSig |SsRegisterManagerReq |wait |Cc(1,100,213,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.91^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] Type=0 UpdateSsType=F Key=0 Manager=(1,225,9) Name=CCDRequestingService
00151469.000 |09:50:20.310 |SdlSig |SsRegisterManagerRes |registeringManager |CCDRequestingService(1,100,225,9) |Cc(1,100,213,1) |1,100,37,1.91^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] Type=16777263 Key=0 NodeId=1
00151470.000 |09:50:20.310 |SdlSig-Q |SNFNotifyReq |wait |CCDRequestingService(1,100,225,9) |CCDServiceManager(1,100,228,1) |1,100,37,1.91^*^* |
00151470.001 |09:50:20.310 |AppInfo |CCDRequestingService::wait_SNFNotifyReq, subId = 1|0|19, safins=0, trunkFound=1
00151471.000 |09:50:20.310 |SdlSig |SAFRegisterReq |wait |SAFClientControl(1,100,79,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.91^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,225,9), networkClientHandle= 0
00151471.001 |09:50:20.310 |AppInfo |Begin SAFClientControl::wait_SAFRegisterReq()
00151471.002 |09:50:20.310 |AppInfo |End SAFClientControl::wait_SAFRegisterReq() - DUMPPPPP
ClientEntry
SafForwarderInfoCache
SAFConnectionInfoCacheDetails
SAFConnectionBlockDetails
subStatus ................ UnSubscribed
SAFConnectionInfoDetails
ipAddr.................... 10.1.5.100
port ..................... 5050
connStatus ............... SafEstablished
clientHandle ............. 0
sdlTCPPid ................ (0, 0, 0, 0)
safClientSettingPkid ..... 9e84e508-a07b-564e-3a6e-6ea058574c80
numConnetionReAttempts ... 1
timerReconnectInterval ... 20
numSafPacketsSent ........ 2
numOfRegisteredApps ...... 0
forwarderName ............ HQ-SAF-Forwarder
clientName ............... UCM/CM_HQPUB/NodeId=1/9.1.2.10000-28
clientLabel .............. CUCM-HQ
keepAliveInterval ........ 0
isKATimerRunning ........ 0
designation .............. SafNoForwarderDesignation
isRegistered ............. 1
enableTcpKeepalive ....... 1
safNotificationsWindowSize 7
Security Profile Details
password............... **************
userName .............. ciscociscocisco
authenticationMode .... 2
mServiceAttributes
ServiceIdentity .
serviceId.................. 0
subServiceId .............. 0
instanceId[0] ............. 0
instanceId[1] ............. 0
instanceId[2] ............. 0
instanceId[3] ............. 0
mApplClientHandle ....... SWKVMLTUPJZTEXFJUVFQ
mCurrentState ........... App_AwaitRegister
mReceivingProcess ....... (1, 100, 225, 9)
mSubInstructions ........ SafSubscribeToNone
00151472.000 |09:50:20.310 |SdlSig |SAFRegisterRes |registeringSAFClient |CCDRequestingService(1,100,225,9) |SAFClientControl(1,100,79,1) |1,100,37,1.91^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(0,0,0), networkClientHandle= 0, clientHandle= SWKVMLTUPJZTEXFJUVFQ,
00151473.000 |09:50:20.311 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.91^*^* |[T:N-H:0,N:3,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 1 replyId= 0 destination= pkid= 825e63e0-c061-4ec8-aa84-1a919f220d37
00151473.001 |09:50:20.311 |AppInfo | updateRTMTReportingDynamic
00151473.002 |09:50:20.311 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151473.003 |09:50:20.311 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=1 WHERE pkid='825e63e0-c061-4ec8-aa84-1a919f220d37']
00151473.004 |09:50:20.316 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151474.000 |09:50:20.316 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,8) |1,100,37,1.91^*^* |[T:N-H:0,N:2,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 1 replyId= 0 destination= pkid= 002ed778-7d38-41c0-8b7e-52d8e0aa46b9
00151474.001 |09:50:20.316 |AppInfo | updateRTMTReportingDynamic
00151474.002 |09:50:20.316 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151474.003 |09:50:20.316 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=1 WHERE pkid='002ed778-7d38-41c0-8b7e-52d8e0aa46b9']
00151475.000 |09:50:20.319 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.92^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151475.001 |09:50:20.319 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151475.002 |09:50:20.319 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151475.003 |09:50:20.319 |AppInfo |SAFClientControl::processRTMTReportingTable
00151474.004 |09:50:20.332 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151476.000 |09:50:20.332 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.91^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 2 replyId= 0 destination= pkid= 825e63e0-c061-4ec8-aa84-1a919f220d37
00151476.001 |09:50:20.332 |AppInfo | updateRTMTReportingDynamic
00151476.002 |09:50:20.332 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151476.003 |09:50:20.332 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=2 WHERE pkid='825e63e0-c061-4ec8-aa84-1a919f220d37']
00151477.000 |09:50:20.343 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.93^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151477.001 |09:50:20.343 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151477.002 |09:50:20.343 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151477.003 |09:50:20.343 |AppInfo |SAFClientControl::processRTMTReportingTable
00151478.000 |09:50:20.346 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.94^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151478.001 |09:50:20.346 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151478.002 |09:50:20.346 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151478.003 |09:50:20.346 |AppInfo |SAFClientControl::processRTMTReportingTable
00151476.004 |09:50:20.347 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151479.000 |09:50:20.350 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.91^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 2 replyId= 0 destination= pkid= 002ed778-7d38-41c0-8b7e-52d8e0aa46b9
00151479.001 |09:50:20.350 |AppInfo | updateRTMTReportingDynamic
00151479.002 |09:50:20.350 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151479.003 |09:50:20.350 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=2 WHERE pkid='002ed778-7d38-41c0-8b7e-52d8e0aa46b9']
00151479.004 |09:50:20.353 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151480.000 |09:50:20.358 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.95^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151480.001 |09:50:20.358 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151480.002 |09:50:20.358 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151480.003 |09:50:20.358 |AppInfo |SAFClientControl::processRTMTReportingTable
00151481.000 |09:50:20.617 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151482.000 |09:50:20.733 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151483.000 |09:50:20.925 |NetError | | |SdlTCPConnector(1,100,12,3341) | |1,100,12,3339.1^*^* |Cannot get remote address for connector socket.
00151484.000 |09:50:20.925 |NetError | | |SdlTCPConnector(1,100,12,3341) | |1,100,12,3339.1^*^* |SdlConnectorBase::connectionError
00151485.000 |09:50:20.926 |Stopping | | |SdlTCPConnector(1,100,12,3341) |SdlTCPConnector(1,100,12,3341) | |NumOfCurrentInstances: 1
00151486.000 |09:50:20.926 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151486.001 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 59 (SIPSPI_EV_CONNECTION_CREATE_FAILED)
00151486.002 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWConnectionFailed: context=0xe07fb88
00151486.003 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnFailed: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, transport=TCP
00151486.004 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0xe0808f8,addr=10.1.5.5, port=5060
00151486.005 |09:50:20.926 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151486.006 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0xf2fbee8/sipTransportPostInternalMsg: Posting Internal Msg type=1
00151486.007 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostCloseConnection: Posting TCP conn close for addr=10.1.5.5, port=5060, connid=3328
00151486.008 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0x0/sipDeleteConnInstance: Deleted conn=0xe0808f8, connid=3328, addr=10.1.5.5, port=5060, transport=TCP
00151487.000 |09:50:20.926 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151487.001 |09:50:20.926 |AppInfo |SIPTcp - Did not find table entry in Close Msg,Index = 3328
00151488.000 |09:50:20.926 |SdlSig |SIPConnControlInd |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3341.1^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3328
00151488.001 |09:50:20.926 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getRemoteAddrInfo: RemoteAddrInfo = 10.1.5.5:5060
00151489.000 |09:50:20.926 |SdlSig |SIPConnControlInd |restart0 |SIPD(1,100,74,1) |SIPHandler(1,100,72,1) |1,100,12,3341.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3328
00151489.001 |09:50:20.926 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Rcvd ConnControl with operation = 1, connIdx = 3328, X.509 Name =
00151489.002 |09:50:20.926 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Cannot find TLSConnTbl entry, connIdx = 3328, X.509 Name =
00151490.000 |09:50:20.926 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151490.001 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 63 (SIPSPI_EV_INTERNAL_MSG)
00151490.002 |09:50:20.926 |AppInfo |//SIP/Stack/Error/0xf2fbee8/sipTransportPostSendFailure: Posting send failure msg with tcb:0xf2f7968 reason=4
00151491.000 |09:50:20.926 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151491.001 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 54 (SIPSPI_EV_SEND_FAILURE_MSG)
00151491.002 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0xf2fbee8/ccsip_spi_process_event: Send Error for event(0xf2a3498) on TCB(0xf2f7968)
00151491.003 |09:50:20.926 |AppInfo |//SIP/Stack/Error/0xf2fbee8/sipSPIHandleTCBSendError: Failed to send tcb=0xf2f7968
00151491.004 |09:50:20.926 |AppInfo |//SIP/Stack/Error/0x0/sipAppGetHeader: Corrupted/Freed Container=(nil)
00151491.005 |09:50:20.926 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/fillInSipPeerAddrByContact: Contact header missing in the message.
00151491.006 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 210 (UNDEFINED)
00151491.007 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_SUBSCRIPTION_TERMINATED] received in State [SUBSCRIBE_STATE_DIALOG_PENDING]
00151491.008 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0xf2fbee8/sipSPITerminateEvent: Freeing SCB(0xf2fbee8), 0 active events present
00151491.009 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerUnregisterCtxtInCon: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, unregistering context=
00151491.010 |09:50:20.926 |AppInfo |//SIP/Stack/Error/0x0/sipConnectionManagerUnregisterCtxtInCon: Connection not found for addr=10.1.5.5, port=5060
00151491.011 |09:50:20.926 |AppInfo |//SIP/Stack/Transport/0xf2fbee8/sipSPITransportContextCleanup: Could not purge context gcb=0xf2fbee8 from the connection; gcb m
00151491.012 |09:50:20.926 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4130/ccsip_platform_delete_scb: scb mgmt deleted 0xf2fbee8 (context_id=4125)
00151491.013 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0xf2fbee8/sipSPIFreeOneSCB: EnQing SCB(0xf2fbee8) for reuse
00151491.014 |09:50:20.926 |AppInfo |//SIP/Stack/Info/0xf2fbee8/sipSPIReportRequestSendStatus: ccsip_api_publish_result returned: SIP_SUCCESS
00151492.000 |09:50:20.926 |SdlSig |SIPPublishResult |waitForResponse |PublishEPA(1,100,106,2) |SIPHandler(1,100,72,1) |1,100,12,3341.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4130 respCode=4 event=presence
00151492.001 |09:50:20.926 |AppInfo |Received 408 response. localEpaCount=2, retryInterval=30.011724.
00151493.000 |09:50:21.626 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151494.000 |09:50:21.626 |SdlStat |Period: 6s #Lines: 177 #Bytes: 34167 Total Number of Buffers: 10000 Free LWM: 9950 Free LWM(total): 9509
00151495.000 |09:50:22.629 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151496.000 |09:50:23.756 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151497.000 |09:50:24.709 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151497.001 |09:50:24.709 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151498.000 |09:50:24.709 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151499.000 |09:50:24.769 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151500.000 |09:50:25.749 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151501.000 |09:50:25.793 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151502.000 |09:50:26.810 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151503.000 |09:50:27.818 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151504.000 |09:50:27.818 |SdlStat |Period: 6s #Lines: 10 #Bytes: 2664 Total Number of Buffers: 10000 Free LWM: 9997 Free LWM(total): 9509
00151505.000 |09:50:28.825 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151506.000 |09:50:29.837 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151507.000 |09:50:30.761 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151508.000 |09:50:30.845 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151509.000 |09:50:31.853 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151510.000 |09:50:32.865 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151511.000 |09:50:33.877 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151512.000 |09:50:33.877 |SdlStat |Period: 6s #Lines: 7 #Bytes: 1830 Total Number of Buffers: 10000 Free LWM: 9998 Free LWM(total): 9509
00151513.000 |09:50:34.725 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151513.001 |09:50:34.725 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151514.000 |09:50:34.725 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151515.000 |09:50:34.881 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151516.000 |09:50:35.786 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151517.000 |09:50:35.910 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151518.000 |09:50:36.914 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151519.000 |09:50:37.925 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151520.000 |09:50:38.942 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151521.000 |09:50:39.962 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151522.000 |09:50:39.962 |SdlStat |Period: 6s #Lines: 10 #Bytes: 2664 Total Number of Buffers: 10000 Free LWM: 9997 Free LWM(total): 9509
00151523.000 |09:50:40.806 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151524.000 |09:50:40.969 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151525.000 |09:50:41.977 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151526.000 |09:50:42.998 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151527.000 |09:50:43.530 |SdlSig |PublishRetryAfterTimer |restart0 |PublishEPA(1,100,106,5) |SdlTimerService(1,100,3,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151527.001 |09:50:43.530 |AppInfo |sendPublishOut starts.
00151528.000 |09:50:43.530 |SdlSig |SIPPublishReq |wait |SIPHandler(1,100,72,1) |PublishEPA(1,100,106,5) |1,100,12,3340.1^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4131 event=presence duration=3600 sip_if_match= callingNumber=hqph1 calledNumber=hqph1 TransType=1 --TransSecurity=0 PeerAddr = 10.1.5.5:5060 addrList: |ipAddrType=0 (0)10.1.5.5:5060|
00151528.001 |09:50:43.530 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4131/ccsip_publish_request: Got DeviceName By SipAddr, Device is CIMP-TR
00151528.002 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xf2fcb58) with key=[4126] to table
00151528.003 |09:50:43.530 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4131/ccsip_platform_init_scb: scb mgmt added 0xf2fcb58 (context_id=4126)
00151528.004 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0xf2fcb58/ccsip_new_scb: Created new scb: 0xf2fcb58 with id: 4131
00151528.005 |09:50:43.530 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=5060, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0
00151528.006 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 207 (UNDEFINED)
00151528.007 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_PUBLISH] received in State [SUBSCRIBE_STATE_IDLE]
00151528.008 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_tcb: Created TCB: 0xf2f7ad8
00151528.009 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/act_idle_publish_event: TCB 0xf2f7ad8's parent is 0xf2fcb58
00151528.010 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateTCBFromTargetInfo: Dialog Transaction Address 10.1.5.5,Port 5060, Port Present TRUE, Transpo
00151528.011 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/sipSPICreateSubscribeKey: Generated Subscribe Key : 2a9ce900-33413ac3-e17-205010a@10.1.5.2819246120
00151528.012 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0xf2fcb58/sipSPIPutSCBInClientTable: Inserting SCB with key 2a9ce900-33413ac3-e17-205010a@10.1.5.2819246120 into ClientTable
00151528.013 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: TCB 0xf2f7ad8's method is 113
00151528.014 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBClientMarker: Client TCB's Marker Updated to: z9hG4bKe164ac244d1
00151528.015 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_edb: Created new edb(0xf300578)
00151528.016 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateEDBEvent: EDB updated with Event Name: presence Event ID:
00151528.017 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0xf2fcb58/sipSPICreateNewEDB: Q'd EDB(0xf300578) on SCB(0xf2fcb58)
00151528.018 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/sipSPIQueTcb: Q'd TCB(0xf2f7ad8) on EDB(0xf300578)
00151528.019 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0xf2fcb58/sipSPITCBSendPackage: Sending Package on tcb: 0xf2f7ad8 and parent: 0xf2fcb58
00151528.020 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0xf2fcb58/sipSPITransportSendMessage: msg=0xb0ea25f0, addr=10.1.5.5, port=5060, sentBy_port=0, is_req=1, transpo
00151528.021 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostRequestConnection: Posting TCP conn create request for addr=10.1.5.5, port=5060, conte
00151528.022 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer: Wait timer set for connection=0xe080970,addr=10.1.5.5, port=5060
00151528.023 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer:
Wait Conn Timer started for 5000 msec
00151528.024 |09:50:43.530 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151528.025 |09:50:43.530 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151528.026 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipCreateConnInstance: Created new initiated conn=0xe080970, connid=-1, addr=10.1.5.5, port=5060, transport=
00151528.027 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: Registering gcb=0xf2fcb58 with connection=0xe080970
00151528.028 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0xf2fcb58/sipSPITransportSendMessage: Deferred sending msg=0xb0ea25f0
00151528.029 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_DIALOG_PENDING
00151529.000 |09:50:43.530 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151529.001 |09:50:43.530 |AppInfo |SIPTcp - SIPSPI_EV_CREATE_CONNECTION: Addr=10.1.5.5, Port=5060, globalTCPInst=0xe07fb88
00151530.000 |09:50:43.530 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151530.001 |09:50:43.530 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 58 (SIPSPI_EV_CONNECTION_CREATED)
00151530.002 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnCreated: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, connid=3329, transport=TCP
00151530.003 |09:50:43.530 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceHandleConnectionCreated: Moving connection=0xe080970, connid=3329state to pending
00151531.000 |09:50:43.530 |SdlSig |SdlConnectReq |started |SdlTCPService(1,100,10,1) |SIPTcp(1,100,64,1) |1,100,12,3340.1^*^* |*TraceFlagOverrode
00151531.001 |09:50:43.530 |Created | | |SdlTCPConnector(1,100,12,3342) |SdlTCPService(1,100,10,1) | |NumOfCurrentInstances: 1
00151532.000 |09:50:44.014 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151533.000 |09:50:44.558 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.96^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151534.000 |09:50:44.559 |SdlSig |RequestingServiceDBChangeNotification |wait |CCDServiceManager(1,100,228,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.96^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151534.001 |09:50:44.559 |AppInfo |CCDServiceManager::wait_RequestingServiceDBChangeNotification, action = 4
00151534.002 |09:50:44.559 |AppInfo |CCDServiceManager::processCcdRequestingServiceProfileUpdate - update
00151534.003 |09:50:44.559 |AppInfo |CCDServiceManager::subscribeRegistration()- key=eed50027-e419-56e0-6260-3d92188c4262.
00151535.000 |09:50:44.559 |SdlSig |RequestingServiceDBChangeNotification |wait |CCDRequestingService(1,100,225,9) |CCDServiceManager(1,100,228,1) |1,100,37,1.96^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151535.001 |09:50:44.559 |AppInfo |CCDRequestingService::wait_RequestingServiceDBChangeNotification, action = 4
00151535.002 |09:50:44.559 |AppInfo |CCDRequestingService::ccdRequestingServiceProfileUpdate() - CCD Service is reset!!!
00151535.003 |09:50:44.559 |Stopping | | |CCDRequestingService(1,100,225,9) |CCDRequestingService(1,100,225,9) | |NumOfCurrentInstances: 1
00151536.000 |09:50:44.559 |SdlSig |DaUnregisterDn |wait |Da(1,100,204,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.96^*^* |[R:N-H:0,N:4,L:0,V:0,Z:0,D:0] CCDRequestingService(1,225,9) SsType=16777263 Partition=8499f27c-a6ff-829c-2ffb-9ad9e9ce6c05 Number= DialPlan= StatIndex=0
00151537.000 |09:50:44.559 |SdlSig |SsUnregisterManagerReq |wait |Cc(1,100,213,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.96^*^* |[R:N-H:0,N:3,L:0,V:0,Z:0,D:0] Type=16777263 Key=0 NodeId=1 UnregisterDaAct=1
00151538.000 |09:50:44.559 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.96^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 1 replyId= 0 destination= pkid= 825e63e0-c061-4ec8-aa84-1a919f220d37
00151538.001 |09:50:44.559 |AppInfo | updateRTMTReportingDynamic
00151538.002 |09:50:44.559 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151538.003 |09:50:44.559 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=1 WHERE pkid='825e63e0-c061-4ec8-aa84-1a919f220d37']
00151539.000 |09:50:44.561 |SdlSig |SNFUnSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.96^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|19, bulkUnSubscribeFlag = ,F subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151539.001 |09:50:44.561 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribe - subId = 1|0|19, subscriberPid=(1,228,1), bulkUnSub=0
00151539.002 |09:50:44.561 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList - InSubId = 1|0|19, ougoing sub id=1|0|11, type = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151539.003 |09:50:44.561 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList for destinationPid =(1,74,6)
00151539.004 |09:50:44.561 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribeForSubId - done for subId = 1|0|19
00151540.000 |09:50:44.561 |SdlSig |SAFUnregisterReq |wait |SAFClientControl(1,100,79,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.96^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,225,9), networkClientHandle= 0, clientHandle= SWKVMLTUPJZTEXFJUVFQ,
00151540.001 |09:50:44.561 |AppInfo |Begin SAFClientControl::wait_SAFUnregisterReq()
00151540.002 |09:50:44.561 |AppInfo |SAFClientControl - updatePerfmonCounts unregistered = 1, registered = 0 clients = 1
00151540.003 |09:50:44.561 |AppInfo |SAFClientControl::wait_SAFUnregisterReq() App not registered - removing
00151541.000 |09:50:44.562 |SdlSig |SNFSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.96^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|20, SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =
00151541.001 |09:50:44.562 |AppInfo |SMDMSharedData::findSubscriptionIdExistsInSubInfo - subId=1|0|20 not found
00151541.002 |09:50:44.562 |AppInfo |SMDMSharedData::insertSubscriptionInfo - subId=1|0|20 inserted in mSubscriptionInfo hash map, bulkUnSub = 0 for this subscription, Subscription Type= REGISTRATION
00151541.003 |09:50:44.562 |AppInfo |SMDMSharedData::updateDestinationCepnInSubInfo - subId=1|0|20, destinationCepn=eed50027-e419-56e0-6260-3d92188c4262
00151541.004 |09:50:44.562 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - for subId = 1|0|20, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262
00151541.005 |09:50:44.562 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151541.006 |09:50:44.562 |AppInfo |SMDMSharedData::updateDestinationPidInSubMsg - subId=1|0|20, destinationPid=(1,74,6)
00151541.007 |09:50:44.562 |AppInfo |SMDMSharedData::updateAuthStateInSubInfo - subId=1|0|20, authorized=1
00151541.008 |09:50:44.562 |AppInfo |SMDMSharedData::insertPendingSubscription - Cepn=eed50027-e419-56e0-6260-3d92188c4262, SubscriptionId=1|0|20 inserted
00151541.009 |09:50:44.562 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo -Sub Info for subid=1|0|20 of Subscription Type= REGISTRATION
00151541.010 |09:50:44.562 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo - subId=1|0|20, NextHopPid=(1,74,6)
00151541.011 |09:50:44.562 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - calling notifyRegisterAndForwardSubscription for subId = 1|0|20, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262, nextHopPid = (1,74,6)
00151541.012 |09:50:44.562 |AppInfo |SMDMSharedData::notifyRegisterAndForwardSubscription - subId = 1|0|20, subMsg=SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (1,74,6), destinationCepn = eed50027-e419-56e0-6260-3d92188c4262, pretransformTagsList = , pretransformPositionalMatchList = , NextHopPid=(1,74,6)
00151541.013 |09:50:44.562 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151541.014 |09:50:44.562 |AppInfo |SMDMSharedData::sendRegistrationStatus - subId = 1|0|20, outgoing sub id = None, subscribeeCepn =eed50027-e419-56e0-6260-3d92188c4262 subscribeePid=(1,74,6) to subscriberPid=(1,228,1)
00151541.015 |09:50:44.562 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify subId not found adding - subid=1|0|20
00151541.016 |09:50:44.562 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify - found state entry for outSubId = 1|0|11, inSubId = 1|0|20, subscribee pid=(1,74,6), subType = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151542.000 |09:50:44.565 |SdlSig |SNFNotifyReq |wait |CCDServiceManager(1,100,228,1) |SubscriptionManager(1,100,55,1) |1,100,37,1.96^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|20, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151542.001 |09:50:44.565 |AppInfo |CCDServiceManager::wait_SNFNotifyReq, childPkid=287aa875-3a2e-372b-adc5-97433b2bbc96, trunkCepn = eed50027-e419-56e0-6260-3d92188c4262
00151542.002 |09:50:44.565 |Created | | |CCDRequestingService(1,100,225,10) |CCDServiceManager(1,100,228,1) | |NumOfCurrentInstances: 1
00151543.000 |09:50:44.565 |SdlSig |SNFNotifyReq |registeringManager |CCDRequestingService(1,100,225,10) |CCDServiceManager(1,100,228,1) |1,100,37,1.96^*^* |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|20, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151544.000 |09:50:44.565 |SdlSig-S |SNFNotifyReq |registeringManager |CCDRequestingService(1,100,225,10) |CCDServiceManager(1,100,228,1) |1,100,37,1.96^*^* |
00151545.000 |09:50:44.565 |SdlSig |SsRegisterManagerReq |wait |Cc(1,100,213,1) |CCDRequestingService(1,100,225,10) |1,100,37,1.96^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] Type=0 UpdateSsType=F Key=0 Manager=(1,225,10) Name=CCDRequestingService
00151546.000 |09:50:44.565 |SdlSig |SsRegisterManagerRes |registeringManager |CCDRequestingService(1,100,225,10) |Cc(1,100,213,1) |1,100,37,1.96^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] Type=16777264 Key=0 NodeId=1
00151547.000 |09:50:44.565 |SdlSig-Q |SNFNotifyReq |wait |CCDRequestingService(1,100,225,10) |CCDServiceManager(1,100,228,1) |1,100,37,1.96^*^* |
00151547.001 |09:50:44.565 |AppInfo |CCDRequestingService::wait_SNFNotifyReq, subId = 1|0|20, safins=0, trunkFound=1
00151548.000 |09:50:44.565 |SdlSig |SAFRegisterReq |wait |SAFClientControl(1,100,79,1) |CCDRequestingService(1,100,225,10) |1,100,37,1.96^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,225,10), networkClientHandle= 0
00151548.001 |09:50:44.565 |AppInfo |Begin SAFClientControl::wait_SAFRegisterReq()
00151548.002 |09:50:44.565 |AppInfo |End SAFClientControl::wait_SAFRegisterReq() - DUMPPPPP
ClientEntry
SafForwarderInfoCache
SAFConnectionInfoCacheDetails
SAFConnectionBlockDetails
subStatus ................ UnSubscribed
SAFConnectionInfoDetails
ipAddr.................... 10.1.5.100
port ..................... 5050
connStatus ............... SafEstablished
clientHandle ............. 0
sdlTCPPid ................ (0, 0, 0, 0)
safClientSettingPkid ..... 9e84e508-a07b-564e-3a6e-6ea058574c80
numConnetionReAttempts ... 1
timerReconnectInterval ... 20
numSafPacketsSent ........ 2
numOfRegisteredApps ...... 0
forwarderName ............ HQ-SAF-Forwarder
clientName ............... UCM/CM_HQPUB/NodeId=1/9.1.2.10000-28
clientLabel .............. CUCM-HQ
keepAliveInterval ........ 0
isKATimerRunning ........ 0
designation .............. SafNoForwarderDesignation
isRegistered ............. 1
enableTcpKeepalive ....... 1
safNotificationsWindowSize 7
Security Profile Details
password............... **************
userName .............. ciscociscocisco
authenticationMode .... 2
mServiceAttributes
ServiceIdentity .
serviceId.................. 0
subServiceId .............. 0
instanceId[0] ............. 0
instanceId[1] ............. 0
instanceId[2] ............. 0
instanceId[3] ............. 0
mApplClientHandle ....... GAIGOQWDBDCTKNPZZJKH
mCurrentState ........... App_AwaitRegister
mReceivingProcess ....... (1, 100, 225, 10)
mSubInstructions ........ SafSubscribeToNone
00151549.000 |09:50:44.565 |SdlSig |SAFRegisterRes |registeringSAFClient |CCDRequestingService(1,100,225,10) |SAFClientControl(1,100,79,1) |1,100,37,1.96^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(0,0,0), networkClientHandle= 0, clientHandle= GAIGOQWDBDCTKNPZZJKH,
00151538.004 |09:50:44.571 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151550.000 |09:50:44.571 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,9) |1,100,37,1.96^*^* |[T:N-H:0,N:2,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 1 replyId= 0 destination= pkid= 002ed778-7d38-41c0-8b7e-52d8e0aa46b9
00151550.001 |09:50:44.571 |AppInfo | updateRTMTReportingDynamic
00151550.002 |09:50:44.571 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151550.003 |09:50:44.571 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=1 WHERE pkid='002ed778-7d38-41c0-8b7e-52d8e0aa46b9']
00151550.004 |09:50:44.583 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151551.000 |09:50:44.583 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,10) |1,100,37,1.96^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 2 replyId= 0 destination= pkid= 825e63e0-c061-4ec8-aa84-1a919f220d37
00151551.001 |09:50:44.583 |AppInfo | updateRTMTReportingDynamic
00151551.002 |09:50:44.583 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151551.003 |09:50:44.583 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=2 WHERE pkid='825e63e0-c061-4ec8-aa84-1a919f220d37']
00151551.004 |09:50:44.603 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151552.000 |09:50:44.603 |SdlSig |DbUpdateRTMTReportReq |initialized |Db(1,100,202,1) |CCDRequestingService(1,100,225,10) |1,100,37,1.96^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] changeStatus= 1 status= 2 replyId= 0 destination= pkid= 002ed778-7d38-41c0-8b7e-52d8e0aa46b9
00151552.001 |09:50:44.603 |AppInfo | updateRTMTReportingDynamic
00151552.002 |09:50:44.603 |AppInfo |DB: CFastAccess(RTMTReportingDynamic)
00151552.003 |09:50:44.603 |AppInfo |DB: SQL[UPDATE rtmtreportingdynamic SET tktaskstatus=2 WHERE pkid='002ed778-7d38-41c0-8b7e-52d8e0aa46b9']
00151552.004 |09:50:44.612 |AppInfo |DB: ~CFastAccess(RTMTReportingDynamic)
00151553.000 |09:50:44.715 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.97^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0]
00151553.001 |09:50:44.715 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151553.002 |09:50:44.715 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151553.003 |09:50:44.715 |AppInfo |SAFClientControl::processRTMTReportingTable
00151553.004 |09:50:44.715 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151553.005 |09:50:44.715 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151553.006 |09:50:44.715 |AppInfo |SAFClientControl::processRTMTReportingTable
00151554.000 |09:50:44.715 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.98^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151555.000 |09:50:44.717 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.99^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151555.001 |09:50:44.717 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151555.002 |09:50:44.717 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151555.003 |09:50:44.717 |AppInfo |SAFClientControl::processRTMTReportingTable
00151556.000 |09:50:44.723 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.100^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151556.001 |09:50:44.723 |AppInfo |CCDRequestingService::processRTMTReportingTable
00151556.002 |09:50:44.723 |AppInfo |CCDRequestingService::processRTMTReportingTable(): status change, no need to react!
00151556.003 |09:50:44.723 |AppInfo |SAFClientControl::processRTMTReportingTable
00151557.000 |09:50:44.737 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151557.001 |09:50:44.737 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151558.000 |09:50:44.737 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:1,N:0,L:0,V:0,Z:0,D:0]
00151559.000 |09:50:45.029 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151560.000 |09:50:45.029 |SdlStat |Period: 6s #Lines: 135 #Bytes: 27065 Total Number of Buffers: 10000 Free LWM: 9948 Free LWM(total): 9509
00151561.000 |09:50:45.825 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151562.000 |09:50:46.045 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151563.000 |09:50:46.534 |NetError | | |SdlTCPConnector(1,100,12,3342) | |1,100,12,3340.1^*^* |Cannot get remote address for connector socket.
00151564.000 |09:50:46.534 |NetError | | |SdlTCPConnector(1,100,12,3342) | |1,100,12,3340.1^*^* |SdlConnectorBase::connectionError
00151565.000 |09:50:46.534 |Stopping | | |SdlTCPConnector(1,100,12,3342) |SdlTCPConnector(1,100,12,3342) | |NumOfCurrentInstances: 1
00151566.000 |09:50:46.534 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3342.1^*^* |*TraceFlagOverrode
00151566.001 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 59 (SIPSPI_EV_CONNECTION_CREATE_FAILED)
00151566.002 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWConnectionFailed: context=0xe07fb88
00151566.003 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnFailed: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, transport=TCP
00151566.004 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0xe080970,addr=10.1.5.5, port=5060
00151566.005 |09:50:46.534 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151566.006 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0xf2fcb58/sipTransportPostInternalMsg: Posting Internal Msg type=1
00151566.007 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostCloseConnection: Posting TCP conn close for addr=10.1.5.5, port=5060, connid=3329
00151566.008 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0x0/sipDeleteConnInstance: Deleted conn=0xe080970, connid=3329, addr=10.1.5.5, port=5060, transport=TCP
00151567.000 |09:50:46.534 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3342.1^*^* |*TraceFlagOverrode
00151567.001 |09:50:46.534 |AppInfo |SIPTcp - Did not find table entry in Close Msg,Index = 3329
00151568.000 |09:50:46.534 |SdlSig |SIPConnControlInd |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3342.1^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3329
00151568.001 |09:50:46.534 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getRemoteAddrInfo: RemoteAddrInfo = 10.1.5.5:5060
00151569.000 |09:50:46.534 |SdlSig |SIPConnControlInd |restart0 |SIPD(1,100,74,1) |SIPHandler(1,100,72,1) |1,100,12,3342.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3329
00151569.001 |09:50:46.534 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Rcvd ConnControl with operation = 1, connIdx = 3329, X.509 Name =
00151569.002 |09:50:46.534 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Cannot find TLSConnTbl entry, connIdx = 3329, X.509 Name =
00151570.000 |09:50:46.534 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3342.1^*^* |*TraceFlagOverrode
00151570.001 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 63 (SIPSPI_EV_INTERNAL_MSG)
00151570.002 |09:50:46.534 |AppInfo |//SIP/Stack/Error/0xf2fcb58/sipTransportPostSendFailure: Posting send failure msg with tcb:0xf2f7ad8 reason=4
00151571.000 |09:50:46.534 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3342.1^*^* |*TraceFlagOverrode
00151571.001 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 54 (SIPSPI_EV_SEND_FAILURE_MSG)
00151571.002 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0xf2fcb58/ccsip_spi_process_event: Send Error for event(0xf2a59c8) on TCB(0xf2f7ad8)
00151571.003 |09:50:46.534 |AppInfo |//SIP/Stack/Error/0xf2fcb58/sipSPIHandleTCBSendError: Failed to send tcb=0xf2f7ad8
00151571.004 |09:50:46.534 |AppInfo |//SIP/Stack/Error/0x0/sipAppGetHeader: Corrupted/Freed Container=(nil)
00151571.005 |09:50:46.534 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/fillInSipPeerAddrByContact: Contact header missing in the message.
00151571.006 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 210 (UNDEFINED)
00151571.007 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_SUBSCRIPTION_TERMINATED] received in State [SUBSCRIBE_STATE_DIALOG_PENDING]
00151571.008 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0xf2fcb58/sipSPITerminateEvent: Freeing SCB(0xf2fcb58), 0 active events present
00151571.009 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerUnregisterCtxtInCon: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, unregistering context=
00151571.010 |09:50:46.534 |AppInfo |//SIP/Stack/Error/0x0/sipConnectionManagerUnregisterCtxtInCon: Connection not found for addr=10.1.5.5, port=5060
00151571.011 |09:50:46.534 |AppInfo |//SIP/Stack/Transport/0xf2fcb58/sipSPITransportContextCleanup: Could not purge context gcb=0xf2fcb58 from the connection; gcb m
00151571.012 |09:50:46.534 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4131/ccsip_platform_delete_scb: scb mgmt deleted 0xf2fcb58 (context_id=4126)
00151571.013 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0xf2fcb58/sipSPIFreeOneSCB: EnQing SCB(0xf2fcb58) for reuse
00151571.014 |09:50:46.534 |AppInfo |//SIP/Stack/Info/0xf2fcb58/sipSPIReportRequestSendStatus: ccsip_api_publish_result returned: SIP_SUCCESS
00151572.000 |09:50:46.534 |SdlSig |SIPPublishResult |waitForResponse |PublishEPA(1,100,106,5) |SIPHandler(1,100,72,1) |1,100,12,3342.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4131 respCode=4 event=presence
00151572.001 |09:50:46.534 |AppInfo |Received 408 response. localEpaCount=2, retryInterval=30.307031.
00151573.000 |09:50:47.054 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151574.000 |09:50:48.070 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151575.000 |09:50:49.077 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151576.000 |09:50:50.093 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151577.000 |09:50:50.837 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151578.000 |09:50:50.945 |SdlSig |PublishRetryAfterTimer |restart0 |PublishEPA(1,100,106,2) |SdlTimerService(1,100,3,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151578.001 |09:50:50.945 |AppInfo |sendPublishOut starts.
00151579.000 |09:50:50.945 |SdlSig |SIPPublishReq |wait |SIPHandler(1,100,72,1) |PublishEPA(1,100,106,2) |1,100,12,3341.1^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4132 event=presence duration=3600 sip_if_match= callingNumber=hqph3 calledNumber=hqph3 TransType=1 --TransSecurity=0 PeerAddr = 10.1.5.5:5060 addrList: |ipAddrType=0 (0)10.1.5.5:5060|
00151579.001 |09:50:50.945 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4132/ccsip_publish_request: Got DeviceName By SipAddr, Device is CIMP-TR
00151579.002 |09:50:50.945 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xf2fd7c8) with key=[4127] to table
00151579.003 |09:50:50.945 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4132/ccsip_platform_init_scb: scb mgmt added 0xf2fd7c8 (context_id=4127)
00151579.004 |09:50:50.945 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/ccsip_new_scb: Created new scb: 0xf2fd7c8 with id: 4132
00151579.005 |09:50:50.946 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=5060, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0
00151579.006 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 207 (UNDEFINED)
00151579.007 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_PUBLISH] received in State [SUBSCRIBE_STATE_IDLE]
00151579.008 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_tcb: Created TCB: 0xf2f7c48
00151579.009 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/act_idle_publish_event: TCB 0xf2f7c48's parent is 0xf2fd7c8
00151579.010 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateTCBFromTargetInfo: Dialog Transaction Address 10.1.5.5,Port 5060, Port Present TRUE, Transpo
00151579.011 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/sipSPICreateSubscribeKey: Generated Subscribe Key : 2ec90680-33413aca-e18-205010a@10.1.5.22088293275
00151579.012 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/sipSPIPutSCBInClientTable: Inserting SCB with key 2ec90680-33413aca-e18-205010a@10.1.5.22088293275 into ClientTable
00151579.013 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: TCB 0xf2f7c48's method is 113
00151579.014 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBClientMarker: Client TCB's Marker Updated to: z9hG4bKe1749a02f3e
00151579.015 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_edb: Created new edb(0xf3006d8)
00151579.016 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateEDBEvent: EDB updated with Event Name: presence Event ID:
00151579.017 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/sipSPICreateNewEDB: Q'd EDB(0xf3006d8) on SCB(0xf2fd7c8)
00151579.018 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/sipSPIQueTcb: Q'd TCB(0xf2f7c48) on EDB(0xf3006d8)
00151579.019 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/sipSPITCBSendPackage: Sending Package on tcb: 0xf2f7c48 and parent: 0xf2fd7c8
00151579.020 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0xf2fd7c8/sipSPITransportSendMessage: msg=0xb0e87d30, addr=10.1.5.5, port=5060, sentBy_port=0, is_req=1, transpo
00151579.021 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostRequestConnection: Posting TCP conn create request for addr=10.1.5.5, port=5060, conte
00151579.022 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer: Wait timer set for connection=0xe0809e8,addr=10.1.5.5, port=5060
00151579.023 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportSetConnWaitTimer:
Wait Conn Timer started for 5000 msec
00151579.024 |09:50:50.946 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151579.025 |09:50:50.946 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151579.026 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipCreateConnInstance: Created new initiated conn=0xe0809e8, connid=-1, addr=10.1.5.5, port=5060, transport=
00151579.027 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: Registering gcb=0xf2fd7c8 with connection=0xe0809e8
00151579.028 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0xf2fd7c8/sipSPITransportSendMessage: Deferred sending msg=0xb0e87d30
00151579.029 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/act_idle_continue_publish_event: Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_DIALOG_PENDING
00151580.000 |09:50:50.946 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151580.001 |09:50:50.946 |AppInfo |SIPTcp - SIPSPI_EV_CREATE_CONNECTION: Addr=10.1.5.5, Port=5060, globalTCPInst=0xe07fb88
00151581.000 |09:50:50.946 |SdlSig |SdlConnectReq |started |SdlTCPService(1,100,10,1) |SIPTcp(1,100,64,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151581.001 |09:50:50.946 |Created | | |SdlTCPConnector(1,100,12,3343) |SdlTCPService(1,100,10,1) | |NumOfCurrentInstances: 1
00151582.000 |09:50:50.946 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3341.1^*^* |*TraceFlagOverrode
00151582.001 |09:50:50.946 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 58 (SIPSPI_EV_CONNECTION_CREATED)
00151582.002 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnCreated: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, connid=3330, transport=TCP
00151582.003 |09:50:50.946 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceHandleConnectionCreated: Moving connection=0xe0809e8, connid=3330state to pending
00151583.000 |09:50:51.118 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151584.000 |09:50:51.118 |SdlStat |Period: 6s #Lines: 87 #Bytes: 15659 Total Number of Buffers: 10000 Free LWM: 9960 Free LWM(total): 9509
00151585.000 |09:50:52.130 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151586.000 |09:50:53.139 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151587.000 |09:50:53.949 |NetError | | |SdlTCPConnector(1,100,12,3343) | |1,100,12,3341.1^*^* |Cannot get remote address for connector socket.
00151588.000 |09:50:53.949 |NetError | | |SdlTCPConnector(1,100,12,3343) | |1,100,12,3341.1^*^* |SdlConnectorBase::connectionError
00151589.000 |09:50:53.949 |Stopping | | |SdlTCPConnector(1,100,12,3343) |SdlTCPConnector(1,100,12,3343) | |NumOfCurrentInstances: 1
00151590.000 |09:50:53.949 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3343.1^*^* |*TraceFlagOverrode
00151590.001 |09:50:53.949 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 59 (SIPSPI_EV_CONNECTION_CREATE_FAILED)
00151590.002 |09:50:53.949 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWConnectionFailed: context=0xe07fb88
00151590.003 |09:50:53.949 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessConnFailed: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, transport=TCP
00151590.004 |09:50:53.949 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportStopConnWaitTimer: Wait timer stopped for connection=0xe0809e8,addr=10.1.5.5, port=5060
00151590.005 |09:50:53.949 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0
00151590.006 |09:50:53.949 |AppInfo |//SIP/Stack/Transport/0xf2fd7c8/sipTransportPostInternalMsg: Posting Internal Msg type=1
00151590.007 |09:50:53.949 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportPostCloseConnection: Posting TCP conn close for addr=10.1.5.5, port=5060, connid=3330
00151590.008 |09:50:53.949 |AppInfo |//SIP/Stack/Transport/0x0/sipDeleteConnInstance: Deleted conn=0xe0809e8, connid=3330, addr=10.1.5.5, port=5060, transport=TCP
00151591.000 |09:50:53.950 |SdlSig |SIPSPISignal |wait |SIPTcp(1,100,64,1) |SIPHandler(1,100,72,1) |1,100,12,3343.1^*^* |*TraceFlagOverrode
00151591.001 |09:50:53.950 |AppInfo |SIPTcp - Did not find table entry in Close Msg,Index = 3330
00151592.000 |09:50:53.950 |SdlSig |SIPConnControlInd |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,12,3343.1^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3330
00151592.001 |09:50:53.950 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/getRemoteAddrInfo: RemoteAddrInfo = 10.1.5.5:5060
00151593.000 |09:50:53.950 |SdlSig |SIPConnControlInd |restart0 |SIPD(1,100,74,1) |SIPHandler(1,100,72,1) |1,100,12,3343.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] controlOperation=1 IP=10.1.5.5 connIndex=3330
00151593.001 |09:50:53.950 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Rcvd ConnControl with operation = 1, connIdx = 3330, X.509 Name =
00151593.002 |09:50:53.950 |AppInfo |SIPD(1) - restart0_SIPConnControlInd: mTsp.DeviceName[CIMP-TR] - Cannot find TLSConnTbl entry, connIdx = 3330, X.509 Name =
00151594.000 |09:50:53.950 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3343.1^*^* |*TraceFlagOverrode
00151594.001 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 63 (SIPSPI_EV_INTERNAL_MSG)
00151594.002 |09:50:53.950 |AppInfo |//SIP/Stack/Error/0xf2fd7c8/sipTransportPostSendFailure: Posting send failure msg with tcb:0xf2f7c48 reason=4
00151595.000 |09:50:53.950 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,12,3343.1^*^* |*TraceFlagOverrode
00151595.001 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 54 (SIPSPI_EV_SEND_FAILURE_MSG)
00151595.002 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/ccsip_spi_process_event: Send Error for event(0xf2a73d0) on TCB(0xf2f7c48)
00151595.003 |09:50:53.950 |AppInfo |//SIP/Stack/Error/0xf2fd7c8/sipSPIHandleTCBSendError: Failed to send tcb=0xf2f7c48
00151595.004 |09:50:53.950 |AppInfo |//SIP/Stack/Error/0x0/sipAppGetHeader: Corrupted/Freed Container=(nil)
00151595.005 |09:50:53.950 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/fillInSipPeerAddrByContact: Contact header missing in the message.
00151595.006 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 210 (UNDEFINED)
00151595.007 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_SUBSCRIPTION_TERMINATED] received in State [SUBSCRIBE_STATE_DIALOG_PENDING]
00151595.008 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/sipSPITerminateEvent: Freeing SCB(0xf2fd7c8), 0 active events present
00151595.009 |09:50:53.950 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerUnregisterCtxtInCon: gConnTab=0xe07fb88, addr=10.1.5.5, port=5060, unregistering context=
00151595.010 |09:50:53.950 |AppInfo |//SIP/Stack/Error/0x0/sipConnectionManagerUnregisterCtxtInCon: Connection not found for addr=10.1.5.5, port=5060
00151595.011 |09:50:53.950 |AppInfo |//SIP/Stack/Transport/0xf2fd7c8/sipSPITransportContextCleanup: Could not purge context gcb=0xf2fd7c8 from the connection; gcb m
00151595.012 |09:50:53.950 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=4132/ccsip_platform_delete_scb: scb mgmt deleted 0xf2fd7c8 (context_id=4127)
00151595.013 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/sipSPIFreeOneSCB: EnQing SCB(0xf2fd7c8) for reuse
00151595.014 |09:50:53.950 |AppInfo |//SIP/Stack/Info/0xf2fd7c8/sipSPIReportRequestSendStatus: ccsip_api_publish_result returned: SIP_SUCCESS
00151596.000 |09:50:53.950 |SdlSig |SIPPublishResult |waitForResponse |PublishEPA(1,100,106,2) |SIPHandler(1,100,72,1) |1,100,12,3343.1^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=4132 respCode=4 event=presence
00151596.001 |09:50:53.950 |AppInfo |Received 408 response. localEpaCount=2, retryInterval=30.473584.
00151597.000 |09:50:54.145 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151598.000 |09:50:54.236 |SdlSig |SdlReadRsp |wait |SIPTcp(1,100,64,1) |SdlTCPConnection(1,100,13,10) |1,100,13,10.523^10.1.110.4^* |*TraceFlagOverrode
00151598.001 |09:50:54.236 |AppInfo |SIPTcp - SdlRead bufferLen=859
00151598.002 |09:50:54.236 |AppInfo |SIPTcp - SignalCounter = 521
00151599.000 |09:50:54.236 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,13,10.523^10.1.110.4^SEP8478ACE6A666 |*TraceFlagOverrode
00151599.001 |09:50:54.236 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 1 (SIPSPI_EV_NEW_MESSAGE)
00151599.002 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWNewConnMsg: context=(nil)
00151599.003 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessNewConnMsg: gConnTab=0xe07fb88, addr=10.1.110.4, port=53052, connid=3, transport=TCP
00151599.004 |09:50:54.236 |AppInfo |//SIP/Stack/Info/0x0/sipHolderGetConnInstance: Return existing connection for port 53052 connId 3
00151599.005 |09:50:54.236 |AppInfo |//SIP/Stack/Info/0x0/sipSPIFindRegisterCcb: *****CCB NOT found in UAS Request table. ccb=(nil)
00151599.006 |09:50:54.236 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xf2d2100) with key=[4128] to table
00151599.007 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.1.110.4,Port 53052, Transport 2, SentBy Port 53052
00151599.008 |09:50:54.236 |AppInfo |//SIP/Stack/States/0xf2d2100/sipSPIChangeState: 0xf2d2100 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
00151599.009 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.1.110.4,Port 53052, Transport 2, SentBy Port 5060
00151599.010 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.1.110.4,Port 53052, Transport 2, SentBy Port 53052
00151599.011 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipSPISendResponse: Sending INFO Response to the transport layer
00151599.012 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipSPITransportSendMessage: msg=0xb0ea2700, addr=10.1.110.4, port=53052, sentBy_port=53052, is_req=0, transpo
00151599.013 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: Registering gcb=0xf2d2100 with connection=0xe080538 context list
00151599.014 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipTransportLogicSendMsg: Connection obtained...sending msg=0xb0ea2700
00151599.015 |09:50:54.236 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipTransportPostSendMessage: Posting send for msg=0xb0ea2700, addr=10.1.110.4, port=53052, connId=3 for TC
00151599.016 |09:50:54.236 |AppInfo |//SIP/Stack/Info/0xf2d2100/sipSPIUaddCcbToTable: Added to table. ccb=0xf2d2100 key=8478ace6-a666001b-2ffcfaa8-57bdca72@10.1.110.420038478ace6a66627d361af9d38-56c6f13a
00151599.017 |09:50:54.236 |AppInfo |//SIP/SIPHandler/ccbId=4133/scbId=0/findDevicePID: Routed to SIPStationInit
00151599.018 |09:50:54.236 |AppInfo |//SIP/Stack/Event/0xf2d2100/sact_idle_new_message_register:
ccsip_api_register_ind return value : SIP_SUCCESS
00151599.019 |09:50:54.236 |AppInfo |//SIP/Stack/States/0xf2d2100/sipSPIChangeState: 0xf2d2100 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE)
00151600.000 |09:50:54.236 |SdlSig |SIPRegisterInd |wait |SIPStationInit(1,100,65,1) |SIPHandler(1,100,72,1) |1,100,13,10.523^10.1.110.4^SEP8478ACE6A666 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 4133 --TransType=1 --TransSecurity=0 PeerAddr = 10.1.110.4:53052 MAC = DeviceName = X509SubjectName = SubjectAltname =
00151601.000 |09:50:54.236 |SdlSig |SIPRegisterInd |wait |SIPStationD(1,100,66,1) |SIPStationInit(1,100,65,1) |1,100,13,10.523^10.1.110.4^SEP8478ACE6A666 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 4133 --TransType=1 --TransSecurity=0 PeerAddr = 10.1.110.4:53052 MAC = 8478ACE6A666 DeviceName = SEP8478ACE6A666 X509SubjectName = SubjectAltname =
00151601.001 |09:50:54.236 |AppInfo |SIPStationD(1) - setUaTypeAndCepn: uaType is CISCO_ENHANCED_PHONE
00151601.002 |09:50:54.237 |AppInfo |SIPStationD(1) - parseSupportedHeader: X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=F X-cisco-graceful-reg=F X-cisco-srtp-fallback=T X-cisco-ix=F X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.0.1 X-cisco-sis=T sis-version=6.0.0 extended-refer=T norefersub=T join=T cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=3 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,xCiscoNumber=T,OrigCalled=T,iXChannel=F,BFCP=F,mobileConnectStatusRefresh=F,confDisplayInstanceSupport=F]MobilityExtension Feature Indication is empty
00151601.003 |09:50:54.237 |AppInfo |SIPStationD(1) - storeContactHeader: Request-URI is sip:4c5df33c-5bc9-43f4-ae4f-9f516a14f62d@10.1.110.4:53052;transport=tcp
00151601.004 |09:50:54.237 |AppInfo |setSIPAoR: aor = sip:2003@10.1.5.2
00151602.000 |09:50:54.237 |SdlSig |SIPRegisterResp |wait |SIPHandler(1,100,72,1) |SIPStationD(1,100,66,1) |1,100,13,10.523^10.1.110.4^SEP8478ACE6A666 |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 4133 --TransType=1 --TransSecurity=0 PeerAddr= 10.1.110.4:53052 respCode= 200 action= 2 device=
00151602.001 |09:50:54.237 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 (SIP_APPLICATION_MSG), for event 42 (SIPSPI_EV_REGISTER_RESP)
00151602.002 |09:50:54.237 |AppInfo |//SIP/Stack/Info/0xf2d2100/sipSPISendResponse: Associated container=0xb0e895d0 to REGISTER Response 200
00151602.003 |09:50:54.237 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipSPISendResponse: Sending INFO Response to the transport layer
00151602.004 |09:50:54.237 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipSPITransportSendMessage: msg=0xb0ea3988, addr=10.1.110.4, port=53052, sentBy_port=53052, is_req=0, transpo
00151602.005 |09:50:54.237 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: gcb=0xf2d2100 is already on connection=0xe080538 context_list
00151602.006 |09:50:54.237 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipTransportLogicSendMsg: Connection obtained...sending msg=0xb0ea3988
00151602.007 |09:50:54.237 |AppInfo |//SIP/Stack/Transport/0xf2d2100/sipTransportPostSendMessage: Posting send for msg=0xb0ea3988, addr=10.1.110.4, port=53052, connId=3 for TC
00151602.008 |09:50:54.237 |AppInfo |//SIP/Stack/Info/0xf2d2100/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be f
00151602.009 |09:50:54.237 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerUnregisterCtxtInCon: gConnTab=0xe07fb88, addr=10.1.110.4, port=53052, unregistering context=
00151602.010 |09:50:54.237 |AppInfo |//SIP/Stack/Info/0xf2d2100/sipSPIUfreeOneCCB: Freeing ccb f2d2100
00151602.011 |09:50:54.237 |AppInfo |//SIP/Stack/Transport/0x0/sipSPITransportSendMessage: gcb has cleanedup, Done with send operations for this context
00151603.000 |09:50:54.746 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151603.001 |09:50:54.746 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151604.000 |09:50:54.746 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151605.000 |09:50:55.165 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151606.000 |09:50:55.849 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151607.000 |09:50:56.185 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151608.000 |09:50:57.205 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151609.000 |09:50:57.205 |SdlStat |Period: 6s #Lines: 90 #Bytes: 17150 Total Number of Buffers: 10000 Free LWM: 9959 Free LWM(total): 9509
00151610.000 |09:50:58.229 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151611.000 |09:50:58.407 |SdlSig |CcmDbTableChangeNotify |wait |CcmDbChangeNotify(1,100,37,1) |CcmDbChangeNotify(1,100,37,1) |1,100,37,1.101^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151612.000 |09:50:58.407 |SdlSig |AdvertisingServiceDBChangeNotification |wait |CCDServiceManager(1,100,228,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.101^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151612.001 |09:50:58.407 |AppInfo |CCDServiceManager::processCCDAdvertisingServiceProfileUpdate()- update
00151612.002 |09:50:58.407 |AppInfo |CCDServiceManager::subscribeRegistration()- key=eed50027-e419-56e0-6260-3d92188c4262.
00151613.000 |09:50:58.407 |SdlSig |AdvertisingServiceDBChangeNotification |wait |CCDAdvertisingService(1,100,227,8) |CCDServiceManager(1,100,228,1) |1,100,37,1.101^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]
00151613.001 |09:50:58.408 |AppInfo |CCDAdvertisingService::ccdProfileUpdate() - CCD Advertising Service Service is reset!!!
00151613.002 |09:50:58.408 |AppInfo |CCDAdvertisingService::unpublishService(), trunk=2.5.1.10:5060, prot=11, version=0, published=0
00151613.003 |09:50:58.408 |AppInfo |CCDAdvertisingService::trunksAreAvailable() true!!!
00151613.004 |09:50:58.408 |Stopping | | |CCDAdvertisingService(1,100,227,8) |CCDAdvertisingService(1,100,227,8) | |NumOfCurrentInstances: 1
00151614.000 |09:50:58.409 |SdlSig |SAFUnregisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,8) |1,100,37,1.101^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,8), networkClientHandle= 0, clientHandle= JKXDIVSPGBQSPXAZPPZJ,
00151614.001 |09:50:58.409 |AppInfo |Begin SAFClientControl::wait_SAFUnregisterReq()
00151614.002 |09:50:58.409 |AppInfo |SAFClientControl - updatePerfmonCounts unregistered = 1, registered = 0 clients = 1
00151614.003 |09:50:58.409 |AppInfo |SAFClientControl::wait_SAFUnregisterReq() App not registered - removing
00151615.000 |09:50:58.410 |SdlSig |SNFUnSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.101^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|17, bulkUnSubscribeFlag = ,F subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151615.001 |09:50:58.410 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribe - subId = 1|0|17, subscriberPid=(1,228,1), bulkUnSub=0
00151615.002 |09:50:58.410 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList - InSubId = 1|0|17, ougoing sub id=1|0|11, type = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151615.003 |09:50:58.410 |AppInfo |SMDMSharedData::deleteSubIdFromSubscribeeStateNotifyList for destinationPid =(1,74,6)
00151615.004 |09:50:58.410 |AppInfo |SMDMSharedData::deleteSubscriptionAndUnSubscribeForSubId - done for subId = 1|0|17
00151616.000 |09:50:58.410 |SdlSig |SNFSubscribeInd |initialized |SubscriptionManager(1,100,55,1) |CCDServiceManager(1,100,228,1) |1,100,37,1.101^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|21, SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =
00151616.001 |09:50:58.410 |AppInfo |SMDMSharedData::findSubscriptionIdExistsInSubInfo - subId=1|0|21 not found
00151616.002 |09:50:58.410 |AppInfo |SMDMSharedData::insertSubscriptionInfo - subId=1|0|21 inserted in mSubscriptionInfo hash map, bulkUnSub = 0 for this subscription, Subscription Type= REGISTRATION
00151616.003 |09:50:58.410 |AppInfo |SMDMSharedData::updateDestinationCepnInSubInfo - subId=1|0|21, destinationCepn=eed50027-e419-56e0-6260-3d92188c4262
00151616.004 |09:50:58.410 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - for subId = 1|0|21, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262
00151616.005 |09:50:58.410 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151616.006 |09:50:58.410 |AppInfo |SMDMSharedData::updateDestinationPidInSubMsg - subId=1|0|21, destinationPid=(1,74,6)
00151616.007 |09:50:58.410 |AppInfo |SMDMSharedData::updateAuthStateInSubInfo - subId=1|0|21, authorized=1
00151616.008 |09:50:58.410 |AppInfo |SMDMSharedData::insertPendingSubscription - Cepn=eed50027-e419-56e0-6260-3d92188c4262, SubscriptionId=1|0|21 inserted
00151616.009 |09:50:58.410 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo -Sub Info for subid=1|0|21 of Subscription Type= REGISTRATION
00151616.010 |09:50:58.410 |AppInfo |SMDMSharedData::updateNextHopPidInSubInfo - subId=1|0|21, NextHopPid=(1,74,6)
00151616.011 |09:50:58.410 |AppInfo |SubscriptionManager::determineSubscribeePidAndForwardSub - calling notifyRegisterAndForwardSubscription for subId = 1|0|21, subscribeeCepn = eed50027-e419-56e0-6260-3d92188c4262, nextHopPid = (1,74,6)
00151616.012 |09:50:58.410 |AppInfo |SMDMSharedData::notifyRegisterAndForwardSubscription - subId = 1|0|21, subMsg=SNFSubscriptionMsg contents: SNFSubscriptionType = REGISTRATION , duration = -1, routeFlag = false, authFlag = false, forwardFlag = true, cacheStateFlag = true, bulkUnSubscribeFlag = false, subscriber = , subscribee = eed50027-e419-56e0-6260-3d92188c4262, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (1,74,6), destinationCepn = eed50027-e419-56e0-6260-3d92188c4262, pretransformTagsList = , pretransformPositionalMatchList = , NextHopPid=(1,74,6)
00151616.013 |09:50:58.410 |AppInfo |SMDMSharedData::findLocalDevice - Name=SAF-CCD-Trunk Key=eed50027-e419-56e0-6260-3d92188c4262 isActvie=1 Pid=(1,74,6) found
00151616.014 |09:50:58.410 |AppInfo |SMDMSharedData::sendRegistrationStatus - subId = 1|0|21, outgoing sub id = None, subscribeeCepn =eed50027-e419-56e0-6260-3d92188c4262 subscribeePid=(1,74,6) to subscriberPid=(1,228,1)
00151616.015 |09:50:58.410 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify subId not found adding - subid=1|0|21
00151616.016 |09:50:58.410 |AppInfo |SMDMSharedData::findSubscribeeStateAndNotify - found state entry for outSubId = 1|0|11, inSubId = 1|0|21, subscribee pid=(1,74,6), subType = REGISTRATION , subscribee = eed50027-e419-56e0-6260-3d92188c4262
00151617.000 |09:50:58.417 |SdlSig |SNFNotifyReq |wait |CCDServiceManager(1,100,228,1) |SubscriptionManager(1,100,55,1) |1,100,37,1.101^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|21, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151617.001 |09:50:58.417 |AppInfo |CCDServiceManager::wait_SNFNotifyReq, childPkid=69dd389c-f712-d54c-5b4f-c13eb3be26db, trunkCepn = eed50027-e419-56e0-6260-3d92188c4262
00151617.002 |09:50:58.417 |Created | | |CCDAdvertisingService(1,100,227,9) |CCDServiceManager(1,100,228,1) | |NumOfCurrentInstances: 1
00151618.000 |09:50:58.417 |SdlSig |SNFNotifyReq |wait |CCDAdvertisingService(1,100,227,9) |CCDServiceManager(1,100,228,1) |1,100,37,1.101^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|21, SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType = REGISTRATION , content = SNFLegacyContent with content data set to: registered, cepn = eed50027-e419-56e0-6260-3d92188c4262, mSubscribeePid = (1,74,6)
00151618.001 |09:50:58.417 |AppInfo |CCDAdvertisingService::updateCCDAdvertisingTrunkAfterReset()- SIP devRowSIP.pkid_m=eed50027-e419-56e0-6260-3d92188c4262.
00151618.002 |09:50:58.417 |AppInfo |CCDAdvertisingService::wait_SNFNotifyReq() reg SIP, remoteAddr = 2.5.1.10, 5060, subId = 1|0|21, safins=0
00151619.000 |09:50:58.417 |SdlSig |SAFRegisterReq |wait |SAFClientControl(1,100,79,1) |CCDAdvertisingService(1,100,227,9) |1,100,37,1.101^*^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(1,227,9), networkClientHandle= 0
00151619.001 |09:50:58.417 |AppInfo |Begin SAFClientControl::wait_SAFRegisterReq()
00151619.002 |09:50:58.417 |AppInfo |End SAFClientControl::wait_SAFRegisterReq() - DUMPPPPP
ClientEntry
SafForwarderInfoCache
SAFConnectionInfoCacheDetails
SAFConnectionBlockDetails
subStatus ................ UnSubscribed
SAFConnectionInfoDetails
ipAddr.................... 10.1.5.100
port ..................... 5050
connStatus ............... SafEstablished
clientHandle ............. 0
sdlTCPPid ................ (0, 0, 0, 0)
safClientSettingPkid ..... 9e84e508-a07b-564e-3a6e-6ea058574c80
numConnetionReAttempts ... 1
timerReconnectInterval ... 20
numSafPacketsSent ........ 2
numOfRegisteredApps ...... 0
forwarderName ............ HQ-SAF-Forwarder
clientName ............... UCM/CM_HQPUB/NodeId=1/9.1.2.10000-28
clientLabel .............. CUCM-HQ
keepAliveInterval ........ 0
isKATimerRunning ........ 0
designation .............. SafNoForwarderDesignation
isRegistered ............. 1
enableTcpKeepalive ....... 1
safNotificationsWindowSize 7
Security Profile Details
password............... **************
userName .............. ciscociscocisco
authenticationMode .... 2
mServiceAttributes
ServiceIdentity .
serviceId.................. 0
subServiceId .............. 0
instanceId[0] ............. 0
instanceId[1] ............. 0
instanceId[2] ............. 0
instanceId[3] ............. 0
mApplClientHandle ....... VJUJJFMDBFGOGAYKVYVM
mCurrentState ........... App_AwaitRegister
mReceivingProcess ....... (1, 100, 227, 9)
mSubInstructions ........ SafSubscribeToNone
00151620.000 |09:50:58.417 |SdlSig |SAFRegisterRes |registeringSAFClient |CCDAdvertisingService(1,100,227,9) |SAFClientControl(1,100,79,1) |1,100,37,1.101^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] responseCode=0serviceId=0, subServiceId=0, instanceId=0, 0, 0, 0, 0, , transportType=0index=0, flags=0, index=0, flags=0, serviceVersion=0, sendingCrp=(0,0,0), networkClientHandle= 0, clientHandle= VJUJJFMDBFGOGAYKVYVM,
00151620.001 |09:50:58.417 |AppInfo |CCDAdvertisingService::buildAdvertisedPatterns() est=655, max=2000.
00151620.002 |09:50:58.417 |AppInfo |CCDAdvertisingService::buildAndStorePatterns(), hostedDNList = 1, est len = 655, nextPatternInfoIndex = 1
00151620.003 |09:50:58.417 |AppInfo |CCDAdvertisingService::buildCCDHostedDnService() completed, len=655.
00151620.004 |09:50:58.417 |AppInfo |CCDAdvertisingService::encodeSafXmlOpaqueData() success, lene=668.
00151620.005 |09:50:58.417 |AppInfo |CCDAdvertisingService::buildServiceIdInstanceForTrunk() trunk=2.5.1.10:5060, rc=1!!, xml=
UCM9.1.2.10000-28StandAloneClusterCM_HQPUB
sip:eed50027-e419-56e0-6260-3d92188c4262@HQPUB
2XXX
00151620.006 |09:50:58.417 |AppInfo |CCDAdvertisingService::buildCcdAdvertisedService-advertised SIP, find SIP trunk=true
00151621.000 |09:50:59.245 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151622.000 |09:51:00.261 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151623.000 |09:51:00.857 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151624.000 |09:51:01.269 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151625.000 |09:51:02.281 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151626.000 |09:51:03.289 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151627.000 |09:51:03.289 |SdlStat |Period: 6s #Lines: 58 #Bytes: 14703 Total Number of Buffers: 10000 Free LWM: 9978 Free LWM(total): 9509
00151628.000 |09:51:04.297 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151629.000 |09:51:04.765 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151629.001 |09:51:04.765 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151630.000 |09:51:04.765 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151631.000 |09:51:05.305 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151632.000 |09:51:05.869 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151633.000 |09:51:06.326 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151634.000 |09:51:07.329 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151635.000 |09:51:08.337 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151636.000 |09:51:09.165 |SdlSig |B2BLinkQualityTimer |wait |ViPRManager(1,100,43,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151637.000 |09:51:09.165 |SdlStat |Period: 6s #Lines: 10 #Bytes: 2653 Total Number of Buffers: 10000 Free LWM: 9997 Free LWM(total): 9509
00151636.001 |09:51:09.165 |AppInfo |wait_B2BLinkQualityTimer - Started
00151636.002 |09:51:09.165 |AppInfo |wait_B2BLinkQualityTimer - B2BLinkQualityTimer set for 120 seconds
00151638.000 |09:51:09.357 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151639.000 |09:51:10.381 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151640.000 |09:51:10.877 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00151641.000 |09:51:11.389 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151642.000 |09:51:12.528 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151643.000 |09:51:13.549 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151644.000 |09:51:14.569 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00151645.000 |09:51:14.789 |SdlSig |SdlPollReqTimer |wait |SdlLinkControl(1,100,18,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |*TraceFlagOverrode
00151645.001 |09:51:14.789 |SdlError | | | | | |SdlLinkHandler::sendPollRequest - Did not received Poll Response. Going Down. NodeId: 1, AppId: 300, TCPAddr[:758331705]
00151646.000 |09:51:14.789 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]