cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
865
Views
5
Helpful
12
Replies

Not showing full dialed number after call establishment

Suresh Hudda
VIP Alumni
VIP Alumni

I’m facing an issue when user call from Alcatel phone to CUCM IP Phone via SME, then once call has connected then Alcatel user is not able to see entire number (Dialed number) on their phones, they only can see extension number.

Below is the call scenario..

Alcatel user dial #4 300 2000 from their Alcatel phone to reach Cisco IP Phone.

#4 is the access code to reach SME and it removed at Alcatel callmanager itself.

300 is the site code for Cisco CUCM which get removed at SME when sending call to CUCM and CUCM gets only 2000 and make phone ring.

Call Flow..

IP Phone registered on Alcatel -> Cisco SME -> IP Phone registered on CUCM

In above example Customer’s requirement is like they want to see 3002000 on Alcatel phone after connecting the call when they dial #43002000

Could you please let me know how can I achieve this.

12 Replies 12

Give us a debug isdn q931 output please for this call

Sent from Cisco Technical Support iPhone App

Best Regards

Hi Hermanus, we integrated the Alcatel with SME using SIP trunk. We are using SIP trunk between Alcatel-SME & SME-CUCM for calling purpose.

HI,

I found this it explains what is supported and what not.

https://www.cisco.com/en/US/docs/voice_ip_comm/cucme/pbx/interop/notes/560517nt.pdf

and here is a config guide also taking limitations into consideration:

http://www.cisco.com/en/US/solutions/collateral/ns340/ns414/ns728/ns832/1001103.pdf

Best Regards

Manish Prasad
Level 5
Level 5

Hi Suresh,

Now the question is who is cliping CLID , (CUCM or  Alcatel). I guess you are using SIP protocol as a communication protocol , can you share SIP messages exchange when you call IP phone from Alcatel phone.

Thanks

Manish

Hi Manish,

yes, we are using SIP trunk between Alcatel-SME && SME-CUCM. I dont have logs of CUCM but below is SIP traces of Alcatel callmanger, if you can find something in these.

traced started ...
1389583817 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583817 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583817 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583817 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> INCMNGT:get_one_instance dom=0
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> INCMNGT:get_one_instance dom=0
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583818 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> INCMNGT:get_one_instance dom=0
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> INCMNGT:get_one_instance dom=0
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583819 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583820 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583820 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583820 -> INCMNGT:get_one_instance dom=0
1389583820 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583820 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583820 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583820 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583820 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583821 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583821 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583821 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583822 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583822 ->  main.trace.timer = 480mn
1389583822 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> Mon Jan 13 09:00:23 2014 [ipc_thread] IPC Thread : Ipc reception 1771.
Mon Jan 13 09:00:23 2014 [ipc_thread] IPC Thread : TCL thread signaled.
Mon Jan 13 09:00:23 2014 [exec_ipc] in 1770.
Mon Jan 13 09:00:23 2014 [display_ipc_in] ------------ Begin ---------------
Mon Jan 13 09:00:23 2014 neqt : 7433 Id : -1
Mon Jan 13 09:00:23 2014 INVITE
Mon Jan 13 09:00:23 2014 REQUEST URI : <> 6004005@10.203.25.10:5060 ; user=phone
Mon Jan 13 09:00:23 2014 FROM : 6109686@bghpbx001.asian.ad.aricent.com:5060 ; user=phone
Mon Jan 13 09:00:23 2014 TO : <> 6004005@10.203.25.10:5060 ; user=phone
Mon Jan 13 09:00:23 2014 CAC : 0
Mon Jan 13 09:00:23 2014 neqt CAC : -1
Mon Jan 13 09:00:23 2014 CAC-CSBU info : UNKNOWN
Mon Jan 13 09:00:23 2014 CLIR : 0
Mon Jan 13 09:00:23 2014 SDP :
Mon Jan 13 09:00:23 2014   ADDRESS : 10.204.209.4410.204.209.44 :32514
Mon Jan 13 09:00:23 2014   ALGOS :
Mon Jan 13 09:00:23 2014     G729
Mon Jan 13 09:00:23 2014     G723
Mon Jan 13 09:00:23 2014     97
Mon Jan 13 09:00:23 2014   DIRECTION : SEND & RECEIVE
Mon Jan 13 09:00:23 2014   crypto index : 0
Mon Jan 13 09:00:23 2014  N_GW_EXT : 36
Mon Jan 13 09:00:23 2014 [display_ipc_in] ------------- End ----------------
Mon Jan 13 09:00:23 2014 [CMotorCallManager::onIncomingEvent] an event 10752 arrived on the eqt 7433 -1.
Mon Jan 13 09:00:23 2014 [CMotorCallManager::onIncomingEvent] New Call created.
Mon Jan 13 09:00:23 2014 [CMotorCallManager::callFactory].
Mon Jan 13 09:00:23 2014 [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R10.1.1 j2.603.25.a
1389583823 -> Mon Jan 13 09:00:23 2014 [CMotorCallManager::callFactory]=84fa060.
Mon Jan 13 09:00:23 2014 [CMotorCallManager::insertCallwithEqt] CMotorCall 7433 inserted.
Mon Jan 13 09:00:23 2014 inviteBuildFromAssertedId-->extGw=-1
Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::inviteBuildAurlWithBelongingDomain] extGw=36
Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 [quoteString] => "Sudhindra R"
Mon Jan 13 09:00:23 2014 [isAddrInDico] user=6004005 host=10.203.25.10
Mon Jan 13 09:00:23 2014 [isUserInDico] 6004005@10.203.25.10
Mon Jan 13 09:00:23 2014 isUserInDico] NOT found in the dictionnary.
Mon Jan 13 09:00:23 2014 [isAddrInDico] other sip user
Mon Jan 13 09:00:23 2014 113d[CMotorCall::inviteBuildAurlWithoutBelongingDomain] P-Asserted-Identity: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>
Mon Jan 13 09:00:23 2014 [quoteString] => "Sudhindra R"
Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::setFilterUsedMode] To be traced = 0
Mon Jan 13 09:00:23 2014 [CMotorCallManager::onIncomingEvent] state = INITIAL_STATE.
Mon Jan 13 09:00:23 2014 [onIncomingEvent] INVITE arrived.
Mon Jan 13 09:00:23 2014 [receiveInviteEvent] Call:  eqt: 7433 INITIAL_STATE received an event.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::emitInviteMessage] begin. size=656 call_type: 0
Mon Jan 13 09:00:23 2014 [isAddrInDico] user=6004005 host=10.203.25.10
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> Mon Jan 13 09:00:23 2014 [isUserInDico] 6004005@10.203.25.10
Mon Jan 13 09:00:23 2014 isUserInDico] NOT found in the dictionnary.
Mon Jan 13 09:00:23 2014 [isAddrInDico] other sip user
Mon Jan 13 09:00:23 2014 inviteBuildFromAssertedId-->extGw=36
Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::inviteBuildAurlWithBelongingDomain] extGw=36
Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 [quoteString] => "Sudhindra R"
Mon Jan 13 09:00:23 2014 [isAddrInDico] user=6004005 host=10.203.25.10
Mon Jan 13 09:00:23 2014 [isUserInDico] 6004005@10.203.25.10
Mon Jan 13 09:00:23 2014 isUserInDico] NOT found in the dictionnary.
Mon Jan 13 09:00:23 2014 [isAddrInDico] other sip user
Mon Jan 13 09:00:23 2014 113d[CMotorCall::inviteBuildAurlWithoutBelongingDomain] P-Asserted-Identity: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>
Mon Jan 13 09:00:23 2014 [quoteString] => "Sudhindra R"
Mon Jan 13 09:00:23 2014 113d[CMotorCall::emitInviteMessage] From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>
Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::emitInviteMessage] reqUri :sip:6004005@10.203.25.10;user=phone
Mon Jan 13 09:00:23 2014 113d[CMotorCall::emitInviteMessage]-->SIP_INFO_allowed=0
Mon Jan 13 09:00:23 2014 113d[CMotorCall  ISDN ExtGW
Mon Jan 13 09:00:23 2014 113d[CMotorCall  ISDN ExtGW
Mon Jan 13 09:00:23 2014 113d[CMotorCall::inviteBuildSupportedHeader] Invite directed to external gw : 36
1389583823 -> Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::emitInviteMessage]  To: <6004005>
Mon Jan 13 09:00:23 2014  am_I_PCS SCSWorking : NO
Mon Jan 13 09:00:23 2014  check_PCSstate
Mon Jan 13 09:00:23 2014  am_I_PCS SCSWorking : NO
Mon Jan 13 09:00:23 2014  OXE contains PCS configuration
Mon Jan 13 09:00:23 2014  check_PCSstate get the address of the user
Mon Jan 13 09:00:23 2014 113d[CMotorCall::addrToMediaInit] cac case.
Mon Jan 13 09:00:23 2014 [isUserRegistred] getRegistration=6004005.
Mon Jan 13 09:00:23 2014 [isUserRegistred] try with alias.
Mon Jan 13 09:00:23 2014 [getUrlByAlias] user=6004005 domain=10.203.25.10.
Mon Jan 13 09:00:23 2014 [getUrlByAlias] NOK
Mon Jan 13 09:00:23 2014 [isUserRegistred] result=-1.
Mon Jan 13 09:00:23 2014 [convertIPStrIntoTuipv] 10.203.25.10 => 10.203.25.10
Mon Jan 13 09:00:23 2014 [isIpAddressType] 10.203.25.10.
Mon Jan 13 09:00:23 2014 [isIpAddressType] YES.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::addrToMediaInit] S_OK.
Mon Jan 13 09:00:23 2014  check_PCSstate we have found the ip if user
Mon Jan 13 09:00:23 2014  check_PCSstate toAddress [0]= 10
Mon Jan 13 09:00:23 2014  check_PCSstate toAddress [1]= 203
Mon Jan 13 09:00:23 2014  check_PCSstate toAddress [2]= 25
Mon Jan 13 09:00:23 2014  check_PCSstate toAddress [3]= 10
Mon Jan 13 09:00:23 2014  check_PCSstate toAddress [10.203.25.10]
Mon Jan 13 09:00:23 2014  check_PCSstate user IP is Found ? = YES
Mon Jan 13 09:00:23 2014    The recevied host 10.203.25.10
1389583823 -> Mon Jan 13 09:00:23 2014  Trying to find the ip address in domain list  
Mon Jan 13 09:00:23 2014  The entry dom : 2 add_type=1
Mon Jan 13 09:00:23 2014  The entry dom ip low :10.203.235.6
Mon Jan 13 09:00:23 2014  The entry ipaddress from low :10.203.25.10
Mon Jan 13 09:00:23 2014  The entry compare :1
Mon Jan 13 09:00:23 2014  The entry compare 2
Mon Jan 13 09:00:23 2014  iplink_is_good_range_for_reg
Mon Jan 13 09:00:23 2014  The entry dom : 3 add_type=1
Mon Jan 13 09:00:23 2014  The entry dom ip low :10.203.254.6
Mon Jan 13 09:00:23 2014  The entry ipaddress from low :10.203.25.10
Mon Jan 13 09:00:23 2014  The entry compare :1
Mon Jan 13 09:00:23 2014  The entry compare 2
Mon Jan 13 09:00:23 2014  iplink_is_good_range_for_reg
Mon Jan 13 09:00:23 2014  The entry dom : 1 add_type=1
Mon Jan 13 09:00:23 2014  The entry dom ip low :10.203.219.6
Mon Jan 13 09:00:23 2014  The entry ipaddress from low :10.203.25.10
Mon Jan 13 09:00:23 2014  The entry compare :1
Mon Jan 13 09:00:23 2014  The entry compare 2
Mon Jan 13 09:00:23 2014  iplink_is_good_range_for_reg
Mon Jan 13 09:00:23 2014  The entry dom : 4 add_type=1
Mon Jan 13 09:00:23 2014  The entry dom ip low :10.203.221.6
Mon Jan 13 09:00:23 2014  The entry ipaddress from low :10.203.25.10
Mon Jan 13 09:00:23 2014  The entry compare :1
Mon Jan 13 09:00:23 2014  The entry compare 2
Mon Jan 13 09:00:23 2014  iplink_is_good_range_for_reg
Mon Jan 13 09:00:23 2014  The user is ipadd  not in any Domain range return state as -1
1389583823 -> Mon Jan 13 09:00:23 2014  check_PCSstate stat of PCS  is : -1
Mon Jan 13 09:00:23 2014 [inviteBuildContact] trunk_number=1001 extGw =36.
Mon Jan 13 09:00:23 2014 [137339-InviteBuildContact]  userinfo : 6109686
Mon Jan 13 09:00:23 2014 113d[CMotorCall::sipUriType] sip Uri.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::isTLSProtRequiredForExtGw] =0.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::inviteBuildContact]  Contact: sip:6109686@10.203.228.9
Mon Jan 13 09:00:23 2014 113d[CMotorCall::isExtGwTLSwithREINVITE] ExtGw NOT manged with TLS and refresh REINVITE
Mon Jan 13 09:00:23 2014 113d[CMotorCall::inviteBuildSessionTimer] uacGatewayMode=1 uacRefresher=1.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeRequestSdp] Begin sdp.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::appendAudioAttributToMedia] Direction: 0.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::appendAudioAttributToMedia] format 97
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeRequestSdp] crypto index3=0 clearMediaLine=0.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeRequestSdp] appendMediaDescription ok.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeRequestSdp] Final sdp.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::emitInviteMessage] The sdp build.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::new_determineTargetsForTheRequest] is not Responsible For This URI.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeInitialMotorRequest] target is not the local node.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeInitialMotorRequest] nb contacts=1.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeInitialMotorRequest] *pcszNameToFind : 6004005
1389583823 -> Mon Jan 13 09:00:23 2014 [isUserInDico] 6004005@10.203.25.10
Mon Jan 13 09:00:23 2014 isUserInDico] NOT found in the dictionnary.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeInitialMotorRequest] contact : sip:6004005@10.203.25.10;user=phone
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeInitialMotorRequest] nb available licenses=26 .
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeInitialMotorRequest] req_Uri_OK=0 and pSipAddSpec->getHost()=10.203.25.10
Mon Jan 13 09:00:23 2014 [CGWMngData::isMainServer] CPU is MAIN.
Mon Jan 13 09:00:23 2014 [CGWMngData::isMainServer] checkwith PCS = 0
Mon Jan 13 09:00:23 2014 113d[CMotorCall::isTLSProtRequiredForExtGw] =0.
Mon Jan 13 09:00:23 2014 113d [CCall::makeGenericRequest] INVITE
Mon Jan 13 09:00:23 2014 113d [CCall::getDialog] Confirmed Dialog is not found (ID =
Mon Jan 13 09:00:23 2014 113d [CCall::getDialog] Initial Dialog Client not found
Mon Jan 13 09:00:23 2014 1558 [CDialog::createRequest]
Mon Jan 13 09:00:23 2014 1558 [CDialog::buildServicesForAllRequest]
Mon Jan 13 09:00:23 2014 1558 [CDialog::createInviteRequest]
Mon Jan 13 09:00:23 2014 2151 [CTransaction::changeState] STATE CHANGED TO INITIAL
Mon Jan 13 09:00:23 2014 2151 [CTransaction::changeState] notifying the parent dialog
Mon Jan 13 09:00:23 2014 1558 [CDialog::onTransactionState(pTrans = 2151, previousState = Terminated, currentState = Initial, reason = None]
Mon Jan 13 09:00:23 2014 6E9D [CRequest::CRequest] Creation of a request
Mon Jan 13 09:00:23 2014 6E9D [CMessage::send] sip_sendMessage (10.203.25.10:5060)
1389583823 -> Mon Jan 13 09:00:23 2014 SEND MESSAGE TO NETWORK (10.203.25.10:5060 [UDP]) (BUFF LEN = 1080)
----------------------utf8-----------------------
INVITE sip:6004005@10.203.25.10;user=phone SIP/2.0

Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, SUBSCRIBE, OPTIONS, UPDATE

Supported: timer,path,100rel

User-Agent: OmniPCX Enterprise R10.1.1 j2.603.25.a

Session-Expires: 1800;refresher=uac

Min-SE: 900

P-Asserted-Identity: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>

Content-Type: application/sdp

To: <6004005>

From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

Contact: <6109686>

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281776 INVITE

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bKce4238b527c3022448184a43e04b8d08

Max-Forwards: 70

Content-Length: 289

v=0

o=OXE 1389583823 1389583823 IN IP4 10.203.228.9

s=abs

c=IN IP4 10.204.209.44

t=0 0

m=audio 32514 RTP/AVP 18 4 97

a=sendrecv

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:4 G723/8000

a=ptime:30

a=maxptime:30

a=rtpmap:97 telephone-event/8000

-------------------------------------------------
Mon Jan 13 09:00:23 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:23 2014 [CTransport::getConnection] RemoteHost=(10.203.25.10 5060) already exist, using connection f0b8
1389583823 -> Mon Jan 13 09:00:23 2014 [CConnectionUdp::sendToNetwork] The bytes received is not null

Mon Jan 13 09:00:23 2014  udp_send fd:4
Mon Jan 13 09:00:23 2014 udp_send fd:4 port 5060 iBytesSend:1080
Mon Jan 13 09:00:23 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:23 2014 6E9D [CMessage::onSendSuccess]
Mon Jan 13 09:00:23 2014 2151 [CTransaction::onSendSuccess] CMessage 6E9D send Success
Mon Jan 13 09:00:23 2014 1558 [CDialog::onSendSuccess]
Mon Jan 13 09:00:23 2014 113d[CMotorCall::onSendSuccess] DNS resolution ok.
Mon Jan 13 09:00:23 2014 [convertIPStrIntoTuipv] 10.203.25.10 => 10.203.25.10
Mon Jan 13 09:00:23 2014 [isIpAddressType] 10.203.25.10.
Mon Jan 13 09:00:23 2014 [isIpAddressType] YES.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::onSendSuccessIpAddr]  try to delete the cache2 extgw=36
Mon Jan 13 09:00:23 2014 [CMotorCallManager::deleteDnsCacheAddr] extGw 36.
Mon Jan 13 09:00:23 2014 [CMotorCallManager::deleteDnsCacheAddr] extGw 36 cache empty !!!!.
Mon Jan 13 09:00:23 2014 2151 [CTransInitialState::createRequest] Transaction changes to Calling state
Mon Jan 13 09:00:23 2014 2151 [CTransaction::changeState] STATE CHANGED TO CALLING
Mon Jan 13 09:00:23 2014 2151 [CTransaction::changeState] notifying the parent dialog
Mon Jan 13 09:00:23 2014 1558 [CDialog::onTransactionState(pTrans = 2151, previousState = Initial, currentState = Calling, reason = Request creation]
Mon Jan 13 09:00:23 2014 2151 [CTransaction::startTimer] Timer A is started (delay = 500 ms)
1389583823 -> Mon Jan 13 09:00:23 2014 2151 [CTransaction::startTimer] Timer B is started (delay = 4000 ms)
Mon Jan 13 09:00:23 2014 1558 [CDialog::getTransactionByCSeq] CSeq = 1352281776
Mon Jan 13 09:00:23 2014 1558 [CDialog::addTransaction] added transaction 2151 with branch z9hG4bKce4238b527c3022448184a43e04b8d08, with CSeq 1352281776
Mon Jan 13 09:00:23 2014 [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Client, UA)
Mon Jan 13 09:00:23 2014 [CSessionTimerContext::updateSupported] Request timer supported
Mon Jan 13 09:00:23 2014 [CSessionTimerContext::updateMinSE] Min-SE updated : 900
Mon Jan 13 09:00:23 2014 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
Mon Jan 13 09:00:23 2014 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Mon Jan 13 09:00:23 2014 [CForkedBranch::CForkedBranch] context = 0x84fa50c.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::branchAllocation] pForkedBranch = 0x84e54f0 and the branch number is.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::makeInitialMotorRequest] reqUri duplication  pForkedBranch = 0x84e54f0.
Mon Jan 13 09:00:23 2014 113d[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Mon Jan 13 09:00:23 2014 113d[CMotorCall::getOutgoingCall] outgoingCall:1
Mon Jan 13 09:00:23 2014 113d [CCall::getCallDuplicationData]
Mon Jan 13 09:00:23 2014 [CViaSetSerializer::copy] Added via 7c81 at index 0
Mon Jan 13 09:00:23 2014 113d [CCall::getCallDuplicationData] : Request URI = sip:6004005@10.203.25.10;user=phone, Call ID = 854142a2ddc337dff7632958ded6fbc1@10.203.228.9, Local URI = sip:6109686@bghpbx001.asian.ad.aricent.com;user=phone, Local Tag = ac2f546ae2199b5877de94cd61de62fe, Remote URI = sip:6004005@10.203.25.10;user=phone, Remote Tag = , Local CSeq = 1352281776, Remote CSeq = 0
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> Mon Jan 13 09:00:23 2014 [CViaSetSerializer::serialize] Serialized via set : ViaSerialize=Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bKce4238b527c3022448184a43e04b8d08
Mon Jan 13 09:00:23 2014 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Mon Jan 13 09:00:23 2014 [onIncomingEvent] CallID must be inserted.
Mon Jan 13 09:00:23 2014 [onIncomingEvent] CallID inserted.
Mon Jan 13 09:00:23 2014 [exec_ipc] in end 1770.
Mon Jan 13 09:00:23 2014 udp_recv fd:4
Mon Jan 13 09:00:23 2014 udp_recv fd:4 iBytesRead:418
Mon Jan 13 09:00:23 2014 UdpInputProc iSock:4 iBytesRead:418 Host:10.203.25.10 Port:5060
Mon Jan 13 09:00:23 2014 [CTransportUdp::receiveMessage] from 10.203.25.10:5060 rc:418
Mon Jan 13 09:00:23 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:23 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) MaxNbMsgByPeriod:25 Period:3
Mon Jan 13 09:00:23 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) NbMsg:5 < seuil
Mon Jan 13 09:00:23 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:23 2014 RECEIVE MESSAGE FROM NETWORK (10.203.25.10:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bKce4238b527c3022448184a43e04b8d08

From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

To: <6004005>

Date: Mon, 13 Jan 2014 03:30:23 GMT

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281776 INVITE

Allow-Events: presence

Content-Length: 0

-------------------------------------------------

1389583823 -> Mon Jan 13 09:00:23 2014 [CIOCom::receiveResponse] CResponse 84EB ref=1
Mon Jan 13 09:00:23 2014 [CDispatcher::processResponseFromManagerIndex] start at index 0
Mon Jan 13 09:00:23 2014  101 [CCallManager::onIncomingResponse]
Mon Jan 13 09:00:23 2014 113d [CCall::receiveResponse] 100
Mon Jan 13 09:00:23 2014 113d [CCall::getDialog] Confirmed Dialog is not found (ID = ac2f546ae2199b5877de94cd61de62fe;)
Mon Jan 13 09:00:23 2014 113d [CCall::getDialog] Returning Initial Dialog Client
Mon Jan 13 09:00:23 2014 1558 [CDialog::receiveResponse]
Mon Jan 13 09:00:23 2014 2151 [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
Mon Jan 13 09:00:23 2014 2151 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
Mon Jan 13 09:00:23 2014 2151 [CTransaction::changeState] notifying the parent dialog
Mon Jan 13 09:00:23 2014 1558 [CDialog::onTransactionState(pTrans = 2151, previousState = Calling, currentState = Proceeding, reason = 1xx response reception]
Mon Jan 13 09:00:23 2014 2151 [CTransaction::freeTimerToken] Timer A is freed
Mon Jan 13 09:00:23 2014 2151 [CTransaction::freeTimerToken] Timer B is freed
Mon Jan 13 09:00:23 2014 113d[CMotorCall::searchForkedBranch]pForkedBranch=0x84e54f0.
Mon Jan 13 09:00:23 2014 [receiveInformationalMessage] Call: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 eqt: 7433 PROCEEDING_STATE received a message.
Mon Jan 13 09:00:23 2014 [receiveInformationalMessage] is in the else part of SDP not present
Mon Jan 13 09:00:23 2014 udp_recv fd:4
1389583823 -> Mon Jan 13 09:00:23 2014 udp_recv fd:4 iBytesRead:816
Mon Jan 13 09:00:23 2014 UdpInputProc iSock:4 iBytesRead:816 Host:10.203.25.10 Port:5060
Mon Jan 13 09:00:23 2014 [CTransportUdp::receiveMessage] from 10.203.25.10:5060 rc:816
Mon Jan 13 09:00:23 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:23 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) MaxNbMsgByPeriod:25 Period:3
Mon Jan 13 09:00:23 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) NbMsg:6 < seuil
Mon Jan 13 09:00:23 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:23 2014 RECEIVE MESSAGE FROM NETWORK (10.203.25.10:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bKce4238b527c3022448184a43e04b8d08

From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

To: <6004005>;tag=f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514

Date: Mon, 13 Jan 2014 03:30:23 GMT

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281776 INVITE

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

Allow-Events: presence

Contact: <6004005>

Supported: X-cisco-srtp-fallback

Supported: Geolocation

P-Asserted-Identity: "Bridge Support" <4005>

Remote-Party-ID: "Bridge Support" <4005>;party=called;screen=yes;privacy=off

Content-Length: 0

-------------------------------------------------

1389583823 -> Mon Jan 13 09:00:23 2014 [CIOCom::receiveResponse] CResponse 84EC ref=1
Mon Jan 13 09:00:23 2014 [CDispatcher::processResponseFromManagerIndex] start at index 0
Mon Jan 13 09:00:23 2014  101 [CCallManager::onIncomingResponse]
Mon Jan 13 09:00:23 2014 113d [CCall::receiveResponse] 180
Mon Jan 13 09:00:23 2014 113d [CCall::getDialog] Confirmed Dialog is not found (ID = ac2f546ae2199b5877de94cd61de62fe;f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514)
Mon Jan 13 09:00:23 2014 113d [CCall::getDialog] Returning Initial Dialog Client
Mon Jan 13 09:00:23 2014 1558 [CDialog::receiveResponse]
Mon Jan 13 09:00:23 2014 84EB [~CResponse] resp(100) 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281776 INVITE
Mon Jan 13 09:00:23 2014 1558 [CDialog::updateRouteSet] there are 0 record-route headers
Mon Jan 13 09:00:23 2014 113d[CMotorCall::searchForkedBranch]pForkedBranch=0x84e54f0.
Mon Jan 13 09:00:23 2014 [receiveInformationalMessage] Call: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 eqt: 7433 PROCEEDING_STATE received a message.
Mon Jan 13 09:00:23 2014 [receiveInformationalMessage] is in the else part of SDP not present
Mon Jan 13 09:00:23 2014 [methodAllowedByDistant] INFO allowed by distant.
Mon Jan 13 09:00:23 2014 113d[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Mon Jan 13 09:00:23 2014 113d[emitEventToMonitel]1 T38 REINVITE TRUE
Mon Jan 13 09:00:23 2014 113d[emitEventToMonitel] CAC = 0 eqtCAC = -1
Mon Jan 13 09:00:23 2014 113d[emitEventToMonitel] cacUsed=0 eqtCac=-1
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583823 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583824 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583824 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> Mon Jan 13 09:00:23 2014 113d[emitEventToMonitel] displayIPCMessageOut
Mon Jan 13 09:00:23 2014 ** SIP TRUNKING **
Mon Jan 13 09:00:23 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:23 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:23 2014 [display_ipc_out] ------------ Begin ---------------
Mon Jan 13 09:00:23 2014 Id : -1
Mon Jan 13 09:00:23 2014 INFORMATIONAL
Mon Jan 13 09:00:23 2014 xx :       80
Mon Jan 13 09:00:23 2014  RELATIVE REQUEST : INVITE
Mon Jan 13 09:00:23 2014 Allow Update : 0
Mon Jan 13 09:00:23 2014 [display_ipc_out] ------------- End ----------------
Mon Jan 13 09:00:25 2014 udp_recv fd:4
Mon Jan 13 09:00:25 2014 udp_recv fd:4 iBytesRead:1100
Mon Jan 13 09:00:25 2014 UdpInputProc iSock:4 iBytesRead:1100 Host:10.203.25.10 Port:5060
Mon Jan 13 09:00:25 2014 [CTransportUdp::receiveMessage] from 10.203.25.10:5060 rc:1100
Mon Jan 13 09:00:25 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:25 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) MaxNbMsgByPeriod:25 Period:3
Mon Jan 13 09:00:25 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) NbMsg:7 < seuil
Mon Jan 13 09:00:25 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:25 2014 RECEIVE MESSAGE FROM NETWORK (10.203.25.10:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 183 Session Progress

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bKce4238b527c3022448184a43e04b8d08

From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

To: <6004005>;tag=f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514

Date: Mon, 13 Jan 2014 03:30:23 GMT

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281776 INVITE

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

Allow-Events: presence, kpml

Contact: <6004005>

Supported: X-cisco-srtp-fallback

Supported: Geolocation

P-Asserted-Identity: "Bridge Support" <4005>

Remote-Party-ID: "Bridge Support" <4005>;party=called;screen=yes;privacy=off

Content-Type: application/sdp

Content-Length: 236

v=0

o=CiscoSystemsCCM-SIP 2000 1 IN IP4 10.203.25.10

s=SIP Call

c=IN IP4 10.203.21.136

t=0 0

m=audio 24016 RTP/AVP 18 101

a=rtpmap:18 G729/8000

a=ptime:20

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

-------------------------------------------------

1389583825 -> Mon Jan 13 09:00:25 2014 [CIOCom::receiveResponse] CResponse 84ED ref=1
Mon Jan 13 09:00:25 2014 [CDispatcher::processResponseFromManagerIndex] start at index 0
Mon Jan 13 09:00:25 2014  101 [CCallManager::onIncomingResponse]
Mon Jan 13 09:00:25 2014 113d [CCall::receiveResponse] 183
Mon Jan 13 09:00:25 2014 113d [CCall::getDialog] Confirmed Dialog is not found (ID = ac2f546ae2199b5877de94cd61de62fe;f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514)
Mon Jan 13 09:00:25 2014 113d [CCall::getDialog] Returning Initial Dialog Client
Mon Jan 13 09:00:25 2014 1558 [CDialog::receiveResponse]
Mon Jan 13 09:00:25 2014 84EC [~CResponse] resp(180) 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281776 INVITE
Mon Jan 13 09:00:25 2014 1558 [CDialog::updateRouteSet] there are 0 record-route headers
Mon Jan 13 09:00:25 2014 113d[CMotorCall::searchForkedBranch]pForkedBranch=0x84e54f0.
Mon Jan 13 09:00:25 2014 [receiveInformationalMessage] Call: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 eqt: 7433 PROCEEDING_STATE received a message.
Mon Jan 13 09:00:25 2014 [receiveInformationalMessage] is in the first IF of SDP present
Mon Jan 13 09:00:25 2014 [checkSdpValidity] Media 0 type 1 contains 2 formats.
Mon Jan 13 09:00:25 2014 [checkSdpValidity] Format : 18.
Mon Jan 13 09:00:25 2014 [receiveInformationalMessage] is in the else part of SDP present
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] No Direction in the session part.
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1389583825 -> Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] media AUDIO detected (previous crypto=0).
Mon Jan 13 09:00:25 2014 [convertCryptoInfoInFile] clearMediaLine2:  0.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] The audio media contains  2 format(s).
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Format 0 is 18.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Format 1 is 101.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] 101.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Format is DTMF:101.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] invalid Direction in this media part.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Connection address retrieved in sdp: 10.203.21.136.
Mon Jan 13 09:00:25 2014 [convertIPStrIntoTuipv] 10.203.21.136 => 10.203.21.136
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] sdp index=0.
Mon Jan 13 09:00:25 2014 [display_sdp] address =10.203.21.136
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =17.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =101.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =255.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =255.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =255.
Mon Jan 13 09:00:25 2014 [display_sdp] direction=0.
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] only one media taken into account xxx crypto_index=0 clear media=1
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] crypto_index=0 clear media=1.
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel]1 T38 REINVITE TRUE
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] CAC = 0 eqtCAC = -1
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] cacUsed=0 eqtCac=-1
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] displayIPCMessageOut
Mon Jan 13 09:00:25 2014 ** SIP TRUNKING **
Mon Jan 13 09:00:25 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:25 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:25 2014 [display_ipc_out] ------------ Begin ---------------
Mon Jan 13 09:00:25 2014 Id : -1
Mon Jan 13 09:00:25 2014 INFORMATIONAL
Mon Jan 13 09:00:25 2014 xx :       83
Mon Jan 13 09:00:25 2014  RELATIVE REQUEST : INVITE
Mon Jan 13 09:00:25 2014 Allow Update : 0
Mon Jan 13 09:00:25 2014 SDP :
Mon Jan 13 09:00:25 2014   ADDRESS : 10.203.21.13610.203.21.136 :24016
Mon Jan 13 09:00:25 2014   ALGOS :
Mon Jan 13 09:00:25 2014     G729
Mon Jan 13 09:00:25 2014     101
Mon Jan 13 09:00:25 2014   DIRECTION : SEND & RECEIVE
Mon Jan 13 09:00:25 2014   crypto index : 0
Mon Jan 13 09:00:25 2014 [display_ipc_out] ------------- End ----------------
Mon Jan 13 09:00:25 2014 udp_recv fd:4
Mon Jan 13 09:00:25 2014 udp_recv fd:4 iBytesRead:1162
Mon Jan 13 09:00:25 2014 UdpInputProc iSock:4 iBytesRead:1162 Host:10.203.25.10 Port:5060
Mon Jan 13 09:00:25 2014 [CTransportUdp::receiveMessage] from 10.203.25.10:5060 rc:1162
Mon Jan 13 09:00:25 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:25 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) MaxNbMsgByPeriod:25 Period:3
1389583825 -> Mon Jan 13 09:00:25 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) NbMsg:8 < seuil
Mon Jan 13 09:00:25 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:25 2014 RECEIVE MESSAGE FROM NETWORK (10.203.25.10:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bKce4238b527c3022448184a43e04b8d08

From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

To: <6004005>;tag=f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514

Date: Mon, 13 Jan 2014 03:30:23 GMT

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281776 INVITE

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

Allow-Events: presence, kpml

Contact: <6004005>

Supported: replaces

Supported: X-cisco-srtp-fallback

Supported: Geolocation

Session-Expires:  1800;refresher=uac

Require:  timer

P-Asserted-Identity: "Bridge Support" <4005>

Remote-Party-ID: "Bridge Support" <4005>;party=called;screen=yes;privacy=off

Content-Type: application/sdp

Content-Length: 236

v=0

o=CiscoSystemsCCM-SIP 2000 1 IN IP4 10.203.25.10

s=SIP Call

c=IN IP4 10.203.21.136

t=0 0

m=audio 24016 RTP/AVP 18 101

a=rtpmap:18 G729/8000

a=ptime:20

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

-------------------------------------------------

1389583825 -> Mon Jan 13 09:00:25 2014 [CIOCom::receiveResponse] CResponse 84EE ref=1
Mon Jan 13 09:00:25 2014 [CDispatcher::processResponseFromManagerIndex] start at index 0
Mon Jan 13 09:00:25 2014  101 [CCallManager::onIncomingResponse]
Mon Jan 13 09:00:25 2014 113d [CCall::receiveResponse] 200
Mon Jan 13 09:00:25 2014 113d [CCall::getDialog] Confirmed Dialog is not found (ID = ac2f546ae2199b5877de94cd61de62fe;f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514)
Mon Jan 13 09:00:25 2014 113d [CCall::getDialog] Returning Initial Dialog Client
Mon Jan 13 09:00:25 2014 1558 [CDialog::receiveResponse]
Mon Jan 13 09:00:25 2014 1558 [CDialog::receiveResponse] create a CONFIRMED dialog
Mon Jan 13 09:00:25 2014 1559 [CDialog::CDialog] look for the transaction #0, transaction key = z9hG4bKce4238b527c3022448184a43e04b8d08
Mon Jan 13 09:00:25 2014 1559 [CDialog::CDialog] copy the transaction #0, transaction key = z9hG4bKce4238b527c3022448184a43e04b8d08
Mon Jan 13 09:00:25 2014 2152 [CTransaction::CTransaction] Transaction is cloned in 4 state
Mon Jan 13 09:00:25 2014 1559 [CDialog::addTransaction] added transaction 2152 with branch z9hG4bKce4238b527c3022448184a43e04b8d08, with CSeq 1352281776
Mon Jan 13 09:00:25 2014 1559 [CDialog::startDialogTTL]
Mon Jan 13 09:00:25 2014 1559 [CDialog::receiveResponse]
Mon Jan 13 09:00:25 2014 2152 [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
Mon Jan 13 09:00:25 2014 2152 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Mon Jan 13 09:00:25 2014 2152 [CTransaction::changeState] notifying the parent dialog
1389583825 -> Mon Jan 13 09:00:25 2014 1559 [CDialog::onTransactionState(pTrans = 2152, previousState = Proceeding, currentState = Completed, reason = Final resp reception]
Mon Jan 13 09:00:25 2014 2152 [CTransaction::startTimer] Timer D is started (delay = 32150 ms)
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from response (Client, UA)
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::updateSupported] Request timer supported
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::updateMinSE] Min-SE updated : 900
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::startTimers] Start session timer expiration : 1800000
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::startTimers] Start session timer will expires : 900000
Mon Jan 13 09:00:25 2014 [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=UPDATE
Mon Jan 13 09:00:25 2014 1559 [CDialog::updateRouteSet] there are 0 record-route headers
Mon Jan 13 09:00:25 2014 113d [CCall::insertInDialogMap] added dialog #1559 with the key ac2f546ae2199b5877de94cd61de62fe;f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514

Mon Jan 13 09:00:25 2014 1558 [CDialog::getTransactionByCSeq] CSeq = 1352281777
1389583825 -> Mon Jan 13 09:00:25 2014 1558 [CDialog::updateRouteSet] there are 0 record-route headers
Mon Jan 13 09:00:25 2014 113d[CMotorCall::searchForkedBranch]pForkedBranch=0x84e54f0.
Mon Jan 13 09:00:25 2014         [CForkedBranch::setFinalResponse] response=200.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::chooseBestResponse] pForkedBranch=0x84e54f0 iResponseCode=2000.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::chooseBestResponse] last iResponseCode=200.
Mon Jan 13 09:00:25 2014         [CForkedBranch::setFinalResponse] pResponse=0x84f5a60.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::chooseBestResponse] next branch.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
Mon Jan 13 09:00:25 2014 [receiveSuccessfulMessage] Call: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 eqt: 7433 PROCEEDING_STATE received a message.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::isExtGwTLSwithREINVITE] ExtGw NOT manged with TLS and refresh REINVITE
Mon Jan 13 09:00:25 2014 113d[CMotorCall::analyseSessionTimer] exit with  uacGatewayMode=1 uacRefresher=1.
Mon Jan 13 09:00:25 2014 [decodeACACALU] no header.
Mon Jan 13 09:00:25 2014 [checkSdpValidity] Media 0 type 1 contains 2 formats.
Mon Jan 13 09:00:25 2014 [checkSdpValidity] Format : 18.
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] No Direction in the session part.
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1389583825 -> Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] media AUDIO detected (previous crypto=0).
Mon Jan 13 09:00:25 2014 [convertCryptoInfoInFile] clearMediaLine2:  0.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] The audio media contains  2 format(s).
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Format 0 is 18.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Format 1 is 101.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] 101.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Format is DTMF:101.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] invalid Direction in this media part.
Mon Jan 13 09:00:25 2014 [convertAudioMedia] Connection address retrieved in sdp: 10.203.21.136.
Mon Jan 13 09:00:25 2014 [convertIPStrIntoTuipv] 10.203.21.136 => 10.203.21.136
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] sdp index=0.
Mon Jan 13 09:00:25 2014 [display_sdp] address =10.203.21.136
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =17.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =101.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =255.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =255.
Mon Jan 13 09:00:25 2014 [display_sdp] codecs =255.
Mon Jan 13 09:00:25 2014 [display_sdp] direction=0.
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] only one media taken into account xxx crypto_index=0 clear media=1
Mon Jan 13 09:00:25 2014 [convertSdpIntoTsdp] crypto_index=0 clear media=1.
Mon Jan 13 09:00:25 2014 [methodAllowedByDistant] INFO allowed by distant.
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel]1 T38 REINVITE TRUE
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] CAC = 0 eqtCAC = -1
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] cacUsed=0 eqtCac=-1
Mon Jan 13 09:00:25 2014 113d[emitEventToMonitel] displayIPCMessageOut
Mon Jan 13 09:00:25 2014 ** SIP TRUNKING **
Mon Jan 13 09:00:25 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:25 2014 [ipc_thread] IPC Thread : Ipc reception 1771.
Mon Jan 13 09:00:25 2014 [ipc_thread] IPC Thread : TCL thread signaled.
Mon Jan 13 09:00:25 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:25 2014 [display_ipc_out] ------------ Begin ---------------
Mon Jan 13 09:00:25 2014 Id : -1
Mon Jan 13 09:00:25 2014 SUCCESSFUL
Mon Jan 13 09:00:25 2014 xx :        0
Mon Jan 13 09:00:25 2014  RELATIVE REQUEST : INVITE
Mon Jan 13 09:00:25 2014 CAC-CSBU info : UNKNOWN
Mon Jan 13 09:00:25 2014 CLIR : 0
Mon Jan 13 09:00:25 2014 COLP : 1
Mon Jan 13 09:00:25 2014 SDP :
Mon Jan 13 09:00:25 2014   ADDRESS : 10.203.21.13610.203.21.136 :24016
Mon Jan 13 09:00:25 2014   ALGOS :
Mon Jan 13 09:00:25 2014     G729
Mon Jan 13 09:00:25 2014     101
Mon Jan 13 09:00:25 2014   DIRECTION : SEND & RECEIVE
Mon Jan 13 09:00:25 2014   crypto index : 0
Mon Jan 13 09:00:25 2014 [display_ipc_out] ------------- End ----------------
Mon Jan 13 09:00:25 2014 [CProceedingState::receiveSuccessfulMessage] SRV_OK received update FROM TAG on the StandBy.
Mon Jan 13 09:00:25 2014 113d [CCall::getCallDuplicationData]
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> Mon Jan 13 09:00:25 2014 113d [CCall::getCallDuplicationData] : Request URI = , Call ID = 854142a2ddc337dff7632958ded6fbc1@10.203.228.9, Local URI = sip:6109686@bghpbx001.asian.ad.aricent.com;user=phone, Local Tag = ac2f546ae2199b5877de94cd61de62fe, Remote URI = sip:6004005@10.203.25.10;user=phone, Remote Tag = f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514, Local CSeq = 1352281776, Remote CSeq = 0
Mon Jan 13 09:00:25 2014 [CViaSetSerializer::serialize] Serialized via set :
Mon Jan 13 09:00:25 2014 [CRouteSet::serialize] Serialized route set : Contact=sip:6004005@10.203.25.10:5060
Mon Jan 13 09:00:25 2014 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Mon Jan 13 09:00:25 2014 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Mon Jan 13 09:00:25 2014 113d[CMotorCall::getOutgoingCall] outgoingCall:1
Mon Jan 13 09:00:25 2014 113d[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
Mon Jan 13 09:00:25 2014 [exec_ipc] in 1770.
Mon Jan 13 09:00:25 2014 [display_ipc_in] ------------ Begin ---------------
Mon Jan 13 09:00:25 2014 neqt : 7433 Id : -1
Mon Jan 13 09:00:25 2014 ACK
Mon Jan 13 09:00:25 2014 [display_ipc_in] ------------- End ----------------
Mon Jan 13 09:00:25 2014 [CMotorCallManager::onIncomingEvent] an event 10754 arrived on the eqt 7433 -1.
Mon Jan 13 09:00:25 2014 [CMotorCallManager::onIncomingEvent] state = ACCEPTED_STATE.
Mon Jan 13 09:00:25 2014 [onIncomingEvent] ACK arrived.
1389583825 -> Mon Jan 13 09:00:25 2014 [receiveAckEvent] Call: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 eqt: 7433 ACCEPTED_STATE received an event.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::emitAckMessage] begin.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::emitAckMessage] SDP ok.
Mon Jan 13 09:00:25 2014 113d [CCall::makeGenericRequest] ACK
Mon Jan 13 09:00:25 2014 113d [CCall::getDialog] Confirmed Dialog found
Mon Jan 13 09:00:25 2014 1559 [CDialog::createRequest]
Mon Jan 13 09:00:25 2014 1559 [CDialog::buildServicesForAllRequest]
Mon Jan 13 09:00:25 2014 1559 [CDialog::createAckRequest]
Mon Jan 13 09:00:25 2014 1559 [CDialog::createAckRequest], previous trans 2152 is found
Mon Jan 13 09:00:25 2014 1559 [CDialog::createAckRequest], previous trans 2152 is client
Mon Jan 13 09:00:25 2014 6E9E [CRequest::CRequest] Creation of a request
Mon Jan 13 09:00:25 2014 1559 [CDialog::buildServicesForAck]
Mon Jan 13 09:00:25 2014 6E9E [CMessage::send] sip_sendMessage (10.203.25.10:5060)
Mon Jan 13 09:00:25 2014 SEND MESSAGE TO NETWORK (10.203.25.10:5060 [UDP]) (BUFF LEN = 532)
----------------------utf8-----------------------
ACK sip:6004005@10.203.25.10:5060 SIP/2.0

Contact: sip:6109686@10.203.228.9

User-Agent: OmniPCX Enterprise R10.1.1 j2.603.25.a

To: <6004005>;tag=f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514

From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281776 ACK

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bK8f20776768f408b8277fe6f65e4fa1e7

Max-Forwards: 70

Content-Length: 0

-------------------------------------------------
1389583825 -> Mon Jan 13 09:00:25 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:25 2014 [CTransport::getConnection] RemoteHost=(10.203.25.10 5060) already exist, using connection f0b8
Mon Jan 13 09:00:25 2014 [CConnectionUdp::sendToNetwork] The bytes received is not null

Mon Jan 13 09:00:25 2014  udp_send fd:4
Mon Jan 13 09:00:25 2014 udp_send fd:4 port 5060 iBytesSend:532
Mon Jan 13 09:00:25 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:25 2014 6E9E [CMessage::onSendSuccess]
Mon Jan 13 09:00:25 2014 1559 [CDialog::onSendSuccess]
Mon Jan 13 09:00:25 2014 113d[CMotorCall::onSendSuccess] DNS resolution ok.
Mon Jan 13 09:00:25 2014 [convertIPStrIntoTuipv] 10.203.25.10 => 10.203.25.10
Mon Jan 13 09:00:25 2014 [isIpAddressType] 10.203.25.10.
Mon Jan 13 09:00:25 2014 [isIpAddressType] YES.
Mon Jan 13 09:00:25 2014 113d[CMotorCall::onSendSuccessIpAddr]  try to delete the cache2 extgw=36
Mon Jan 13 09:00:25 2014 [CMotorCallManager::deleteDnsCacheAddr] extGw 36.
Mon Jan 13 09:00:25 2014 [CMotorCallManager::deleteDnsCacheAddr] extGw 36 cache empty !!!!.
Mon Jan 13 09:00:25 2014 2152 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Mon Jan 13 09:00:25 2014 2152 [CTransaction::freeTimerToken] Timer D is freed
Mon Jan 13 09:00:25 2014 2152 [CTransaction::changeState] no transaction state, terminated reason : Request creation
Mon Jan 13 09:00:25 2014 1559 [CDialog::createAckRequest] successful INVITE is terminated after the ACK is sent
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583825 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583826 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583826 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583827 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583829 -> Mon Jan 13 09:00:25 2014 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Mon Jan 13 09:00:25 2014 113d [CCall::getCallDuplicationData]
Mon Jan 13 09:00:25 2014 113d [CCall::getCallDuplicationData] : Request URI = , Call ID = 854142a2ddc337dff7632958ded6fbc1@10.203.228.9, Local URI = sip:6109686@bghpbx001.asian.ad.aricent.com;user=phone, Local Tag = ac2f546ae2199b5877de94cd61de62fe, Remote URI = sip:6004005@10.203.25.10;user=phone, Remote Tag = f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514, Local CSeq = 1352281776, Remote CSeq = 0
Mon Jan 13 09:00:25 2014 [CViaSetSerializer::serialize] Serialized via set :
Mon Jan 13 09:00:25 2014 [CRouteSet::serialize] Serialized route set : Contact=sip:6004005@10.203.25.10:5060
Mon Jan 13 09:00:25 2014 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Mon Jan 13 09:00:25 2014 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Mon Jan 13 09:00:25 2014 [receiveAckEvent] continue with session Timer
Mon Jan 13 09:00:25 2014 [exec_ipc] in end 1770.
Mon Jan 13 09:00:25 2014 [CTransaction::notifyDialog]
Mon Jan 13 09:00:25 2014 [CTransaction::notifyDialog] notify parent dialog
Mon Jan 13 09:00:25 2014 1559 [CDialog::onTransactionState(pTrans = 2152, previousState = null, currentState = Terminated, reason = Request creation]
Mon Jan 13 09:00:25 2014 2152 [~CTransaction] transaction 1352281776 INVITE

1389583829 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583829 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583829 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> Mon Jan 13 09:00:31 2014 [ipc_thread] IPC Thread : Ipc reception 1771.
Mon Jan 13 09:00:31 2014 [ipc_thread] IPC Thread : TCL thread signaled.
Mon Jan 13 09:00:31 2014 [exec_ipc] in 1770.
Mon Jan 13 09:00:31 2014 [display_ipc_in] ------------ Begin ---------------
Mon Jan 13 09:00:31 2014 neqt : 7433 Id : -1
Mon Jan 13 09:00:31 2014 BYE
Mon Jan 13 09:00:31 2014 [display_ipc_in] ------------- End ----------------
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onIncomingEvent] an event 10755 arrived on the eqt 7433 -1.
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onIncomingEvent] state = COMPLETED_STATE.
Mon Jan 13 09:00:31 2014 [onIncomingEvent] BYE arrived.
Mon Jan 13 09:00:31 2014 [receiveByeEvent] Call: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 eqt: 7433 COMPLETED_STATE received an event.
Mon Jan 13 09:00:31 2014 113d[CMotorCall  ISDN ExtGW
Mon Jan 13 09:00:31 2014 113d [CCall::makeGenericRequest] BYE
Mon Jan 13 09:00:31 2014 113d [CCall::getDialog] Confirmed Dialog found
Mon Jan 13 09:00:31 2014 1559 [CDialog::createRequest]
Mon Jan 13 09:00:31 2014 1559 [CDialog::buildServicesForAllRequest]
Mon Jan 13 09:00:31 2014 1559 [CDialog::createByeRequest]
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] STATE CHANGED TO INITIAL
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] notifying the parent dialog
Mon Jan 13 09:00:31 2014 1559 [CDialog::onTransactionState(pTrans = 2153, previousState = Terminated, currentState = Initial, reason = None]
Mon Jan 13 09:00:31 2014 6E9F [CRequest::CRequest] Creation of a request
1389583831 -> Mon Jan 13 09:00:31 2014 6E9F [CMessage::send] sip_sendMessage (10.203.25.10:5060)
Mon Jan 13 09:00:31 2014 SEND MESSAGE TO NETWORK (10.203.25.10:5060 [UDP]) (BUFF LEN = 513)
----------------------utf8-----------------------
BYE sip:6004005@10.203.25.10:5060 SIP/2.0

Supported: timer,path,100rel

User-Agent: OmniPCX Enterprise R10.1.1 j2.603.25.a

To: <6004005>;tag=f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514

From: <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281777 BYE

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bK4141eaeee19b01f1e343a5950ff93b6f

Max-Forwards: 70

Content-Length: 0

-------------------------------------------------
Mon Jan 13 09:00:31 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:31 2014 [CTransport::getConnection] RemoteHost=(10.203.25.10 5060) already exist, using connection f0b8
Mon Jan 13 09:00:31 2014 [CConnectionUdp::sendToNetwork] The bytes received is not null

Mon Jan 13 09:00:31 2014  udp_send fd:4
Mon Jan 13 09:00:31 2014 udp_send fd:4 port 5060 iBytesSend:513
Mon Jan 13 09:00:31 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:31 2014 6E9F [CMessage::onSendSuccess]
Mon Jan 13 09:00:31 2014 2153 [CTransaction::onSendSuccess] CMessage 6E9F send Success
Mon Jan 13 09:00:31 2014 1559 [CDialog::onSendSuccess]
1389583831 -> Mon Jan 13 09:00:31 2014 113d[CMotorCall::onSendSuccess] DNS resolution ok.
Mon Jan 13 09:00:31 2014 [convertIPStrIntoTuipv] 10.203.25.10 => 10.203.25.10
Mon Jan 13 09:00:31 2014 [isIpAddressType] 10.203.25.10.
Mon Jan 13 09:00:31 2014 [isIpAddressType] YES.
Mon Jan 13 09:00:31 2014 113d[CMotorCall::onSendSuccessIpAddr]  try to delete the cache2 extgw=36
Mon Jan 13 09:00:31 2014 [CMotorCallManager::deleteDnsCacheAddr] extGw 36.
Mon Jan 13 09:00:31 2014 [CMotorCallManager::deleteDnsCacheAddr] extGw 36 cache empty !!!!.
Mon Jan 13 09:00:31 2014 2153 [CTransInitialState::createRequest] Transaction changes to Trying state
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] STATE CHANGED TO TRYING
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] notifying the parent dialog
Mon Jan 13 09:00:31 2014 1559 [CDialog::onTransactionState(pTrans = 2153, previousState = Initial, currentState = Trying, reason = Request creation]
Mon Jan 13 09:00:31 2014 2153 [CTransTryingState::enterInState] Init timers E and F
Mon Jan 13 09:00:31 2014 2153 [CTransaction::startTimer] Timer E is started (delay = 500 ms)
Mon Jan 13 09:00:31 2014 2153 [CTransaction::startTimer] Timer F is started (delay = 16000 ms)
Mon Jan 13 09:00:31 2014 1559 [CDialog::addTransaction] added transaction 2153 with branch z9hG4bK4141eaeee19b01f1e343a5950ff93b6f, with CSeq 1352281777
Mon Jan 13 09:00:31 2014 [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
Mon Jan 13 09:00:31 2014 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> Mon Jan 13 09:00:31 2014 [exec_ipc] in end 1770.
Mon Jan 13 09:00:31 2014 udp_recv fd:4
Mon Jan 13 09:00:31 2014 udp_recv fd:4 iBytesRead:437
Mon Jan 13 09:00:31 2014 UdpInputProc iSock:4 iBytesRead:437 Host:10.203.25.10 Port:5060
Mon Jan 13 09:00:31 2014 [CTransportUdp::receiveMessage] from 10.203.25.10:5060 rc:437
Mon Jan 13 09:00:31 2014 [CTransport::findConnection] pConnection = f0b8 remoteHost=10.203.25.10, remotePort=5060
Mon Jan 13 09:00:31 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) MaxNbMsgByPeriod:25 Period:3
Mon Jan 13 09:00:31 2014 f0b8 [CConnection::dOSTest](10.203.25.10,5060,0) NbMsg:9 < seuil
Mon Jan 13 09:00:31 2014 f0b8 [CConnection::resetConnectionTimer] Creating the activity timer
Mon Jan 13 09:00:31 2014 RECEIVE MESSAGE FROM NETWORK (10.203.25.10:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.203.228.9;branch=z9hG4bK4141eaeee19b01f1e343a5950ff93b6f

From: "Sudhindra R" <>6109686@bghpbx001.asian.ad.aricent.com;user=phone>;tag=ac2f546ae2199b5877de94cd61de62fe

To: <6004005>;tag=f4e2ae3b-5545-4b09-bba6-7d25c800704e-25290514

Date: Mon, 13 Jan 2014 03:30:31 GMT

Call-ID: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9

CSeq: 1352281777 BYE

Content-Length: 0

-------------------------------------------------

Mon Jan 13 09:00:31 2014 [CIOCom::receiveResponse] CResponse 84EF ref=1
Mon Jan 13 09:00:31 2014 [CDispatcher::processResponseFromManagerIndex] start at index 0
Mon Jan 13 09:00:31 2014  101 [CCallManager::onIncomingResponse]
1389583831 -> Mon Jan 13 09:00:31 2014 113d [CCall::receiveResponse] 200
Mon Jan 13 09:00:31 2014 113d [CCall::getDialog] Confirmed Dialog found
Mon Jan 13 09:00:31 2014 1559 [CDialog::receiveResponse]
Mon Jan 13 09:00:31 2014 2153 [CTransTryingState::receiveResponse] Final : Transaction changes to Completed state
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] notifying the parent dialog
Mon Jan 13 09:00:31 2014 1559 [CDialog::onTransactionState(pTrans = 2153, previousState = Trying, currentState = Completed, reason = Final resp reception]
Mon Jan 13 09:00:31 2014 2153 [CTransaction::freeTimerToken] Timer E is freed
Mon Jan 13 09:00:31 2014 2153 [CTransaction::freeTimerToken] Timer F is freed
Mon Jan 13 09:00:31 2014 2153 [CTransaction::startTimer] Timer K is started (delay = 5000 ms)
Mon Jan 13 09:00:31 2014 113d[CMotorCall::onReceiveResponse] Update2 the Other context.
Mon Jan 13 09:00:31 2014 [CTerminatedState::receiveSuccessfulMessage] Call: 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 eqt: 7433 TERMINATED_STATE received a message.
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] CAC = 0 eqtCAC = -1
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] cacUsed=0 eqtCac=-1
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] displayIPCMessageOut
Mon Jan 13 09:00:31 2014 ** SIP TRUNKING **
Mon Jan 13 09:00:31 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> Mon Jan 13 09:00:31 2014 [ipc_thread] IPC Thread : Ipc reception 1771.
Mon Jan 13 09:00:31 2014 [ipc_thread] IPC Thread : TCL thread signaled.
Mon Jan 13 09:00:31 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:31 2014 [display_ipc_out] ------------ Begin ---------------
Mon Jan 13 09:00:31 2014 Id : -1
Mon Jan 13 09:00:31 2014 SUCCESSFUL
Mon Jan 13 09:00:31 2014 xx :        0
Mon Jan 13 09:00:31 2014  RELATIVE REQUEST : BYE
Mon Jan 13 09:00:31 2014 CAC-CSBU info : UNKNOWN
Mon Jan 13 09:00:31 2014 CLIR : 0
Mon Jan 13 09:00:31 2014 COLP : 0
Mon Jan 13 09:00:31 2014 [display_ipc_out] ------------- End ----------------
Mon Jan 13 09:00:31 2014 [exec_ipc] in 1770.
Mon Jan 13 09:00:31 2014 [display_ipc_in] ------------ Begin ---------------
Mon Jan 13 09:00:31 2014 neqt : 7433 Id : -1
Mon Jan 13 09:00:31 2014 SIP EQT RELEASED
Mon Jan 13 09:00:31 2014 [display_ipc_in] ------------- End ----------------
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 7433 -1.
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onIncomingEvent] The call with eqt: 7433 has released its equipment.
Mon Jan 13 09:00:31 2014 113d[CMotorCall::emitSipEqtReleaseAck] SIP_EQT_RELEASE_ACK
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel]1 T38 REINVITE TRUE
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] CAC = 0 eqtCAC = -1
Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] cacUsed=0 eqtCac=-1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583831 -> Mon Jan 13 09:00:31 2014 113d[emitEventToMonitel] displayIPCMessageOut
Mon Jan 13 09:00:31 2014 ** SIP TRUNKING **
Mon Jan 13 09:00:31 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:31 2014 [sendLgEvtSip] Event sent on eqt : 7433 Id :-1
Mon Jan 13 09:00:31 2014 [display_ipc_out] ------------ Begin ---------------
Mon Jan 13 09:00:31 2014 Id : -1
Mon Jan 13 09:00:31 2014 SIP_EQT_RELEASE_ACK
Mon Jan 13 09:00:31 2014 [display_ipc_out] ------------- End ----------------
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
Mon Jan 13 09:00:31 2014 113d[CMotorCall::unRegister] Remove eqt : 7433 diag : -1 from the map.
Mon Jan 13 09:00:31 2014 [CMotorCallManager::eraseCallwithEqt] erase 7433 -1.
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onIncomingEvent] killSession.
Mon Jan 13 09:00:31 2014 113d [CCall::killSession]
Mon Jan 13 09:00:31 2014 2151 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Mon Jan 13 09:00:31 2014 2151 [CTransaction::changeState] no transaction state, terminated reason : None
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Mon Jan 13 09:00:31 2014 2153 [CTransaction::freeTimerToken] Timer K is freed
Mon Jan 13 09:00:31 2014 2153 [CTransaction::changeState] no transaction state, terminated reason : None
Mon Jan 13 09:00:31 2014 113d [CCall::killSession] notifying the call manager
Mon Jan 13 09:00:31 2014  101 [CCallManager::onCallClosed]
Mon Jan 13 09:00:31 2014  101 [CCallManager::onCallClosed()] Call 113d closed. Removing call 139436128 from the maps
1389583831 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583832 -> Mon Jan 13 09:00:31 2014 [CMotorCallManager::onNotifyCallClosed] Call : 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 is closed.
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onNotifyCallClosed] Call : 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 is closed.
Mon Jan 13 09:00:31 2014 [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
Mon Jan 13 09:00:31 2014  101 [CCallManager::onCallClosed] pCall added in the Garbage List
Mon Jan 13 09:00:31 2014 [exec_ipc] in end 1770.
Mon Jan 13 09:00:32 2014 [CTransaction::notifyDialog]
Mon Jan 13 09:00:32 2014 [CTransaction::notifyDialog] notify parent dialog
Mon Jan 13 09:00:32 2014 1558 [CDialog::onTransactionState(pTrans = 2151, previousState = null, currentState = Terminated, reason = None]
Mon Jan 13 09:00:32 2014 1558 [CDialog::onTransactionState] dialog is terminated
Mon Jan 13 09:00:32 2014 1558 [CDialog::onTransactionState] onDialogClosed
Mon Jan 13 09:00:32 2014 113d [CCall::onDialogClosed]
Mon Jan 13 09:00:32 2014 113d [CCall::onDialogClosed] not only initial *********
Mon Jan 13 09:00:32 2014 113d [CCall::onDialogClosed]: the dialog 1559 is NOT closed
Mon Jan 13 09:00:32 2014 2151 [~CTransaction] transaction 1352281776 INVITE
Mon Jan 13 09:00:32 2014 84ED [~CResponse] resp(183) 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281776 INVITE
Mon Jan 13 09:00:32 2014 [CTransaction::notifyDialog]
Mon Jan 13 09:00:32 2014 [CTransaction::notifyDialog] notify parent dialog
Mon Jan 13 09:00:32 2014 1559 [CDialog::onTransactionState(pTrans = 2153, previousState = null, currentState = Terminated, reason = None]
1389583832 -> Mon Jan 13 09:00:32 2014 113d [CCall::onDialogClosed]
Mon Jan 13 09:00:32 2014 113d [CCall::onDialogClosed] not only initial *********
Mon Jan 13 09:00:32 2014 113d [CCall::onDialogClosed]: the dialog 1559 is closed
Mon Jan 13 09:00:32 2014 113d [CCall::onDialogClosed] All the dialogs in the Call are closed
Mon Jan 13 09:00:32 2014 2153 [~CTransaction] transaction 1352281777 BYE
Mon Jan 13 09:00:32 2014 84EF [~CResponse] resp(200) 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281777 BYE
Mon Jan 13 09:00:32 2014  101 [CCallManager::removeGarbage] at index i = 0
Mon Jan 13 09:00:32 2014 113d [CMotorCall::clear_subscription] no subscription managed
Mon Jan 13 09:00:32 2014 113d[CMotorCall::~CMotorCall] Call : 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 is being destructed.
Mon Jan 13 09:00:32 2014 113d[CMotorCall::~CMotorCall] License is released for this call.
Mon Jan 13 09:00:32 2014 113d[CMotorCall::~CMotorCall] number of available licenses are =26 .
Mon Jan 13 09:00:32 2014 113d [CCall::~CCall]
Mon Jan 13 09:00:32 2014 113d [CCall::removeGarbage]
Mon Jan 13 09:00:32 2014 1559 [CDialog::~CDialog]
Mon Jan 13 09:00:32 2014 1559 [~CDialog] delete ack request # 0
Mon Jan 13 09:00:32 2014 6E9E [~CRequest] req 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281776 ACK
Mon Jan 13 09:00:32 2014 84EE [~CResponse] resp(200) 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281776 INVITE
Mon Jan 13 09:00:32 2014 1558 [CDialog::~CDialog]
Mon Jan 13 09:00:32 2014 [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
1389583833 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583833 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583833 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583833 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583833 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583834 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583834 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583834 -> EAu: sent_msgs_no_ack: 1 / dl_connect->window: 1
1389583836 -> Mon Jan 13 09:00:32 2014 6E9D [~CRequest] req 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281776 INVITE
Mon Jan 13 09:00:32 2014 6E9F [~CRequest] req 854142a2ddc337dff7632958ded6fbc1@10.203.228.9 1352281777 BYE

Thanks,

Suresh

Could you please check with Alcatel PBX whether there  is any setting/service parameter to display only the original dialed number in the PBX irrespective of call state?

May be, you can check the Display/ASCII Display & Alerting/ASCII Alerting Name fields of the Cisco Phone though it is only for internal cisco calls, the extn number might have been passed over sip trunk as a caller ID name.

The SIP trace should be able to tell us how the 4 digit extension is passed to PBX over SIP Trunk.

Please rate all the useful posts

//Suresh Please rate all the useful posts.

Hi Suresh, If we register a softphone in Cisco SME and when Alcatel user dial that soft phone extension then they are able to see all 7 digits.I have attached SIP trace of alcatel callmanager. I will post these CUCM sip trace in this weeken after call testing.

Yes that is expected because the 7 digit extension exist on the SME. You strip the site code on SME before sending the call to CUCM. That suggests that on CUCM you have a 4 digit extension. I dont see how this is possible unless you are able to retain the called number in the PBX.

Please rate all useful posts

"The essence of christianity is not the enthronement but the obliteration of self --William Barclay"

Please rate all useful posts

in that case, I would suggest you to crosscheck the SIP Trunk settings between SME & CUCM.

The field '' decided whether or not the CUCM sends the called party number to calling party over SIP Trunk. The default value for Connected Line ID Presentation specifies Default, which  translates to Allowed.

You may select this as 'Resrticted' in the CUCM to SME SIP Trunk and check the behaviour.

Please rate all the useful posts

//Suresh Please rate all the useful posts.

Thank you Suresh, I will try this on coming Sunday and lets see. Meanwhile could you please tell me what is meaning of "

Redirecting Diversion Header Delivery_Inbound" actually this parameter is currently unchecked at SIP trunk for SME->CUCM

Redirecting Diversion Header Delivery - Inbound

Check this check box to accept the Redirecting Number in the  incoming INVITE message to the Cisco Unified Communications Manager.

Uncheck the check box to exclude the Redirecting Number in the  incoming INVITE message to the Cisco Unified Communications Manager.

You use Redirecting Number for voice-messaging integration only.  If your configured voice-messaging system supports Redirecting Number, you  should check the check box.

The default value for Redirecting Number IE Deliver - Inbound  specifies not checked.

Please rate all the useful posts

//Suresh Please rate all the useful posts.

Suresh Hudda
VIP Alumni
VIP Alumni

Got the issue resolved :-)

I have made below changes in SIP trunk which is pointing to Alcatel and now they can see entire seven digits number.

Uncheck "Remote-Party-Id" & "Asserted-Identity".

But I want to know what exaclty these parameters do or why it creating the problem ? can somone tell me the eaxct concept behind it.