Добрый день! Подскажите, может, кто сталкивался. Я пытаюсь организовать входящую SIP-связь с CCM 4.1 через SIP-прокси сервер на OXE 9.1. Схема сложная и с преобразованием полей INVITE сообщений. В общем, важно то, что на OXE приходит INVITE и в ответ 502 bad gateway (после TRYING и раздумий), а при такой же схеме вызова с другого OXE или SIP-шлюза в ответ приходит RINGING. На принимающнй OXE SIP-транк группа ABC-F и внешний gateway.
Код
traced
** UNIX-trace-daemon started ... (static user group No 1) **
traced started ...
1321792691 -> RECEIVE MESSAGE FROM NETWORK (10.77.9.21:32773 [UDP])
----------------------utf8-----------------------
INVITE sip:9341333@10.34.9.4:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~9
Via: SIP/2.0/UDP 10.33.9.4:5060;branch=z9hG4bK478e8f1c
Max-Forwards: 69
To: <sip:9341333@sip-cluster.fssp;user=phone>
From: "Test phone" <sip:9331666@node33;user=phone>;tag=16783129
Contact: <sip:9331666@10.33.9.4:5060;transport=udp>
Expires: 180
Remote-Party-ID: "Test phone" <sip:9331666@10.33.9.4>;party=calling;screen=no;privacy=off
Call-ID: d2190980-1e21317f-db-409210a@10.33.9.4
CSeq: 101 INVITE
Content-Length: 218
Date: Sun, 20 Nov 2011 13:37:44 GMT
Supported: timer
Min-SE: 1800
User-Agent: Cisco-CCM4.1
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK
Allow-Events: telephone-event
Content-Type: application/sdp
v=0
o=CiscoSystemsCCM-SIP 2000 1000 IN IP4 10.33.9.4
s=SIP Call
c=IN IP4 10.33.9.4
t=0 0
m=audio 24890 RTP/AVP 8 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
-------------------------------------------------
1321792691 -> C8 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1321792691 -> [CIOCom::receiveRequest] Received CRequest C8 ref=1
1321792691 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321792691 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321792691 -> [CIOCom::receiveRequest] rport1 not empty
1321792691 -> [CDispatcher::onIncomingRequest()]
1321792691 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1321792691 -> 102 [CCallManager::onIncomingRequest()]
1321792691 -> [CMotorCallManager::callFactory] For call : d2190980-1e21317f-db-409210a@10.33.9.4.
1321792691 -> [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R9.1 i1.605.16
1321792691 -> [CMotorCallManager::callFactory]=97d2a38.
1321792691 -> 1153 [CCall::receiveRequest] INVITE
1321792691 -> 1153 [CCall::getDialog] Confirmed Dialog is not found (ID = ;16783129)
1321792691 -> 1153 [CCall::getDialog] Initial Dialog Server not found
1321792691 -> 155b [CDialog::receiveRequest]
1321792691 -> 155b [CDialog::receiveInviteRequest]
1321792691 -> 2184 [CTransaction::changeState] STATE CHANGED TO INITIAL
1321792691 -> 2184 [CTransaction::changeState] notifying the parent dialog
1321792691 -> 155b [CDialog::onTransactionState(pTrans = 2184, previousState = Terminated, currentState = Initial, reason = None]
1321792691 -> 2184 [CTransInitialState::receiveRequest] Transaction changes to Proceeding state
1321792691 -> 2184 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1321792691 -> 2184 [CTransaction::changeState] notifying the parent dialog
1321792691 -> 155b [CDialog::onTransactionState(pTrans = 2184, previousState = Initial, currentState = Proceeding, reason = Request reception]
1321792691 -> 2184 [CTransProceedingState::createResponse] CResponse 106 ref=1
1321792691 -> 106 [CMessage::send] sip_sendMessage (10.77.9.21:5060)
1321792691 -> SEND MESSAGE TO NETWORK (10.77.9.21:5060 [UDP]) (BUFF LEN = 350)
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:9341333@sip-cluster.fssp;user=phone>
From: "Test phone" <sip:9331666@node33;user=phone>;tag=16783129
Call-ID: d2190980-1e21317f-db-409210a@10.33.9.4
CSeq: 101 INVITE
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~9
Via: SIP/2.0/UDP 10.33.9.4:5060;branch=z9hG4bK478e8f1c
Content-Length: 0
-------------------------------------------------
1321792691 -> 155b [CDialog::receiveInviteRequest] a new Server INVITE transaction is in progress
1321792691 -> [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Server, UA)
1321792691 -> [CSessionTimerContext::updateAfterRefreshReception] Upd ate CSessionTimerContext (refresh reception)
1321792691 -> [CSessionTimerContext::updateSupported] Request timer supported
1321792691 -> [CSessionTimerContext::updateMinSE] Min-SE updated : 1800
1321792691 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 0
1321792691 -> [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=INVITE
1321792691 -> 155b [CDialog::addTransaction] added transaction 2184 with branch z9hG4bKQsDAoqFjZNu56d4.S1mokg~~9, with CSeq 101
1321792691 -> 1153 [CCall::checkAuthentication] INVITE
1321792691 -> 1153[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1321792691 -> [CGwMngDat a::isAuthenticationRequiredForThisClient] node33 .
1321792691 -> [isDomainFromGwExt] Host from request is : node33.
1321792691 -> [isDomainFromGwExt] User from request is : 9331666
1321792691 -> [domain not from an External Gateway.
1321792691 -> 1153[CMotorCall::setFilterUsedMode] To be traced = 0
1321792691 -> [getFromHeader].
1321792691 -> [isUserDeclared] 9331666@node33
1321792691 -> isUserDeclared] NOT found in the dictionnary.
1321792691 -> [isSeplosStation] other station
1321792691 -> 1153[CMotorCall::toGatewayOrProxy] request for proxydomain=10.34.9.4.
1321792691 -> [isUserRegistred] getRegistration=9341333.
1321792691 -> [isUserRegistred] result=-1.
1321792691 -> 1153[CMotorCall::onReceiveRequest] request for gateway.
1321792691 -> 1153[CMotorCall::onReceiveRequest] state = INITIAL_STATE.
1321792691 -> 1153[CMotorCall::methodInviteReceived] nb available licenses=30 .
1321792691 -> [isUserDeclared] 9331666@node33
1321792691 -> isUserDeclared] NOT found in the dictionnary.
1321792691 -> 1153[CMotorCall::compareDialogFromContext] presumed to be the same Dialog
1321792691 -> 1153[CMotorCall::methodInviteReceived] Invite => update the inviteContext.
1321792691 -> [CForkedBranch::CForkedBranch] context = 0x97d2e68.
1321792691 -> [CForkedBranch::reqUriDuplication] reqUri :sip:9331666@10.33.9.4:5060;transport=udp
1321792691 -> 1153[CMotorCall::methodInviteReceived] CAC = 0
1321792691 -> [receiveInviteMessage] Call: d2190980-1e21317f-db-409210a@10.33.9.4 eqt: -1 INITIAL_STATE received a message.
1321792691 -> [decodeInviteCLIR].
1321792691 -> [isDomainFromGwExt] Host from request is : node33.
1321792691 -> [isDomainFromGwExt] User from request is : 9331666
1321792691 -> am_I_PCS SCSWorking : NO
1321792691 -> check_PCSstate
1321792691 -> am_I_PCS SCSWorking : NO
1321792691 -> check_PCSstate stat of PCS is : -1
1321792691 -> [isSessionTimerTooSmall].
1321792691 -> 1153[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=0 uacRefresher=0.
1321792691 -> [decodeInviteSDP].
1321792691 -> [checkSdpValidity] Sdp contains 1 media description(s).
1321792691 -> [checkSdpValidity] Media 0 type 1 contains 2 formats.
1321792691 -> [checkSdpValidity] Format : 8.
1321792691 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1321792691 -> [convertSdpIntoTsdp] No Direction in the session part.
1321792691 -> [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1321792691 -> [convertSdpIntoTsdp] The audio media contains 2 format(s).
1321792691 -> [convertSdpIntoTsdp] Format 0 is 8.
1321792691 -> [convertSdpIntoTsdp] Format 1 is 97.
1321792691 -> [convertSdpIntoTsdp] Get the direction.
1321792691 -> [convertSdpIntoTsdp] Direction is sendrecv.
1321792691 -> [convertSdpIntoTsdp] The connection is at session level.
1321792691 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 10.33.9.4.
1321792691 -> [convertIPStrIntoTuipv] 10.33.9.4 => 10.33.9.4
1321792691 -> [receiveInviteMessage] NO 100rel REQUIRED for this call.
1321792691 -> [isCACWithInvalidMediaAddr].
1321792691 -> [decodeInviteTo].
1321792691 -> [decodeInviteReferredBy].
1321792691 -> [decodeInviteACSBU].
1321792691 -> 1153[CMotorCall::setFilterUsedMode] To be traced = 0
1321792691 -> 1153[emitEventToMonitel] content size (lginfo): 656
1321792691 -> [CMotorCallManager::insertCallwithEqt] CMotorCall 1240 inserted.
1321792691 -> 1153[sendLgEvtSipCreate] Event sent on eqt : 1240
1321792691 -> 1153[CMotorCall::getOutgoingCall] outgoingCall:0
1321792691 -> 1153 [CCall::getCallDuplicationData]
1321792691 -> [CViaSetSerializer::copy] Added via 7cc2 at index 0
1321792691 -> [CViaSetSerializer::copy] Added via 7cc3 at index 1
1321792691 -> 1153 [CCall::getCallDuplicationData] : Request URI = sip:9341333@10.34.9.4:5060;user=phone;transport=udp, Call ID = d2190980-1e21317f-db-409210a@10.33.9.4, Local URI = sip:9341333@sip-cluster.fssp;user=phone, Local Tag = , Remote URI = sip:9331666@node33;user=phone, Remote Tag = 16783129, Local CSeq = 1344967239, Remote CSeq = 101
1321792691 -> [CViaSetSerializer::serialize] Serialized via set : ViaSerialize=Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~9,Via=Via: SIP/2.0/UDP 10.33.9.4:5060;branch=z9hG4bK478e8f1c
1321792691 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321792691 -> [receiveInviteMessage] send RemoteSdp to the StandBy.
1321792691 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321792691 -> [ipc_thread] IPC Thread : Ipc reception 2432.
1321792691 -> [ipc_thread] IPC Thread : TCL thread signaled.
1321792691 -> [exec_ipc] in 2431.
1321792691 -> [display_ipc_in] ------------ Begin ---------------
1321792691 -> neqt : 1240 Id : -1
1321792691 -> SERVER FAILURE
1321792691 -> xx : 2
1321792691 -> RELATIVE REQUEST : INVITE
1321792691 -> [display_ipc_in] ------------- End ----------------
1321792691 -> [CMotorCallManager::onIncomingEvent] an event 10768 arrived on the eqt 1240--1.
1321792691 -> [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1321792691 -> [onIncomingEvent] SERVER FAILURE arrived.
1321792691 -> [receiveServerFailureEvent] Call: d2190980-1e21317f-db-409210a@10.33.9.4 eqt: 1240 PROCEEDING_STATE received an event.
1321792691 -> 1153[CMotorCall::getOutgoingCall] outgoingCall:0
1321792691 -> [receiveServerFailureEvent] incoming call.
1321792691 -> 1153 [CCall::makeGenericResponse] 502
1321792691 -> 1153 [CCall::getDialog] Confirmed Dialog is not found (ID = ;16783129)
1321792691 -> 1153 [CCall::getDialog] Returning Initial Dialog Server
1321792691 -> 155b [CDialog::createResponse] 502
1321792691 -> 2184 [CTransProceedingState::createResponse] CResponse 107 ref=1
1321792691 -> 107 [CMessage::send] sip_sendMessage (10.77.9.21:5060)
1321792691 -> SEND MESSAGE TO NETWORK (10.77.9.21:5060 [UDP]) (BUFF LEN = 439)
----------------------utf8-----------------------
SIP/2.0 502 Bad Gateway
User-Agent: OmniPCX Enterprise R9.1 i1.605.16
To: <sip:9341333@sip-cluster.fssp;user=phone>;tag=05f5b2c7a2a087c0f7b084c3d1f57368
From: "Test phone" <sip:9331666@node33;user=phone>;tag=16783129
Call-ID: d2190980-1e21317f-db-409210a@10.33.9.4
CSeq: 101 INVITE
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~9
Via: SIP/2.0/UDP 10.33.9.4:5060;branch=z9hG4bK478e8f1c
Content-Length: 0
-------------------------------------------------
1321792691 -> 106 [~CResponse] resp(100) d2190980-1e21317f-db-409210a@10.33.9.4 101 INVITE
1321792691 -> 2184 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
1321792691 -> 2184 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1321792691 -> 2184 [CTransaction::changeState] notifying the parent dialog
1321792691 -> 155b [CDialog::onTransactionState(pTrans = 2184, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
1321792691 -> 2184 [CTransaction::startTimer] Timer G is started (delay = 500 ms)
1321792691 -> 2184 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1321792691 -> 155b [CDialog::updateRouteSet] there are 0 record-route headers
1321792691 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321792691 -> [exec_ipc] in end 2431.
1321792691 -> RECEIVE MESSAGE FROM NETWORK (10.77.9.21:32773 [UDP])
----------------------utf8-----------------------
ACK sip:9341333@10.34.9.4:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~9
Max-Forwards: 70
To: <sip:9341333@sip-cluster.fssp;user=phone>;tag=05f5b2c7a2a087c0f7b084c3d1f57368
From: "Test phone" <sip:9331666@node33;user=phone>;tag=16783129
Call-ID: d2190980-1e21317f-db-409210a@10.33.9.4
CSeq: 101 ACK
Content-Length: 0
-------------------------------------------------
1321792691 -> C9 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1321792691 -> [CIOCom::receiveRequest] Received CRequest C9 ref=1
1321792691 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321792691 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321792691 -> [CIOCom::receiveRequest] rport1 not empty
1321792691 -> [CDispatcher::onIncomingRequest()]
1321792691 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1321792691 -> 102 [CCallManager::onIncomingRequest()]
1321792691 -> 1153 [CCall::receiveRequest] ACK
1321792691 -> 1153 [CCall::getDialog] Confirmed Dialog is not found (ID = 05f5b2c7a2a087c0f7b084c3d1f57368;16783129)
1321792691 -> 1153 [CCall::getDialog] Returning Initial Dialog Server
1321792691 -> 155b [CDialog::receiveRequest]
1321792691 -> 155b [CDialog::receiveAckRequest]
1321792691 -> 2184 [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
1321792691 -> 2184 [CTransaction::changeState] STATE CHANGED TO CONFIRMED
1321792691 -> 2184 [CTransaction::changeState] notifying the parent dialog
1321792691 -> 155b [CDialog::onTransactionState(pTrans = 2184, previousState = Completed, currentState = Confirmed, reason = Request reception]
1321792691 -> 2184 [CTransaction::freeTimerToken] Timer G is freed
1321792691 -> 2184 [CTransaction::freeTimerToken] Timer H is freed
1321792691 -> 2184 [CTransaction::startTimer] Timer I is started (delay = 5000 ms)
1321792691 -> 155b [CDialog::receiveAckRequest] receiving an ACK on a previously rejected INVITE
1321792691 -> 1153 [CCall::checkAuthentication] ACK
1321792691 -> 1153[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1321792691 -> 1153[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1321792691 -> [getFromHeader].
1321792691 -> [isUserDeclared] 9331666@node33
1321792691 -> isUserDeclared] NOT found in the dictionnary.
1321792691 -> [isSeplosStation] other station
1321792691 -> 1153[CMotorCall::toGatewayOrProxy] request for proxydomain=10.34.9.4.
1321792691 -> [isUserRegistred] getRegistration=9341333.
1321792691 -> [isUserRegistred] result=-1.
1321792691 -> 1153[CMotorCall::onReceiveRequest] request for gateway.
1321792691 -> 1153[CMotorCall::onReceiveRequest] state = TERMINATED_STATE.
1321792691 -> 1153[CMotorCall::compareDialogFromContext] same Dialog
1321792691 -> 1153[CMotorCall::methodAckReceived] Ack => update the inviteContext.
1321792691 -> [receiveAckMessage] Call: d2190980-1e21317f-db-409210a@10.33.9.4 eqt: 1240 TERMINATED_STATE received a message.
1321792691 -> 1153[emitEventToMonitel] content size (lginfo): 656
1321792691 -> 1153[emitEventToMonitel] CAC = 0 eqtCAC = -1
1321792691 -> 1153[emitEventToMonitel] CAC = 0
1321792691 -> [sendLgEvtSip] Event sent on eqt : 1240 Id :-1
1321792691 -> [display_ipc_out] ------------ Begin ---------------
1321792691 -> Id : -1
1321792691 -> ACK
1321792691 -> [display_ipc_out] ------------- End ----------------
1321792691 -> [ipc_thread] IPC Thread : Ipc reception 2432.
1321792691 -> [ipc_thread] IPC Thread : TCL thread signaled.
1321792691 -> [exec_ipc] in 2431.
1321792691 -> [display_ipc_in] ------------ Begin ---------------
1321792691 -> neqt : 1240 Id : -1
1321792691 -> SIP EQT RELEASED
1321792691 -> [display_ipc_in] ------------- End ----------------
1321792691 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 1240--1.
1321792691 -> [CMotorCallManager::onIncomingEvent] The call with eqt: 1240 has released its equipment.
1321792691 -> 1153[CMotorCall::emitSipEqtReleaseAck] SIP_EQT_RELEASE_ACK
1321792691 -> 1153[emitEventToMonitel] content size (lginfo): 656
1321792691 -> 1153[emitEventToMonitel] CAC = 0 eqtCAC = -1
1321792691 -> 1153[emitEventToMonitel] CAC = 0
1321792691 -> [sendLgEvtSip] Event sent on eqt : 1240 Id :-1
1321792691 -> [display_ipc_out] ------------ Begin ---------------
1321792691 -> Id : -1
1321792691 -> UNKNOWN MESSAGE
1321792691 -> [display_ipc_out] ------------- End ----------------
1321792691 -> 1153[CMotorCall::unRegister] Remove eqt : 1240 diag : -1 from the map.
1321792691 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 1240 erased.
1321792691 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1321792691 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321792691 -> [CMotorCallManager::onIncomingEvent] killSession.
1321792691 -> 1153 [CCall::killSession]
1321792691 -> 2184 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1321792691 -> 2184 [CTransaction::freeTimerToken] Timer I is freed
1321792691 -> 2184 [CTransaction::changeState] no transaction state, terminated reason : None
1321792691 -> 1153 [CCall::onKill Session Call manager close is called]
1321792691 -> 1153 [CCall::killSession] notifying the call manager
1321792691 -> 102 [CCallManager::onCallClosed]
1321792691 -> 102 [CCallManager::onCallClosed()] Call 1153 closed. Removing call 159197752 from the maps
1321792691 -> [CMotorCallManager::onNotifyCallClosed] Call : d2190980-1e21317f-db-409210a@10.33.9.4 is closed.
1321792691 -> [CMotorCallManager::onNotifyCallClosed] Call : d2190980-1e21317f-db-409210a@10.33.9.4 is closed.
1321792691 -> [CMotorCallManager::onNotifyCallClosed] call processResponse.
1321792691 -> 1153[CMotorCall::processResponse] call chooseBestResponse.
1321792691 -> 1153[CMotorCall::chooseBestResponse] pForkedBranch=0x97d3348 iResponseCode=2000.
1321792691 -> [CForkedBranch::getResponseCode] response=2000.
1321792691 -> 1153[CMotorCall::chooseBestResponse] next branch.
1321792691 -> 1153[CMotorCall::processResponse] no response found.
1321792691 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1321792691 -> [CCall::onKill] se t callmanager to null
1321792691 -> [exec_ipc] in end 2431.
1321792691 -> [CTransaction::notifyDialog]
1321792691 -> [CTransaction::notifyDialog] notify parent dialog
1321792691 -> 155b [CDialog::onTransactionState(pTrans = 2184, previousState = null, currentState = Terminated, reason = None]
1321792691 -> 155b [CDialog::onTransactionState] dialog is terminated
1321792691 -> 155b [CDialog::onTransactionState] onDialogClosed
1321792691 -> 1153 [CCall::onDialogClosed]
1321792691 -> 1153 [CCall::onDialogClosed] only initial *********
1321792691 -> 1153 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1321792691 -> 2184 [~CTransaction] transaction 101 INVITE
1321792691 -> C9 [~CRequest] req d2190980-1e21317f-db-409210a@10.33.9.4 101 ACK
1321792692 -> 102 [CCallManager::removeGarbage] at index i = 0
1321792692 -> 1153[CMotorCall::~CMotorCall] Call : d2190980-1e21317f-db-409210a@10.33.9.4 is being destructed.
1321792692 -> 1153 [CCall::~CCall]
1321792692 -> 1153 [CCall::removeGarbage]
1321792692 -> 155b [CDialog::~CDialog]
1321792692 -> [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
1321792692 -> 107 [~CResponse] resp(502) d2190980-1e21317f-db-409210a@10.33.9.4 101 INVITE
1321792692 -> C8 [~CRequest] req d2190980-1e21317f-db-409210a@10.33.9.4 101 INVITE
1321792691 -> 1153[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication 1321792691 -> [CGwMngDat a::isAuthenticationRequiredForThisClient] node33 . 1321792691 -> [isDomainFromGwExt] Host from request is : node33. 1321792691 -> [isDomainFromGwExt] User from request is : 9331666 1321792691 -> [domain not from an External Gateway. 1321792691 -> 1153[CMotorCall::setFilterUsedMode] To be traced = 0 1321792691 -> [getFromHeader]. 1321792691 -> [isUserDeclared] 9331666@node33 1321792691 -> isUserDeclared] NOT found in the dictionnary. 1321792691 -> [isSeplosStation] other station 1321792691 -> 1153[CMotorCall::toGatewayOrProxy] request for proxydomain=10.34.9.4. 1321792691 -> [isUserRegistred] getRegistration=9341333. 1321792691 -> [isUserRegistred] result=-1. 1321792691 -> 1153[CMotorCall::onReceiveRequest] request for gateway. 1321792691 -> 1153[CMotorCall::onReceiveRequest] state = INITIAL_STATE. 1321792691 -> 1153[CMotorCall::methodInviteReceived] nb available licenses=30 . 1321792691 -> [isUserDeclared] 9331666@node33 1321792691 -> isUserDeclared] NOT found in the dictionnary.
имхо надо разобраться кто куда и откуда звонит в плане соответствия имен и IP адресов и постановки их в нужное место.
У Дарта Вейдера в столе всякого навалено: карты разные галактик и портрет Гагарина.
Да, я с этого и начинал, манипулируя полями домена и номеров. Это не привело ни к какому результату. Более того, я могу представить трейс точно такого же звонка, инициировавнного с OXE, и там точно такой же lookup происходит с такими же результатами. Видимо, это последствия того, что remote gateway один на все направления принимает звонки без определённого указания домена.
оч сомневаюсь что ОДИНАКОВЫЙ инвайт будет обрабатываться по-разному. впрочем если пробовали - покажите удачно обрабтанный инвайт на этот же номер. посмотрим на него, возможно станет яснее.
У Дарта Вейдера в столе всякого навалено: карты разные галактик и портрет Гагарина.
А не поможет ли, случайно, создание ещё одной SIP транковой группы типа ISDN для входящей связи с ССМ, ещё одного remote gateway с указанием этой транковой группы. Исходящая связь может идти через другие группы. И домен можно другой.
etc, Поможет и даже будет работать. Но это плохой вариант: слишком много станций в сети. Собственно, для унификации и централизации управления SIP прокси и используется.
Dmitry Ryzhakov, они не одинаковые, но очень похожие. А вот процесс и результат поиска, действительно, такой же. Вот трейс: удачного вызова (на поле To не обращайте внимание, так надо):
Код
traced
** UNIX-trace-daemon started ... (static user group No 1) **
traced started ...
1321856216 -> RECEIVE MESSAGE FROM NETWORK (10.77.9.21:32798 [UDP])
----------------------utf8-----------------------
INVITE sip:9341333@10.34.9.4:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96
Via: SIP/2.0/UDP 10.1.9.4;branch=z9hG4bKd8f3330c3a6241158e07fd08748162ec
Max-Forwards: 69
To: <sip:49341333@sip-cluster.fssp;user=phone>
From: "Service Adygeya" <sip:9011666@node01;user=phone>;tag=931b3b3e8f06acfc04f86843e0521f76
Contact: <sip:9011666@10.1.9.4;transport=udp>
Call-ID: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4
CSeq: 966424859 INVITE
Content-Length: 282
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE, INFO
Supported: replaces,timer,100rel
User-Agent: OmniPCX Enterprise R9.1 i1.605.25
Session-Expires: 1800;refresher=uac
Min-SE: 900
P-Asserted-Identity: "Service Adygeya" <sip:9011666@node01;user=phone>
Content-Type: application/sdp
v=0
o=OXE 1321856208 1321856208 IN IP4 10.1.9.4
s=abs
c=IN IP4 10.0.8.168
t=0 0
m=audio 32514 RTP/AVP 18 8 97
a=sendrecv
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:30
a=maxptime:40
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1321856216 -> D3 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1321856216 -> [CIOCom::receiveRequest] Received CRequest D3 ref=1
1321856216 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321856216 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321856216 -> [CIOCom::receiveRequest] rport1 not empty
1321856216 -> [CDispatcher::onIncomingRequest()]
1321856216 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1321856216 -> 102 [CCallManager::onIncomingRequest()]
1321856216 -> [CMotorCallManager::callFactory] For call : d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4.
1321856216 -> [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R9.1 i1.605.16
1321856216 -> [CMotorCallManager::callFactory]=98c9ed0.
1321856216 -> 1158 [CCall::receiveRequest] INVITE
1321856216 -> 1158 [CCall::getDialog] Confirmed Dialog is not found (ID = ;931b3b3e8f06acfc04f86843e0521f76)
1321856216 -> 1158 [CCall::getDialog] Initial Dialog Server not found
1321856216 -> 1560 [CDialog::receiveRequest]
1321856216 -> 1560 [CDialog::receiveInviteRequest]
1321856216 -> 218b [CTransaction::changeState] STATE CHANGED TO INITIAL
1321856216 -> 218b [CTransaction::changeState] notifying the parent dialog
1321856216 -> 1560 [CDialog::onTransactionState(pTrans = 218b, previousState = Terminated, currentState = Initial, reason = None]
1321856216 -> 218b [CTransInitialState::receiveRequest] Transaction changes to Proceeding state
1321856216 -> 218b [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1321856216 -> 218b [CTransaction::changeState] notifying the parent dialog
1321856216 -> 1560 [CDialog::onTransactionState(pTrans = 218b, previousState = Initial, currentState = Proceeding, reason = Request reception]
1321856216 -> 218b [CTransProceedingState::createResponse] CResponse 114 ref=1
1321856216 -> 114 [CMessage::send] sip_sendMessage (10.77.9.21:5060)
1321856216 -> SEND MESSAGE TO NETWORK (10.77.9.21:5060 [UDP]) (BUFF LEN = 408)
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:49341333@sip-cluster.fssp;user=phone>
From: "Service Adygeya" <sip:9011666@node01;user=phone>;tag=931b3b3e8f06acfc04f86843e0521f76
Call-ID: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4
CSeq: 966424859 INVITE
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96
Via: SIP/2.0/UDP 10.1.9.4;branch=z9hG4bKd8f3330c3a6241158e07fd08748162ec
Content-Length: 0
-------------------------------------------------
1321856216 -> 1560 [CDialog::receiveInviteRequest] a new Server INVITE transaction is in progress
1321856216 -> [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Server, UA)
1321856216 -> [CSessionTimerContext::updateAfterRefreshReception] Upd ate CSessionTimerContext (refresh reception)
1321856216 -> [CSessionTimerContext::updateSupported] Request timer supported
1321856216 -> [CSessionTimerContext::updateMinSE] Min-SE updated : 900
1321856216 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1321856216 -> [CSessionTimerContext::updateSessionExpires] updated
1321856216 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : r
1321856216 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1321856216 -> [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=UPDATE
1321856216 -> 1560 [CDialog::addTransaction] added transaction 218b with branch z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96, with CSeq 966424859
1321856216 -> 1158 [CCall::checkAuthentication] INVITE
1321856216 -> 1158[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1321856216 -> [CGwMngDat a::isAuthenticationRequiredForThisClient] node01 .
1321856216 -> [isDomainFromGwExt] Host from request is : node01.
1321856216 -> [isDomainFromGwExt] User from request is : 9011666
1321856216 -> [domain not from an External Gateway.
1321856216 -> 1158[CMotorCall::setFilterUsedMode] To be traced = 0
1321856216 -> [getFromHeader].
1321856216 -> [isUserDeclared] 9011666@node01
1321856216 -> isUserDeclared] NOT found in the dictionnary.
1321856216 -> [isSeplosStation] other station
1321856216 -> 1158[CMotorCall::toGatewayOrProxy] request for proxydomain=10.34.9.4.
1321856216 -> [isUserRegistred] getRegistration=9341333.
1321856216 -> [isUserRegistred] result=-1.
1321856216 -> 1158[CMotorCall::onReceiveRequest] request for gateway.
1321856216 -> 1158[CMotorCall::onReceiveRequest] state = INITIAL_STATE.
1321856216 -> 1158[CMotorCall::methodInviteReceived] nb available licenses=30 .
1321856216 -> [isUserDeclared] 9011666@node01
1321856216 -> isUserDeclared] NOT found in the dictionnary.
1321856216 -> 1158[CMotorCall::compareDialogFromContext] presumed to be the same Dialog
1321856216 -> 1158[CMotorCall::methodInviteReceived] Invite => update the inviteContext.
1321856216 -> [CForkedBranch::CForkedBranch] context = 0x98ca300.
1321856216 -> [CForkedBranch::reqUriDuplication] reqUri :sip:9011666@10.1.9.4;transport=udp
1321856216 -> 1158[CMotorCall::methodInviteReceived] CAC = 0
1321856216 -> [receiveInviteMessage] Call: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 eqt: -1 INITIAL_STATE received a message.
1321856216 -> [decodeInviteCLIR].
1321856216 -> [receiveInviteMessage] UPDATE present in Allow header
1321856216 -> [receiveInviteMessage] INFO present in Allow header
1321856216 -> [isDomainFromGwExt] Host from request is : node01.
1321856216 -> [isDomainFromGwExt] User from request is : 9011666
1321856216 -> am_I_PCS SCSWorking : NO
1321856216 -> check_PCSstate
1321856216 -> am_I_PCS SCSWorking : NO
1321856216 -> check_PCSstate stat of PCS is : -1
1321856216 -> [isSessionTimerTooSmall].
1321856216 -> 1158[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=0 uacRefresher=1.
1321856216 -> [decodeInviteSDP].
1321856216 -> [checkSdpValidity] Sdp contains 1 media description(s).
1321856216 -> [checkSdpValidity] Media 0 type 1 contains 3 formats.
1321856216 -> [checkSdpValidity] Format : 18.
1321856216 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1321856216 -> [convertSdpIntoTsdp] No Direction in the session part.
1321856216 -> [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1321856216 -> [convertSdpIntoTsdp] The audio media contains 3 format(s).
1321856216 -> [convertSdpIntoTsdp] Format 0 is 18.
1321856216 -> [convertSdpIntoTsdp] Format 1 is 8.
1321856216 -> [convertSdpIntoTsdp] Format 2 is 97.
1321856216 -> [convertSdpIntoTsdp] Get the direction.
1321856216 -> [convertSdpIntoTsdp] Direction is sendrecv.
1321856216 -> [convertSdpIntoTsdp] The connection is at session level.
1321856216 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 10.0.8.168.
1321856216 -> [convertIPStrIntoTuipv] 10.0.8.168 => 10.0.8.168
1321856216 -> [receiveInviteMessage] NO 100rel REQUIRED for this call.
1321856216 -> [isCACWithInvalidMediaAddr].
1321856216 -> [decodeInviteTo].
1321856216 -> [decodeInviteReferredBy].
1321856216 -> [decodeInviteACSBU].
1321856216 -> 1158[CMotorCall::setFilterUsedMode] To be traced = 0
1321856216 -> 1158[emitEventToMonitel] content size (lginfo): 656
1321856216 -> [ipc_thread] IPC Thread : Ipc reception 2432.
1321856216 -> [ipc_thread] IPC Thread : TCL thread signaled.
1321856216 -> [CMotorCallManager::insertCallwithEqt] CMotorCall 1241 inserted.
1321856216 -> 1158[sendLgEvtSipCreate] Event sent on eqt : 1241
1321856216 -> 1158[CMotorCall::getOutgoingCall] outgoingCall:0
1321856216 -> 1158 [CCall::getCallDuplicationData]
1321856216 -> [CViaSetSerializer::copy] Added via 7c25 at index 0
1321856216 -> [CViaSetSerializer::copy] Added via 7c26 at index 1
1321856216 -> 1158 [CCall::getCallDuplicationData] : Request URI = sip:9341333@10.34.9.4:5060;user=phone;transport=udp, Call ID = d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4, Local URI = sip:49341333@sip-cluster.fssp;user=phone, Local Tag = , Remote URI = sip:9011666@node01;user=phone, Remote Tag = 931b3b3e8f06acfc04f86843e0521f76, Local CSeq = 927150112, Remote CSeq = 966424859
1321856216 -> [CViaSetSerializer::serialize] Serialized via set : ViaSerialize=Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96,Via=Via: SIP/2.0/UDP 10.1.9.4;branch=z9hG4bKd8f3330c3a6241158e07fd08748162ec
1321856216 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321856216 -> [receiveInviteMessage] send RemoteSdp to the StandBy.
1321856216 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321856216 -> [exec_ipc] in 2431.
1321856216 -> [display_ipc_in] ------------ Begin ---------------
1321856216 -> neqt : 1241 Id : -1
1321856216 -> INFORMATIONAL
1321856216 -> xx : 80
1321856216 -> RELATIVE REQUEST : INVITE
1321856216 -> [display_ipc_in] ------------- End ----------------
1321856216 -> [CMotorCallManager::onIncomingEvent] an event 10764 arrived on the eqt 1241--1.
1321856216 -> [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1321856216 -> [onIncomingEvent] INFORMATIONAL arrived.
1321856216 -> [receiveInformationalEvent] Call: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 eqt: 1241 PROCEEDING_STATE received an event.
1321856216 -> 1158 [CCall::makeGenericResponse] 180
1321856216 -> 1158 [CCall::getDialog] Confirmed Dialog is not found (ID = ;931b3b3e8f06acfc04f86843e0521f76)
1321856216 -> 1158 [CCall::getDialog] Returning Initial Dialog Server
1321856216 -> 1560 [CDialog::createResponse] 180
1321856216 -> 218b [CTransProceedingState::createResponse] CResponse 115 ref=1
1321856216 -> 115 [CMessage::send] sip_sendMessage (10.77.9.21:5060)
1321856216 -> SEND MESSAGE TO NETWORK (10.77.9.21:5060 [UDP]) (BUFF LEN = 600)
----------------------utf8-----------------------
SIP/2.0 180 Ringing
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
Contact: sip:10.34.9.4
User-Agent: OmniPCX Enterprise R9.1 i1.605.16
To: <sip:49341333@sip-cluster.fssp;user=phone>;tag=ec9f8dc978ada343355d172ab8cbe0f4
From: "Service Adygeya" <sip:9011666@node01;user=phone>;tag=931b3b3e8f06acfc04f86843e0521f76
Call-ID: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4
CSeq: 966424859 INVITE
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96
Via: SIP/2.0/UDP 10.1.9.4;branch=z9hG4bKd8f3330c3a6241158e07fd08748162ec
Content-Length: 0
-------------------------------------------------
1321856216 -> 114 [~CResponse] resp(100) d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 966424859 INVITE
1321856216 -> 1560 [CDialog::updateRouteSet] there are 0 record-route headers
1321856216 -> [receiveInformationalEvent] UpdateContext send on the StandBy.
1321856216 -> 1158 [CCall::getCallDuplicationData]
1321856216 -> [CViaSetSerializer::copy] Added via 7c2d at index 0
1321856216 -> [CViaSetSerializer::copy] Added via 7c2e at index 1
1321856216 -> 1158 [CCall::getCallDuplicationData] : Request URI = sip:9341333@10.34.9.4:5060;user=phone;transport=udp, Call ID = d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4, Local URI = sip:49341333@sip-cluster.fssp;user=phone, Local Tag = ec9f8dc978ada343355d172ab8cbe0f4, Remote URI = sip:9011666@node01;user=phone, Remote Tag = 931b3b3e8f06acfc04f86843e0521f76, Local CSeq = 927150112, Remote CSeq = 966424859
1321856216 -> [CViaSetSerializer::serialize] Serialized via set : ViaSerialize=Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96,Via=Via: SIP/2.0/UDP 10.1.9.4;branch=z9hG4bKd8f3330c3a6241158e07fd08748162ec
1321856216 -> [CRouteSet::serialize] Serialized route set : Contact=sip:9011666@10.1.9.4;transport=udp
1321856216 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321856216 -> [exec_ipc] in end 2431.
1321856217 -> RECEIVE MESSAGE FROM NETWORK (10.77.9.21:32798 [UDP])
----------------------utf8-----------------------
CANCEL sip:9341333@10.34.9.4:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96
Max-Forwards: 70
To: <sip:49341333@sip-cluster.fssp;user=phone>
From: "Service Adygeya" <sip:9011666@node01;user=phone>;tag=931b3b3e8f06acfc04f86843e0521f76
Call-ID: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4
CSeq: 966424859 CANCEL
Content-Length: 0
-------------------------------------------------
1321856217 -> D4 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1321856217 -> [CIOCom::receiveRequest] Received CRequest D4 ref=1
1321856217 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321856217 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321856217 -> [CIOCom::receiveRequest] rport1 not empty
1321856217 -> [CDispatcher::onIncomingRequest()]
1321856217 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1321856217 -> 102 [CCallManager::onIncomingRequest()]
1321856217 -> 1158 [CCall::receiveRequest] CANCEL
1321856217 -> 1158 [CCall::getDialog] Confirmed Dialog is not found (ID = ;931b3b3e8f06acfc04f86843e0521f76)
1321856217 -> 1158 [CCall::getDialog] Returning Initial Dialog Server
1321856217 -> 1560 [CDialog::receiveRequest]
1321856217 -> 1560 [CDialog::receiveCancelRequest]
1321856217 -> 218c [CTransaction::changeState] STATE CHANGED TO INITIAL
1321856217 -> 218c [CTransaction::changeState] notifying the parent dialog
1321856217 -> 1560 [CDialog::onTransactionState(pTrans = 218c, previousState = Terminated, currentState = Initial, reason = None]
1321856217 -> 218c [CTransInitialState::receiveRequest] Transaction changes to Trying state
1321856217 -> 218c [CTransaction::changeState] STATE CHANGED TO TRYING
1321856217 -> 218c [CTransaction::changeState] notifying the parent dialog
1321856217 -> 1560 [CDialog::onTransactionState(pTrans = 218c, previousState = Initial, currentState = Trying, reason = Request reception]
1321856217 -> 1158 [CCall::checkAuthentication] CANCEL
1321856217 -> 1158[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1321856217 -> 1158[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1321856217 -> [getFromHeader].
1321856217 -> [isUserDeclared] 9011666@node01
1321856217 -> isUserDeclared] NOT found in the dictionnary.
1321856217 -> [isSeplosStation] other station
1321856217 -> 1158[CMotorCall::toGatewayOrProxy] request for proxydomain=10.34.9.4.
1321856217 -> [isUserRegistred] getRegistration=9341333.
1321856217 -> [isUserRegistred] result=-1.
1321856217 -> 1158[CMotorCall::onReceiveRequest] request for gateway.
1321856217 -> 1158[CMotorCall::onReceiveRequest] state = PROCEEDING_STATE.
1321856217 -> [receiveCancelMessage] Call: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 eqt: 1241 PROCEEDING_STATE received a message.
1321856217 -> 1158[emitEventToMonitel] content size (lginfo): 656
1321856217 -> 1158[emitEventToMonitel] CAC = 0 eqtCAC = -1
1321856217 -> 1158[emitEventToMonitel] CAC = 0
1321856217 -> [ipc_thread] IPC Thread : Ipc reception 2432.
1321856217 -> [ipc_thread] IPC Thread : TCL thread signaled.
1321856217 -> [sendLgEvtSip] Event sent on eqt : 1241 Id :-1
1321856217 -> [display_ipc_out] ------------ Begin ---------------
1321856217 -> Id : -1
1321856217 -> CANCEL
1321856217 -> [display_ipc_out] ------------- End ----------------
1321856217 -> [exec_ipc] in 2431.
1321856217 -> [display_ipc_in] ------------ Begin ---------------
1321856217 -> neqt : 1241 Id : -1
1321856217 -> SUCCESSFUL
1321856217 -> xx : 0
1321856217 -> RELATIVE REQUEST : CANCEL
1321856217 -> CLIR : 0
1321856217 -> COLP : 0
1321856217 -> [display_ipc_in] ------------- End ----------------
1321856217 -> [CMotorCallManager::onIncomingEvent] an event 10765 arrived on the eqt 1241--1.
1321856217 -> [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1321856217 -> [onIncomingEvent] SUCCESSFUL arrived.
1321856217 -> [onIncomingEvent] relative_request = 10756
1321856217 -> [CProceedingState::receiveSuccessfulEvent] Call: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 eqt: 1241 PROCEEDING_STATE received an event.
1321856217 -> 1158 [CCall::findFirstServerTrans]
1321856217 -> 1560 [CCall::findFirstServerTrans]
1321856217 -> 1560 [CDialog::findFirstServerTrans] Current Request in cancelTransactionMap
1321856217 -> 1158 [CCall::makeGenericResponse] 200
1321856217 -> 1158 [CCall::getDialog] Confirmed Dialog is not found (ID = ;931b3b3e8f06acfc04f86843e0521f76)
1321856217 -> 1158 [CCall::getDialog] Returning Initial Dialog Server
1321856217 -> 1560 [CDialog::createResponse] 200
1321856217 -> 218c [CTransTryingState::createResponse] CResponse 116 ref=1
1321856217 -> 116 [CMessage::send] sip_sendMessage (10.77.9.21:5060)
1321856217 -> SEND MESSAGE TO NETWORK (10.77.9.21:5060 [UDP]) (BUFF LEN = 411)
----------------------utf8-----------------------
SIP/2.0 200 OK
Supported: replaces,timer,100rel
User-Agent: OmniPCX Enterprise R9.1 i1.605.16
To: <sip:49341333@sip-cluster.fssp;user=phone>
From: "Service Adygeya" <sip:9011666@node01;user=phone>;tag=931b3b3e8f06acfc04f86843e0521f76
Call-ID: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4
CSeq: 966424859 CANCEL
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96
Content-Length: 0
-------------------------------------------------
1321856217 -> 218c [CTransTryingState::createResponse] Final : Transaction changes to Completed state
1321856217 -> 218c [CTransaction::changeState] STATE CHANGED TO COMPLETED
1321856217 -> 218c [CTransaction::changeState] notifying the parent dialog
1321856217 -> 1560 [CDialog::onTransactionState(pTrans = 218c, previousState = Trying, currentState = Completed, reason = Final resp creation]
1321856217 -> 218c [CTransaction::startTimer] Timer J is started (delay = 32000 ms)
1321856217 -> 1560 [CDialog::updateRouteSet] there are 0 record-route headers
1321856217 -> [exec_ipc] in end 2431.
1321856217 -> [ipc_thread] IPC Thread : Ipc reception 2432.
1321856217 -> [ipc_thread] IPC Thread : TCL thread signaled.
1321856217 -> [exec_ipc] in 2431.
1321856217 -> [display_ipc_in] ------------ Begin ---------------
1321856217 -> neqt : 1241 Id : -1
1321856217 -> REQUEST FAILURE
1321856217 -> xx : 87
1321856217 -> RELATIVE REQUEST : INVITE
1321856217 -> [display_ipc_in] ------------- End ----------------
1321856217 -> [CMotorCallManager::onIncomingEvent] an event 10767 arrived on the eqt 1241--1.
1321856217 -> [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1321856217 -> [onIncomingEvent] REQUEST FAILURE arrived.
1321856217 -> [receiveRequestFailureEvent] Call: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 eqt: 1241 PROCEEDING_STATE received an event.
1321856217 -> 1158[CMotorCall::getOutgoingCall] outgoingCall:0
1321856217 -> [receiveRequestFailureEvent] incoming call.
1321856217 -> 1158 [CCall::makeGenericResponse] 487
1321856217 -> 1158 [CCall::getDialog] Confirmed Dialog is not found (ID = ec9f8dc978ada343355d172ab8cbe0f4;931b3b3e8f06acfc04f86843e0521f76)
1321856217 -> 1158 [CCall::getDialog] Returning Initial Dialog Server
1321856217 -> 1560 [CDialog::createResponse] 487
1321856217 -> 218b [CTransProceedingState::createResponse] CResponse 117 ref=1
1321856217 -> 117 [CMessage::send] sip_sendMessage (10.77.9.21:5060)
1321856217 -> SEND MESSAGE TO NETWORK (10.77.9.21:5060 [UDP]) (BUFF LEN = 587)
----------------------utf8-----------------------
SIP/2.0 487 Request Terminated
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
User-Agent: OmniPCX Enterprise R9.1 i1.605.16
To: <sip:49341333@sip-cluster.fssp;user=phone>;tag=ec9f8dc978ada343355d172ab8cbe0f4
From: "Service Adygeya" <sip:9011666@node01;user=phone>;tag=931b3b3e8f06acfc04f86843e0521f76
Call-ID: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4
CSeq: 966424859 INVITE
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96
Via: SIP/2.0/UDP 10.1.9.4;branch=z9hG4bKd8f3330c3a6241158e07fd08748162ec
Content-Length: 0
-------------------------------------------------
1321856217 -> 115 [~CResponse] resp(180) d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 966424859 INVITE
1321856217 -> 218b [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
1321856217 -> 218b [CTransaction::changeState] STATE CHANGED TO COMPLETED
1321856217 -> 218b [CTransaction::changeState] notifying the parent dialog
1321856217 -> 1560 [CDialog::onTransactionState(pTrans = 218b, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
1321856217 -> 218b [CTransaction::startTimer] Timer G is started (delay = 500 ms)
1321856217 -> 218b [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1321856217 -> 1560 [CDialog::updateRouteSet] there are 0 record-route headers
1321856217 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321856217 -> [exec_ipc] in end 2431.
1321856217 -> RECEIVE MESSAGE FROM NETWORK (10.77.9.21:32798 [UDP])
----------------------utf8-----------------------
ACK sip:9341333@10.34.9.4:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.77.9.21:5060;branch=z9hG4bKQsDAoqFjZNu56d4.S1mokg~~96
Max-Forwards: 70
To: <sip:49341333@sip-cluster.fssp;user=phone>;tag=ec9f8dc978ada343355d172ab8cbe0f4
From: "Service Adygeya" <sip:9011666@node01;user=phone>;tag=931b3b3e8f06acfc04f86843e0521f76
Call-ID: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4
CSeq: 966424859 ACK
Content-Length: 0
-------------------------------------------------
1321856217 -> D5 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1321856217 -> [CIOCom::receiveRequest] Received CRequest D5 ref=1
1321856217 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321856217 -> [CIOCom::checkHostInBlackList] host=10.77.9.21
1321856217 -> [CIOCom::receiveRequest] rport1 not empty
1321856217 -> [CDispatcher::onIncomingRequest()]
1321856217 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1321856217 -> 102 [CCallManager::onIncomingRequest()]
1321856217 -> 1158 [CCall::receiveRequest] ACK
1321856217 -> 1158 [CCall::getDialog] Confirmed Dialog is not found (ID = ec9f8dc978ada343355d172ab8cbe0f4;931b3b3e8f06acfc04f86843e0521f76)
1321856217 -> 1158 [CCall::getDialog] Returning Initial Dialog Server
1321856217 -> 1560 [CDialog::receiveRequest]
1321856217 -> 1560 [CDialog::receiveAckRequest]
1321856217 -> 218b [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
1321856217 -> 218b [CTransaction::changeState] STATE CHANGED TO CONFIRMED
1321856217 -> 218b [CTransaction::changeState] notifying the parent dialog
1321856217 -> 1560 [CDialog::onTransactionState(pTrans = 218b, previousState = Completed, currentState = Confirmed, reason = Request reception]
1321856217 -> 218b [CTransaction::freeTimerToken] Timer G is freed
1321856217 -> 218b [CTransaction::freeTimerToken] Timer H is freed
1321856217 -> 218b [CTransaction::startTimer] Timer I is started (delay = 5000 ms)
1321856217 -> 1560 [CDialog::receiveAckRequest] receiving an ACK on a previously rejected INVITE
1321856217 -> 1158 [CCall::checkAuthentication] ACK
1321856217 -> 1158[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1321856217 -> 1158[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1321856217 -> [getFromHeader].
1321856217 -> [isUserDeclared] 9011666@node01
1321856217 -> isUserDeclared] NOT found in the dictionnary.
1321856217 -> [isSeplosStation] other station
1321856217 -> 1158[CMotorCall::toGatewayOrProxy] request for proxydomain=10.34.9.4.
1321856217 -> [isUserRegistred] getRegistration=9341333.
1321856217 -> [isUserRegistred] result=-1.
1321856217 -> 1158[CMotorCall::onReceiveRequest] request for gateway.
1321856217 -> 1158[CMotorCall::onReceiveRequest] state = TERMINATED_STATE.
1321856217 -> 1158[CMotorCall::compareDialogFromContext] same Dialog
1321856217 -> 1158[CMotorCall::methodAckReceived] Ack => update the inviteContext.
1321856217 -> [receiveAckMessage] Call: d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 eqt: 1241 TERMINATED_STATE received a message.
1321856217 -> 1158[emitEventToMonitel] content size (lginfo): 656
1321856217 -> 1158[emitEventToMonitel] CAC = 0 eqtCAC = -1
1321856217 -> 1158[emitEventToMonitel] CAC = 0
1321856217 -> [ipc_thread] IPC Thread : Ipc reception 2432.
1321856217 -> [ipc_thread] IPC Thread : TCL thread signaled.
1321856217 -> [sendLgEvtSip] Event sent on eqt : 1241 Id :-1
1321856217 -> [display_ipc_out] ------------ Begin ---------------
1321856217 -> Id : -1
1321856217 -> ACK
1321856217 -> [display_ipc_out] ------------- End ----------------
1321856217 -> [exec_ipc] in 2431.
1321856217 -> [display_ipc_in] ------------ Begin ---------------
1321856217 -> neqt : 1241 Id : -1
1321856217 -> SIP EQT RELEASED
1321856217 -> [display_ipc_in] ------------- End ----------------
1321856217 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 1241--1.
1321856217 -> [CMotorCallManager::onIncomingEvent] The call with eqt: 1241 has released its equipment.
1321856217 -> 1158[CMotorCall::emitSipEqtReleaseAck] SIP_EQT_RELEASE_ACK
1321856217 -> 1158[emitEventToMonitel] content size (lginfo): 656
1321856217 -> 1158[emitEventToMonitel] CAC = 0 eqtCAC = -1
1321856217 -> 1158[emitEventToMonitel] CAC = 0
1321856217 -> [sendLgEvtSip] Event sent on eqt : 1241 Id :-1
1321856217 -> [display_ipc_out] ------------ Begin ---------------
1321856217 -> Id : -1
1321856217 -> UNKNOWN MESSAGE
1321856217 -> [display_ipc_out] ------------- End ----------------
1321856217 -> 1158[CMotorCall::unRegister] Remove eqt : 1241 diag : -1 from the map.
1321856217 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 1241 erased.
1321856217 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1321856217 -> SendToSipgwCpuSec: Message sent to the STAND-BY CPU
1321856217 -> [CMotorCallManager::onIncomingEvent] killSession.
1321856217 -> 1158 [CCall::killSession]
1321856217 -> 218c [CTransaction::changeState] STATE CHANGED TO TERMINATED
1321856217 -> 218c [CTransaction::freeTimerToken] Timer J is freed
1321856217 -> 218c [CTransaction::changeState] no transaction state, terminated reason : None
1321856217 -> 218b [CTransaction::changeState] STATE CHANGED TO TERMINATED
1321856217 -> 218b [CTransaction::freeTimerToken] Timer I is freed
1321856217 -> 218b [CTransaction::changeState] no transaction state, terminated reason : None
1321856217 -> 1158 [CCall::onKill Session Call manager close is called]
1321856217 -> 1158 [CCall::killSession] notifying the call manager
1321856217 -> 102 [CCallManager::onCallClosed]
1321856217 -> 102 [CCallManager::onCallClosed()] Call 1158 closed. Removing call 160210640 from the maps
1321856217 -> [CMotorCallManager::onNotifyCallClosed] Call : d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 is closed.
1321856217 -> [CMotorCallManager::onNotifyCallClosed] Call : d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 is closed.
1321856217 -> [CMotorCallManager::onNotifyCallClosed] call processResponse.
1321856217 -> 1158[CMotorCall::processResponse] call chooseBestResponse.
1321856217 -> 1158[CMotorCall::chooseBestResponse] pForkedBranch=0x98ca850 iResponseCode=2000.
1321856217 -> [CForkedBranch::getResponseCode] response=2000.
1321856217 -> 1158[CMotorCall::chooseBestResponse] next branch.
1321856217 -> 1158[CMotorCall::processResponse] no response found.
1321856217 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1321856217 -> [CCall::onKill] se t callmanager to null
1321856217 -> [exec_ipc] in end 2431.
1321856217 -> [CTransaction::notifyDialog]
1321856217 -> [CTransaction::notifyDialog] notify parent dialog
1321856217 -> 1560 [CDialog::onTransactionState(pTrans = 218c, previousState = null, currentState = Terminated, reason = None]
1321856217 -> 218C [~CTransaction] transaction 966424859 CANCEL
1321856217 -> 116 [~CResponse] resp(200) d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 966424859 CANCEL
1321856217 -> D4 [~CRequest] req d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 966424859 CANCEL
1321856217 -> [CTransaction::notifyDialog]
1321856217 -> [CTransaction::notifyDialog] notify parent dialog
1321856217 -> 1560 [CDialog::onTransactionState(pTrans = 218b, previousState = null, currentState = Terminated, reason = None]
1321856217 -> 1560 [CDialog::onTransactionState] dialog is terminated
1321856217 -> 1560 [CDialog::onTransactionState] onDialogClosed
1321856217 -> 1158 [CCall::onDialogClosed]
1321856217 -> 1158 [CCall::onDialogClosed] only initial *********
1321856217 -> 1158 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1321856217 -> 218B [~CTransaction] transaction 966424859 INVITE
1321856217 -> D5 [~CRequest] req d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 966424859 ACK
1321856219 -> is_my_node_in_campus false
1321856219 -> 102 [CCallManager::removeGarbage] at index i = 0
1321856219 -> 1158[CMotorCall::~CMotorCall] Call : d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 is being destructed.
1321856219 -> 1158 [CCall::~CCall]
1321856219 -> 1158 [CCall::removeGarbage]
1321856219 -> 1560 [CDialog::~CDialog]
1321856219 -> [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
1321856219 -> 117 [~CResponse] resp(487) d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 966424859 INVITE
1321856219 -> D3 [~CRequest] req d8bfed80114fa6e92ca3cf9f0570d17c@10.1.9.4 966424859 INVITE
etc пишет: создание ещё одной SIP транковой группы типа ISDN для входящей связи с ССМ,
делали стык по sip с авайя через ABC-F и ISDN, было фиолетово, единственная фишка было что на OXE надо было включить proxy
для автора поста два варианта - (как писали выше) либо отказываться от dns на sip-транках, либо в dns регистрировать OXE во втором случае подводный камень - к имени "node33" надо прикрутить еще что-то типа этого "node33.alcatel" с вытекающими последствиями
error, От dns отказаться не могу (придётся тогда оставить SIP-прокси без резервирования). Правда это касается только полей Request-URI и To. Можно отказаться от "node33" для поля From и перевести его в IP, но это ж только belonging domain для внешнего SIP-gateway и не более. К тому же Алкатель на Алкатель нормально вызывает с таким именем.
смеяться будешь различие между node33 и node01 лишь патчах на node33 стоит 16, на node01 стоит 25 поднимайте патч со времен от 16 до 25 много воды утекло, в SIP есть поправки в лимитирование CAC и много чего
error, не, смеяться не буду:) node33 - это Cisco CallManager 4.1 (со времени его выпуска, действительно, очень много воды утекло и патчей/версий/релизов вышло), но обновить я его не могу.
у вас есть две OXE атс1 (с софтом R9.1 i1.605.16), и другая атс2 (с софтом R9.1 i1.605.25) тупо сравните настройки "sip gateway" атс1 с атс2 (после изменений надо killall sipmotor) если все одинаково то проблема не решится из аргумента "обновить не могу"
Правда не понял - в чем плохость SIP TG с сигнализацией ISDN. В частности - звонит кто-то нам, а абонент заказал переадресацию. При ABC-F - исходящая сторона делает повторный звонок на указанный номер (забавно, при существовании одинаковых номеров на обоих узлах). При ISDN - принимающая сторона отправляет по переадресации.
vad, При ABC-F транк группе можно использовать рутинг префикс, который не отрежется. А так разницы нет. Я не говорил, что это плохой вариант, я говорил, что он также не работает:)
Мне вот кажется, что проблема кроется где-то (если по первому трейсу смотреть) в строчках после TRYING и до Server Failure, но я не могу понять где, я как это выяснить.
