собственно сам трейс описанного звонка


1392649124 -> RECEIVE MESSAGE FROM NETWORK (172.27.2.16:5060 [UDP])
----------------------utf8-----------------------
INVITE sip:1111@172.27.3.14:5060 SIP/2.0

Max-Forwards: 70

Via: SIP/2.0/UDP 172.27.2.16:5060;branch=z9hG4bK-11286538521

From: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

To: <sip:1111@172.27.3.14:5060>

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 11 INVITE

Contact: <sip:77019290234@172.27.2.16:5060>

User-Agent: 2N VoiceBlueNext 1.5.1.7.2

Allow: INVITE,BYE,ACK,CANCEL,OPTIONS,REFER,NOTIFY

Content-Type: application/sdp

Content-Length: 242



v=0

o=VoiceBlueNext 44079 16056 IN IP4 172.27.3.1

s=GSM Call

c=IN IP4 172.27.3.1

t=0 0

a=sendrecv

m=audio 10270 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:102 telephone-event/8000

a=fmtp:102 0-11

a=sendrecv

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

1392649124 -> 5B [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1392649124 -> [CIOCom::receiveRequest] Received CRequest 5B ref=1
1392649124 -> [CIOCom::checkHostInBlackList] host=172.27.2.16
1392649124 -> [CIOCom::checkHostInBlackList] host=172.27.2.16
1392649124 -> [CIOCom::receiveRequest] rport1 not empty
1392649124 -> [CDispatcher::onIncomingRequest()]
1392649124 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1392649124 ->  102 [CCallManager::onIncomingRequest()]
1392649124 -> [CMotorCallManager::callFactory] For call : 7c1eb3fe026d-7-11143b19.
1392649124 -> [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R9.1 i1.605.12
1392649124 -> [CMotorCallManager::callFactory]=857a550.
1392649124 -> 1139 [CCall::receiveRequest] INVITE
1392649124 -> 1139 [CCall::getDialog] Confirmed Dialog is not found (ID = ;7c1eb3fe026d-10-11143b19)
1392649124 -> 1139 [CCall::getDialog] Initial Dialog Server not found
1392649124 -> 153f [CDialog::receiveRequest]
1392649124 -> 153f [CDialog::receiveInviteRequest]
1392649124 -> 2150 [CTransaction::changeState] STATE CHANGED TO INITIAL
1392649124 -> 2150 [CTransaction::changeState] notifying the parent dialog
1392649124 -> 153f [CDialog::onTransactionState(pTrans = 2150, previousState = Terminated, currentState = Initial, reason = None]
1392649124 -> 2150 [CTransInitialState::receiveRequest] Transaction changes to Proceeding state
1392649124 -> 2150 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1392649124 -> 2150 [CTransaction::changeState] notifying the parent dialog
1392649124 -> 153f [CDialog::onTransactionState(pTrans = 2150, previousState = Initial, currentState = Proceeding, reason = Request reception]
1392649124 -> 2150 [CTransProceedingState::createResponse] CResponse 60 ref=1
1392649124 -> 60 [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649124 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 258)
----------------------utf8-----------------------
SIP/2.0 100 Trying

To: <sip:1111@172.27.3.14:5060>

From: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 11 INVITE

Via: SIP/2.0/UDP 172.27.2.16:5060;branch=z9hG4bK-11286538521

Content-Length: 0



-------------------------------------------------
1392649124 -> 153f [CDialog::receiveInviteRequest] a new Server INVITE transaction is in progress
1392649124 -> [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Server, UA)
1392649124 -> [CSessionTimerContext::updateAfterRefreshReception] Update CSessionTimerContext (refresh reception)
1392649124 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 0
1392649124 -> [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=INVITE
1392649124 -> 153f [CDialog::addTransaction] added transaction 2150 with branch z9hG4bK-11286538521, with CSeq 11
1392649124 -> 1139 [CCall::checkAuthentication] INVITE
1392649124 -> 1139[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1392649124 -> [CGwMngDat a::isAuthenticationRequiredForThisClient] 172.27.3.14 .
1392649124 -> [isDomainFromGwExt] Host from request is : 172.27.3.14.
1392649124 -> [isDomainFromGwExt] User from request is : 77019290234
1392649124 -> [domain not from an External Gateway.
1392649124 -> 1139[CMotorCall::setFilterUsedMode] To be traced = 0
1392649124 -> [getFromHeader].
1392649124 -> [isUserDeclared] 77019290234@172.27.3.14
1392649124 -> isUserDeclared] NOT found in the dictionnary.
1392649124 -> [isSeplosStation] other station
1392649124 -> 1139[CMotorCall::toGatewayOrProxy] request for proxydomain=172.27.3.14.
1392649124 -> [isUserRegistred] getRegistration=1111.
1392649124 -> [isUserRegistred] result=-1.
1392649124 -> 1139[CMotorCall::onReceiveRequest] request for gateway.
1392649124 -> 1139[CMotorCall::onReceiveRequest] state = INITIAL_STATE.
1392649124 -> 1139[CMotorCall::methodInviteReceived] nb available licenses=10 .
1392649124 -> [isUserDeclared] 77019290234@172.27.3.14
1392649124 -> isUserDeclared] NOT found in the dictionnary.
1392649124 -> 1139[CMotorCall::compareDialogFromContext] presumed to be the same Dialog
1392649124 -> 1139[CMotorCall::methodInviteReceived] Invite => update the inviteContext.
1392649124 -> [CForkedBranch::CForkedBranch] context = 0x857a980.
1392649124 -> 1139[CMotorCall::methodInviteReceived] reqUri stored: sip:77019290234@172.27.2.16:5060
1392649124 ->         [CForkedBranch::reqUriDuplication] reqUri :sip:77019290234@172.27.2.16:5060
1392649124 -> 1139[CMotorCall::methodInviteReceived] CAC = 0
1392649124 -> [receiveInviteMessage] Call: 7c1eb3fe026d-7-11143b19 eqt: -1 INITIAL_STATE received a message.
1392649124 -> [decodeInviteCLIR].
1392649124 -> [isDomainFromGwExt] Host from request is : 172.27.3.14.
1392649124 -> [isDomainFromGwExt] User from request is : 77019290234
1392649124 ->  am_I_PCS SCSWorking : NO
1392649124 ->  check_PCSstate
1392649124 ->  am_I_PCS SCSWorking : NO
1392649124 ->  OXE contains PCS configuration
1392649124 ->  check_PCSstate get the address of the user
1392649124 -> 1139[CMotorCall::addrToMediaInit] cac case.
1392649124 -> [isUserRegistred] getRegistration=77019290234.
1392649124 -> [isUserRegistred] try with alias.
1392649124 -> [getUrlByAlias] user=77019290234 domain=172.27.3.14.
1392649124 -> [getUrlByAlias] NOK
1392649124 -> [isUserRegistred] result=-1.
1392649124 -> [convertIPStrIntoTuipv] 172.27.3.14 => 172.27.3.14
1392649124 -> [isIpAddressType] .¬.
1392649124 -> 1139[CMotorCall::addrToMediaInit] S_OK.
1392649124 ->  check_PCSstate we have found the ip if user
1392649124 ->  check_PCSstate toAddress [0]= 172
1392649124 ->  check_PCSstate toAddress [1]= 27
1392649124 ->  check_PCSstate toAddress [2]= 3
1392649124 ->  check_PCSstate toAddress [3]= 14
1392649124 ->  check_PCSstate toAddress [172.27.3.14]
1392649124 ->  check_PCSstate user IP is Found ? = YES
1392649124 ->    The recevied host 172.27.3.14
1392649124 ->  Trying to find the ip address in domain list  
1392649124 ->  The entry dom : 1 add_type=1
1392649124 ->  The entry dom ip low :172.27.2.15
1392649124 ->  The entry ipaddress from low :172.27.3.14
1392649124 ->  The entry compare :-1
1392649124 ->  The entry compare 2 :0
1392649124 ->  iplink_is_good_range_for_reg
1392649124 ->  The user is ipadd  not in any Domain range return state as -1
1392649124 ->  check_PCSstate stat of PCS  is : -1
1392649124 -> [isSessionTimerTooSmall].
1392649124 -> 1139[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=0 uacRefresher=0.
1392649124 -> [decodeInviteSDP].
1392649124 -> [checkSdpValidity] Sdp contains 1 media description(s).
1392649124 -> [checkSdpValidity] Media 0 type 1 contains 2 formats.
1392649124 -> [checkSdpValidity] Format : 18.
1392649124 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1392649124 -> [convertSdpIntoTsdp] No Direction in the session part.
1392649124 -> [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1392649124 -> [convertSdpIntoTsdp] The audio media contains 2 format(s).
1392649124 -> [convertSdpIntoTsdp] Format 0 is 18.
1392649124 -> [convertSdpIntoTsdp] Format 1 is 102.
1392649124 -> [convertSdpIntoTsdp] Get the direction.
1392649124 -> [convertSdpIntoTsdp] Direction is sendrecv.
1392649124 -> [convertSdpIntoTsdp] The connection is at session level.
1392649124 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 172.27.3.1.
1392649124 -> [convertIPStrIntoTuipv] 172.27.3.1 => 172.27.3.1
1392649124 -> [receiveInviteMessage] NO 100rel REQUIRED for this call.
1392649124 -> [isCACWithInvalidMediaAddr].
1392649124 -> [decodeInviteTo].
1392649124 -> [decodeInviteReferredBy].
1392649124 -> [decodeInviteACSBU].
1392649124 -> 1139[CMotorCall::setFilterUsedMode] To be traced = 0
1392649124 -> 1139[emitEventToMonitel] content size (lginfo): 656
1392649124 -> [ipc_thread] IPC Thread : Ipc reception 28290.
1392649124 -> [ipc_thread] IPC Thread : TCL thread signaled.
1392649124 -> [CMotorCallManager::insertCallwithEqt] CMotorCall 1718 inserted.
1392649124 -> 1139[sendLgEvtSipCreate] Event sent on eqt : 1718
1392649124 -> [exec_ipc] in 28289.
1392649124 -> [display_ipc_in] ------------ Begin ---------------
1392649124 -> neqt : 1718 Id : -1
1392649124 -> INFORMATIONAL
1392649124 -> xx :       80
1392649124 -> RELATIVE REQUEST : INVITE
1392649124 -> SDP :
1392649124 ->   ADDRESS : 172.27.2.17 :32568
1392649124 ->   ALGOS :
1392649124 ->     G729
1392649124 ->     102
1392649124 ->   DIRECTION : SEND & RECEIVE
1392649124 -> [display_ipc_in] ------------- End ----------------
1392649124 -> [CMotorCallManager::onIncomingEvent] an event 10764 arrived on the eqt 1718--1.
1392649124 -> [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1392649124 -> [onIncomingEvent] INFORMATIONAL arrived.
1392649124 -> [receiveInformationalEvent] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 PROCEEDING_STATE received an event.
1392649124 -> 1139[CMotorCall::inviteBuildContact] Contact: sip:172.27.3.14
1392649124 -> 1139[CMotorCall::makeResponseSdp] Begin sdp : v=0

o=OXE 1392649124 1392649124 IN IP4 172.27.3.14

s=-

c=IN IP4 0.0.0.0

t=0 0

.
1392649124 -> 1139[CMotorCall::makeResponseSdp] Audio media.
1392649124 -> 1139[CMotorCall::makeResponseSdp] format 102
1392649124 -> 1139[CMotorCall::makeResponseSdp] fromSdp.getMediaDesciprionCount :1
1392649124 -> 1139[CMotorCall::makeResponseSdp] Media accepted 0 : v=0

o=OXE 1392649124 1392649124 IN IP4 172.27.3.14

s=-

c=IN IP4 0.0.0.0

t=0 0

.
1392649124 -> 1139[CMotorCall::makeResponseSdp] Final Sdp: v=0

o=OXE 1392649124 1392649124 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv

.
1392649124 -> 1139[CMotorCall::emitInformationalMessage] Answer sdp: v=0

o=OXE 1392649124 1392649124 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv


1392649124 -> 1139 [CCall::makeGenericResponse] 180
1392649124 -> 1139 [CCall::getDialog] Confirmed Dialog is not found (ID = ;7c1eb3fe026d-10-11143b19)
1392649124 -> 1139 [CCall::getDialog] Returning Initial Dialog Server
1392649124 -> 153f [CDialog::createResponse] 180
1392649124 -> 2150 [CTransProceedingState::createResponse] CResponse 61 ref=1
1392649124 -> 61 [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649124 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 715)
----------------------utf8-----------------------
SIP/2.0 180 Ringing

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

Contact: sip:172.27.3.14

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

Content-Type: application/sdp

To: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

From: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 11 INVITE

Via: SIP/2.0/UDP 172.27.2.16:5060;branch=z9hG4bK-11286538521

Content-Length: 237



v=0

o=OXE 1392649124 1392649124 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv

-------------------------------------------------
1392649124 -> 60 [~CResponse] resp(100) 7c1eb3fe026d-7-11143b19 11 INVITE
1392649124 -> 153f [CDialog::updateRouteSet] there are 0 record-route headers
1392649124 -> [exec_ipc] in end 28289.
1392649126 ->  main.trace.timer = 480mn
1392649129 -> [ipc_thread] IPC Thread : Ipc reception 28290.
1392649129 -> [ipc_thread] IPC Thread : TCL thread signaled.
1392649129 -> [exec_ipc] in 28289.
1392649129 -> [display_ipc_in] ------------ Begin ---------------
1392649129 -> neqt : 1718 Id : -1
1392649129 -> SUCCESSFUL
1392649129 -> xx :        0
1392649129 -> RELATIVE REQUEST : INVITE
1392649129 -> CLIR : 0
1392649129 -> COLP : 1
1392649129 -> SDP :
1392649129 ->   ADDRESS : 172.27.2.17 :32568
1392649129 ->   ALGOS :
1392649129 ->     G729
1392649129 ->     102
1392649129 ->   DIRECTION : SEND & RECEIVE
1392649129 -> [display_ipc_in] ------------- End ----------------
1392649129 -> [CMotorCallManager::onIncomingEvent] an event 10765 arrived on the eqt 1718--1.
1392649129 -> [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1392649129 -> [onIncomingEvent] SUCCESSFUL arrived.
1392649129 -> [onIncomingEvent] relative_request = 10752
1392649129 -> [CProceedingState::receiveSuccessfulEvent] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 PROCEEDING_STATE received an event.
1392649129 -> [quoteString] => "Viktor Dooll"
1392649129 -> 1139[CMotorCall::emitSuccessfulMessage] P-Asserted-Identity: "Viktor Dooll" <sip:1111@172.27.3.14;user=phone>
1392649129 -> 1139[CMotorCall::inviteBuildContact] Contact: sip:172.27.3.14
1392649129 -> 1139[CMotorCall::emitSuccessfullMessage] uacGatewayMode=0 uacRefresher=0.
1392649129 -> 1139[CMotorCall::makeResponseSdp] Begin sdp : v=0

o=OXE 1392649124 1392649125 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv

.
1392649129 -> 1139[CMotorCall::makeResponseSdp] Audio media.
1392649129 -> 1139[CMotorCall::makeResponseSdp] format 102
1392649129 -> 1139[CMotorCall::makeResponseSdp] fromSdp.getMediaDesciprionCount :1
1392649129 -> 1139[CMotorCall::makeResponseSdp] Media accepted 0 : v=0

o=OXE 1392649124 1392649125 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv

.
1392649129 -> 1139[CMotorCall::makeResponseSdp] Final Sdp: v=0

o=OXE 1392649124 1392649125 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv

.
1392649129 -> 1139[CMotorCall::emitSuccesfulMessage] Answer sdp: v=0

o=OXE 1392649124 1392649125 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv


1392649129 -> 1139 [CCall::makeGenericResponse] 200
1392649129 -> 1139 [CCall::getDialog] Confirmed Dialog is not found (ID = 25daee7629453dc0f488d19759b53801;7c1eb3fe026d-10-11143b19)
1392649129 -> 1139 [CCall::getDialog] Returning Initial Dialog Server
1392649129 -> 153f [CDialog::createResponse] 200
1392649129 -> 153f [CDialog::createResponse] create a CONFIRMED dialog
1392649129 -> 1540 [CDialog::CDialog] look for the transaction #0, transaction key = z9hG4bK-11286538521
1392649129 -> 1540 [CDialog::CDialog] copy the transaction #0, transaction key = z9hG4bK-11286538521
1392649129 -> 2151 [CTransaction::CTransaction] Transaction is cloned in 4 state
1392649129 -> 1540 [CDialog::addTransaction] added transaction 2151 with branch z9hG4bK-11286538521, with CSeq 11
1392649129 -> 1540 [CDialog::startDialogTTL]
1392649129 -> 1540 [CDialog::createResponse] 200
1392649129 -> 2151 [CTransProceedingState::createResponse] CResponse 62 ref=1
1392649129 -> 62 [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649129 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 852)
----------------------utf8-----------------------
SIP/2.0 200 OK

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

Contact: sip:172.27.3.14

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

Session-Expires: 1800;refresher=uas

P-Asserted-Identity: "Viktor Dooll" <sip:1111@172.27.3.14;user=phone>

Content-Type: application/sdp

To: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

From: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 11 INVITE

Via: SIP/2.0/UDP 172.27.2.16:5060;branch=z9hG4bK-11286538521

Content-Length: 237



v=0

o=OXE 1392649124 1392649125 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv

-------------------------------------------------
1392649129 -> 2151 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
1392649129 -> 2151 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1392649129 -> 2151 [CTransaction::changeState] notifying the parent dialog
1392649129 -> 1540 [CDialog::onTransactionState(pTrans = 2151, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
1392649129 -> 2151 [CTransaction::startTimer] Timer G is started (delay = 500 ms)
1392649129 -> 2151 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1392649129 -> 1540 [CDialog::updateRouteSet] there are 0 record-route headers
1392649129 -> [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from response (Server, UA)
1392649129 -> [CSessionTimerContext::updateAfterRefreshReception] Update CSessionTimerContext (refresh reception)
1392649129 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 0
1392649129 -> [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=INVITE
1392649129 -> [CSessionTimerContext::updateAfterResponseRefreshCreation] Update CSessionTimerContext (response refresh creation)
1392649129 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1392649129 -> [CSessionTimerContext::updateSessionExpires] updated
1392649129 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1392649129 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1392649129 -> [CSessionTimerContext::startTimers] Start session timer expiration : 1800000
1392649129 -> [CSessionTimerContext::startTimers] Start session timer will expires : 900000
1392649129 -> 1139 [CCall::insertInDialogMap] added dialog #1540 with the key 25daee7629453dc0f488d19759b53801;7c1eb3fe026d-10-11143b19

1392649129 -> 153f [CDialog::updateRouteSet] there are 0 record-route headers
1392649129 -> [CProceedingState::receiveSuccessfulEvent] emitSuccessfulMessage OK.
1392649129 -> [exec_ipc] in end 28289.
1392649129 -> RECEIVE MESSAGE FROM NETWORK (172.27.2.16:5060 [UDP])
----------------------utf8-----------------------
ACK sip:1111@172.27.3.14:5060 SIP/2.0

Max-Forwards: 70

Via: SIP/2.0/UDP 172.27.2.16:5060;branch=z9hG4bK-11286538521

From: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

To: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 11 ACK

Contact: <sip:77019290234@172.27.2.16:5060>

User-Agent: 2N VoiceBlueNext 1.5.1.7.2

Allow: INVITE,BYE,ACK,CANCEL,OPTIONS,REFER,NOTIFY

Content-Length: 0



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

1392649129 -> 5C [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1392649129 -> [CIOCom::receiveRequest] Received CRequest 5C ref=1
1392649129 -> [CIOCom::checkHostInBlackList] host=172.27.2.16
1392649129 -> [CIOCom::checkHostInBlackList] host=172.27.2.16
1392649129 -> [CIOCom::receiveRequest] rport1 not empty
1392649129 -> [CDispatcher::onIncomingRequest()]
1392649129 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1392649129 ->  102 [CCallManager::onIncomingRequest()]
1392649129 -> 1139 [CCall::receiveRequest] ACK
1392649129 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649129 -> 1540 [CDialog::receiveRequest]
1392649129 -> 1540 [CDialog::receiveAckRequest]
1392649129 -> 2151 [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
1392649129 -> 2151 [CTransaction::changeState] STATE CHANGED TO CONFIRMED
1392649129 -> 2151 [CTransaction::changeState] notifying the parent dialog
1392649129 -> 1540 [CDialog::onTransactionState(pTrans = 2151, previousState = Completed, currentState = Confirmed, reason = Request reception]
1392649129 -> 2151 [CTransaction::freeTimerToken] Timer G is freed
1392649129 -> 2151 [CTransaction::freeTimerToken] Timer H is freed
1392649129 -> 2151 [CTransaction::startTimer] Timer I is started (delay = 5000 ms)
1392649129 -> 1540 [CDialog::receiveAckRequest] receiving an ACK with the same branch as the accepted INVITE
1392649129 -> 1139 [CCall::checkAuthentication] ACK
1392649129 -> 1139[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1392649129 -> 1139[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1392649129 -> [getFromHeader].
1392649129 -> [isUserDeclared] 77019290234@172.27.3.14
1392649129 -> isUserDeclared] NOT found in the dictionnary.
1392649129 -> [isSeplosStation] other station
1392649129 -> 1139[CMotorCall::toGatewayOrProxy] request for proxydomain=172.27.3.14.
1392649129 -> [isUserRegistred] getRegistration=1111.
1392649129 -> [isUserRegistred] result=-1.
1392649129 -> 1139[CMotorCall::onReceiveRequest] request for gateway.
1392649129 -> 1139[CMotorCall::onReceiveRequest] state = ACCEPTED_STATE.
1392649129 -> 1139[CMotorCall::compareDialogFromContext] same Dialog
1392649129 -> 1139[CMotorCall::methodAckReceived] Ack => update the inviteContext.
1392649129 -> [receiveAckMessage] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 ACCEPTED_STATE received a message.
1392649129 -> [receiveAckMessage] g711u: 255 g711A: 255 g723: 255 g729: 255.
1392649129 -> 1139[emitEventToMonitel] content size (lginfo): 656
1392649129 -> 1139[emitEventToMonitel] CAC = 0 eqtCAC = -1
1392649129 -> 1139[emitEventToMonitel] CAC = 0
1392649129 -> [sendLgEvtSip] Event sent on eqt : 1718 Id :-1
1392649129 -> [display_ipc_out] ------------ Begin ---------------
1392649129 -> Id : -1
1392649129 -> ACK
1392649129 -> [display_ipc_out] ------------- End ----------------
1392649129 -> 1139 [CCall::getCallDuplicationData]
1392649129 -> 1139 [CCall::getCallDuplicationData] : Request URI = , Call ID = 7c1eb3fe026d-7-11143b19, Local URI = sip:1111@172.27.3.14:5060, Local Tag = 25daee7629453dc0f488d19759b53801, Remote URI = sip:77019290234@172.27.3.14:5060, Remote Tag = 7c1eb3fe026d-10-11143b19, Local CSeq = 1439058296, Remote CSeq = 11
1392649129 -> [CViaSetSerializer::serialize] Serialized via set :
1392649129 -> [CRouteSet::serialize] Serialized route set : Contact=sip:77019290234@172.27.2.16:5060
1392649134 -> 2151 [CTransConfirmedState::timerFires] Timer I fires
1392649134 -> 2151 [CTransConfirmedState::timerFires] TimerI : Transaction changes to Terminated state
1392649134 -> 2151 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1392649134 -> 2151 [CTransaction::changeState] no transaction state, terminated reason : Timer I fires
1392649134 -> [CTransaction::notifyDialog]
1392649134 -> [CTransaction::notifyDialog] notify parent dialog
1392649134 -> 1540 [CDialog::onTransactionState(pTrans = 2151, previousState = null, currentState = Terminated, reason = Timer I fires]
1392649134 -> 2151 [~CTransaction] transaction 11 INVITE
1392649134 -> 5C [~CRequest] req 7c1eb3fe026d-7-11143b19 11 ACK
1392649136 -> [ipc_thread] IPC Thread : Ipc reception 28290.
1392649136 -> [ipc_thread] IPC Thread : TCL thread signaled.
1392649136 -> [exec_ipc] in 28289.
1392649136 -> [display_ipc_in] ------------ Begin ---------------
1392649136 -> neqt : 1718 Id : -1
1392649136 -> RE_INVITE
1392649136 -> SDP :
1392649136 ->   ADDRESS : 172.27.3.15 :32624
1392649136 ->   ALGOS :
1392649136 ->     G729
1392649136 ->     102
1392649136 ->   DIRECTION : SEND ONLY
1392649136 -> [display_ipc_in] ------------- End ----------------
1392649136 -> [CMotorCallManager::onIncomingEvent] an event 10753 arrived on the eqt 1718--1.
1392649136 -> [CMotorCallManager::onIncomingEvent] state = COMPLETED_STATE.
1392649136 -> [onIncomingEvent] RE_INVITE arrived.
1392649136 -> [receiveReInviteEvent] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 COMPLETED_STATE received an event.
1392649136 -> 1139[emitReInviteMessage]-->SIP_INFO_allowed=0
1392649136 -> 1139[CMotorCall::emitReInviteMessage] uacGatewayMode=1 uacRefresher=1.
1392649136 -> 1139[CMotorCall::inviteBuildContact] Contact: sip:172.27.3.14
1392649136 -> 1139[CMotorCall::makeRequestSdp] Begin sdp : v=0

o=OXE 1392649124 1392649126 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32568 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

a=sendrecv

.
1392649136 -> 1139[CMotorCall::makeRequestSdp] Direction: 1.
1392649136 -> 1139[CMotorCall::makeResponseSdp] format 102
1392649136 -> 1139[CMotorCall::makeRequestSdp] Final sdp: v=0

o=OXE 1392649124 1392649126 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.3.15

t=0 0

m=audio 32624 RTP/AVP 18 102

a=sendonly

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

.
1392649136 -> 1139[CMotorCall::emitReInviteMessage] The sdp build: .
1392649136 -> 1139[CMotorCall::searchForkedBranch]pForkedBranch=0x857e718.
1392649136 -> 1139[CMotorCall::emitReInviteMessage] reqUri got :sip:77019290234@172.27.2.16:5060
1392649136 -> 1139[CMotorCall::emitReInviteMessage] continue.
1392649136 -> 1139 [CCall::makeGenericRequest] INVITE
1392649136 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649136 -> 1540 [CDialog::createRequest]
1392649136 -> 1540 [CDialog::buildServicesForAllRequest]
1392649136 -> 1540 [CDialog::createInviteRequest]
1392649136 -> 2152 [CTransaction::changeState] STATE CHANGED TO INITIAL
1392649136 -> 2152 [CTransaction::changeState] notifying the parent dialog
1392649136 -> 1540 [CDialog::onTransactionState(pTrans = 2152, previousState = Terminated, currentState = Initial, reason = None]
1392649136 -> 5D [CRequest::CRequest] Creation of a request
1392649136 -> 5D [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649136 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 864)
----------------------utf8-----------------------
INVITE sip:77019290234@172.27.2.16:5060 SIP/2.0

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

Contact: sip:172.27.3.14

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

Session-Expires: 1800;refresher=uac

Min-SE: 900

Content-Type: application/sdp

To: sip:77019290234@172.27.3.14:5060;tag=7c1eb3fe026d-10-11143b19

From: sip:1111@172.27.3.14:5060;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058297 INVITE

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bK31a71e527429b303fd535fe488bf45d6

Max-Forwards: 70

Content-Length: 237



v=0

o=OXE 1392649124 1392649126 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.3.15

t=0 0

m=audio 32624 RTP/AVP 18 102

a=sendonly

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

-------------------------------------------------
1392649136 -> 2152 [CTransInitialState::createRequest] Transaction changes to Calling state
1392649136 -> 2152 [CTransaction::changeState] STATE CHANGED TO CALLING
1392649136 -> 2152 [CTransaction::changeState] notifying the parent dialog
1392649136 -> 1540 [CDialog::onTransactionState(pTrans = 2152, previousState = Initial, currentState = Calling, reason = Request creation]
1392649136 -> 2152 [CTransaction::startTimer] Timer A is started (delay = 500 ms)
1392649136 -> 2152 [CTransaction::startTimer] Timer B is started (delay = 8000 ms)
1392649136 -> 1540 [CDialog::addTransaction] added transaction 2152 with branch z9hG4bK31a71e527429b303fd535fe488bf45d6, with CSeq 1439058297
1392649136 -> [CSessionTimerContext::updateAfterRefreshCreation] Update CSessionTimerContext (refresh creation)
1392649136 -> [CSessionTimerContext::updateSupported] Request timer supported
1392649136 -> [CSessionTimerContext::updateMinSE] Min-SE updated : 900
1392649136 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1392649136 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1392649136 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1392649136 -> [CForkedBranch::CForkedBranch] context = 0x857a980.
1392649136 -> 1139[CMotorCall::emitReInviteMessage]Request built branch=1.
1392649136 ->         [CForkedBranch::reqUriDuplication] reqUri :sip:77019290234@172.27.2.16:5060
1392649136 -> [exec_ipc] in end 28289.
1392649136 -> RECEIVE MESSAGE FROM NETWORK (172.27.2.16:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK

Max-Forwards: 70

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bK31a71e527429b303fd535fe488bf45d6

From: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

To: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058297 INVITE

Contact: <sip:77019290234@172.27.2.16:5060>

User-Agent: 2N VoiceBlueNext 1.5.1.7.2

Allow: INVITE,BYE,ACK,CANCEL,OPTIONS,REFER,NOTIFY

Content-Type: application/sdp

Content-Length: 242



v=0

o=VoiceBlueNext 44079 16056 IN IP4 172.27.3.1

s=GSM Call

c=IN IP4 172.27.3.1

t=0 0

a=sendrecv

m=audio 10272 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:102 telephone-event/8000

a=fmtp:102 0-11

a=recvonly

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

1392649136 -> [CIOCom::receiveResponse] CResponse 63 ref=1
1392649136 -> [CDispatcher::onIncomingResponse]
1392649136 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1392649136 ->  102 [CCallManager::onIncomingResponse]
1392649136 -> 1139 [CCall::receiveResponse] 200
1392649136 -> 1139 [CCall::receiveResponse]Trying to find the right dialog
1392649136 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649136 -> 1139 [CCall::receiveResponse]One dialog found
1392649136 -> 1540 [CDialog::receiveResponse]
1392649136 -> 2152 [CTransCallingState::receiveResponse] Final : Transaction changes to Completed state
1392649136 -> 2152 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1392649136 -> 2152 [CTransaction::changeState] notifying the parent dialog
1392649136 -> 1540 [CDialog::onTransactionState(pTrans = 2152, previousState = Calling, currentState = Completed, reason = Final resp reception]
1392649136 -> 2152 [CTransaction::freeTimerToken] Timer A is freed
1392649136 -> 2152 [CTransaction::freeTimerToken] Timer B is freed
1392649136 -> 2152 [CTransaction::startTimer] Timer D is started (delay = 32064 ms)
1392649136 -> [CSessionTimerContext::updateAfterResponseRefreshReception] Update CSessionTimerContext (response refresh reception)
1392649136 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1392649136 -> [CSessionTimerContext::startTimers] Start session timer expiration : 1800000
1392649136 -> [CSessionTimerContext::startTimers] Start session timer will expires : 900000
1392649136 -> 1540 [CDialog::updateRouteSet] there are 0 record-route headers
1392649136 -> 1139[CMotorCall::searchForkedBranch]pForkedBranch=0x857ea70.
1392649136 ->         [CForkedBranch::setFinalResponse] pResponse=0x857e7d8.
1392649136 ->         [CForkedBranch::setFinalResponse] response=200.
1392649136 -> 1139[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
1392649136 -> 1139[CMotorCall::chooseBestResponse] pForkedBranch=0x857ea70 iResponseCode=2000.
1392649136 ->         [CForkedBranch::getResponseCode] response=200.
1392649136 ->         [CForkedBranch::getResponseCode] response=200.
1392649136 -> 1139[CMotorCall::chooseBestResponse] last iResponseCode=200.
1392649136 ->         [CForkedBranch::setFinalResponse] pResponse=0x857e7d8.
1392649136 -> 1139[CMotorCall::chooseBestResponse] next branch.
1392649136 -> 1139[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
1392649136 -> [receiveSuccessfulMessage] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 MODIFICATION_PROCEEDING_STATE received a message.
1392649136 -> 1139[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=1 uacRefresher=1.
1392649136 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1392649136 -> [convertSdpIntoTsdp] default T38 config.
1392649136 -> [convertSdpIntoTsdp] No Direction in the session part.
1392649136 -> [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1392649136 -> [convertSdpIntoTsdp] The audio media contains 2 format(s).
1392649136 -> [convertSdpIntoTsdp] Format 0 is 18.
1392649136 -> [convertSdpIntoTsdp] Format 1 is 102.
1392649136 -> [convertSdpIntoTsdp] Get the direction.
1392649136 -> [convertSdpIntoTsdp] Direction is recvonly.
1392649136 -> [convertSdpIntoTsdp] The connection is at session level.
1392649136 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 172.27.3.1.
1392649136 -> [convertIPStrIntoTuipv] 172.27.3.1 => 172.27.3.1
1392649136 -> [receiveSuccessfulMessage] T38 = 0.
1392649136 -> [methodAllowedByDistant] INFO not allowed by distant !!
1392649136 -> 1139[emitEventToMonitel] content size (lginfo): 656
1392649136 -> 1139[emitEventToMonitel] CAC = 0 eqtCAC = -1
1392649136 -> 1139[emitEventToMonitel] CAC = 0
1392649136 -> [ipc_thread] IPC Thread : Ipc reception 28290.
1392649136 -> [ipc_thread] IPC Thread : TCL thread signaled.
1392649136 -> [sendLgEvtSip] Event sent on eqt : 1718 Id :-1
1392649136 -> [display_ipc_out] ------------ Begin ---------------
1392649136 -> Id : -1
1392649136 -> SUCCESSFUL
1392649136 -> xx :        0
1392649136 -> RELATIVE REQUEST : RE_INVITE
1392649136 -> CLIR : 0
1392649136 -> COLP : 0
1392649136 -> SDP :
1392649136 ->   ADDRESS : 172.27.3.1 :10272
1392649136 ->   ALGOS :
1392649136 ->     G729
1392649136 ->     102
1392649136 ->   DIRECTION : RECEIVE ONLY
1392649136 -> [display_ipc_out] ------------- End ----------------
1392649136 -> 1139[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
1392649136 -> 1139[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
1392649136 ->         [CForkedBranch::getResponseCode] response=200.
1392649136 ->         [CForkedBranch::getResponseCode] response=200.
1392649136 -> [exec_ipc] in 28289.
1392649136 -> [display_ipc_in] ------------ Begin ---------------
1392649136 -> neqt : 1718 Id : -1
1392649136 -> ACK
1392649136 -> [display_ipc_in] ------------- End ----------------
1392649136 -> [CMotorCallManager::onIncomingEvent] an event 10754 arrived on the eqt 1718--1.
1392649136 -> [CMotorCallManager::onIncomingEvent] state = MODIFICATION_PROCEEDING_STATE.
1392649136 -> [onIncomingEvent] ACK arrived.
1392649136 -> [receiveAckEvent] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 MODIFICATION_PROCEEDING_STATE received an event.
1392649136 -> 1139[CMotorCall::inviteBuildContact] Contact: sip:172.27.3.14
1392649136 -> 1139[CMotorCall::emitAckMessage] begin.
1392649136 -> 1139[CMotorCall::emitAckMessage] SDP ok.
1392649136 -> 1139 [CCall::makeGenericRequest] ACK
1392649136 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649136 -> 1540 [CDialog::createRequest]
1392649136 -> 1540 [CDialog::buildServicesForAllRequest]
1392649136 -> 1540 [CDialog::createAckRequest]
1392649136 -> 1540 [CDialog::createAckRequest], previous trans 2152 is found
1392649136 -> 1540 [CDialog::createAckRequest], previous trans 2152 is client
1392649136 -> 5E [CRequest::CRequest] Creation of a request
1392649136 -> 1540 [CDialog::buildServicesForAck]
1392649136 -> 5E [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649136 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 432)
----------------------utf8-----------------------
ACK sip:77019290234@172.27.2.16:5060 SIP/2.0

Contact: sip:172.27.3.14

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

To: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

From: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058297 ACK

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bKc97b9ef5186607c0b1ef0623f64f1c20

Max-Forwards: 70

Content-Length: 0



-------------------------------------------------
1392649136 -> 2152 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1392649136 -> 2152 [CTransaction::freeTimerToken] Timer D is freed
1392649136 -> 2152 [CTransaction::changeState] no transaction state, terminated reason : Request creation
1392649136 -> 1540 [CDialog::createAckRequest] successful INVITE is terminated after the ACK is sent
1392649136 -> 1139 [CCall::getCallDuplicationData]
1392649136 -> 1139 [CCall::getCallDuplicationData] : Request URI = , Call ID = 7c1eb3fe026d-7-11143b19, Local URI = sip:1111@172.27.3.14:5060, Local Tag = 25daee7629453dc0f488d19759b53801, Remote URI = sip:77019290234@172.27.3.14:5060, Remote Tag = 7c1eb3fe026d-10-11143b19, Local CSeq = 1439058297, Remote CSeq = 11
1392649136 -> [CViaSetSerializer::serialize] Serialized via set :
1392649136 -> [CRouteSet::serialize] Serialized route set : Contact=sip:77019290234@172.27.2.16:5060
1392649136 -> [exec_ipc] in end 28289.
1392649136 -> [CTransaction::notifyDialog]
1392649136 -> [CTransaction::notifyDialog] notify parent dialog
1392649136 -> 1540 [CDialog::onTransactionState(pTrans = 2152, previousState = null, currentState = Terminated, reason = Request creation]
1392649136 -> 2152 [~CTransaction] transaction 1439058297 INVITE
1392649137 -> [ipc_thread] IPC Thread : Ipc reception 28290.
1392649137 -> [ipc_thread] IPC Thread : TCL thread signaled.
1392649137 -> [exec_ipc] in 28289.
1392649137 -> [display_ipc_in] ------------ Begin ---------------
1392649137 -> neqt : 1718 Id : -1
1392649137 -> RE_INVITE
1392649137 -> SDP :
1392649137 ->   ADDRESS : 172.27.2.17 :32592
1392649137 ->   ALGOS :
1392649137 ->     G729
1392649137 ->     102
1392649137 ->   DIRECTION : SEND & RECEIVE
1392649137 -> [display_ipc_in] ------------- End ----------------
1392649137 -> [CMotorCallManager::onIncomingEvent] an event 10753 arrived on the eqt 1718--1.
1392649137 -> [CMotorCallManager::onIncomingEvent] state = COMPLETED_STATE.
1392649137 -> [onIncomingEvent] RE_INVITE arrived.
1392649137 -> [receiveReInviteEvent] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 COMPLETED_STATE received an event.
1392649137 -> 1139[emitReInviteMessage]-->SIP_INFO_allowed=0
1392649137 -> 1139[CMotorCall::emitReInviteMessage] uacGatewayMode=1 uacRefresher=1.
1392649137 -> 1139[CMotorCall::inviteBuildContact] Contact: sip:172.27.3.14
1392649137 -> 1139[CMotorCall::makeRequestSdp] Begin sdp : v=0

o=OXE 1392649124 1392649127 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.3.15

t=0 0

m=audio 32624 RTP/AVP 18 102

a=sendonly

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

.
1392649137 -> 1139[CMotorCall::makeRequestSdp] Direction: 0.
1392649137 -> 1139[CMotorCall::makeResponseSdp] format 102
1392649137 -> 1139[CMotorCall::makeRequestSdp] Final sdp: v=0

o=OXE 1392649124 1392649127 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32592 RTP/AVP 18 102

a=sendrecv

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

.
1392649137 -> 1139[CMotorCall::emitReInviteMessage] The sdp build: .
1392649137 -> 1139[CMotorCall::searchForkedBranch]pForkedBranch=0x857ea70.
1392649137 -> 1139[CMotorCall::emitReInviteMessage] reqUri got :sip:77019290234@172.27.2.16:5060
1392649137 -> 1139[CMotorCall::emitReInviteMessage] continue.
1392649137 -> 1139 [CCall::makeGenericRequest] INVITE
1392649137 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649137 -> 1540 [CDialog::createRequest]
1392649137 -> 1540 [CDialog::buildServicesForAllRequest]
1392649137 -> 1540 [CDialog::createInviteRequest]
1392649137 -> 2153 [CTransaction::changeState] STATE CHANGED TO INITIAL
1392649137 -> 2153 [CTransaction::changeState] notifying the parent dialog
1392649137 -> 1540 [CDialog::onTransactionState(pTrans = 2153, previousState = Terminated, currentState = Initial, reason = None]
1392649137 -> 5F [CRequest::CRequest] Creation of a request
1392649137 -> 5F [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649137 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 864)
----------------------utf8-----------------------
INVITE sip:77019290234@172.27.2.16:5060 SIP/2.0

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

Contact: sip:172.27.3.14

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

Session-Expires: 1800;refresher=uac

Min-SE: 900

Content-Type: application/sdp

To: sip:77019290234@172.27.3.14:5060;tag=7c1eb3fe026d-10-11143b19

From: sip:1111@172.27.3.14:5060;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058298 INVITE

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bK065baa20795ecd5048a6157e1f87a62c

Max-Forwards: 70

Content-Length: 237



v=0

o=OXE 1392649124 1392649127 IN IP4 172.27.3.14

s=abs

c=IN IP4 172.27.2.17

t=0 0

m=audio 32592 RTP/AVP 18 102

a=sendrecv

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=ptime:20

a=maxptime:40

a=rtpmap:102 telephone-event/8000

-------------------------------------------------
1392649137 -> 2153 [CTransInitialState::createRequest] Transaction changes to Calling state
1392649137 -> 2153 [CTransaction::changeState] STATE CHANGED TO CALLING
1392649137 -> 2153 [CTransaction::changeState] notifying the parent dialog
1392649137 -> 1540 [CDialog::onTransactionState(pTrans = 2153, previousState = Initial, currentState = Calling, reason = Request creation]
1392649137 -> 2153 [CTransaction::startTimer] Timer A is started (delay = 500 ms)
1392649137 -> 2153 [CTransaction::startTimer] Timer B is started (delay = 8000 ms)
1392649137 -> 1540 [CDialog::addTransaction] added transaction 2153 with branch z9hG4bK065baa20795ecd5048a6157e1f87a62c, with CSeq 1439058298
1392649137 -> [CSessionTimerContext::updateAfterRefreshCreation] Update CSessionTimerContext (refresh creation)
1392649137 -> [CSessionTimerContext::updateSupported] Request timer supported
1392649137 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1392649137 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1392649137 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1392649137 -> [CForkedBranch::CForkedBranch] context = 0x857a980.
1392649137 -> 1139[CMotorCall::emitReInviteMessage]Request built branch=1.
1392649137 ->         [CForkedBranch::reqUriDuplication] reqUri :sip:77019290234@172.27.2.16:5060
1392649137 -> [exec_ipc] in end 28289.
1392649137 -> RECEIVE MESSAGE FROM NETWORK (172.27.2.16:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK

Max-Forwards: 70

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bK065baa20795ecd5048a6157e1f87a62c

From: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

To: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058298 INVITE

Contact: <sip:77019290234@172.27.2.16:5060>

User-Agent: 2N VoiceBlueNext 1.5.1.7.2

Allow: INVITE,BYE,ACK,CANCEL,OPTIONS,REFER,NOTIFY

Content-Type: application/sdp

Content-Length: 244



v=0

o=VoiceBlueNext 44079 16056 IN IP4 172.27.2.16

s=GSM Call

c=IN IP4 172.27.2.16

t=0 0

a=sendrecv

m=audio 32698 RTP/AVP 18 102

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:102 telephone-event/8000

a=fmtp:102 0-11

a=sendrecv

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

1392649137 -> [CIOCom::receiveResponse] CResponse 64 ref=1
1392649137 -> [CDispatcher::onIncomingResponse]
1392649137 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1392649137 ->  102 [CCallManager::onIncomingResponse]
1392649137 -> 1139 [CCall::receiveResponse] 200
1392649137 -> 1139 [CCall::receiveResponse]Trying to find the right dialog
1392649137 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649137 -> 1139 [CCall::receiveResponse]One dialog found
1392649137 -> 1540 [CDialog::receiveResponse]
1392649137 -> 2153 [CTransCallingState::receiveResponse] Final : Transaction changes to Completed state
1392649137 -> 2153 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1392649137 -> 2153 [CTransaction::changeState] notifying the parent dialog
1392649137 -> 1540 [CDialog::onTransactionState(pTrans = 2153, previousState = Calling, currentState = Completed, reason = Final resp reception]
1392649137 -> 2153 [CTransaction::freeTimerToken] Timer A is freed
1392649137 -> 2153 [CTransaction::freeTimerToken] Timer B is freed
1392649137 -> 2153 [CTransaction::startTimer] Timer D is started (delay = 32460 ms)
1392649137 -> [CSessionTimerContext::updateAfterResponseRefreshReception] Update CSessionTimerContext (response refresh reception)
1392649137 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1392649137 -> [CSessionTimerContext::startTimers] Start session timer expiration : 1800000
1392649137 -> [CSessionTimerContext::startTimers] Start session timer will expires : 900000
1392649137 -> 1540 [CDialog::updateRouteSet] there are 0 record-route headers
1392649137 -> 1139[CMotorCall::searchForkedBranch]pForkedBranch=0x857ea70.
1392649137 ->         [CForkedBranch::setFinalResponse] pResponse=0x8589438.
1392649137 ->         [CForkedBranch::setFinalResponse] response=200.
1392649137 -> 1139[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
1392649137 -> 1139[CMotorCall::chooseBestResponse] pForkedBranch=0x857ea70 iResponseCode=2000.
1392649137 ->         [CForkedBranch::getResponseCode] response=200.
1392649137 ->         [CForkedBranch::getResponseCode] response=200.
1392649137 -> 1139[CMotorCall::chooseBestResponse] last iResponseCode=200.
1392649137 ->         [CForkedBranch::setFinalResponse] pResponse=0x8589438.
1392649137 -> 1139[CMotorCall::chooseBestResponse] next branch.
1392649137 -> 1139[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
1392649137 -> [receiveSuccessfulMessage] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 MODIFICATION_PROCEEDING_STATE received a message.
1392649137 -> 1139[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=1 uacRefresher=1.
1392649137 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1392649137 -> [convertSdpIntoTsdp] default T38 config.
1392649137 -> [convertSdpIntoTsdp] No Direction in the session part.
1392649137 -> [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1392649137 -> [convertSdpIntoTsdp] The audio media contains 2 format(s).
1392649137 -> [convertSdpIntoTsdp] Format 0 is 18.
1392649137 -> [convertSdpIntoTsdp] Format 1 is 102.
1392649137 -> [convertSdpIntoTsdp] Get the direction.
1392649137 -> [convertSdpIntoTsdp] Direction is sendrecv.
1392649137 -> [convertSdpIntoTsdp] The connection is at session level.
1392649137 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 172.27.2.16.
1392649137 -> [convertIPStrIntoTuipv] 172.27.2.16 => 172.27.2.16
1392649137 -> [receiveSuccessfulMessage] T38 = 0.
1392649137 -> [methodAllowedByDistant] INFO not allowed by distant !!
1392649137 -> 1139[emitEventToMonitel] content size (lginfo): 656
1392649137 -> 1139[emitEventToMonitel] CAC = 0 eqtCAC = -1
1392649137 -> 1139[emitEventToMonitel] CAC = 0
1392649137 -> [ipc_thread] IPC Thread : Ipc reception 28290.
1392649137 -> [ipc_thread] IPC Thread : TCL thread signaled.
1392649137 -> [sendLgEvtSip] Event sent on eqt : 1718 Id :-1
1392649137 -> [display_ipc_out] ------------ Begin ---------------
1392649137 -> Id : -1
1392649137 -> SUCCESSFUL
1392649137 -> xx :        0
1392649137 -> RELATIVE REQUEST : RE_INVITE
1392649137 -> CLIR : 0
1392649137 -> COLP : 0
1392649137 -> SDP :
1392649137 ->   ADDRESS : 172.27.2.16 :32698
1392649137 ->   ALGOS :
1392649137 ->     G729
1392649137 ->     102
1392649137 ->   DIRECTION : SEND & RECEIVE
1392649137 -> [display_ipc_out] ------------- End ----------------
1392649137 -> 1139[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
1392649137 -> 1139[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
1392649137 ->         [CForkedBranch::getResponseCode] response=200.
1392649137 ->         [CForkedBranch::getResponseCode] response=200.
1392649137 -> [exec_ipc] in 28289.
1392649137 -> [display_ipc_in] ------------ Begin ---------------
1392649137 -> neqt : 1718 Id : -1
1392649137 -> ACK
1392649137 -> [display_ipc_in] ------------- End ----------------
1392649137 -> [CMotorCallManager::onIncomingEvent] an event 10754 arrived on the eqt 1718--1.
1392649137 -> [CMotorCallManager::onIncomingEvent] state = MODIFICATION_PROCEEDING_STATE.
1392649137 -> [onIncomingEvent] ACK arrived.
1392649137 -> [receiveAckEvent] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 MODIFICATION_PROCEEDING_STATE received an event.
1392649137 -> 1139[CMotorCall::inviteBuildContact] Contact: sip:172.27.3.14
1392649137 -> 1139[CMotorCall::emitAckMessage] begin.
1392649137 -> 1139[CMotorCall::emitAckMessage] SDP ok.
1392649137 -> 1139 [CCall::makeGenericRequest] ACK
1392649137 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649137 -> 1540 [CDialog::createRequest]
1392649137 -> 1540 [CDialog::buildServicesForAllRequest]
1392649137 -> 1540 [CDialog::createAckRequest]
1392649137 -> 1540 [CDialog::createAckRequest], previous trans 2153 is found
1392649137 -> 1540 [CDialog::createAckRequest], previous trans 2153 is client
1392649137 -> 60 [CRequest::CRequest] Creation of a request
1392649137 -> 1540 [CDialog::buildServicesForAck]
1392649137 -> 60 [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649137 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 432)
----------------------utf8-----------------------
ACK sip:77019290234@172.27.2.16:5060 SIP/2.0

Contact: sip:172.27.3.14

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

To: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

From: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058298 ACK

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bK7a42c76db1fe68683f5259ec4d66edb6

Max-Forwards: 70

Content-Length: 0



-------------------------------------------------
1392649137 -> 2153 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1392649137 -> 2153 [CTransaction::freeTimerToken] Timer D is freed
1392649137 -> 2153 [CTransaction::changeState] no transaction state, terminated reason : Request creation
1392649137 -> 1540 [CDialog::createAckRequest] successful INVITE is terminated after the ACK is sent
1392649137 -> 1139 [CCall::getCallDuplicationData]
1392649137 -> 1139 [CCall::getCallDuplicationData] : Request URI = , Call ID = 7c1eb3fe026d-7-11143b19, Local URI = sip:1111@172.27.3.14:5060, Local Tag = 25daee7629453dc0f488d19759b53801, Remote URI = sip:77019290234@172.27.3.14:5060, Remote Tag = 7c1eb3fe026d-10-11143b19, Local CSeq = 1439058298, Remote CSeq = 11
1392649137 -> [CViaSetSerializer::serialize] Serialized via set :
1392649137 -> [CRouteSet::serialize] Serialized route set : Contact=sip:77019290234@172.27.2.16:5060
1392649137 -> [exec_ipc] in end 28289.
1392649137 -> [CTransaction::notifyDialog]
1392649137 -> [CTransaction::notifyDialog] notify parent dialog
1392649137 -> 1540 [CDialog::onTransactionState(pTrans = 2153, previousState = null, currentState = Terminated, reason = Request creation]
1392649137 -> 2153 [~CTransaction] transaction 1439058298 INVITE
1392649143 -> [ipc_thread] IPC Thread : Ipc reception 28290.
1392649143 -> [ipc_thread] IPC Thread : TCL thread signaled.
1392649143 -> [exec_ipc] in 28289.
1392649143 -> [display_ipc_in] ------------ Begin ---------------
1392649143 -> neqt : 1718 Id : -1
1392649143 -> BYE
1392649143 -> [display_ipc_in] ------------- End ----------------
1392649143 -> [CMotorCallManager::onIncomingEvent] an event 10755 arrived on the eqt 1718--1.
1392649143 -> [CMotorCallManager::onIncomingEvent] state = COMPLETED_STATE.
1392649143 -> [onIncomingEvent] BYE arrived.
1392649143 -> [receiveByeEvent] Call: 7c1eb3fe026d-7-11143b19 eqt: 1718 COMPLETED_STATE received an event.
1392649143 -> 1139 [CCall::makeGenericRequest] BYE
1392649143 -> 1139 [CCall::getDialog] Confirmed Dialog found
1392649143 -> 1540 [CDialog::createRequest]
1392649143 -> 1540 [CDialog::buildServicesForAllRequest]
1392649143 -> 1540 [CDialog::createByeRequest]
1392649143 -> 2154 [CTransaction::changeState] STATE CHANGED TO INITIAL
1392649143 -> 2154 [CTransaction::changeState] notifying the parent dialog
1392649143 -> 1540 [CDialog::onTransactionState(pTrans = 2154, previousState = Terminated, currentState = Initial, reason = None]
1392649143 -> 61 [CRequest::CRequest] Creation of a request
1392649143 -> 61 [CMessage::send] sip_sendMessage (172.27.2.16:5060)
1392649143 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 436)
----------------------utf8-----------------------
BYE sip:77019290234@172.27.2.16:5060 SIP/2.0

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

To: sip:77019290234@172.27.3.14:5060;tag=7c1eb3fe026d-10-11143b19

From: sip:1111@172.27.3.14:5060;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058299 BYE

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bKf9516feb680c85b66b70fbc536114a90

Max-Forwards: 70

Content-Length: 0



-------------------------------------------------
1392649143 -> 2154 [CTransInitialState::createRequest] Transaction changes to Trying state
1392649143 -> 2154 [CTransaction::changeState] STATE CHANGED TO TRYING
1392649143 -> 2154 [CTransaction::changeState] notifying the parent dialog
1392649143 -> 1540 [CDialog::onTransactionState(pTrans = 2154, previousState = Initial, currentState = Trying, reason = Request creation]
1392649143 -> 2154 [CTransTryingState::enterInState] Init timers E and F
1392649143 -> 2154 [CTransaction::startTimer] Timer E is started (delay = 500 ms)
1392649143 -> 2154 [CTransaction::startTimer] Timer F is started (delay = 32000 ms)
1392649143 -> 1540 [CDialog::addTransaction] added transaction 2154 with branch z9hG4bKf9516feb680c85b66b70fbc536114a90, with CSeq 1439058299
1392649143 -> [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
1392649143 -> [exec_ipc] in end 28289.
1392649144 -> 2154 [CTransTryingState::timerFires] Timer E fires
1392649144 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 436)
----------------------utf8-----------------------
BYE sip:77019290234@172.27.2.16:5060 SIP/2.0

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

To: sip:77019290234@172.27.3.14:5060;tag=7c1eb3fe026d-10-11143b19

From: sip:1111@172.27.3.14:5060;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058299 BYE

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bKf9516feb680c85b66b70fbc536114a90

Max-Forwards: 70

Content-Length: 0



-------------------------------------------------
1392649144 -> 2154 [CTransaction::startTimer] Timer E is started (delay = 1000 ms)
1392649145 -> 2154 [CTransTryingState::timerFires] Timer E fires
1392649145 -> SEND MESSAGE TO NETWORK (172.27.2.16:5060 [UDP]) (BUFF LEN = 436)
----------------------utf8-----------------------
BYE sip:77019290234@172.27.2.16:5060 SIP/2.0

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.12

To: sip:77019290234@172.27.3.14:5060;tag=7c1eb3fe026d-10-11143b19

From: sip:1111@172.27.3.14:5060;tag=25daee7629453dc0f488d19759b53801

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058299 BYE

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bKf9516feb680c85b66b70fbc536114a90

Max-Forwards: 70

Content-Length: 0



-------------------------------------------------
1392649145 -> 2154 [CTransaction::startTimer] Timer E is started (delay = 2000 ms)
1392649145 -> RECEIVE MESSAGE FROM NETWORK (172.27.2.16:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK

Max-Forwards: 70

Via: SIP/2.0/UDP 172.27.3.14;branch=z9hG4bKf9516feb680c85b66b70fbc536114a90

From: <sip:1111@172.27.3.14:5060>;tag=25daee7629453dc0f488d19759b53801

To: <sip:77019290234@172.27.3.14:5060>;tag=7c1eb3fe026d-10-11143b19

Call-ID: 7c1eb3fe026d-7-11143b19

CSeq: 1439058299 BYE

Contact: <sip:77019290234@172.27.2.16:5060>

User-Agent: 2N VoiceBlueNext 1.5.1.7.2

Allow: INVITE,BYE,ACK,CANCEL,OPTIONS,REFER,NOTIFY

Content-Length: 0

у вас одна дорога - ставить последний патч, от i1.605.12 до i1.605.41 много чего было в sip-е

довольно наглядно с "недокрутами" разбираться в wireshark - Telephony > VoIP calls > graph - схема прохождения медиа потоков там наглядная и все адреса-порты видно. читать разлапистую трассировку алкатель имеет смысл если есть подозрение в ошибочной работе СИП после первичного анализа Ваершарком

по моему вам надо плотнее посмотреть роутинг между сетями и в частности - содержимое пакетов на 24.132 и далее... до этого обмена у вас медиа потоки с адреса 127.27.2.17 просто не поднимались и соответственного голоса небыло.

ну так покажите ему эту картинку - куда уж нагляднее - стрелка влево, стрелка вправо