Re: trunk SIP OXE-XIVO
Posted: 29 Sep 2016 09:26
I progress.. no more 404 error but now SIP/2.0 480 Temporarily not available
The #1 Worldwide board for technical support on Alcatel-Lucent Voice & Data gear.
https://alcatelunleashed.com/
Code: Select all
----------------------utf8-----------------------
INVITE sip:2299@XXX.XXX.XXX.XXX:5060 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XX.XX:5060;branch=z9hG4bK7788bb82
Max-Forwards: 70
From: "jean-marc " <sip:667@XXX.XXX.XX.XX>;tag=as50d36879
To: <sip:2299@XXX.XXX.XXX.XXX:5060>
Contact: <sip:667@XXX.XXX.XX.XX:5060>
Call-ID: 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX
CSeq: 102 INVITE
User-Agent: XIVO PBX
Date: Thu, 29 Sep 2016 13:59:06 GMT
Session-Expires: 1800
Min-SE: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Remote-Party-ID: "jean-marc " <sip:667@XXX.XXX.XX.XX>;party=calling;privacy=off;screen=no
Content-Type: application/sdp
Content-Length: 317
v=0
o=root 418981995 418981995 IN IP4 XXX.XXX.XX.XX
s=Asterisk PBX 13.9.1
c=IN IP4 XXX.XXX.XX.XX
t=0 0
m=audio 13060 RTP/AVP 8 0 4 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:4 G723/8000
a=fmtp:4 annexa=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
-------------------------------------------------
Thu Sep 29 15:59:06 2016 [CIOCom::receiveRequest] optimisation value 1
Thu Sep 29 15:59:06 2016 [CIOCom::IsNoeOptionsMsg] Not in same domain FAILED => XXX.XXX.XXX.XXX node000000
Thu Sep 29 15:59:06 2016 [CIOCom::IsNoeOptionsMsg] NoeOpt 0
Thu Sep 29 15:59:06 2016 9E5DD [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1475157546 -> Thu Sep 29 15:59:06 2016 [CIOCom::receiveRequest] Received CRequest 9E5DD ref=1
Thu Sep 29 15:59:06 2016 [CIOCom::receiveRequest] rport1 not empty
Thu Sep 29 15:59:06 2016 [CDispatcher::onIncomingRequest()]
Thu Sep 29 15:59:06 2016 [CDispatcher::processRequestFromManagerIndex()] start at index 0
Thu Sep 29 15:59:06 2016 102 [CCallManager::onIncomingRequest()]
Thu Sep 29 15:59:06 2016 102 [CCallManager::onIncomingRequest()] creates a new Call if this request doesn't refer to any old call
Thu Sep 29 15:59:06 2016 [CMotorCallManager::callFactory] For call : 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX.
Thu Sep 29 15:59:06 2016 [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R10.0 j1.410.49.a
Thu Sep 29 15:59:06 2016 [CMotorCallManager::callFactory]=8508f18.
Thu Sep 29 15:59:06 2016 11c3 [CCall::receiveRequest] INVITE
Thu Sep 29 15:59:06 2016 11c3 [CCall::getDialog] Confirmed Dialog is not found (ID = ;as50d36879)
Thu Sep 29 15:59:06 2016 11c3 [CCall::getDialog] Initial Dialog Server not found
Thu Sep 29 15:59:06 2016 1525 [CDialog::receiveRequest]
Thu Sep 29 15:59:06 2016 1525 [CDialog::receiveInviteRequest]
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] STATE CHANGED TO INITIAL
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] notifying the parent dialog
Thu Sep 29 15:59:06 2016 1525 [CDialog::onTransactionState(pTrans = 2105, previousState = Terminated, currentState = Initial, reason = None]
Thu Sep 29 15:59:06 2016 2105 [CTransInitialState::receiveRequest] Transaction changes to Proceeding state
1475157546 -> Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] notifying the parent dialog
Thu Sep 29 15:59:06 2016 1525 [CDialog::onTransactionState(pTrans = 2105, previousState = Initial, currentState = Proceeding, reason = Request reception]
Thu Sep 29 15:59:06 2016 B7FBD [CResponse::CResponse] Creation of a response
Thu Sep 29 15:59:06 2016 2105 [CTransProceedingState::createResponse] CResponse B7FBD ref=1
Thu Sep 29 15:59:06 2016 B7FBD [CResponse::create] RESPONSE CREATION
Thu Sep 29 15:59:06 2016 B7FBD [CResponse::checkStatusLine] Create StatusLine from code given as parameter
Thu Sep 29 15:59:06 2016 B7FBD [CResponse::createViaHeaders] Copy Via headers from previous request (number : 1)
Thu Sep 29 15:59:06 2016 9E5DD [CMessage::getHeaderAtIndexFromSipMessage(Timestamp, 0)] sip_getHeaderAtIndex FAILED => HSS ERROR No exist
Thu Sep 29 15:59:06 2016 B7FBD [CResponse::create] Initialize context (Local = To & Remote = From)
Thu Sep 29 15:59:06 2016 B7FBD [CMessage::send] sip_sendMessage (XXX.XXX.XX.XX:5060)
Thu Sep 29 15:59:06 2016 SEND MESSAGE TO NETWORK (XXX.XXX.XX.XX:5060 [UDP]) (BUFF LEN = 273)
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:2299@XXX.XXX.XXX.XXX:5060>
From: "jean-marc " <sip:667@XXX.XXX.XX.XX>;tag=as50d36879
Call-ID: 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX
CSeq: 102 INVITE
Via: SIP/2.0/UDP XXX.XXX.XX.XX:5060;branch=z9hG4bK7788bb82
Content-Length: 0
-------------------------------------------------
1475157546 -> Thu Sep 29 15:59:06 2016 [sip_freeEventContext]
Thu Sep 29 15:59:06 2016 1525 [CDialog::receiveInviteRequest] a new Server INVITE transaction is in progress
Thu Sep 29 15:59:06 2016 [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Server, UA)
Thu Sep 29 15:59:06 2016 [CSessionTimerContext::updateAfterRefreshReception] Update CSessionTimerContext (refresh reception)
Thu Sep 29 15:59:06 2016 [CSessionTimerContext::updateSupported] Request timer supported
Thu Sep 29 15:59:06 2016 [CSessionTimerContext::updateMinSE] Min-SE updated : 300
Thu Sep 29 15:59:06 2016 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Thu Sep 29 15:59:06 2016 [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=INVITE
Thu Sep 29 15:59:06 2016 1525 [CDialog::addTransaction] added transaction 2105 with branch z9hG4bK7788bb82, with CSeq 102
Thu Sep 29 15:59:06 2016 11c3 [CCall::checkAuthentication] INVITE
Thu Sep 29 15:59:06 2016 9E5DD [CMessage::getAssertedIdCount] need to create the wrapper
Thu Sep 29 15:59:06 2016 9E5DD [CMessage::createAssertedIdWrapperFromMessage]
Thu Sep 29 15:59:06 2016 9E5DD [CMessage::createAssertedIdWrapperFromMessage] there is 0 P-Asserted-Identity header in the message
Thu Sep 29 15:59:06 2016 9E5DD [CMessage::getPreferredIdCount] need to create the wrapper
Thu Sep 29 15:59:06 2016 9E5DD [CMessage::createPreferredIdWrapperFromMessage] there is 0 P-Preferred-Identity header in the message
Thu Sep 29 15:59:06 2016 [CGwMngData::isAuthenticationRequiredForThisClient] XXX.XXX.XX.XX .
1475157546 -> Thu Sep 29 15:59:06 2016 isDomainFromGwExt SCSWorking: NO
Thu Sep 29 15:59:06 2016 [isDomainFromGwExt] Host from request is : XXX.XXX.XX.XX.
Thu Sep 29 15:59:06 2016 [isDomainFromGwExt] User from request is : 667
Thu Sep 29 15:59:06 2016 isDomainFromGwExt--> For Non-PCS case GwExt=7
Thu Sep 29 15:59:06 2016 domain from External Gateway 7.
Thu Sep 29 15:59:06 2016 8794 [CSipAddrSpec::getAddrSpecAsString]
Thu Sep 29 15:59:06 2016 8795 [CSipAddrSpec::getAddrSpecAsString]
Thu Sep 29 15:59:06 2016 8798 [CSipAddrSpec::getAddrSpecAsString]
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::setFilterUsedMode] To be traced = 0
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::initOfUserType] values are reseted
Thu Sep 29 15:59:06 2016 [getFromHeader] displayName="jean-marc ".
Thu Sep 29 15:59:06 2016 [getFromHeader] =667@XXX.XXX.XX.XX.
Thu Sep 29 15:59:06 2016 [getFromHeader] clirPresent=0.
Thu Sep 29 15:59:06 2016 [isAddrInDico] user=667 host=XXX.XXX.XX.XX
Thu Sep 29 15:59:06 2016 [isUserInDico] 667@XXX.XXX.XX.XX
Thu Sep 29 15:59:06 2016 isUserInDico] NOT found in the dictionnary.
Thu Sep 29 15:59:06 2016 [isAddrInDico] other sip user
Thu Sep 29 15:59:06 2016 isDomainFromGwExt SCSWorking: NO
Thu Sep 29 15:59:06 2016 [isDomainFromGwExt] Host from request is : XXX.XXX.XX.XX.
Thu Sep 29 15:59:06 2016 [isDomainFromGwExt] User from request is : 667
Thu Sep 29 15:59:06 2016 isDomainFromGwExt--> For Non-PCS case GwExt=7
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::getUserType] extGw = 7.
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
1475157546 -> Thu Sep 29 15:59:06 2016 11c3[CMotorCall::toGatewayOrProxy] request for proxydomain=XXX.XXX.XXX.XXX.
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 11c3[CMotorCall ABCF ExtGW
Thu Sep 29 15:59:06 2016 [isUserRegistred] getRegistration=2299.
Thu Sep 29 15:59:06 2016 [isUserRegistred] result=-1.
Thu Sep 29 15:59:06 2016 [isAddrInDico] user=2299 host=XXX.XXX.XXX.XXX
Thu Sep 29 15:59:06 2016 [isUserInDico] 2299@XXX.XXX.XXX.XXX
Thu Sep 29 15:59:06 2016 [isUserInDico] found in the dictionnary.
Thu Sep 29 15:59:06 2016 [isAddrInDico] other sip user
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::storeRequestInCall] inRequest storage=0x8532bc8.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::onReceiveRequest] request for gateway.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::onReceiveRequest] state = INITIAL_STATE.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] nb available licenses=74 .
Thu Sep 29 15:59:06 2016 [isUserInDico] 667@XXX.XXX.XX.XX
Thu Sep 29 15:59:06 2016 isUserInDico] NOT found in the dictionnary.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] license OK.
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 3538 [CSipContextID::isValid] Call-ID is not valid
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::compareDialogFromContext] presumed to be the same Dialog
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] Invite => update the inviteContext.
Thu Sep 29 15:59:06 2016 87a1 [CSipAddrSpec::CSipAddrSpec()] Invalid type
1475157546 -> Thu Sep 29 15:59:06 2016 [CForkedBranch::CForkedBranch] context = 0x85093c0.
Thu Sep 29 15:59:06 2016 87a4 [CSipAddrSpec::getAddrSpecAsString]
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] reqUri stored: sip:667@XXX.XXX.XX.XX:5060
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] cacUsed = 0 before.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] returnStat = 0 before.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] cacUsed = 0 after.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodInviteReceived] CAC = 0
Thu Sep 29 15:59:06 2016 [receiveInviteMessage] Call: 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX eqt: -1 INITIAL_STATE received a message.
Thu Sep 29 15:59:06 2016 [decodeInviteCLIR].
Thu Sep 29 15:59:06 2016 [receiveInviteMessage] INFO present in Allow header
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 am_I_PCS SCSWorking : NO
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 check_extgw_PCSstate EXTGW is : 7
Thu Sep 29 15:59:06 2016 The check_extgw_PCSstate extgwid : 7
Thu Sep 29 15:59:06 2016 The state of PCS which rescued the extgw is : -1
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 [decodeInviteFrom].
Thu Sep 29 15:59:06 2016 [receiveInviteMessage] octet3a_scrn = 1
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=0 uacRefresher=0.
1475157546 -> Thu Sep 29 15:59:06 2016 [CSipMediaDescription::parseFormatString] Got 4 format(s) from string 8 0 4 101
Thu Sep 29 15:59:06 2016 [checkSdpValidity] Media 0 type 1 contains 4 formats.
Thu Sep 29 15:59:06 2016 [checkSdpValidity] Format : 8.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::isCryptoAuthorized] config crypto=0/0.
Thu Sep 29 15:59:06 2016 [convertSdpIntoTsdp] No Direction in the session part.
Thu Sep 29 15:59:06 2016 [convertSdpIntoTsdp] Check the direction in Session part - result:0.
Thu Sep 29 15:59:06 2016 [convertSdpIntoTsdp] media AUDIO detected (previous crypto=0).
Thu Sep 29 15:59:06 2016 [convertAudioMedia] The audio media contains 4 format(s).
Thu Sep 29 15:59:06 2016 [convertAudioMedia] Format 0 is 8.
Thu Sep 29 15:59:06 2016 [convertAudioMedia] Format 1 is 0.
Thu Sep 29 15:59:06 2016 [convertAudioMedia] Format 2 is 4.
Thu Sep 29 15:59:06 2016 [convertAudioMedia] Format 3 is 101.
Thu Sep 29 15:59:06 2016 [convertAudioMedia] 101.
Thu Sep 29 15:59:06 2016 [convertAudioMedia] Format is DTMF:101.
Thu Sep 29 15:59:06 2016 [convertAudioMedia] Direction is sendrecv.
Thu Sep 29 15:59:06 2016 [convertAudioMedia] Connection address retrieved in sdp: XXX.XXX.XX.XX.
Thu Sep 29 15:59:06 2016 [convertIPStrIntoTuipv] XXX.XXX.XX.XX => XXX.XXX.XX.XX
Thu Sep 29 15:59:06 2016 [display_sdp] address =XXX.XXX.XX.XX
Thu Sep 29 15:59:06 2016 [display_sdp] direction=0.
Thu Sep 29 15:59:06 2016 [convertSdpIntoTsdp] only one media taken into account xxx crypto_index=0 clear media=1
Thu Sep 29 15:59:06 2016 [convertSdpIntoTsdp] crypto_index=0 clear media=1.
1475157546 -> Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::isCryptoAuthorized] config crypto=0/0.
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 [receiveInviteMessage] NO 100rel REQUIRED for this call.
Thu Sep 29 15:59:06 2016 [CInitialState::receiveinvitemessage allow_update 0 prack_required 0
Thu Sep 29 15:59:06 2016 [decodeInviteTo].
Thu Sep 29 15:59:06 2016 [decodeInviteReferredBy].
Thu Sep 29 15:59:06 2016 [decodeInviteACSBU].
Thu Sep 29 15:59:06 2016 8794 [CSipAddrSpec::getAddrSpecAsString]
Thu Sep 29 15:59:06 2016 8795 [CSipAddrSpec::getAddrSpecAsString]
Thu Sep 29 15:59:06 2016 8798 [CSipAddrSpec::getAddrSpecAsString]
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::setFilterUsedMode] To be traced = 0
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 ** SIP TRUNKING **
Thu Sep 29 15:59:06 2016 [ipc_thread] IPC Thread : Ipc reception 30139.
Thu Sep 29 15:59:06 2016 [ipc_thread] IPC Thread : TCL thread signaled.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::insertCallwithEqt] CMotorCall 3525 inserted.
Thu Sep 29 15:59:06 2016 11c3[sendLgEvtSipCreate] Event sent on eqt : 3525
Thu Sep 29 15:59:06 2016 [display_ipc_out] ------------ Begin ---------------
Thu Sep 29 15:59:06 2016 Id : -1
Thu Sep 29 15:59:06 2016 INVITE
Thu Sep 29 15:59:06 2016 REQUEST URI : <> 2299@XXX.XXX.XXX.XXX:5060 ; user=name
1475157546 -> Thu Sep 29 15:59:06 2016 FROM : <jean-marc > 667@XXX.XXX.XX.XX:5060 ; user=name
Thu Sep 29 15:59:06 2016 TO : <> 2299@XXX.XXX.XXX.XXX:5060 ; user=name
Thu Sep 29 15:59:06 2016 CAC : 0
Thu Sep 29 15:59:06 2016 CAC ADDRESS :
Thu Sep 29 15:59:06 2016 CAC-CSBU info : UNKNOWN
Thu Sep 29 15:59:06 2016 CLIR : 0
Thu Sep 29 15:59:06 2016 Prack Required : 0
Thu Sep 29 15:59:06 2016 Allow Update : 0
Thu Sep 29 15:59:06 2016 SDP :
Thu Sep 29 15:59:06 2016 ADDRESS : XXX.XXX.XX.XX :13060
Thu Sep 29 15:59:06 2016 ALGOS :
Thu Sep 29 15:59:06 2016 PCMA
Thu Sep 29 15:59:06 2016 G723
Thu Sep 29 15:59:06 2016 101
Thu Sep 29 15:59:06 2016 DIRECTION : SEND & RECEIVE
Thu Sep 29 15:59:06 2016 crypto index : 0
Thu Sep 29 15:59:06 2016 N_GW_EXT : 7
Thu Sep 29 15:59:06 2016 [display_ipc_out] ------------- End ----------------
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Thu Sep 29 15:59:06 2016 [exec_ipc] in 30138.
Thu Sep 29 15:59:06 2016 [display_ipc_in] ------------ Begin ---------------
Thu Sep 29 15:59:06 2016 neqt : 3525 Id : -1
Thu Sep 29 15:59:06 2016 REQUEST FAILURE
Thu Sep 29 15:59:06 2016 xx : 80
Thu Sep 29 15:59:06 2016 RELATIVE REQUEST : INVITE
Thu Sep 29 15:59:06 2016 [display_ipc_in] ------------- End ----------------
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onIncomingEvent] an event 10767 arrived on the eqt 3525 -1.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1475157546 -> Thu Sep 29 15:59:06 2016 [onIncomingEvent] REQUEST FAILURE arrived.
Thu Sep 29 15:59:06 2016 [receiveRequestFailureEvent] Call: 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX eqt: 3525 PROCEEDING_STATE received an event.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::getOutgoingCall] outgoingCall:0
Thu Sep 29 15:59:06 2016 [receiveRequestFailureEvent] incoming call.
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 11c3[CMotorCall ABCF ExtGW
Thu Sep 29 15:59:06 2016 11c3 [CCall::makeGenericResponse] 480
Thu Sep 29 15:59:06 2016 11c3 [CCall::getDialog] Confirmed Dialog is not found (ID = ;as50d36879)
Thu Sep 29 15:59:06 2016 11c3 [CCall::getDialog] Returning Initial Dialog Server
Thu Sep 29 15:59:06 2016 1525 [CDialog::createResponse] 480
Thu Sep 29 15:59:06 2016 B7FBE [CResponse::CResponse] Creation of a response
Thu Sep 29 15:59:06 2016 2105 [CTransProceedingState::createResponse] CResponse B7FBE ref=1
Thu Sep 29 15:59:06 2016 B7FBE [CResponse::create] RESPONSE CREATION
Thu Sep 29 15:59:06 2016 B7FBE [CResponse::checkStatusLine] Create StatusLine from code given as parameter
Thu Sep 29 15:59:06 2016 B7FBE [CResponse::createToHeader] Initialize To TAG from context
Thu Sep 29 15:59:06 2016 B7FBE [CResponse::createToHeader] Creation a tag for To header
Thu Sep 29 15:59:06 2016 B7FBE [CResponse::createViaHeaders] Copy Via headers from previous request (number : 1)
Thu Sep 29 15:59:06 2016 B7FBE [CResponse::create] Initialize context (Local = To & Remote = From)
1475157546 -> Thu Sep 29 15:59:06 2016 B7FBE [CMessage::send] sip_sendMessage (XXX.XXX.XX.XX:5060)
Thu Sep 29 15:59:06 2016 SEND MESSAGE TO NETWORK (XXX.XXX.XX.XX:5060 [UDP]) (BUFF LEN = 462)
----------------------utf8-----------------------
SIP/2.0 480 Temporarily not available
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
User-Agent: OmniPCX Enterprise R10.0 j1.410.49.a
To: <sip:2299@XXX.XXX.XXX.XXX:5060>;tag=20c70d21cb0a365004c0cc4ab2fef74b
From: "jean-marc " <sip:667@XXX.XXX.XX.XX>;tag=as50d36879
Call-ID: 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX
CSeq: 102 INVITE
Via: SIP/2.0/UDP XXX.XXX.XX.XX:5060;branch=z9hG4bK7788bb82
Content-Length: 0
-------------------------------------------------
Thu Sep 29 15:59:06 2016 [sip_freeEventContext]
Thu Sep 29 15:59:06 2016 B7FBD [~CResponse] resp(100) 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX 102 INVITE
Thu Sep 29 15:59:06 2016 2105 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] notifying the parent dialog
Thu Sep 29 15:59:06 2016 1525 [CDialog::onTransactionState(pTrans = 2105, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
Thu Sep 29 15:59:06 2016 2105 [CTransaction::startTimer] Timer G is started (delay = 500 ms)
Thu Sep 29 15:59:06 2016 2105 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1475157546 -> Thu Sep 29 15:59:06 2016 1525 [CDialog::updateRouteSet] there are 0 record-route headers
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Thu Sep 29 15:59:06 2016 [exec_ipc] in end 30138.
Thu Sep 29 15:59:06 2016 RECEIVE MESSAGE FROM NETWORK (XXX.XXX.XX.XX:5060 [UDP])
----------------------utf8-----------------------
ACK sip:2299@XXX.XXX.XXX.XXX:5060 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XX.XX:5060;branch=z9hG4bK7788bb82
Max-Forwards: 70
From: "jean-marc " <sip:667@XXX.XXX.XX.XX>;tag=as50d36879
To: <sip:2299@XXX.XXX.XXX.XXX:5060>;tag=20c70d21cb0a365004c0cc4ab2fef74b
Contact: <sip:667@XXX.XXX.XX.XX:5060>
Call-ID: 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX
CSeq: 102 ACK
User-Agent: XIVO PBX
Content-Length: 0
-------------------------------------------------
Thu Sep 29 15:59:06 2016 [CIOCom::receiveRequest] optimisation value 1
Thu Sep 29 15:59:06 2016 [CIOCom::IsNoeOptionsMsg] Not in same domain FAILED => XXX.XXX.XXX.XXX node000000
Thu Sep 29 15:59:06 2016 [CIOCom::IsNoeOptionsMsg] NoeOpt 0
Thu Sep 29 15:59:06 2016 9E5DE [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
Thu Sep 29 15:59:06 2016 [CIOCom::receiveRequest] Received CRequest 9E5DE ref=1
Thu Sep 29 15:59:06 2016 [CIOCom::receiveRequest] rport1 not empty
Thu Sep 29 15:59:06 2016 [CDispatcher::onIncomingRequest()]
Thu Sep 29 15:59:06 2016 [CDispatcher::processRequestFromManagerIndex()] start at index 0
Thu Sep 29 15:59:06 2016 102 [CCallManager::onIncomingRequest()]
1475157546 -> Thu Sep 29 15:59:06 2016 11c3 [CCall::receiveRequest] ACK
Thu Sep 29 15:59:06 2016 11c3 [CCall::getDialog] Confirmed Dialog is not found (ID = 20c70d21cb0a365004c0cc4ab2fef74b;as50d36879)
Thu Sep 29 15:59:06 2016 11c3 [CCall::getDialog] Returning Initial Dialog Server
Thu Sep 29 15:59:06 2016 1525 [CDialog::receiveRequest]
Thu Sep 29 15:59:06 2016 1525 [CDialog::receiveAckRequest]
Thu Sep 29 15:59:06 2016 2105 [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] STATE CHANGED TO CONFIRMED
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] notifying the parent dialog
Thu Sep 29 15:59:06 2016 1525 [CDialog::onTransactionState(pTrans = 2105, previousState = Completed, currentState = Confirmed, reason = Request reception]
Thu Sep 29 15:59:06 2016 2105 [CTransaction::freeTimerToken] Timer G is freed
Thu Sep 29 15:59:06 2016 2105 [CTransaction::freeTimerToken] Timer H is freed
Thu Sep 29 15:59:06 2016 2105 [CTransaction::startTimer] Timer I is started (delay = 5000 ms)
Thu Sep 29 15:59:06 2016 1525 [CDialog::receiveAckRequest] receiving an ACK on a previously rejected INVITE
Thu Sep 29 15:59:06 2016 11c3 [CCall::checkAuthentication] ACK
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::onReceiveRequest] s=0 d=0 v=0 o=1.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::onReceiveRequest] request for gateway.
1475157546 -> Thu Sep 29 15:59:06 2016 11c3[CMotorCall::onReceiveRequest] state = TERMINATED_STATE.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::compareDialogFromContext] same Dialog
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::methodAckReceived] Ack => update the inviteContext.
Thu Sep 29 15:59:06 2016 [receiveAckMessage] Call: 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX eqt: 3525 TERMINATED_STATE received a message.
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] CAC = 0 eqtCAC = -1
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] cacUsed=0 eqtCac=-1
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] displayIPCMessageOut
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 ** SIP TRUNKING **
Thu Sep 29 15:59:06 2016 [sendLgEvtSip] Event sent on eqt : 3525 Id :-1
Thu Sep 29 15:59:06 2016 [ipc_thread] IPC Thread : Ipc reception 30139.
Thu Sep 29 15:59:06 2016 [ipc_thread] IPC Thread : TCL thread signaled.
Thu Sep 29 15:59:06 2016 [sendLgEvtSip] Event sent on eqt : 3525 Id :-1
Thu Sep 29 15:59:06 2016 [display_ipc_out] ------------ Begin ---------------
Thu Sep 29 15:59:06 2016 Id : -1
Thu Sep 29 15:59:06 2016 ACK
Thu Sep 29 15:59:06 2016 [display_ipc_out] ------------- End ----------------
Thu Sep 29 15:59:06 2016 [exec_ipc] in 30138.
Thu Sep 29 15:59:06 2016 [display_ipc_in] ------------ Begin ---------------
1475157546 -> Thu Sep 29 15:59:06 2016 neqt : 3525 Id : -1
Thu Sep 29 15:59:06 2016 SIP EQT RELEASED
Thu Sep 29 15:59:06 2016 [display_ipc_in] ------------- End ----------------
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 3525 -1.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onIncomingEvent] The call with eqt: 3525 has released its equipment.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::emitSipEqtReleaseAck] SIP_EQT_RELEASE_ACK
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel]1 T38 REINVITE TRUE
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] CAC = 0 eqtCAC = -1
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] cacUsed=0 eqtCac=-1
Thu Sep 29 15:59:06 2016 11c3[emitEventToMonitel] displayIPCMessageOut
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 [isValidGwExt] ext gw 7 is valid
Thu Sep 29 15:59:06 2016 ** SIP TRUNKING **
Thu Sep 29 15:59:06 2016 [sendLgEvtSip] Event sent on eqt : 3525 Id :-1
Thu Sep 29 15:59:06 2016 [sendLgEvtSip] Event sent on eqt : 3525 Id :-1
Thu Sep 29 15:59:06 2016 [display_ipc_out] ------------ Begin ---------------
Thu Sep 29 15:59:06 2016 Id : -1
Thu Sep 29 15:59:06 2016 SIP_EQT_RELEASE_ACK
Thu Sep 29 15:59:06 2016 [display_ipc_out] ------------- End ----------------
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
1475157546 -> Thu Sep 29 15:59:06 2016 11c3[CMotorCall::unRegister] Remove eqt : 3525 diag : -1 from the map.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::eraseCallwithEqt] erase 3525 -1.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onIncomingEvent] killSession.
Thu Sep 29 15:59:06 2016 11c3 [CCall::killSession]
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Thu Sep 29 15:59:06 2016 2105 [CTransaction::freeTimerToken] Timer I is freed
Thu Sep 29 15:59:06 2016 2105 [CTransaction::changeState] no transaction state, terminated reason : None
Thu Sep 29 15:59:06 2016 11c3 [CCall::killSession] notifying the call manager
Thu Sep 29 15:59:06 2016 102 [CCallManager::onCallClosed]
Thu Sep 29 15:59:06 2016 102 [CCallManager::onCallClosed()] Call 11c3 closed. Removing call 139497240 from the maps
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onNotifyCallClosed] Call : 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX is closed.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onNotifyCallClosed] Call : 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX is closed.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onNotifyCallClosed] call processResponse.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::chooseBestResponse] pForkedBranch=0x84de870 iResponseCode=2000.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::chooseBestResponse] next branch.
Thu Sep 29 15:59:06 2016 11c3[CMotorCall::processResponse] no response found.
Thu Sep 29 15:59:06 2016 [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1475157548 -> Thu Sep 29 15:59:06 2016 [exec_ipc] in end 30138.
Thu Sep 29 15:59:06 2016 [CTransaction::notifyDialog]
Thu Sep 29 15:59:06 2016 [CTransaction::notifyDialog] notify parent dialog
Thu Sep 29 15:59:06 2016 1525 [CDialog::onTransactionState(pTrans = 2105, previousState = null, currentState = Terminated, reason = None]
Thu Sep 29 15:59:06 2016 1525 [CDialog::onTransactionState] dialog is terminated
Thu Sep 29 15:59:06 2016 1525 [CDialog::onTransactionState] onDialogClosed
Thu Sep 29 15:59:06 2016 11c3 [CCall::onDialogClosed]
Thu Sep 29 15:59:06 2016 11c3 [CCall::onDialogClosed] only initial *********
Thu Sep 29 15:59:06 2016 11c3 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
Thu Sep 29 15:59:06 2016 2105 [~CTransaction] transaction 102 INVITE
Thu Sep 29 15:59:06 2016 9E5DE [~CRequest] req 6701cd16208307e376103e2b456d25ca@XXX.XXX.XX.XX 102 ACK
Thu Sep 29 15:59:08 2016 RECEIVE MESSAGE FROM NETWORK (10.255.254.66:5060 [UDP])