Форум. ООО «ИНТЕРСИСТ-Сервис».

Уважаемые дамы и господа! Для вас сохранен старый форум по адресу http://forum.intersyst.ru

Форум:

Дата создания:

…

Тема:

Сообщение:

Упорядочить:

Конференцфон 4135 не регистрируется

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

27.08.2013 14:10:34

Настройки систем с 9.1 и 10.1.1 абсолютно идентичны, сравнивал побуквенно и транк группы, и SIP GW. А в трассе пишет "не авторизовано", как будто логин-пароль не тот, после "передергивания регистрации" так же просто и естественно начинают идти чистые звонки. Непонятно. Оно, конечно, работать будет, но осадочек остался.

Перейти

Конференцфон 4135 не регистрируется

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

27.08.2013 13:33:20

Здравствуйте, коллеги.
Хочу поделиться опытом.
Подключал аппарат 4135 к OXE в железе MG. После создания абонент не жеьает регистрироваться. Отключаю регистрацию - начинает звонить. Включаю регистрацию - регистрируется и звонит. Ребут АТС (регистрация включена). После ребута не регистрируется. Отключаю регистрацию - пошли звонки. Включаю регистрацию - регистрируется и звонит. Пробовал на патчах 20i и 22.

Фокус в том, что к 9.1 (правда, в кристалле) регистрируется сразу без танцев.

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

16.07.2013 16:10:06

Ну, вроде как в первом приближении все работает. Можно перевести дух.

Всем помогавшим огромное спасибо! Особенно камраду error. Без вас бы еще долго ковырялся. Если кто будете в Москве - коньяк с меня.

Впрочем, при включении ABCF, наверное, еще встретимся...

Изменено: Seller_V - 16.07.2013 16:11:14

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

11.07.2013 00:01:31

Цитата

vad пишет:
Все равно не понятно - зачем вам делать разную регистрацию для разных абонентов станции (вернее не вам, а провайдеру). Что мешает - с точки зрения "не левых" соединений - аутентифицироваться станцией на шлюзе (иметь один external gateway), а отдавая правильный АОН - разруливать тарификацию у провайдера для разных организаций внутри вашей АТС.

Вот ровно такой же вопрос я, не будучи оригинальным, пытался задать нашим проектантам еще в начале этих плясок. "Такие правила" - был ответ. Вот и пришлось реализовывать...
Я ведь не зря в середине ветки интересовался, как у меня на эту схему ABCF на другие узлы ляжет. Носом чувствую еще порцию проблем.

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

10.07.2013 22:20:28

Цитата
Андрей пишет: В 10.1 можно ассоциировать Ext. SIP Gw с транкгруппой и, соответственно, выбирать SIP GW не через Numbering command, а выбором транкгруппы. А транкгруппу можно выбирать в ARS автоматом из Entity.

Схему попробую на стенде, расскажу. Какой-то косяк предвижу: с одной стороны, SIP GW может быть привязан к TG, с другой стороны, в самом SIP GW объявляется используемая TG. А если я в TG укажу один GW, а в этом GW укажу другую TG - кто кого переборет? Короче, буду пробовать.

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

10.07.2013 22:13:49

Цитата

vad пишет:

Цитата
Seller_V пишет: Эге, это значит, надо 10 дискриминаторов делать? Однако...

а у вас чего - абонентам звонят с 10-ти разных гейтвеев? Офигеть.

Ну... В общем, да...
Я в начале ветки описывал схему: у прова 10 номеров (каждый = городскому 6-значному номеру, каждый со своей регистрацией). Соответственно, под каждый заточен свой внешний SIP GW. Номера эти (для меня входящие) разруливаются через DDI транслятор.

Перейти

SIP trunk group: неправильный АОН

Seller_V User Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009	# 10.07.2013 18:22:43 Эге, это значит, надо 10 дискриминаторов делать? Однако...
	Перейти

SIP trunk group: неправильный АОН

Seller_V User Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009	# 10.07.2013 16:25:49 Скажите пожалуйста, а реально ли сделать так, чтобы при исходящем звонке абонент пользовался именно тем GW, с которого на него приходит входящий городской?
	Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

10.07.2013 15:47:23

Пожалуйста, вот трейс успешного исходящего звонка:

1373447514 -> Wed Jul 10 15:11:54 2013 [ipc_thread] IPC Thread : Ipc reception 9282.
Wed Jul 10 15:11:54 2013 [ipc_thread] IPC Thread : TCL thread signaled.
Wed Jul 10 15:11:54 2013 [exec_ipc] in 9281.
Wed Jul 10 15:11:54 2013 [display_ipc_in] ------------ Begin ---------------
Wed Jul 10 15:11:54 2013 neqt : 1152 Id : -1
Wed Jul 10 15:11:54 2013 INVITE
Wed Jul 10 15:11:54 2013 REQUEST URI : <> 89036117511@10.180.140.2:5060 ; user=phone
Wed Jul 10 15:11:54 2013 FROM : <M. Azanova DECT> 550389@10.110.32.90:5060 ; user=phone
Wed Jul 10 15:11:54 2013 TO : <> 89036117511@10.180.140.2:5060 ; user=phone
Wed Jul 10 15:11:54 2013 CAC : 0
Wed Jul 10 15:11:54 2013 neqt CAC : -1
Wed Jul 10 15:11:54 2013 CAC-CSBU info : UNKNOWN
Wed Jul 10 15:11:54 2013 CLIR : 0
Wed Jul 10 15:11:54 2013 SDP :
Wed Jul 10 15:11:54 2013 ADDRESS : 10.110.32.9210.110.32.92 :32600
Wed Jul 10 15:11:54 2013 ALGOS :
Wed Jul 10 15:11:54 2013 PCMA
Wed Jul 10 15:11:54 2013 97
Wed Jul 10 15:11:54 2013 DIRECTION : SEND & RECEIVE
Wed Jul 10 15:11:54 2013 crypto index : 0
Wed Jul 10 15:11:54 2013 N_GW_EXT : 10
Wed Jul 10 15:11:54 2013 [display_ipc_in] ------------- End ----------------
Wed Jul 10 15:11:54 2013 [CMotorCallManager::onIncomingEvent] an event 10752 arrived on the eqt 1152 -1.
Wed Jul 10 15:11:54 2013 [CMotorCallManager::onIncomingEvent] New Call created.
Wed Jul 10 15:11:54 2013 [CMotorCallManager::callFactory].
Wed Jul 10 15:11:54 2013 [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R10.1.1 j2.603.20.i
1373447514 -> Wed Jul 10 15:11:54 2013 [CMotorCallManager::callFactory]=84f9778.
Wed Jul 10 15:11:54 2013 [CMotorCallManager::insertCallwithEqt] CMotorCall 1152 inserted.
Wed Jul 10 15:11:54 2013 inviteBuildFromAssertedId-->extGw=-1
Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::inviteBuildAurlWithBelongingDomain] Reg_Id_for_user_URL_of_PAI =1
Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 [quoteString] => "550389"
Wed Jul 10 15:11:54 2013 [isAddrInDico] user=89036117511 host=10.180.140.2
Wed Jul 10 15:11:54 2013 [isUserInDico] 89036117511@10.180.140.2
Wed Jul 10 15:11:54 2013 isUserInDico] NOT found in the dictionnary.
Wed Jul 10 15:11:54 2013 [isAddrInDico] other sip user
Wed Jul 10 15:11:54 2013 113a[CMotorCall::inviteBuildAurlWithBelongingDomain] P-Asserted-Identity: "550389" <sip:550389@10.180.140.2
>
Wed Jul 10 15:11:54 2013 [quoteString] => "M. Azanova DECT"
Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::setFilterUsedMode] To be traced = 0
Wed Jul 10 15:11:54 2013 [CMotorCallManager::onIncomingEvent] state = INITIAL_STATE.
Wed Jul 10 15:11:54 2013 [onIncomingEvent] INVITE arrived.
Wed Jul 10 15:11:54 2013 [receiveInviteEvent] Call: eqt: 1152 INITIAL_STATE received an event.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitInviteMessage] begin. size=656 call_type: 0
Wed Jul 10 15:11:54 2013 [isAddrInDico] user=89036117511 host=10.180.140.2
1373447514 -> Wed Jul 10 15:11:54 2013 [isUserInDico] 89036117511@10.180.140.2
Wed Jul 10 15:11:54 2013 isUserInDico] NOT found in the dictionnary.
Wed Jul 10 15:11:54 2013 [isAddrInDico] other sip user
Wed Jul 10 15:11:54 2013 inviteBuildFromAssertedId-->extGw=10
Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::inviteBuildAurlWithBelongingDomain] Reg_Id_for_user_URL_of_PAI =1
Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 [quoteString] => "550389"
Wed Jul 10 15:11:54 2013 [isAddrInDico] user=89036117511 host=10.180.140.2
Wed Jul 10 15:11:54 2013 [isUserInDico] 89036117511@10.180.140.2
Wed Jul 10 15:11:54 2013 isUserInDico] NOT found in the dictionnary.
Wed Jul 10 15:11:54 2013 [isAddrInDico] other sip user
Wed Jul 10 15:11:54 2013 113a[CMotorCall::inviteBuildAurlWithBelongingDomain] P-Asserted-Identity: "550389" <sip:550389@10.180.140.2
>
Wed Jul 10 15:11:54 2013 [quoteString] => "M. Azanova DECT"
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitInviteMessage] From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>
Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitInviteMessage] reqUri :sip:89036117511@10.180.140.2;user=phone
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitInviteMessage]-->SIP_INFO_allowed=0
Wed Jul 10 15:11:54 2013 113a[CMotorCall ISDN ExtGW
Wed Jul 10 15:11:54 2013 113a[CMotorCall ISDN ExtGW
Wed Jul 10 15:11:54 2013 113a[CMotorCall::inviteBuildSupportedHeader] Invite directed to external gw : 10
1373447514 -> Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitInviteMessage] To: <sip:89036117511@10.180.140.2;user=phone>
Wed Jul 10 15:11:54 2013 am_I_PCS SCSWorking : NO
Wed Jul 10 15:11:54 2013 check_PCSstate
Wed Jul 10 15:11:54 2013 am_I_PCS SCSWorking : NO
Wed Jul 10 15:11:54 2013 check_PCSstate stat of PCS is : -1
Wed Jul 10 15:11:54 2013 [inviteBuildContact] trunk_number=102 extGw =10.
Wed Jul 10 15:11:54 2013 [137339-InviteBuildContact] userinfo : 550389
Wed Jul 10 15:11:54 2013 113a[CMotorCall::sipUriType] sip Uri.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::isTLSProtRequiredForExtGw] =0.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::inviteBuildContact] Contact: sip:550389@10.110.32.90
Wed Jul 10 15:11:54 2013 113a[CMotorCall::isExtGwTLSwithREINVITE] ExtGw NOT manged with TLS and refresh REINVITE
Wed Jul 10 15:11:54 2013 113a[CMotorCall::inviteBuildSessionTimer] uacGatewayMode=1 uacRefresher=1.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeRequestSdp] Begin sdp.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::appendAudioAttributToMedia] Direction: 0.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::appendAudioAttributToMedia] format 97
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeRequestSdp] crypto index3=0 clearMediaLine=0.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeRequestSdp] appendMediaDescription ok.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeRequestSdp] Final sdp.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitInviteMessage] The sdp build.
1373447514 -> Wed Jul 10 15:11:54 2013 113a[CMotorCall::new_determineTargetsForTheRequest] is not Responsible For This URI.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeInitialMotorRequest] target is not the local node.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeInitialMotorRequest] nb contacts=1.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeInitialMotorRequest] *pcszNameToFind : 89036117511
Wed Jul 10 15:11:54 2013 [isUserInDico] 89036117511@10.180.140.2
Wed Jul 10 15:11:54 2013 isUserInDico] NOT found in the dictionnary.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeInitialMotorRequest] contact : sip:89036117511@10.180.140.2;user=phone
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeInitialMotorRequest] nb available licenses=9 .
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeInitialMotorRequest] req_Uri_OK=0 and pSipAddSpec->getHost()=10.180.140.2
Wed Jul 10 15:11:54 2013 [CGWMngDat a::isMainServer] CPU is MAIN.
Wed Jul 10 15:11:54 2013 [CGWMngDat a::isMainServer] checkwith PCS = 0
Wed Jul 10 15:11:54 2013 113a[CMotorCall::isTLSProtRequiredForExtGw] =0.
Wed Jul 10 15:11:54 2013 113a [CCall::makeGenericRequest] INVITE
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID =

Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Initial Dialog Client not found
Wed Jul 10 15:11:54 2013 151d [CDialog::createRequest]
Wed Jul 10 15:11:54 2013 151d [CDialog::buildServicesForAllRequest]
Wed Jul 10 15:11:54 2013 151d [CDialog::createInviteRequest]
Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] STATE CHANGED TO INITIAL
1373447514 -> Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 212e, previousState = Terminated, currentState = Initial, reason
= None]
Wed Jul 10 15:11:54 2013 34 [CRequest::CRequest] Creation of a request
Wed Jul 10 15:11:54 2013 34 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Wed Jul 10 15:11:54 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 961)
----------------------utf8-----------------------
INVITE sip:89036117511@10.180.140.2;user=phone SIP/2.0
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, SUBSCRIBE, OPTIONS, UPDATE
Supported: timer,path,100rel
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
Session-Expires: 1800;refresher=uac
Min-SE: 900
P-Asserted-Identity: "550389" <sip:550389@10.180.140.2>
Content-Type: application/sdp
To: <sip:89036117511@10.180.140.2;user=phone>
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Contact: <sip:550389@10.110.32.90;transport=UDP>
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397262 INVITE
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK0165a8e887d3d407fc7297d0184292e0
Max-Forwards: 70
Content-Length: 214

v=0
o=OXE 1373447514 1373447514 IN IP4 10.110.32.90
s=abs
c=IN IP4 10.110.32.92
t=0 0
m=audio 32600 RTP/AVP 8 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1373447514 -> Wed Jul 10 15:11:54 2013 34 [CMessage::onSendSuccess]
Wed Jul 10 15:11:54 2013 212e [CTransaction::onSendSuccess] CMessage 34 send Success
Wed Jul 10 15:11:54 2013 151d [CDialog::onSendSuccess]
Wed Jul 10 15:11:54 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Wed Jul 10 15:11:54 2013 [isIpAddressType] 10.180.140.2.
Wed Jul 10 15:11:54 2013 [isIpAddressType] YES.
Wed Jul 10 15:11:54 2013 212e [CTransInitialState::createRequest] Transaction changes to Calling state
Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] STATE CHANGED TO CALLING
Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 212e, previousState = Initial, currentState = Calling, reason =
Request creation]
Wed Jul 10 15:11:54 2013 212e [CTransaction::startTimer] Timer A is started (delay = 500 ms)
Wed Jul 10 15:11:54 2013 212e [CTransaction::startTimer] Timer B is started (delay = 4000 ms)
Wed Jul 10 15:11:54 2013 151d [CDialog::getTransactionByCSeq] CSeq = 519397262
Wed Jul 10 15:11:54 2013 151d [CDialog::addTransaction] added transaction 212e with branch z9hG4bK0165a8e887d3d407fc7297d0184292e0,
with CSeq 519397262
Wed Jul 10 15:11:54 2013 [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Client, UA)
Wed Jul 10 15:11:54 2013 [CSessionTimerContext::updateSupported] Request timer supported
Wed Jul 10 15:11:54 2013 [CSessionTimerContext::updateMinSE] Min-SE updated : 900
1373447514 -> Wed Jul 10 15:11:54 2013 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
Wed Jul 10 15:11:54 2013 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Wed Jul 10 15:11:54 2013 [CForkedBranch::CForkedBranch] context = 0x84f9c24.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::branchAllocation] pForkedBranch = 0x84fe458 and the branch number is.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::makeInitialMotorRequest] reqUri duplication pForkedBranch = 0x84fe458.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Wed Jul 10 15:11:54 2013 NOE-SIP : StandByCallCreation not sent to twin CS
Wed Jul 10 15:11:54 2013 [onIncomingEvent] CallID must be inserted.
Wed Jul 10 15:11:54 2013 [onIncomingEvent] CallID inserted.
Wed Jul 10 15:11:54 2013 [exec_ipc] in end 9281.
Wed Jul 10 15:11:54 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:89036117511@10.180.140.2;user=phone>
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397262 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK0165a8e887d3d407fc7297d0184292e0

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

Wed Jul 10 15:11:54 2013 [CIOCom::receiveResponse] CResponse 38 ref=1
Wed Jul 10 15:11:54 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
1373447514 -> Wed Jul 10 15:11:54 2013 102 [CCallManager::onIncomingResponse]
Wed Jul 10 15:11:54 2013 113a [CCall::receiveResponse] 100
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;)
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:54 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:11:54 2013 212e [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] STATE CHANGED TO PROCEEDING
Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 212e, previousState = Calling, currentState = Proceeding, reason
= 1xx response reception]
Wed Jul 10 15:11:54 2013 212e [CTransaction::freeTimerToken] Timer A is freed
Wed Jul 10 15:11:54 2013 212e [CTransaction::freeTimerToken] Timer B is freed
Wed Jul 10 15:11:54 2013 113a[CMotorCall::searchForkedBranch]pForkedBranch=0x84fe458.
Wed Jul 10 15:11:54 2013 [receiveInformationalMessage] Call: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 eqt: 1152 PROCEEDING_STAT
E received a message.
Wed Jul 10 15:11:54 2013 [receiveInformationalMessage] is in the else part of SDP not present
Wed Jul 10 15:11:54 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 401 Unauthorized
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-90w76txmo0
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397262 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK0165a8e887d3d407fc7297d0184292e0
WWW-Authenticate: Digest realm="10.67.144.34",nonce="51dd25ba0af64f534b1f9441b2ba9038df3dae25",opaque="58e0f3eb7d01b5095d2396886ba08
b1e",qop="auth"
Content-Length: 0

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

1373447514 -> Wed Jul 10 15:11:54 2013 [CIOCom::receiveResponse] CResponse 39 ref=1
Wed Jul 10 15:11:54 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Wed Jul 10 15:11:54 2013 102 [CCallManager::onIncomingResponse]
Wed Jul 10 15:11:54 2013 113a [CCall::receiveResponse] 401
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-90w7
6txmo0)
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:54 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:11:54 2013 38 [~CResponse] resp(100) ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 519397262 INVITE
Wed Jul 10 15:11:54 2013 212e [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] STATE CHANGED TO COMPLETED
Wed Jul 10 15:11:54 2013 212e [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 212e, previousState = Proceeding, currentState = Completed, reas
on = Final resp reception]
Wed Jul 10 15:11:54 2013 35 [CRequest::CRequest] Creation of a request
Wed Jul 10 15:11:54 2013 35 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Wed Jul 10 15:11:54 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 400)
----------------------utf8-----------------------
ACK sip:89036117511@10.180.140.2;user=phone SIP/2.0
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-90w76txmo0
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK0165a8e887d3d407fc7297d0184292e0
CSeq: 519397262 ACK
Content-Length: 0

-------------------------------------------------
1373447514 -> Wed Jul 10 15:11:54 2013 35 [CMessage::onSendSuccess]
Wed Jul 10 15:11:54 2013 212e [CTransaction::onSendSuccess] CMessage 35 send Success
Wed Jul 10 15:11:54 2013 151d [CDialog::onSendSuccess]
Wed Jul 10 15:11:54 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Wed Jul 10 15:11:54 2013 [isIpAddressType] 10.180.140.2.
Wed Jul 10 15:11:54 2013 [isIpAddressType] YES.
Wed Jul 10 15:11:54 2013 212e [CTransaction::startTimer] Timer D is started (delay = 32403 ms)
Wed Jul 10 15:11:54 2013 [unquoteString] "10.67.144.34" => 10.67.144.34
Wed Jul 10 15:11:54 2013 113a[CMotorCall::isKnownAuthenticate] Unquoted realm found : 10.67.144.34 ,Realm in the management : 10.67.
144.34.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::isKnownAuthenticate] matching realm : 10.67.144.34 in authenticate header.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::searchForkedBranch]pForkedBranch=0x84fe458.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitAuthorizedRequest] Begin.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitAuthorizedRequest] authenticateHeader found => Login: 550389.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitAuthorizedRequest] AuthorizationService for qop : "auth" => cnounce : 1373447514.
Wed Jul 10 15:11:54 2013 113a [CCall::makeGenericRequest] INVITE
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-90w7
6txmo0)
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:54 2013 151d [CDialog::createRequest]
1373447514 -> Wed Jul 10 15:11:54 2013 151d [CDialog::buildServicesForAllRequest]
Wed Jul 10 15:11:54 2013 151d [CDialog::createInviteRequest]
Wed Jul 10 15:11:54 2013 212f [CTransaction::changeState] STATE CHANGED TO INITIAL
Wed Jul 10 15:11:54 2013 212f [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 212f, previousState = Terminated, currentState = Initial, reason
= None]
Wed Jul 10 15:11:54 2013 36 [CRequest::CRequest] Creation of a request
Wed Jul 10 15:11:54 2013 36 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Wed Jul 10 15:11:54 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 1245)
----------------------utf8-----------------------
INVITE sip:89036117511@10.180.140.2;user=phone SIP/2.0
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, SUBSCRIBE, OPTIONS, UPDATE
Supported: timer,path,100rel
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
Session-Expires: 1800;refresher=uac
Min-SE: 900
P-Asserted-Identity: "550389" <sip:550389@10.180.140.2>
Content-Type: application/sdp
To: <sip:89036117511@10.180.140.2;user=phone>
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Contact: <sip:550389@10.110.32.90;transport=UDP>
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397263 INVITE
Max-Forwards: 70
Authorization: Digest username="550389",realm="10.67.144.34",nonce="51dd25ba0af64f534b1f9441b2ba9038df3dae25",opaque="58e0f3eb7d01b5
095d2396886ba08b1e",qop=auth,cnonce="1373447514",nc=00000001,uri="sip:89036117511@10.180.140.2;user=phone",response="185bb34740cea87
5e9f9ecfca23d2221"
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK29f4c92a3d7a17198b8434cfad3b0e55
Content-Length: 214

v=0
o=OXE 1373447514 1373447514 IN IP4 10.110.32.90
s=abs
c=IN IP4 10.110.32.92
t=0 0
m=audio 32600 RTP/AVP 8 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1373447514 -> Wed Jul 10 15:11:54 2013 36 [CMessage::onSendSuccess]
Wed Jul 10 15:11:54 2013 212f [CTransaction::onSendSuccess] CMessage 36 send Success
Wed Jul 10 15:11:54 2013 151d [CDialog::onSendSuccess]
Wed Jul 10 15:11:54 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Wed Jul 10 15:11:54 2013 [isIpAddressType] 10.180.140.2.
Wed Jul 10 15:11:54 2013 [isIpAddressType] YES.
Wed Jul 10 15:11:54 2013 212f [CTransInitialState::createRequest] Transaction changes to Calling state
Wed Jul 10 15:11:54 2013 212f [CTransaction::changeState] STATE CHANGED TO CALLING
Wed Jul 10 15:11:54 2013 212f [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 212f, previousState = Initial, currentState = Calling, reason =
Request creation]
Wed Jul 10 15:11:54 2013 212f [CTransaction::startTimer] Timer A is started (delay = 500 ms)
Wed Jul 10 15:11:54 2013 212f [CTransaction::startTimer] Timer B is started (delay = 4000 ms)
Wed Jul 10 15:11:54 2013 151d [CDialog::getTransactionByCSeq] CSeq = 519397263
Wed Jul 10 15:11:54 2013 151d [CDialog::addTransaction] added transaction 212f with branch z9hG4bK29f4c92a3d7a17198b8434cfad3b0e55,
with CSeq 519397263
Wed Jul 10 15:11:54 2013 [CSessionTimerContext::updateSupported] Request timer supported
Wed Jul 10 15:11:54 2013 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
Wed Jul 10 15:11:54 2013 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitAuthorizedRequest] ForkedBranch updated.
1373447514 -> Wed Jul 10 15:11:54 2013 [CForkedBranch::CForkedBranch] context = 0x84fe8ec.
Wed Jul 10 15:11:54 2013 113a[CMotorCall::branchAllocation] pForkedBranch = 0x84ffed0 and the branch number is.
Wed Jul 10 15:11:54 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:89036117511@10.180.140.2;user=phone>
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397263 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK29f4c92a3d7a17198b8434cfad3b0e55

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

Wed Jul 10 15:11:54 2013 [CIOCom::receiveResponse] CResponse 3A ref=1
Wed Jul 10 15:11:54 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Wed Jul 10 15:11:54 2013 102 [CCallManager::onIncomingResponse]
Wed Jul 10 15:11:54 2013 113a [CCall::receiveResponse] 100
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;)
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:54 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:11:54 2013 212f [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
Wed Jul 10 15:11:54 2013 212f [CTransaction::changeState] STATE CHANGED TO PROCEEDING
Wed Jul 10 15:11:54 2013 212f [CTransaction::changeState] notifying the parent dialog
1373447514 -> Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 212f, previousState = Calling, currentState = Proc
eeding, reason = 1xx response reception]
Wed Jul 10 15:11:54 2013 212f [CTransaction::freeTimerToken] Timer A is freed
Wed Jul 10 15:11:54 2013 212f [CTransaction::freeTimerToken] Timer B is freed
Wed Jul 10 15:11:54 2013 113a[CMotorCall::searchForkedBranch]pForkedBranch=0x84ffed0.
Wed Jul 10 15:11:54 2013 [receiveInformationalMessage] Call: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 eqt: 1152 PROCEEDING_STAT
E received a message.
Wed Jul 10 15:11:54 2013 [receiveInformationalMessage] is in the else part of SDP not present
Wed Jul 10 15:11:54 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 183 Session Progress
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397263 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK29f4c92a3d7a17198b8434cfad3b0e55
Allow: INVITE,ACK,CANCEL,INFO,PRACK,UPDATE,OPTIONS,REGISTER,REFER,SUBSCRIBE
Contact: <sip:89036117511@10.180.140.2:5060;user=phone;transport=udp>
Require: 100rel
RSeq: 1
Content-Length: 161
Content-Type: application/sdp
Content-Disposition: session;handling=required

v=0
o=- 6336865 8144681 IN IP4 10.180.140.2
s=-
c=IN IP4 10.180.140.2
b=AS:64
t=0 0
m=audio 20098 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendrecv
-------------------------------------------------

1373447514 -> Wed Jul 10 15:11:54 2013 [CIOCom::receiveResponse] CResponse 3B ref=1
Wed Jul 10 15:11:54 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Wed Jul 10 15:11:54 2013 102 [CCallManager::onIncomingResponse]
Wed Jul 10 15:11:54 2013 113a [CCall::receiveResponse] 183
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-cvp3
ymdyjk)
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:54 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:11:54 2013 3A [~CResponse] resp(100) ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 519397263 INVITE
Wed Jul 10 15:11:54 2013 151d [CDialog::updateRouteSet] there are 0 record-route headers
Wed Jul 10 15:11:54 2013 113a[CMotorCall::prackToSend] Reliable response received.
Wed Jul 10 15:11:54 2013 113a[CMotorCall ISDN ExtGW
Wed Jul 10 15:11:54 2013 113a[CMotorCall::emitPrackMessage] response context is used.
Wed Jul 10 15:11:54 2013 113a [CCall::makeGenericRequest] PRACK
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-cvp3
ymdyjk)
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:54 2013 151d [CDialog::createRequest]
Wed Jul 10 15:11:54 2013 151d [CDialog::buildServicesForAllRequest]
Wed Jul 10 15:11:54 2013 151d [CDialog::createBasicRequest]
Wed Jul 10 15:11:54 2013 2130 [CTransaction::changeState] STATE CHANGED TO INITIAL
1373447514 -> Wed Jul 10 15:11:54 2013 2130 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 2130, previousState = Terminated, currentState = Initial, reason
= None]
Wed Jul 10 15:11:54 2013 37 [CRequest::CRequest] Creation of a request
Wed Jul 10 15:11:54 2013 6801 [CPrackService::applyToMessage] there are 0 route headers in the request
Wed Jul 10 15:11:54 2013 37 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Wed Jul 10 15:11:54 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 524)
----------------------utf8-----------------------
PRACK sip:89036117511@10.180.140.2:5060;user=phone;transport=udp SIP/2.0
Supported: timer,path
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
RAck: 1 519397263 INVITE
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397264 PRACK
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bKe0aef8b382aa2f93731ed15c73dd4110
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------
Wed Jul 10 15:11:54 2013 37 [CMessage::onSendSuccess]
Wed Jul 10 15:11:54 2013 2130 [CTransaction::onSendSuccess] CMessage 37 send Success
Wed Jul 10 15:11:54 2013 151d [CDialog::onSendSuccess]
Wed Jul 10 15:11:54 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Wed Jul 10 15:11:54 2013 [isIpAddressType] 10.180.140.2.
1373447514 -> Wed Jul 10 15:11:54 2013 [isIpAddressType] YES.
Wed Jul 10 15:11:54 2013 2130 [CTransInitialState::createRequest] Transaction changes to Trying state
Wed Jul 10 15:11:54 2013 2130 [CTransaction::changeState] STATE CHANGED TO TRYING
Wed Jul 10 15:11:54 2013 2130 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 2130, previousState = Initial, currentState = Trying, reason = R
equest creation]
Wed Jul 10 15:11:54 2013 2130 [CTransTryingState::enterInState] Init timers E and F
Wed Jul 10 15:11:54 2013 2130 [CTransaction::startTimer] Timer E is started (delay = 500 ms)
Wed Jul 10 15:11:54 2013 2130 [CTransaction::startTimer] Timer F is started (delay = 16000 ms)
Wed Jul 10 15:11:54 2013 151d [CDialog::addTransaction] added transaction 2130 with branch z9hG4bKe0aef8b382aa2f93731ed15c73dd4110,
with CSeq 519397264
Wed Jul 10 15:11:54 2013 113a[CMotorCall::searchForkedBranch]pForkedBranch=0x84ffed0.
Wed Jul 10 15:11:54 2013 [receiveInformationalMessage] Call: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 eqt: 1152 PROCEEDING_STAT
E received a message.
Wed Jul 10 15:11:54 2013 [receiveInformationalMessage] is in the first IF of SDP present
Wed Jul 10 15:11:54 2013 [checkSdpValidity] Media 0 type 1 contains 1 formats.
Wed Jul 10 15:11:54 2013 [checkSdpValidity] Format : 8.
Wed Jul 10 15:11:54 2013 [receiveInformationalMessage] is in the else part of SDP present
Wed Jul 10 15:11:54 2013 [convertSdpIntoTsdp] No Direction in the session part.
1373447514 -> Wed Jul 10 15:11:54 2013 [convertSdpIntoTsdp] Check the direction in Session part - result:0.
Wed Jul 10 15:11:54 2013 [convertSdpIntoTsdp] media AUDIO detected (previous crypto=0).
Wed Jul 10 15:11:54 2013 [convertCryptoInfoInFile] clearMediaLine2: 0.
Wed Jul 10 15:11:54 2013 [convertAudioMedia] The audio media contains 1 format(s).
Wed Jul 10 15:11:54 2013 [convertAudioMedia] Format 0 is 8.
Wed Jul 10 15:11:54 2013 [convertAudioMedia] Direction is sendrecv.
Wed Jul 10 15:11:54 2013 [convertAudioMedia] Connection address retrieved in sdp: 10.180.140.2.
Wed Jul 10 15:11:54 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Wed Jul 10 15:11:54 2013 [convertSdpIntoTsdp] sdp index=0.
Wed Jul 10 15:11:54 2013 [display_sdp] address =10.180.140.2
Wed Jul 10 15:11:54 2013 [display_sdp] codecs =0.
Wed Jul 10 15:11:54 2013 [display_sdp] codecs =255.
Wed Jul 10 15:11:54 2013 [display_sdp] codecs =255.
Wed Jul 10 15:11:54 2013 [display_sdp] codecs =255.
Wed Jul 10 15:11:54 2013 [display_sdp] codecs =255.
Wed Jul 10 15:11:54 2013 [display_sdp] direction=0.
Wed Jul 10 15:11:54 2013 [convertSdpIntoTsdp] only one media taken into account xxx crypto_index=0 clear media=1
Wed Jul 10 15:11:54 2013 [convertSdpIntoTsdp] crypto_index=0 clear media=1.
Wed Jul 10 15:11:54 2013 [methodAllowedByDistant] UPDATE allowed by distant.
Wed Jul 10 15:11:54 2013 113a[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Wed Jul 10 15:11:54 2013 113a[emitEventToMonitel]1 T38 REINVITE TRUE
Wed Jul 10 15:11:54 2013 113a[emitEventToMonitel] CAC = 0 eqtCAC = -1
1373447514 -> Wed Jul 10 15:11:54 2013 113a[emitEventToMonitel] cacUsed=0 eqtCac=-1
Wed Jul 10 15:11:54 2013 113a[emitEventToMonitel] displayIPCMessageOut
Wed Jul 10 15:11:54 2013 ** SIP TRUNKING **
Wed Jul 10 15:11:54 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Wed Jul 10 15:11:54 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Wed Jul 10 15:11:54 2013 [display_ipc_out] ------------ Begin ---------------
Wed Jul 10 15:11:54 2013 Id : -1
Wed Jul 10 15:11:54 2013 INFORMATIONAL
Wed Jul 10 15:11:54 2013 xx : 83
Wed Jul 10 15:11:54 2013 RELATIVE REQUEST : INVITE
Wed Jul 10 15:11:54 2013 Allow Update : 1
Wed Jul 10 15:11:54 2013 SDP :
Wed Jul 10 15:11:54 2013 ADDRESS : 10.180.140.210.180.140.2 :20098
Wed Jul 10 15:11:54 2013 ALGOS :
Wed Jul 10 15:11:54 2013 PCMA
Wed Jul 10 15:11:54 2013 DIRECTION : SEND & RECEIVE
Wed Jul 10 15:11:54 2013 crypto index : 0
Wed Jul 10 15:11:54 2013 [display_ipc_out] ------------- End ----------------
Wed Jul 10 15:11:54 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397264 PRACK
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bKe0aef8b382aa2f93731ed15c73dd4110
Content-Length: 0

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

Wed Jul 10 15:11:54 2013 [CIOCom::receiveResponse] CResponse 3C ref=1
1373447517 -> Wed Jul 10 15:11:54 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Wed Jul 10 15:11:54 2013 102 [CCallManager::onIncomingResponse]
Wed Jul 10 15:11:54 2013 113a [CCall::receiveResponse] 200
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-cvp3
ymdyjk)
Wed Jul 10 15:11:54 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:54 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:11:54 2013 2130 [CTransTryingState::receiveResponse] Final : Transaction changes to Completed state
Wed Jul 10 15:11:54 2013 2130 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Wed Jul 10 15:11:54 2013 2130 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:54 2013 151d [CDialog::onTransactionState(pTrans = 2130, previousState = Trying, currentState = Completed, reason =
Final resp reception]
Wed Jul 10 15:11:54 2013 2130 [CTransaction::freeTimerToken] Timer E is freed
Wed Jul 10 15:11:54 2013 2130 [CTransaction::freeTimerToken] Timer F is freed
Wed Jul 10 15:11:54 2013 2130 [CTransaction::startTimer] Timer K is started (delay = 5000 ms)
Wed Jul 10 15:11:54 2013 113a[CMotorCall::onReceiveResponse] Response to PRACK accepted.
Wed Jul 10 15:11:57 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 180 Ringing
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397263 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK29f4c92a3d7a17198b8434cfad3b0e55
Allow: INVITE,ACK,CANCEL,INFO,PRACK,UPDATE,OPTIONS,REGISTER,REFER,SUBSCRIBE
Contact: <sip:89036117511@10.180.140.2:5060;user=phone;transport=udp>
Require: 100rel
RSeq: 2
Content-Length: 0

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

1373447517 -> Wed Jul 10 15:11:57 2013 [CIOCom::receiveResponse] CResponse 3D ref=1
Wed Jul 10 15:11:57 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Wed Jul 10 15:11:57 2013 102 [CCallManager::onIncomingResponse]
Wed Jul 10 15:11:57 2013 113a [CCall::receiveResponse] 180
Wed Jul 10 15:11:57 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-cvp3
ymdyjk)
Wed Jul 10 15:11:57 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:57 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:11:57 2013 3B [~CResponse] resp(183) ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 519397263 INVITE
Wed Jul 10 15:11:57 2013 151d [CDialog::updateRouteSet] there are 0 record-route headers
Wed Jul 10 15:11:57 2013 113a[CMotorCall::prackToSend] Reliable response received.
Wed Jul 10 15:11:57 2013 113a[CMotorCall ISDN ExtGW
Wed Jul 10 15:11:57 2013 113a[CMotorCall::emitPrackMessage] response context is used.
Wed Jul 10 15:11:57 2013 113a [CCall::makeGenericRequest] PRACK
Wed Jul 10 15:11:57 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-cvp3
ymdyjk)
Wed Jul 10 15:11:57 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:57 2013 151d [CDialog::createRequest]
Wed Jul 10 15:11:57 2013 151d [CDialog::buildServicesForAllRequest]
Wed Jul 10 15:11:57 2013 151d [CDialog::createBasicRequest]
Wed Jul 10 15:11:57 2013 2131 [CTransaction::changeState] STATE CHANGED TO INITIAL
1373447517 -> Wed Jul 10 15:11:57 2013 2131 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:57 2013 151d [CDialog::onTransactionState(pTrans = 2131, previousState = Terminated, currentState = Initial, reason
= None]
Wed Jul 10 15:11:57 2013 38 [CRequest::CRequest] Creation of a request
Wed Jul 10 15:11:57 2013 6802 [CPrackService::applyToMessage] there are 0 route headers in the request
Wed Jul 10 15:11:57 2013 38 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Wed Jul 10 15:11:57 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 524)
----------------------utf8-----------------------
PRACK sip:89036117511@10.180.140.2:5060;user=phone;transport=udp SIP/2.0
Supported: timer,path
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
RAck: 2 519397263 INVITE
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397265 PRACK
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK676a9474974c700872eba6e33d4ddac6
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------
Wed Jul 10 15:11:57 2013 38 [CMessage::onSendSuccess]
Wed Jul 10 15:11:57 2013 2131 [CTransaction::onSendSuccess] CMessage 38 send Success
Wed Jul 10 15:11:57 2013 151d [CDialog::onSendSuccess]
Wed Jul 10 15:11:57 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Wed Jul 10 15:11:57 2013 [isIpAddressType] 10.180.140.2.
1373447517 -> Wed Jul 10 15:11:57 2013 [isIpAddressType] YES.
Wed Jul 10 15:11:57 2013 2131 [CTransInitialState::createRequest] Transaction changes to Trying state
Wed Jul 10 15:11:57 2013 2131 [CTransaction::changeState] STATE CHANGED TO TRYING
Wed Jul 10 15:11:57 2013 2131 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:57 2013 151d [CDialog::onTransactionState(pTrans = 2131, previousState = Initial, currentState = Trying, reason = R
equest creation]
Wed Jul 10 15:11:57 2013 2131 [CTransTryingState::enterInState] Init timers E and F
Wed Jul 10 15:11:57 2013 2131 [CTransaction::startTimer] Timer E is started (delay = 500 ms)
Wed Jul 10 15:11:57 2013 2131 [CTransaction::startTimer] Timer F is started (delay = 16000 ms)
Wed Jul 10 15:11:57 2013 151d [CDialog::addTransaction] added transaction 2131 with branch z9hG4bK676a9474974c700872eba6e33d4ddac6,
with CSeq 519397265
Wed Jul 10 15:11:57 2013 113a[CMotorCall::searchForkedBranch]pForkedBranch=0x84ffed0.
Wed Jul 10 15:11:57 2013 [receiveInformationalMessage] Call: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 eqt: 1152 PROCEEDING_STAT
E received a message.
Wed Jul 10 15:11:57 2013 [receiveInformationalMessage] is in the else part of SDP not present
Wed Jul 10 15:11:57 2013 [methodAllowedByDistant] INFO allowed by distant.
Wed Jul 10 15:11:57 2013 [methodAllowedByDistant] UPDATE allowed by distant.
Wed Jul 10 15:11:57 2013 113a[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Wed Jul 10 15:11:57 2013 113a[emitEventToMonitel]1 T38 REINVITE TRUE
1373447517 -> Wed Jul 10 15:11:57 2013 113a[emitEventToMonitel] CAC = 0 eqtCAC = -1
Wed Jul 10 15:11:57 2013 113a[emitEventToMonitel] cacUsed=0 eqtCac=-1
Wed Jul 10 15:11:57 2013 113a[emitEventToMonitel] displayIPCMessageOut
Wed Jul 10 15:11:57 2013 ** SIP TRUNKING **
Wed Jul 10 15:11:57 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Wed Jul 10 15:11:57 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Wed Jul 10 15:11:57 2013 [display_ipc_out] ------------ Begin ---------------
Wed Jul 10 15:11:57 2013 Id : -1
Wed Jul 10 15:11:57 2013 INFORMATIONAL
Wed Jul 10 15:11:57 2013 xx : 80
Wed Jul 10 15:11:57 2013 RELATIVE REQUEST : INVITE
Wed Jul 10 15:11:57 2013 Allow Update : 1
Wed Jul 10 15:11:57 2013 [display_ipc_out] ------------- End ----------------
Wed Jul 10 15:11:57 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397265 PRACK
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK676a9474974c700872eba6e33d4ddac6
Content-Length: 0

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

Wed Jul 10 15:11:57 2013 [CIOCom::receiveResponse] CResponse 3E ref=1
Wed Jul 10 15:11:57 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Wed Jul 10 15:11:57 2013 102 [CCallManager::onIncomingResponse]
1373447519 -> Wed Jul 10 15:11:57 2013 113a [CCall::receiveResponse] 200
Wed Jul 10 15:11:57 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-cvp3
ymdyjk)
Wed Jul 10 15:11:57 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:11:57 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:11:57 2013 2131 [CTransTryingState::receiveResponse] Final : Transaction changes to Completed state
Wed Jul 10 15:11:57 2013 2131 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Wed Jul 10 15:11:57 2013 2131 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:11:57 2013 151d [CDialog::onTransactionState(pTrans = 2131, previousState = Trying, currentState = Completed, reason =
Final resp reception]
Wed Jul 10 15:11:57 2013 2131 [CTransaction::freeTimerToken] Timer E is freed
Wed Jul 10 15:11:57 2013 2131 [CTransaction::freeTimerToken] Timer F is freed
Wed Jul 10 15:11:57 2013 2131 [CTransaction::startTimer] Timer K is started (delay = 5000 ms)
Wed Jul 10 15:11:57 2013 113a[CMotorCall::onReceiveResponse] Response to PRACK accepted.
Wed Jul 10 15:11:59 2013 2130 [CTransCompletedState::timerFires] Timer K fires
Wed Jul 10 15:11:59 2013 2130 [CTransCompletedState::timerFires] Timer K : Transaction changes to Terminated state
Wed Jul 10 15:11:59 2013 2130 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Wed Jul 10 15:11:59 2013 2130 [CTransaction::changeState] no transaction state, terminated reason : Timer K fires
1373447523 -> Wed Jul 10 15:11:59 2013 [CTransaction::notifyDialog]
Wed Jul 10 15:11:59 2013 [CTransaction::notifyDialog] notify parent dialog
Wed Jul 10 15:11:59 2013 151d [CDialog::onTransactionState(pTrans = 2130, previousState = null, currentState = Terminated, reason =
Timer K fires]
Wed Jul 10 15:11:59 2013 2130 [~CTransaction] transaction 519397264 PRACK
Wed Jul 10 15:11:59 2013 3C [~CResponse] resp(200) ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 519397264 PRACK
Wed Jul 10 15:11:59 2013 37 [~CRequest] req ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 519397264 PRACK
Wed Jul 10 15:12:02 2013 2131 [CTransCompletedState::timerFires] Timer K fires
Wed Jul 10 15:12:02 2013 2131 [CTransCompletedState::timerFires] Timer K : Transaction changes to Terminated state
Wed Jul 10 15:12:02 2013 2131 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Wed Jul 10 15:12:02 2013 2131 [CTransaction::changeState] no transaction state, terminated reason : Timer K fires
Wed Jul 10 15:12:02 2013 [CTransaction::notifyDialog]
Wed Jul 10 15:12:02 2013 [CTransaction::notifyDialog] notify parent dialog
Wed Jul 10 15:12:02 2013 151d [CDialog::onTransactionState(pTrans = 2131, previousState = null, currentState = Terminated, reason =
Timer K fires]
Wed Jul 10 15:12:02 2013 2131 [~CTransaction] transaction 519397265 PRACK
Wed Jul 10 15:12:02 2013 3E [~CResponse] resp(200) ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 519397265 PRACK

1373447523 -> Wed Jul 10 15:12:03 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397263 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK29f4c92a3d7a17198b8434cfad3b0e55
Accept: application/sdp
Allow: INVITE,ACK,CANCEL,BYE,INFO,PRACK,OPTIONS,REGISTER,REFER,SUBSCRIBE
Contact: "89036117511" <sip:89036117511@10.180.140.2:5060;user=phone;transport=udp>
Supported: 100rel
Content-Length: 0

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

Wed Jul 10 15:12:03 2013 [CIOCom::receiveResponse] CResponse 3F ref=1
Wed Jul 10 15:12:03 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Wed Jul 10 15:12:03 2013 102 [CCallManager::onIncomingResponse]
Wed Jul 10 15:12:03 2013 113a [CCall::receiveResponse] 200
Wed Jul 10 15:12:03 2013 113a [CCall::getDialog] Confirmed Dialog is not found (ID = b481d8231ee2a3c186df22142cbf468e;SDsnbge99-cvp3
ymdyjk)
Wed Jul 10 15:12:03 2013 113a [CCall::getDialog] Returning Initial Dialog Client
Wed Jul 10 15:12:03 2013 151d [CDialog::receiveResponse]
Wed Jul 10 15:12:03 2013 151d [CDialog::receiveResponse] create a CONFIRMED dialog
Wed Jul 10 15:12:03 2013 151e [CDialog::CDialog] look for the transaction #0, transaction key = z9hG4bK29f4c92a3d7a17198b8434cfad3b0
e55
1373447523 -> Wed Jul 10 15:12:03 2013 151e [CDialog::CDialog] copy the transaction #0, transaction key = z9hG4bK29f4c92a3d7a17198b8
434cfad3b0e55
Wed Jul 10 15:12:03 2013 2132 [CTransaction::CTransaction] Transaction is cloned in 4 state
Wed Jul 10 15:12:03 2013 151e [CDialog::addTransaction] added transaction 2132 with branch z9hG4bK29f4c92a3d7a17198b8434cfad3b0e55,
with CSeq 519397263
Wed Jul 10 15:12:03 2013 151e [CDialog::startDialogTTL]
Wed Jul 10 15:12:03 2013 151e [CDialog::receiveResponse]
Wed Jul 10 15:12:03 2013 2132 [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
Wed Jul 10 15:12:03 2013 2132 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Wed Jul 10 15:12:03 2013 2132 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:12:03 2013 151e [CDialog::onTransactionState(pTrans = 2132, previousState = Proceeding, currentState = Completed, reas
on = Final resp reception]
Wed Jul 10 15:12:03 2013 2132 [CTransaction::startTimer] Timer D is started (delay = 32196 ms)
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from response (Client, UA)
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::updateSupported] Request timer supported
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::updateMinSE] Min-SE updated : 900
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1373447523 -> Wed Jul 10 15:12:03 2013 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::startTimers] Start session timer expiration : 1800000
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::startTimers] Start session timer will expires : 900000
Wed Jul 10 15:12:03 2013 [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=INVITE
Wed Jul 10 15:12:03 2013 151e [CDialog::updateRouteSet] there are 0 record-route headers
Wed Jul 10 15:12:03 2013 113a [CCall::insertInDialogMap] added dialog #151e with the key b481d8231ee2a3c186df22142cbf468e;SDsnbge99-
cvp3ymdyjk

Wed Jul 10 15:12:03 2013 151d [CDialog::getTransactionByCSeq] CSeq = 519397264
Wed Jul 10 15:12:03 2013 151d [CDialog::updateRouteSet] there are 0 record-route headers
Wed Jul 10 15:12:03 2013 113a[CMotorCall::searchForkedBranch]pForkedBranch=0x84ffed0.
Wed Jul 10 15:12:03 2013 [CForkedBranch::setFinalResponse] response=200.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::chooseBestResponse] pForkedBranch=0x84ffed0 iResponseCode=2000.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::chooseBestResponse] last iResponseCode=200.
Wed Jul 10 15:12:03 2013 [CForkedBranch::setFinalResponse] pResponse=0x84f8d50.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::chooseBestResponse] next branch.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
1373447523 -> Wed Jul 10 15:12:03 2013 [receiveSuccessfulMessage] Call: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 eqt: 1152 PROC
EEDING_STATE received a message.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::isExtGwTLSwithREINVITE] ExtGw NOT manged with TLS and refresh REINVITE
Wed Jul 10 15:12:03 2013 113a[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=1 uacRefresher=1.
Wed Jul 10 15:12:03 2013 [decodeACACALU] no header.
Wed Jul 10 15:12:03 2013 113a[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Wed Jul 10 15:12:03 2013 113a[emitEventToMonitel] CAC = 0 eqtCAC = -1
Wed Jul 10 15:12:03 2013 113a[emitEventToMonitel] cacUsed=0 eqtCac=-1
Wed Jul 10 15:12:03 2013 113a[emitEventToMonitel] displayIPCMessageOut
Wed Jul 10 15:12:03 2013 ** SIP TRUNKING **
Wed Jul 10 15:12:03 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Wed Jul 10 15:12:03 2013 [ipc_thread] IPC Thread : Ipc reception 9282.
Wed Jul 10 15:12:03 2013 [ipc_thread] IPC Thread : TCL thread signaled.
Wed Jul 10 15:12:03 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Wed Jul 10 15:12:03 2013 [display_ipc_out] ------------ Begin ---------------
Wed Jul 10 15:12:03 2013 Id : -1
Wed Jul 10 15:12:03 2013 SUCCESSFUL
Wed Jul 10 15:12:03 2013 xx : 0
Wed Jul 10 15:12:03 2013 RELATIVE REQUEST : INVITE
Wed Jul 10 15:12:03 2013 CAC-CSBU info : UNKNOWN
Wed Jul 10 15:12:03 2013 CLIR : 0
Wed Jul 10 15:12:03 2013 COLP : 0
Wed Jul 10 15:12:03 2013 [display_ipc_out] ------------- End ----------------
Wed Jul 10 15:12:03 2013 113a[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
1373447523 -> Wed Jul 10 15:12:03 2013 113a[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
Wed Jul 10 15:12:03 2013 [exec_ipc] in 9281.
Wed Jul 10 15:12:03 2013 [display_ipc_in] ------------ Begin ---------------
Wed Jul 10 15:12:03 2013 neqt : 1152 Id : -1
Wed Jul 10 15:12:03 2013 ACK
Wed Jul 10 15:12:03 2013 [display_ipc_in] ------------- End ----------------
Wed Jul 10 15:12:03 2013 [CMotorCallManager::onIncomingEvent] an event 10754 arrived on the eqt 1152 -1.
Wed Jul 10 15:12:03 2013 [CMotorCallManager::onIncomingEvent] state = ACCEPTED_STATE.
Wed Jul 10 15:12:03 2013 [onIncomingEvent] authorizationAttempt set to false.
Wed Jul 10 15:12:03 2013 [onIncomingEvent] ACK arrived.
Wed Jul 10 15:12:03 2013 [receiveAckEvent] Call: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 eqt: 1152 ACCEPTED_STATE received an
event.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::emitAckMessage] begin.
Wed Jul 10 15:12:03 2013 113a[CMotorCall::emitAckMessage] SDP ok.
Wed Jul 10 15:12:03 2013 113a [CCall::makeGenericRequest] ACK
Wed Jul 10 15:12:03 2013 113a [CCall::getDialog] Confirmed Dialog found
Wed Jul 10 15:12:03 2013 151e [CDialog::createRequest]
Wed Jul 10 15:12:03 2013 151e [CDialog::buildServicesForAllRequest]
Wed Jul 10 15:12:03 2013 151e [CDialog::createAckRequest]
Wed Jul 10 15:12:03 2013 151e [CDialog::createAckRequest], previous trans 2132 is found
Wed Jul 10 15:12:03 2013 151e [CDialog::createAckRequest], previous trans 2132 is client
1373447523 -> Wed Jul 10 15:12:03 2013 39 [CRequest::CRequest] Creation of a request
Wed Jul 10 15:12:03 2013 151e [CDialog::buildServicesForAck]
Wed Jul 10 15:12:03 2013 [CGwMngDat a::getStackAuthRealm] lg Realm=14
Wed Jul 10 15:12:03 2013 [CGwMngDat a::getStackAuthRealm] Realm : AlcatelOmniPcx
Wed Jul 10 15:12:03 2013 39 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Wed Jul 10 15:12:03 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 807)
----------------------utf8-----------------------
ACK sip:89036117511@10.180.140.2:5060;user=phone;transport=udp SIP/2.0
Contact: sip:550389@10.110.32.90
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
Authorization: Digest username="550389",realm="10.67.144.34",nonce="51dd25ba0af64f534b1f9441b2ba9038df3dae25",opaque="58e0f3eb7d01b5
095d2396886ba08b1e",qop=auth,cnonce="1373447514",nc=00000001,uri="sip:89036117511@10.180.140.2;user=phone",response="185bb34740cea87
5e9f9ecfca23d2221"
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: "M. Azanova DECT" <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397263 ACK
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK036c14e98f03ea0f2413ad7bed582ca2
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------
Wed Jul 10 15:12:03 2013 39 [CMessage::onSendSuccess]
Wed Jul 10 15:12:03 2013 151e [CDialog::onSendSuccess]
Wed Jul 10 15:12:03 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
1373447527 -> Wed Jul 10 15:12:03 2013 [isIpAddressType] 10.180.140.2.
Wed Jul 10 15:12:03 2013 [isIpAddressType] YES.
Wed Jul 10 15:12:03 2013 2132 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Wed Jul 10 15:12:03 2013 2132 [CTransaction::freeTimerToken] Timer D is freed
Wed Jul 10 15:12:03 2013 2132 [CTransaction::changeState] no transaction state, terminated reason : Request creation
Wed Jul 10 15:12:03 2013 151e [CDialog::createAckRequest] successful INVITE is terminated after the ACK is sent
Wed Jul 10 15:12:03 2013 113a[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Wed Jul 10 15:12:03 2013 [receiveAckEvent] continue with session Timer
Wed Jul 10 15:12:03 2013 [exec_ipc] in end 9281.
Wed Jul 10 15:12:03 2013 [CTransaction::notifyDialog]
Wed Jul 10 15:12:03 2013 [CTransaction::notifyDialog] notify parent dialog
Wed Jul 10 15:12:03 2013 151e [CDialog::onTransactionState(pTrans = 2132, previousState = null, currentState = Terminated, reason =
Request creation]
Wed Jul 10 15:12:03 2013 2132 [~CTransaction] transaction 519397263 INVITE

1373447534 -> Wed Jul 10 15:12:14 2013 [ipc_thread] IPC Thread : Ipc reception 9282.
Wed Jul 10 15:12:14 2013 [ipc_thread] IPC Thread : TCL thread signaled.
Wed Jul 10 15:12:14 2013 [exec_ipc] in 9281.
Wed Jul 10 15:12:14 2013 [display_ipc_in] ------------ Begin ---------------
Wed Jul 10 15:12:14 2013 neqt : 1152 Id : -1
Wed Jul 10 15:12:14 2013 BYE
Wed Jul 10 15:12:14 2013 [display_ipc_in] ------------- End ----------------
Wed Jul 10 15:12:14 2013 [CMotorCallManager::onIncomingEvent] an event 10755 arrived on the eqt 1152 -1.
Wed Jul 10 15:12:14 2013 [CMotorCallManager::onIncomingEvent] state = COMPLETED_STATE.
Wed Jul 10 15:12:14 2013 [onIncomingEvent] BYE arrived.
Wed Jul 10 15:12:14 2013 [receiveByeEvent] Call: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90 eqt: 1152 COMPLETED_STATE received an
event.
Wed Jul 10 15:12:14 2013 113a[CMotorCall ISDN ExtGW
Wed Jul 10 15:12:14 2013 113a [CCall::makeGenericRequest] BYE
Wed Jul 10 15:12:14 2013 113a [CCall::getDialog] Confirmed Dialog found
Wed Jul 10 15:12:14 2013 151e [CDialog::createRequest]
Wed Jul 10 15:12:14 2013 151e [CDialog::buildServicesForAllRequest]
Wed Jul 10 15:12:14 2013 151e [CDialog::createByeRequest]
Wed Jul 10 15:12:14 2013 2133 [CTransaction::changeState] STATE CHANGED TO INITIAL
Wed Jul 10 15:12:14 2013 2133 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:12:14 2013 151e [CDialog::onTransactionState(pTrans = 2133, previousState = Terminated, currentState = Initial, reason
= None]
Wed Jul 10 15:12:14 2013 3A [CRequest::CRequest] Creation of a request
1373447534 -> Wed Jul 10 15:12:14 2013 3A [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Wed Jul 10 15:12:14 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 501)
----------------------utf8-----------------------
BYE sip:89036117511@10.180.140.2:5060;user=phone;transport=udp SIP/2.0
Supported: timer,path,100rel
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397266 BYE
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK85e22922ee6a4547a2cc45af1a100122
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------
Wed Jul 10 15:12:14 2013 3A [CMessage::onSendSuccess]
Wed Jul 10 15:12:14 2013 2133 [CTransaction::onSendSuccess] CMessage 3A send Success
Wed Jul 10 15:12:14 2013 151e [CDialog::onSendSuccess]
Wed Jul 10 15:12:14 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Wed Jul 10 15:12:14 2013 [isIpAddressType] 10.180.140.2.
Wed Jul 10 15:12:14 2013 [isIpAddressType] YES.
Wed Jul 10 15:12:14 2013 2133 [CTransInitialState::createRequest] Transaction changes to Trying state
Wed Jul 10 15:12:14 2013 2133 [CTransaction::changeState] STATE CHANGED TO TRYING
Wed Jul 10 15:12:14 2013 2133 [CTransaction::changeState] notifying the parent dialog
Wed Jul 10 15:12:14 2013 151e [CDialog::onTransactionState(pTrans = 2133, previousState = Initial, currentState = Trying, reason = R
equest creation]
1373447534 -> Wed Jul 10 15:12:14 2013 2133 [CTransTryingState::enterInState] Init timers E and F
Wed Jul 10 15:12:14 2013 2133 [CTransaction::startTimer] Timer E is started (delay = 500 ms)
Wed Jul 10 15:12:14 2013 2133 [CTransaction::startTimer] Timer F is started (delay = 16000 ms)
Wed Jul 10 15:12:14 2013 151e [CDialog::addTransaction] added transaction 2133 with branch z9hG4bK85e22922ee6a4547a2cc45af1a100122,
with CSeq 519397266
Wed Jul 10 15:12:14 2013 [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
Wed Jul 10 15:12:14 2013 113a[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Wed Jul 10 15:12:14 2013 [exec_ipc] in end 9281.
Wed Jul 10 15:12:14 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK
To: <sip:89036117511@10.180.140.2;user=phone>;tag=SDsnbge99-cvp3ymdyjk
From: <sip:550389@10.180.140.2;user=phone>;tag=b481d8231ee2a3c186df22142cbf468e
Call-ID: ab1fb399c2f99fda17c2e260dcc13e4f@10.110.32.90
CSeq: 519397266 BYE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK85e22922ee6a4547a2cc45af1a100122
Content-Length: 0

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

10.07.2013 14:17:01

Однако...

Перещли оба с провайдером на G711 и - ура! - исходящие заработали. Сейчас приступил к тщательной проверке всего-всего, потом (если кому интересно) выложу окончательный конфиг Ext SIP GW.

Изменено: Seller_V - 10.07.2013 14:17:19

Перейти

SIP trunk group: неправильный АОН

Seller_V User Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009	# 09.07.2013 17:39:33 Апдейт: Пров поигрался у себя и после второго инвайта с авторизацией ко мне приходит 403 Not Authorised с обьяснением Bearer Capability not authorized. Я как-то могу повлиять? В ARS роуте выставлены и Voice, и Fax, и все, что возможно...
	Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

09.07.2013 16:28:35

Последняя трасса - не совсем честный исходящий. Звоню я туда на номер, приходящий по аналоговым СО (АРА8) на аналоговый внутренний, у которого немедленная переадресация на 8495ххххххх. По-другому никак, клиент уже работает вовсю.

И все равно непонятно, почему каналов не хватает? Может, в кодеках дело? У меня в дефолтном IP домене выставлено Without compression.

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

09.07.2013 15:31:39

Цитата
TMC пишет: Цитата Seller_V пишет: Выплыла странная штука - регистрация происходит в два этапа. Запрос --> 401 Unauthirised --> Запрос --> 200OK. Так и должно быть.

Не знал. Зря паниковал.

Однако лед тронулся. Каким-то волшебством добился повторного Invite при исходящем звонке, где посылается поле Authorization. Теперь получаю 503 ошибку с руганью на отсутствие каналов.

1373356861 -> Tue Jul 9 14:01:01 2013 [ipc_thread] IPC Thread : Ipc reception 9369.
Tue Jul 9 14:01:01 2013 [ipc_thread] IPC Thread : TCL thread signaled.
Tue Jul 9 14:01:01 2013 [exec_ipc] in 9368.
Tue Jul 9 14:01:01 2013 [display_ipc_in] ------------ Begin ---------------
Tue Jul 9 14:01:01 2013 neqt : 1152 Id : -1
Tue Jul 9 14:01:01 2013 INVITE
Tue Jul 9 14:01:01 2013 REQUEST URI : <> 84959332989@10.180.140.2:5060 ; user=phone
Tue Jul 9 14:01:01 2013 FROM : <Test> 550389@10.110.32.90:5060 ; user=phone
Tue Jul 9 14:01:01 2013 TO : <> 84959332989@10.180.140.2:5060 ; user=phone
Tue Jul 9 14:01:01 2013 CAC : 0
Tue Jul 9 14:01:01 2013 neqt CAC : -1
Tue Jul 9 14:01:01 2013 CAC-CSBU info : UNKNOWN
Tue Jul 9 14:01:01 2013 CLIR : 0
Tue Jul 9 14:01:01 2013 SDP :
Tue Jul 9 14:01:01 2013 ADDRESS : 10.110.32.9210.110.32.92 :32612
Tue Jul 9 14:01:01 2013 ALGOS :
Tue Jul 9 14:01:01 2013 PCMA
Tue Jul 9 14:01:01 2013 G723
Tue Jul 9 14:01:01 2013 97
Tue Jul 9 14:01:01 2013 DIRECTION : SEND & RECEIVE
Tue Jul 9 14:01:01 2013 crypto index : 0
Tue Jul 9 14:01:01 2013 N_GW_EXT : 10
Tue Jul 9 14:01:01 2013 [display_ipc_in] ------------- End ----------------
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onIncomingEvent] an event 10752 arrived on the eqt 1152 -1.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onIncomingEvent] New Call created.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::callFactory].
Tue Jul 9 14:01:01 2013 [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R10.1.1 j2.603.20.i
1373356861 -> Tue Jul 9 14:01:01 2013 [CMotorCallManager::callFactory]=84e58d0.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::insertCallwithEqt] CMotorCall 1152 inserted.
Tue Jul 9 14:01:01 2013 inviteBuildFromAssertedId-->extGw=-1
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::inviteBuildAurlWithBelongingDomain] Reg_Id_for_user_URL_of_PAI =1
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
Tue Jul 9 14:01:01 2013 [quoteString] => "550389"
Tue Jul 9 14:01:01 2013 [isAddrInDico] user=84959332989 host=10.180.140.2
Tue Jul 9 14:01:01 2013 [isUserInDico] 84959332989@10.180.140.2
Tue Jul 9 14:01:01 2013 isUserInDico] NOT found in the dictionnary.
Tue Jul 9 14:01:01 2013 [isAddrInDico] other sip user
Tue Jul 9 14:01:01 2013 1103[CMotorCall::inviteBuildAurlWithBelongingDomain] P-Asserted-Identity: "550389" <sip:550389@10.180.140.2
>
Tue Jul 9 14:01:01 2013 [quoteString] => "Test"
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::setFilterUsedMode] To be traced = 0
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onIncomingEvent] state = INITIAL_STATE.
Tue Jul 9 14:01:01 2013 [onIncomingEvent] INVITE arrived.
Tue Jul 9 14:01:01 2013 [receiveInviteEvent] Call: eqt: 1152 INITIAL_STATE received an event.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitInviteMessage] begin. size=656 call_type: 0
Tue Jul 9 14:01:01 2013 [isAddrInDico] user=84959332989 host=10.180.140.2
Tue Jul 9 14:01:01 2013 [isUserInDico] 84959332989@10.180.140.2
1373356861 -> Tue Jul 9 14:01:01 2013 isUserInDico] NOT found in the dictionnary.
Tue Jul 9 14:01:01 2013 [isAddrInDico] other sip user
Tue Jul 9 14:01:01 2013 inviteBuildFromAssertedId-->extGw=10
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::inviteBuildAurlWithBelongingDomain] Reg_Id_for_user_URL_of_PAI =1
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
Tue Jul 9 14:01:01 2013 [quoteString] => "550389"
Tue Jul 9 14:01:01 2013 [isAddrInDico] user=84959332989 host=10.180.140.2
Tue Jul 9 14:01:01 2013 [isUserInDico] 84959332989@10.180.140.2
Tue Jul 9 14:01:01 2013 isUserInDico] NOT found in the dictionnary.
Tue Jul 9 14:01:01 2013 [isAddrInDico] other sip user
Tue Jul 9 14:01:01 2013 1103[CMotorCall::inviteBuildAurlWithBelongingDomain] P-Asserted-Identity: "550389" <sip:550389@10.180.140.2
>
Tue Jul 9 14:01:01 2013 [quoteString] => "Test"
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitInviteMessage] From: "Test" <sip:550389@10.180.140.2;user=phone>
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitInviteMessage] reqUri :sip:84959332989@10.180.140.2;user=phone
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitInviteMessage]-->SIP_INFO_allowed=0
Tue Jul 9 14:01:01 2013 1103[CMotorCall ISDN ExtGW
Tue Jul 9 14:01:01 2013 1103[CMotorCall ISDN ExtGW
Tue Jul 9 14:01:01 2013 1103[CMotorCall::inviteBuildSupportedHeader] Invite directed to external gw : 10
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
1373356861 -> Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitInviteMessage] To: <sip:84959332989@10.180.140.2;user=phone>
Tue Jul 9 14:01:01 2013 am_I_PCS SCSWorking : NO
Tue Jul 9 14:01:01 2013 check_PCSstate
Tue Jul 9 14:01:01 2013 am_I_PCS SCSWorking : NO
Tue Jul 9 14:01:01 2013 check_PCSstate stat of PCS is : -1
Tue Jul 9 14:01:01 2013 [inviteBuildContact] trunk_number=102 extGw =10.
Tue Jul 9 14:01:01 2013 [137339-InviteBuildContact] userinfo : 550389
Tue Jul 9 14:01:01 2013 1103[CMotorCall::sipUriType] sip Uri.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::isTLSProtRequiredForExtGw] =0.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::inviteBuildContact] Contact: sip:550389@10.110.32.90
Tue Jul 9 14:01:01 2013 1103[CMotorCall::isExtGwTLSwithREINVITE] ExtGw NOT manged with TLS and refresh REINVITE
Tue Jul 9 14:01:01 2013 1103[CMotorCall::inviteBuildSessionTimer] uacGatewayMode=1 uacRefresher=1.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeRequestSdp] Begin sdp.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::appendAudioAttributToMedia] Direction: 0.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::appendAudioAttributToMedia] format 97
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeRequestSdp] crypto index3=0 clearMediaLine=0.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeRequestSdp] appendMediaDescription ok.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeRequestSdp] Final sdp.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitInviteMessage] The sdp build.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::new_determineTargetsForTheRequest] is not Responsible For This URI.
1373356861 -> Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeInitialMotorRequest] target is not the local node.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeInitialMotorRequest] nb contacts=1.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeInitialMotorRequest] *pcszNameToFind : 84959332989
Tue Jul 9 14:01:01 2013 [isUserInDico] 84959332989@10.180.140.2
Tue Jul 9 14:01:01 2013 isUserInDico] NOT found in the dictionnary.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeInitialMotorRequest] contact : sip:84959332989@10.180.140.2;user=phone
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeInitialMotorRequest] nb available licenses=10 .
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeInitialMotorRequest] req_Uri_OK=0 and pSipAddSpec->getHost()=10.180.140.2
Tue Jul 9 14:01:01 2013 [CGWMngDat a::isMainServer] CPU is MAIN.
Tue Jul 9 14:01:01 2013 [CGWMngDat a::isMainServer] checkwith PCS = 0
Tue Jul 9 14:01:01 2013 1103[CMotorCall::isTLSProtRequiredForExtGw] =0.
Tue Jul 9 14:01:01 2013 1103 [CCall::makeGenericRequest] INVITE
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Confirmed Dialog is not found (ID =

Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Initial Dialog Client not found
Tue Jul 9 14:01:01 2013 1502 [CDialog::createRequest]
Tue Jul 9 14:01:01 2013 1502 [CDialog::buildServicesForAllRequest]
Tue Jul 9 14:01:01 2013 1502 [CDialog::createInviteRequest]
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] STATE CHANGED TO INITIAL
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] notifying the parent dialog
1373356861 -> Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2102, previousState = Terminated, currentState = I
nitial, reason = None]
Tue Jul 9 14:01:01 2013 2 [CRequest::CRequest] Creation of a request
Tue Jul 9 14:01:01 2013 2 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Tue Jul 9 14:01:01 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 1002)
----------------------utf8-----------------------
INVITE sip:84959332989@10.180.140.2;user=phone SIP/2.0
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, SUBSCRIBE, OPTIONS, UPDATE
Supported: timer,path,100rel
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
Session-Expires: 1800;refresher=uac
Min-SE: 900
P-Asserted-Identity: "550389" <sip:550389@10.180.140.2>
Content-Type: application/sdp
To: <sip:84959332989@10.180.140.2;user=phone>
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
Contact: <sip:550389@10.110.32.90;transport=UDP>
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
CSeq: 1201037864 INVITE
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK13de85af2fabb8be2dd23cb67677bb8d
Max-Forwards: 70
Content-Length: 265

v=0
o=OXE 1373356861 1373356861 IN IP4 10.110.32.90
s=abs
c=IN IP4 10.110.32.92
t=0 0
m=audio 32612 RTP/AVP 8 4 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:4 G723/8000
a=ptime:30
a=maxptime:30
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
Tue Jul 9 14:01:01 2013 2 [CMessage::onSendSuccess]
1373356861 -> Tue Jul 9 14:01:01 2013 2102 [CTransaction::onSendSuccess] CMessage 2 send Success
Tue Jul 9 14:01:01 2013 1502 [CDialog::onSendSuccess]
Tue Jul 9 14:01:01 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Tue Jul 9 14:01:01 2013 [isIpAddressType] 10.180.140.2.
Tue Jul 9 14:01:01 2013 [isIpAddressType] YES.
Tue Jul 9 14:01:01 2013 2102 [CTransInitialState::createRequest] Transaction changes to Calling state
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] STATE CHANGED TO CALLING
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] notifying the parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2102, previousState = Initial, currentState = Calling, reason =
Request creation]
Tue Jul 9 14:01:01 2013 2102 [CTransaction::startTimer] Timer A is started (delay = 500 ms)
Tue Jul 9 14:01:01 2013 2102 [CTransaction::startTimer] Timer B is started (delay = 4000 ms)
Tue Jul 9 14:01:01 2013 1502 [CDialog::getTransactionByCSeq] CSeq = 1201037864
Tue Jul 9 14:01:01 2013 1502 [CDialog::addTransaction] added transaction 2102 with branch z9hG4bK13de85af2fabb8be2dd23cb67677bb8d,
with CSeq 1201037864
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Client, UA)
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::updateSupported] Request timer supported
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::updateMinSE] Min-SE updated : 900
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1373356861 -> Tue Jul 9 14:01:01 2013 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Tue Jul 9 14:01:01 2013 [CForkedBranch::CForkedBranch] context = 0x84e5d7c.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::branchAllocation] pForkedBranch = 0x84e49f8 and the branch number is.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::makeInitialMotorRequest] reqUri duplication pForkedBranch = 0x84e49f8.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Tue Jul 9 14:01:01 2013 NOE-SIP : StandByCallCreation not sent to twin CS
Tue Jul 9 14:01:01 2013 [onIncomingEvent] CallID must be inserted.
Tue Jul 9 14:01:01 2013 [onIncomingEvent] CallID inserted.
Tue Jul 9 14:01:01 2013 [exec_ipc] in end 9368.
Tue Jul 9 14:01:01 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:84959332989@10.180.140.2;user=phone>
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
CSeq: 1201037864 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK13de85af2fabb8be2dd23cb67677bb8d

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

Tue Jul 9 14:01:01 2013 [CIOCom::receiveResponse] CResponse 2 ref=1
Tue Jul 9 14:01:01 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Tue Jul 9 14:01:01 2013 102 [CCallManager::onIncomingResponse]
Tue Jul 9 14:01:01 2013 1103 [CCall::receiveResponse] 100
1373356861 -> Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Confirmed Dialog is not found (ID = 76816edcc2f41fe921ba66b6edd81ba4;
)
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Returning Initial Dialog Client
Tue Jul 9 14:01:01 2013 1502 [CDialog::receiveResponse]
Tue Jul 9 14:01:01 2013 2102 [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] notifying the parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2102, previousState = Calling, currentState = Proceeding, reason
= 1xx response reception]
Tue Jul 9 14:01:01 2013 2102 [CTransaction::freeTimerToken] Timer A is freed
Tue Jul 9 14:01:01 2013 2102 [CTransaction::freeTimerToken] Timer B is freed
Tue Jul 9 14:01:01 2013 1103[CMotorCall::searchForkedBranch]pForkedBranch=0x84e49f8.
Tue Jul 9 14:01:01 2013 [receiveInformationalMessage] Call: a00a03e4296dd158a42d5970e13569d7@10.110.32.90 eqt: 1152 PROCEEDING_STAT
E received a message.
Tue Jul 9 14:01:01 2013 [receiveInformationalMessage] is in the else part of SDP not present
Tue Jul 9 14:01:01 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 401 Unauthorized
To: <sip:84959332989@10.180.140.2;user=phone>;tag=SDmhp6299-s0nj9yipq7
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
CSeq: 1201037864 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK13de85af2fabb8be2dd23cb67677bb8d
WWW-Authenticate: Digest realm="10.67.144.34",nonce="51dbc39dcfec66ef786a43c7311c15af449e59a6",opaque="d115b45f7caffcf5cb8e2e76dedf8
be5",qop="auth"
Content-Length: 0

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

1373356861 -> Tue Jul 9 14:01:01 2013 [CIOCom::receiveResponse] CResponse 3 ref=1
Tue Jul 9 14:01:01 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Tue Jul 9 14:01:01 2013 102 [CCallManager::onIncomingResponse]
Tue Jul 9 14:01:01 2013 1103 [CCall::receiveResponse] 401
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Confirmed Dialog is not found (ID = 76816edcc2f41fe921ba66b6edd81ba4;SDmhp6299-s0nj
9yipq7)
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Returning Initial Dialog Client
Tue Jul 9 14:01:01 2013 1502 [CDialog::receiveResponse]
Tue Jul 9 14:01:01 2013 2 [~CResponse] resp(100) a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037864 INVITE
Tue Jul 9 14:01:01 2013 2102 [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] notifying the parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2102, previousState = Proceeding, currentState = Completed, reas
on = Final resp reception]
Tue Jul 9 14:01:01 2013 3 [CRequest::CRequest] Creation of a request
Tue Jul 9 14:01:01 2013 3 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Tue Jul 9 14:01:01 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 390)
----------------------utf8-----------------------
ACK sip:84959332989@10.180.140.2;user=phone SIP/2.0
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
To: <sip:84959332989@10.180.140.2;user=phone>;tag=SDmhp6299-s0nj9yipq7
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK13de85af2fabb8be2dd23cb67677bb8d
CSeq: 1201037864 ACK
Content-Length: 0

-------------------------------------------------
1373356861 -> Tue Jul 9 14:01:01 2013 3 [CMessage::onSendSuccess]
Tue Jul 9 14:01:01 2013 2102 [CTransaction::onSendSuccess] CMessage 3 send Success
Tue Jul 9 14:01:01 2013 1502 [CDialog::onSendSuccess]
Tue Jul 9 14:01:01 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Tue Jul 9 14:01:01 2013 [isIpAddressType] 10.180.140.2.
Tue Jul 9 14:01:01 2013 [isIpAddressType] YES.
Tue Jul 9 14:01:01 2013 2102 [CTransaction::startTimer] Timer D is started (delay = 32142 ms)
Tue Jul 9 14:01:01 2013 [unquoteString] "10.67.144.34" => 10.67.144.34
Tue Jul 9 14:01:01 2013 1103[CMotorCall::isKnownAuthenticate] Unquoted realm found : 10.67.144.34 ,Realm in the management : 10.67.
144.34.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::isKnownAuthenticate] matching realm : 10.67.144.34 in authenticate header.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::searchForkedBranch]pForkedBranch=0x84e49f8.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitAuthorizedRequest] Begin.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitAuthorizedRequest] authenticateHeader found => Login: 550389.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitAuthorizedRequest] AuthorizationService for qop : "auth" => cnounce : 1373356861.
Tue Jul 9 14:01:01 2013 1103 [CCall::makeGenericRequest] INVITE
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Confirmed Dialog is not found (ID = 76816edcc2f41fe921ba66b6edd81ba4;SDmhp6299-s0nj
9yipq7)
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Returning Initial Dialog Client
Tue Jul 9 14:01:01 2013 1502 [CDialog::createRequest]
1373356861 -> Tue Jul 9 14:01:01 2013 1502 [CDialog::buildServicesForAllRequest]
Tue Jul 9 14:01:01 2013 1502 [CDialog::createInviteRequest]
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] STATE CHANGED TO INITIAL
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] notifying the parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2103, previousState = Terminated, currentState = Initial, reason
= None]
Tue Jul 9 14:01:01 2013 4 [CRequest::CRequest] Creation of a request
Tue Jul 9 14:01:01 2013 4 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Tue Jul 9 14:01:01 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 1286)
----------------------utf8-----------------------
INVITE sip:84959332989@10.180.140.2;user=phone SIP/2.0
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, SUBSCRIBE, OPTIONS, UPDATE
Supported: timer,path,100rel
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
Session-Expires: 1800;refresher=uac
Min-SE: 900
P-Asserted-Identity: "550389" <sip:550389@10.180.140.2>
Content-Type: application/sdp
To: <sip:84959332989@10.180.140.2;user=phone>
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
Contact: <sip:550389@10.110.32.90;transport=UDP>
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
CSeq: 1201037865 INVITE
Max-Forwards: 70
Authorization: Digest username="550389",realm="10.67.144.34",nonce="51dbc39dcfec66ef786a43c7311c15af449e59a6",opaque="d115b45f7caffc
f5cb8e2e76dedf8be5",qop=auth,cnonce="1373356861",nc=00000001,uri="sip:84959332989@10.180.140.2;user=phone",response="db6d98302caa560
4a7cf1cb018ca0ea9"
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK646d4baa378df178134c85ea4d4b995e
Content-Length: 265

v=0
o=OXE 1373356861 1373356861 IN IP4 10.110.32.90
s=abs
c=IN IP4 10.110.32.92
t=0 0
m=audio 32612 RTP/AVP 8 4 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:4 G723/8000
a=ptime:30
a=maxptime:30
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1373356861 -> Tue Jul 9 14:01:01 2013 4 [CMessage::onSendSuccess]
Tue Jul 9 14:01:01 2013 2103 [CTransaction::onSendSuccess] CMessage 4 send Success
Tue Jul 9 14:01:01 2013 1502 [CDialog::onSendSuccess]
Tue Jul 9 14:01:01 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Tue Jul 9 14:01:01 2013 [isIpAddressType] 10.180.140.2.
Tue Jul 9 14:01:01 2013 [isIpAddressType] YES.
Tue Jul 9 14:01:01 2013 2103 [CTransInitialState::createRequest] Transaction changes to Calling state
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] STATE CHANGED TO CALLING
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] notifying the parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2103, previousState = Initial, currentState = Calling, reason =
Request creation]
Tue Jul 9 14:01:01 2013 2103 [CTransaction::startTimer] Timer A is started (delay = 500 ms)
Tue Jul 9 14:01:01 2013 2103 [CTransaction::startTimer] Timer B is started (delay = 4000 ms)
Tue Jul 9 14:01:01 2013 1502 [CDialog::getTransactionByCSeq] CSeq = 1201037865
Tue Jul 9 14:01:01 2013 1502 [CDialog::addTransaction] added transaction 2103 with branch z9hG4bK646d4baa378df178134c85ea4d4b995e,
with CSeq 1201037865
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::updateSupported] Request timer supported
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::updateSessionExpires] Refresher updated : i
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitAuthorizedRequest] ForkedBranch updated.
1373356861 -> Tue Jul 9 14:01:01 2013 [CForkedBranch::CForkedBranch] context = 0x84ee7dc.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::branchAllocation] pForkedBranch = 0x84e49f8 and the branch number is.
Tue Jul 9 14:01:01 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:84959332989@10.180.140.2;user=phone>
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
CSeq: 1201037865 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK646d4baa378df178134c85ea4d4b995e

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

Tue Jul 9 14:01:01 2013 [CIOCom::receiveResponse] CResponse 4 ref=1
Tue Jul 9 14:01:01 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Tue Jul 9 14:01:01 2013 102 [CCallManager::onIncomingResponse]
Tue Jul 9 14:01:01 2013 1103 [CCall::receiveResponse] 100
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Confirmed Dialog is not found (ID = 76816edcc2f41fe921ba66b6edd81ba4;)
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Returning Initial Dialog Client
Tue Jul 9 14:01:01 2013 1502 [CDialog::receiveResponse]
Tue Jul 9 14:01:01 2013 2103 [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] notifying the parent dialog
1373356861 -> Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2103, previousState = Calling, currentState = Proc
eeding, reason = 1xx response reception]
Tue Jul 9 14:01:01 2013 2103 [CTransaction::freeTimerToken] Timer A is freed
Tue Jul 9 14:01:01 2013 2103 [CTransaction::freeTimerToken] Timer B is freed
Tue Jul 9 14:01:01 2013 1103[CMotorCall::searchForkedBranch]pForkedBranch=0x84e49f8.
Tue Jul 9 14:01:01 2013 [receiveInformationalMessage] Call: a00a03e4296dd158a42d5970e13569d7@10.110.32.90 eqt: 1152 PROCEEDING_STAT
E received a message.
Tue Jul 9 14:01:01 2013 [receiveInformationalMessage] is in the else part of SDP not present
Tue Jul 9 14:01:01 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 503 Service Unavailable
To: <sip:84959332989@10.180.140.2;user=phone>;tag=SDmhp6299-ooxn1qcknj
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
CSeq: 1201037865 INVITE
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK646d4baa378df178134c85ea4d4b995e
Reason: Q.850;cause=34;text="No circuit/channel available"
Content-Length: 0

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

Tue Jul 9 14:01:01 2013 [CIOCom::receiveResponse] CResponse 5 ref=1
Tue Jul 9 14:01:01 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Tue Jul 9 14:01:01 2013 102 [CCallManager::onIncomingResponse]
Tue Jul 9 14:01:01 2013 1103 [CCall::receiveResponse] 503
1373356861 -> Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Confirmed Dialog is not found (ID = 76816edcc2f41fe921ba66b6edd81ba4;
SDmhp6299-ooxn1qcknj)
Tue Jul 9 14:01:01 2013 1103 [CCall::getDialog] Returning Initial Dialog Client
Tue Jul 9 14:01:01 2013 1502 [CDialog::receiveResponse]
Tue Jul 9 14:01:01 2013 4 [~CResponse] resp(100) a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037865 INVITE
Tue Jul 9 14:01:01 2013 2103 [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] notifying the parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2103, previousState = Proceeding, currentState = Completed, reas
on = Final resp reception]
Tue Jul 9 14:01:01 2013 5 [CRequest::CRequest] Creation of a request
Tue Jul 9 14:01:01 2013 5 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Tue Jul 9 14:01:01 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 390)
----------------------utf8-----------------------
ACK sip:84959332989@10.180.140.2;user=phone SIP/2.0
Call-ID: a00a03e4296dd158a42d5970e13569d7@10.110.32.90
From: "Test" <sip:550389@10.180.140.2;user=phone>;tag=76816edcc2f41fe921ba66b6edd81ba4
To: <sip:84959332989@10.180.140.2;user=phone>;tag=SDmhp6299-ooxn1qcknj
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK646d4baa378df178134c85ea4d4b995e
CSeq: 1201037865 ACK
Content-Length: 0

-------------------------------------------------
1373356861 -> Tue Jul 9 14:01:01 2013 5 [CMessage::onSendSuccess]
Tue Jul 9 14:01:01 2013 2103 [CTransaction::onSendSuccess] CMessage 5 send Success
Tue Jul 9 14:01:01 2013 1502 [CDialog::onSendSuccess]
Tue Jul 9 14:01:01 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Tue Jul 9 14:01:01 2013 [isIpAddressType] 10.180.140.2.
Tue Jul 9 14:01:01 2013 [isIpAddressType] YES.
Tue Jul 9 14:01:01 2013 2103 [CTransaction::startTimer] Timer D is started (delay = 32208 ms)
Tue Jul 9 14:01:01 2013 1103[CMotorCall::searchForkedBranch]pForkedBranch=0x84e49f8.
Tue Jul 9 14:01:01 2013 [CForkedBranch::setFinalResponse] response=503.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::responseToInviteOrCancel] no response to the CH m_cancelSent=0.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::responseToInviteOrCancel] all responses are received 1.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::chooseBestResponse] pForkedBranch=0x84e49f8 iResponseCode=2000.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::chooseBestResponse] last iResponseCode=503.
Tue Jul 9 14:01:01 2013 [CForkedBranch::setFinalResponse] pResponse=0x84f2380.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::chooseBestResponse] next branch.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
Tue Jul 9 14:01:01 2013 [receiveServerFailureMessage] Call: a00a03e4296dd158a42d5970e13569d7@10.110.32.90 eqt: 1152 PROCEEDING_STAT
E received a message.
1373356861 -> Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] CAC = 0 eqtCAC = -1
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] cacUsed=0 eqtCac=-1
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] displayIPCMessageOut
Tue Jul 9 14:01:01 2013 ** SIP OTHER ICE **
Tue Jul 9 14:01:01 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Tue Jul 9 14:01:01 2013 [ipc_thread] IPC Thread : Ipc reception 9369.
Tue Jul 9 14:01:01 2013 [ipc_thread] IPC Thread : TCL thread signaled.
Tue Jul 9 14:01:01 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Tue Jul 9 14:01:01 2013 [display_ipc_out] ------------ Begin ---------------
Tue Jul 9 14:01:01 2013 Id : -1
Tue Jul 9 14:01:01 2013 SERVER FAILURE
Tue Jul 9 14:01:01 2013 xx : 3
Tue Jul 9 14:01:01 2013 RELATIVE REQUEST : INVITE
Tue Jul 9 14:01:01 2013 [display_ipc_out] ------------- End ----------------
Tue Jul 9 14:01:01 2013 1103[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Tue Jul 9 14:01:01 2013 1103[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
Tue Jul 9 14:01:01 2013 [exec_ipc] in 9368.
Tue Jul 9 14:01:01 2013 [display_ipc_in] ------------ Begin ---------------
Tue Jul 9 14:01:01 2013 neqt : 1152 Id : -1
Tue Jul 9 14:01:01 2013 SIP EQT RELEASED
Tue Jul 9 14:01:01 2013 [display_ipc_in] ------------- End ----------------
1373356861 -> Tue Jul 9 14:01:01 2013 [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 1152 -1.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onIncomingEvent] The call with eqt: 1152 has released its equipment.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::emitSipEqtReleaseAck] SIP_EQT_RELEASE_ACK
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] content size (lginfo): 660 ccal crypted= 0
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel]1 T38 REINVITE TRUE
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] CAC = 0 eqtCAC = -1
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] cacUsed=0 eqtCac=-1
Tue Jul 9 14:01:01 2013 1103[emitEventToMonitel] displayIPCMessageOut
Tue Jul 9 14:01:01 2013 ** SIP OTHER ICE **
Tue Jul 9 14:01:01 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Tue Jul 9 14:01:01 2013 [sendLgEvtSip] Event sent on eqt : 1152 Id :-1
Tue Jul 9 14:01:01 2013 [display_ipc_out] ------------ Begin ---------------
Tue Jul 9 14:01:01 2013 Id : -1
Tue Jul 9 14:01:01 2013 SIP_EQT_RELEASE_ACK
Tue Jul 9 14:01:01 2013 [display_ipc_out] ------------- End ----------------
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Tue Jul 9 14:01:01 2013 1103[CMotorCall::unRegister] Remove eqt : 1152 diag : -1 from the map.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::eraseCallwithEqt] erase 1152 -1.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onIncomingEvent] killSession.
1373356861 -> ascii.alarm.delay = 4h
1373356861 -> Tue Jul 9 14:01:01 2013 1103 [CCall::killSession]
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Tue Jul 9 14:01:01 2013 2102 [CTransaction::freeTimerToken] Timer D is freed
Tue Jul 9 14:01:01 2013 2102 [CTransaction::changeState] no transaction state, terminated reason : None
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Tue Jul 9 14:01:01 2013 2103 [CTransaction::freeTimerToken] Timer D is freed
Tue Jul 9 14:01:01 2013 2103 [CTransaction::changeState] no transaction state, terminated reason : None
Tue Jul 9 14:01:01 2013 1103 [CCall::killSession] notifying the call manager
Tue Jul 9 14:01:01 2013 102 [CCallManager::onCallClosed]
Tue Jul 9 14:01:01 2013 102 [CCallManager::onCallClosed()] Call 1103 closed. Removing call 139352272 from the maps
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onNotifyCallClosed] Call : a00a03e4296dd158a42d5970e13569d7@10.110.32.90 is closed.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onNotifyCallClosed] Call : a00a03e4296dd158a42d5970e13569d7@10.110.32.90 is closed.
Tue Jul 9 14:01:01 2013 [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
Tue Jul 9 14:01:01 2013 [exec_ipc] in end 9368.
Tue Jul 9 14:01:01 2013 [CTransaction::notifyDialog]
Tue Jul 9 14:01:01 2013 [CTransaction::notifyDialog] notify parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2102, previousState = null, currentState = Terminated, reason =
None]
1373356861 -> Tue Jul 9 14:01:01 2013 2102 [~CTransaction] transaction 1201037864 INVITE
Tue Jul 9 14:01:01 2013 3 [~CRequest] req a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037864 ACK
Tue Jul 9 14:01:01 2013 3 [~CResponse] resp(401) a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037864 INVITE
Tue Jul 9 14:01:01 2013 2 [~CRequest] req a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037864 INVITE
Tue Jul 9 14:01:01 2013 [CTransaction::notifyDialog]
Tue Jul 9 14:01:01 2013 [CTransaction::notifyDialog] notify parent dialog
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState(pTrans = 2103, previousState = null, currentState = Terminated, reason =
None]
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState] dialog is terminated
Tue Jul 9 14:01:01 2013 1502 [CDialog::onTransactionState] onDialogClosed
Tue Jul 9 14:01:01 2013 1103 [CCall::onDialogClosed]
Tue Jul 9 14:01:01 2013 1103 [CCall::onDialogClosed] only initial *********
Tue Jul 9 14:01:01 2013 1103 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
Tue Jul 9 14:01:01 2013 2103 [~CTransaction] transaction 1201037865 INVITE
Tue Jul 9 14:01:01 2013 5 [~CRequest] req a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037865 ACK
Tue Jul 9 14:01:01 2013 102 [CCallManager::removeGarbage] at index i = 0
Tue Jul 9 14:01:01 2013 1103 [CMotorCall::clear_subscription] no subscription managed
Tue Jul 9 14:01:01 2013 1103[CMotorCall::~CMotorCall] Call : a00a03e4296dd158a42d5970e13569d7@10.110.32.90 is being destructed.
1373356865 -> Tue Jul 9 14:01:01 2013 1103[CMotorCall::~CMotorCall] License is released for this call.
Tue Jul 9 14:01:01 2013 1103[CMotorCall::~CMotorCall] number of available licenses are =10 .
Tue Jul 9 14:01:01 2013 5 [~CResponse] resp(503) a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037865 INVITE
Tue Jul 9 14:01:01 2013 1103 [CCall::~CCall]
Tue Jul 9 14:01:01 2013 1103 [CCall::removeGarbage]
Tue Jul 9 14:01:01 2013 1502 [CDialog::~CDialog]
Tue Jul 9 14:01:01 2013 [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
Tue Jul 9 14:01:01 2013 4 [~CRequest] req a00a03e4296dd158a42d5970e13569d7@10.110.32.90 1201037865 INVITE

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

08.07.2013 19:15:22

Собрали совсем аналогичную схему у себя на стенде. С такой же циской с NAT, с такими же IP фдресами. Без SBC, правда. Вместо провайдерской SI3000 стоит Астериск. С ходу заработало в обе стороны.

НО! Выплыла странная штука - регистрация происходит в два этапа. Запрос --> 401 Unauthirised --> Запрос --> 200OK. Первый запрос идет без указания realm, второй с указанием. (И у клиента, и у нас на стенде картина одинаковая).

А не в этом ли косяк - регистрация вот так косо установилась, а при звонке в инвайте реалм не посылается, и пров меня не видит.

Вот трасса регистрации

1373288327 -> Mon Jul 8 18:58:47 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 469)
----------------------utf8-----------------------
REGISTER sip:10.180.140.2 SIP/2.0
Supported: 100rel,path
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
To: sip:550389@10.180.140.2
From: sip:550389@10.180.140.2;tag=f0155f89b95bf47da897b8918d6eaf95
Contact: <sip:550389@10.110.32.90;transport=UDP>;expires=600
Call-ID: 1a0692390be7787c61a3703c6b623889@10.110.32.90
CSeq: 953736919 REGISTER
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK64f547e2ede2793e7d95f7ec434d66b3
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------
Mon Jul 8 18:58:47 2013 F [CMessage::onSendSuccess]
Mon Jul 8 18:58:47 2013 210e [CTransaction::onSendSuccess] CMessage F send Success
Mon Jul 8 18:58:47 2013 1508 [CDialog::onSendSuccess]
Mon Jul 8 18:58:47 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Mon Jul 8 18:58:47 2013 [isIpAddressType] 10.180.140.2.
Mon Jul 8 18:58:47 2013 [isIpAddressType] YES.
Mon Jul 8 18:58:47 2013 210e [CTransInitialState::createRequest] Transaction changes to Trying state
Mon Jul 8 18:58:47 2013 210e [CTransaction::changeState] STATE CHANGED TO TRYING
Mon Jul 8 18:58:47 2013 210e [CTransaction::changeState] notifying the parent dialog
Mon Jul 8 18:58:47 2013 1508 [CDialog::onTransactionState(pTrans = 210e, previousState = Initial, currentState = Trying, reason = R
equest creation]
Mon Jul 8 18:58:47 2013 210e [CTransTryingState::enterInState] Init timers E and F
1373288327 -> Mon Jul 8 18:58:47 2013 210e [CTransaction::startTimer] Timer E is started (delay = 500 ms)
Mon Jul 8 18:58:47 2013 210e [CTransaction::startTimer] Timer F is started (delay = 2000 ms)
Mon Jul 8 18:58:47 2013 1508 [CDialog::addTransaction] added transaction 210e with branch z9hG4bK64f547e2ede2793e7d95f7ec434d66b3,
with CSeq 953736919
Mon Jul 8 18:58:47 2013 [CForkedBranch::CForkedBranch] context = 0x84f07f0.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::branchAllocation] pForkedBranch = 0x84e82f0 and the branch number is.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::makeInitialMotorRequest] reqUri duplication pForkedBranch = 0x84e82f0.
Mon Jul 8 18:58:47 2013 [CInitialState::receiveRegisterEvent] emitRegisterMessage OK.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Mon Jul 8 18:58:47 2013 [onIncomingEvent] CallID must be inserted.
Mon Jul 8 18:58:47 2013 [onIncomingEvent] CallID inserted.
Mon Jul 8 18:58:47 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 401 Unauthorized
To: <sip:550389@10.180.140.2>;tag=SDshab899-10g75hp4dw
From: sip:550389@10.180.140.2;tag=f0155f89b95bf47da897b8918d6eaf95
Call-ID: 1a0692390be7787c61a3703c6b623889@10.110.32.90
CSeq: 953736919 REGISTER
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK64f547e2ede2793e7d95f7ec434d66b3
Contact: <sip:550389@10.180.140.2:5060;transport=udp>;expires=600
Expires: 600
WWW-Authenticate: Digest realm="10.67.144.34",nonce="51dab7e8cb268f1305113bd62e5d9fb5190015e6",opaque="115cff3b9285f8825fc3cd4407cc9
2bb",qop="auth"
Content-Length: 0

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

1373288327 -> Mon Jul 8 18:58:47 2013 [CIOCom::receiveResponse] CResponse F ref=1
Mon Jul 8 18:58:47 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Mon Jul 8 18:58:47 2013 104 [CCallManager::onIncomingResponse]
Mon Jul 8 18:58:47 2013 1114 [CCall::receiveResponse] 401
Mon Jul 8 18:58:47 2013 1114 [CCall::getDialog] Confirmed Dialog is not found (ID = f0155f89b95bf47da897b8918d6eaf95;SDshab899-10g7
5hp4dw)
Mon Jul 8 18:58:47 2013 1114 [CCall::getDialog] Initial Dialog Client not found
Mon Jul 8 18:58:47 2013 1114 [CRegistrarCall::onReceiveOutOfContextResponse] 401
Mon Jul 8 18:58:47 2013 1114 [CCall::onDialogClosed]
Mon Jul 8 18:58:47 2013 1114 [CCall::onDialogClosed] only initial *********
Mon Jul 8 18:58:47 2013 1114 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
Mon Jul 8 18:58:47 2013 1114 [CCall] closed
Mon Jul 8 18:58:47 2013 104 [CCallManager::onCallClosed]
Mon Jul 8 18:58:47 2013 104 [CCallManager::onCallClosed()] Call 1114 closed. Removing call 139365888 from the maps
Mon Jul 8 18:58:47 2013 101 [CCallManager::onIncomingResponse]
Mon Jul 8 18:58:47 2013 1113 [CCall::receiveResponse] 401
Mon Jul 8 18:58:47 2013 1113 [CCall::getDialog] Confirmed Dialog is not found (ID = f0155f89b95bf47da897b8918d6eaf95;SDshab899-10g7
5hp4dw)
Mon Jul 8 18:58:47 2013 1113 [CCall::getDialog] Returning Initial Dialog Client
Mon Jul 8 18:58:47 2013 1508 [CDialog::receiveResponse]
Mon Jul 8 18:58:47 2013 210e [CTransTryingState::receiveResponse] Final : Transaction changes to Completed state
1373288327 -> Mon Jul 8 18:58:47 2013 210e [CTransaction::changeState] STATE CHANGED TO COMPLETED
Mon Jul 8 18:58:47 2013 210e [CTransaction::changeState] notifying the parent dialog
Mon Jul 8 18:58:47 2013 1508 [CDialog::onTransactionState(pTrans = 210e, previousState = Trying, currentState = Completed, reason =
Final resp reception]
Mon Jul 8 18:58:47 2013 210e [CTransaction::freeTimerToken] Timer E is freed
Mon Jul 8 18:58:47 2013 210e [CTransaction::freeTimerToken] Timer F is freed
Mon Jul 8 18:58:47 2013 210e [CTransaction::startTimer] Timer K is started (delay = 5000 ms)
Mon Jul 8 18:58:47 2013 [unquoteString] "10.67.144.34" => 10.67.144.34
Mon Jul 8 18:58:47 2013 1113[CMotorCall::isKnownAuthenticate] Unquoted realm found : 10.67.144.34 ,Realm in the management : 10.67.
144.34.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::isKnownAuthenticate] matching realm : 10.67.144.34 in authenticate header.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::searchForkedBranch]pForkedBranch=0x84e82f0.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::emitAuthorizedRequest] Begin.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::emitAuthorizedRequest] authenticateHeader found => Login: 550389.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::emitAuthorizedRequest] AuthorizationService for qop : "auth" => cnounce : 1373288327.
Mon Jul 8 18:58:47 2013 1113 [CCall::makeGenericRequest] REGISTER
Mon Jul 8 18:58:47 2013 1113 [CCall::getDialog] Confirmed Dialog is not found (ID = f0155f89b95bf47da897b8918d6eaf95;SDshab899-10g7
5hp4dw)
1373288327 -> Mon Jul 8 18:58:47 2013 1113 [CCall::getDialog] Returning Initial Dialog Client
Mon Jul 8 18:58:47 2013 1508 [CDialog::createRequest]
Mon Jul 8 18:58:47 2013 1508 [CDialog::buildServicesForAllRequest]
Mon Jul 8 18:58:47 2013 1508 [CDialog::createBasicRequest]
Mon Jul 8 18:58:47 2013 210f [CTransaction::changeState] STATE CHANGED TO INITIAL
Mon Jul 8 18:58:47 2013 210f [CTransaction::changeState] notifying the parent dialog
Mon Jul 8 18:58:47 2013 1508 [CDialog::onTransactionState(pTrans = 210f, previousState = Terminated, currentState = Initial, reason
= None]
Mon Jul 8 18:58:47 2013 10 [CRequest::CRequest] Creation of a request
Mon Jul 8 18:58:47 2013 10 [CMessage::send] sip_sendMessage (10.180.140.2:5060)
Mon Jul 8 18:58:47 2013 SEND MESSAGE TO NETWORK (10.180.140.2:5060 [UDP]) (BUFF LEN = 730)
----------------------utf8-----------------------
REGISTER sip:10.180.140.2 SIP/2.0
Supported: 100rel,path
User-Agent: OmniPCX Enterprise R10.1.1 j2.603.20.i
To: sip:550389@10.180.140.2
From: sip:550389@10.180.140.2;tag=f0155f89b95bf47da897b8918d6eaf95
Contact: <sip:550389@10.110.32.90;transport=UDP>;expires=600
Call-ID: 1a0692390be7787c61a3703c6b623889@10.110.32.90
CSeq: 953736920 REGISTER
Max-Forwards: 70
Authorization: Digest username="550389",realm="10.67.144.34",nonce="51dab7e8cb268f1305113bd62e5d9fb5190015e6",opaque="115cff3b9285f8
825fc3cd4407cc92bb",qop=auth,cnonce="1373288327",nc=00000001,uri="sip:10.180.140.2",response="f8f5ae50e65d34f97824296e1318e6c5"
Via: SIP/2.0/UDP 10.110.32.90;branch=z9hG4bK8f05bf7ea2045cd93c5e35fde0ea3dd6
Content-Length: 0

-------------------------------------------------
1373288327 -> Mon Jul 8 18:58:47 2013 10 [CMessage::onSendSuccess]
Mon Jul 8 18:58:47 2013 210f [CTransaction::onSendSuccess] CMessage 10 send Success
Mon Jul 8 18:58:47 2013 1508 [CDialog::onSendSuccess]
Mon Jul 8 18:58:47 2013 [convertIPStrIntoTuipv] 10.180.140.2 => 10.180.140.2
Mon Jul 8 18:58:47 2013 [isIpAddressType] 10.180.140.2.
Mon Jul 8 18:58:47 2013 [isIpAddressType] YES.
Mon Jul 8 18:58:47 2013 210f [CTransInitialState::createRequest] Transaction changes to Trying state
Mon Jul 8 18:58:47 2013 210f [CTransaction::changeState] STATE CHANGED TO TRYING
Mon Jul 8 18:58:47 2013 210f [CTransaction::changeState] notifying the parent dialog
Mon Jul 8 18:58:47 2013 1508 [CDialog::onTransactionState(pTrans = 210f, previousState = Initial, currentState = Trying, reason = R
equest creation]
Mon Jul 8 18:58:47 2013 210f [CTransTryingState::enterInState] Init timers E and F
Mon Jul 8 18:58:47 2013 210f [CTransaction::startTimer] Timer E is started (delay = 500 ms)
Mon Jul 8 18:58:47 2013 210f [CTransaction::startTimer] Timer F is started (delay = 16000 ms)
Mon Jul 8 18:58:47 2013 1508 [CDialog::addTransaction] added transaction 210f with branch z9hG4bK8f05bf7ea2045cd93c5e35fde0ea3dd6,
with CSeq 953736920
Mon Jul 8 18:58:47 2013 1113[CMotorCall::emitAuthorizedRequest] ForkedBranch updated.
Mon Jul 8 18:58:47 2013 [CForkedBranch::CForkedBranch] context = 0x84e89dc.
Mon Jul 8 18:58:47 2013 1113[CMotorCall::branchAllocation] pForkedBranch = 0x84e82f0 and the branch number is.
Mon Jul 8 18:58:47 2013 RECEIVE MESSAGE FROM NETWORK (10.180.140.2:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK
To: <sip:550389@10.180.140.2>;tag=SDshab899-70g6xovjte
From: sip:550389@10.180.140.2;tag=f0155f89b95bf47da897b8918d6eaf95
Call-ID: 1a0692390be7787c61a3703c6b623889@10.110.32.90
CSeq: 953736920 REGISTER
Via: SIP/2.0/UDP 10.110.32.90:5060;branch=z9hG4bK8f05bf7ea2045cd93c5e35fde0ea3dd6
Contact: <sip:550389@10.110.32.90:5060;transport=UDP>;expires=600
Content-Length: 0

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

1373288327 -> Mon Jul 8 18:58:47 2013 [CIOCom::receiveResponse] CResponse 10 ref=1
Mon Jul 8 18:58:47 2013 [CDispatcher::processResponseFromManagerIndex] start at index 0
Mon Jul 8 18:58:47 2013 104 [CCallManager::onIncomingResponse]
Mon Jul 8 18:58:47 2013 1115 [CCall::receiveResponse] 200
Mon Jul 8 18:58:47 2013 1115 [CCall::getDialog] Confirmed Dialog is not found (ID = f0155f89b95bf47da897b8918d6eaf95;SDshab899-70g6
xovjte)
Mon Jul 8 18:58:47 2013 1115 [CCall::getDialog] Initial Dialog Client not found
Mon Jul 8 18:58:47 2013 1115 [CRegistrarCall::onReceiveOutOfContextResponse] 200
Mon Jul 8 18:58:47 2013 1115 [CCall::onDialogClosed]
Mon Jul 8 18:58:47 2013 1115 [CCall::onDialogClosed] only initial *********
Mon Jul 8 18:58:47 2013 1115 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
Mon Jul 8 18:58:47 2013 1115 [CCall] closed
Mon Jul 8 18:58:47 2013 104 [CCallManager::onCallClosed]
Mon Jul 8 18:58:47 2013 104 [CCallManager::onCallClosed()] Call 1115 closed. Removing call 139351480 from the maps
Mon Jul 8 18:58:47 2013 101 [CCallManager::onIncomingResponse]
Mon Jul 8 18:58:47 2013 1113 [CCall::receiveResponse] 200
Mon Jul 8 18:58:47 2013 1113 [CCall::getDialog] Confirmed Dialog is not found (ID = f0155f89b95bf47da897b8918d6eaf95;SDshab899-70g6
xovjte)
Mon Jul 8 18:58:47 2013 1113 [CCall::getDialog] Returning Initial Dialog Client
Mon Jul 8 18:58:47 2013 1508 [CDialog::receiveResponse]
Mon Jul 8 18:58:47 2013 210f [CTransTryingState::receiveResponse] Final : Transaction changes to Completed state
1373288327 -> Mon Jul 8 18:58:47 2013 210f [CTransaction::changeState] STATE CHANGED TO COMPLETED
Mon Jul 8 18:58:47 2013 210f [CTransaction::changeState] notifying the parent dialog
Mon Jul 8 18:58:47 2013 1508 [CDialog::onTransactionState(pTrans = 210f, previousState = Trying, currentState = Completed, reason =
Final resp reception]
Mon Jul 8 18:58:47 2013 210f [CTransaction::freeTimerToken] Timer E is freed
Mon Jul 8 18:58:47 2013 210f [CTransaction::freeTimerToken] Timer F is freed
Mon Jul 8 18:58:47 2013 210f [CTransaction::startTimer] Timer K is started (delay = 5000 ms)
Mon Jul 8 18:58:47 2013 1113[CMotorCall::onReceiveResponse] Update2 the Other context.
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] Call: 1a0692390be7787c61a3703c6b623889@10.110.32.90 eqt: -1 REGISTER_STATE recei
ved a message.
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] gw 10.
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] getExpires =0.
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] Servcie Count 0
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] Clearing index for already allocated gateway
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] contact getExpires =600.
Mon Jul 8 18:58:47 2013 [buidRegisterContact] Contact: sip:550389@10.110.32.90
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] nego =600.
Mon Jul 8 18:58:47 2013 [CMotorRegisterTimerContext::start] delay =480000.
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] Call: 1a0692390be7787c61a3703c6b623889@10.110.32.90 eqt: -1 REGISTER_STATE timer
=480000.
1373288331 -> Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] Gw=10 Supervision timer = 600
Mon Jul 8 18:58:47 2013 [receiveSuccessfulMessage] Supervision timer managed
Mon Jul 8 18:58:47 2013 [sip_motor_put_gateway_in_service] trunk 102 gw 10.
Mon Jul 8 18:58:47 2013 [sip_motor_start gateway] gwNb:10 sip_extgw[gwNb]:1401303316 sip_extgw[gwNb]->trunk_state:1
Mon Jul 8 18:58:47 2013 [sip_motor_put_gateway_in_service] external gateway 10 in service.
Mon Jul 8 18:58:50 2013 104 [CCallManager::removeGarbage] at index i = 1
Mon Jul 8 18:58:50 2013 1115 [CCall::~CCall]
Mon Jul 8 18:58:50 2013 1115 [CCall::removeGarbage]
Mon Jul 8 18:58:50 2013 104 [CCallManager::removeGarbage] at index i = 0
Mon Jul 8 18:58:50 2013 1114 [CCall::~CCall]
Mon Jul 8 18:58:50 2013 1114 [CCall::removeGarbage]

1373288333 -> Mon Jul 8 18:58:52 2013 210e [CTransCompletedState::timerFires] Timer K fires
Mon Jul 8 18:58:52 2013 210e [CTransCompletedState::timerFires] Timer K : Transaction changes to Terminated state
Mon Jul 8 18:58:52 2013 210e [CTransaction::changeState] STATE CHANGED TO TERMINATED
Mon Jul 8 18:58:52 2013 210e [CTransaction::changeState] no transaction state, terminated reason : Timer K fires
Mon Jul 8 18:58:52 2013 210f [CTransCompletedState::timerFires] Timer K fires
Mon Jul 8 18:58:52 2013 210f [CTransCompletedState::timerFires] Timer K : Transaction changes to Terminated state
Mon Jul 8 18:58:52 2013 210f [CTransaction::changeState] STATE CHANGED TO TERMINATED
Mon Jul 8 18:58:52 2013 210f [CTransaction::changeState] no transaction state, terminated reason : Timer K fires
Mon Jul 8 18:58:53 2013 [CTransaction::notifyDialog]
Mon Jul 8 18:58:53 2013 [CTransaction::notifyDialog] notify parent dialog
Mon Jul 8 18:58:53 2013 1508 [CDialog::onTransactionState(pTrans = 210e, previousState = null, currentState = Terminated, reason =
Timer K fires]
Mon Jul 8 18:58:53 2013 210E [~CTransaction] transaction 953736919 REGISTER
Mon Jul 8 18:58:53 2013 F [~CResponse] resp(401) 1a0692390be7787c61a3703c6b623889@10.110.32.90 953736919 REGISTER
Mon Jul 8 18:58:53 2013 F [~CRequest] req 1a0692390be7787c61a3703c6b623889@10.110.32.90 953736919 REGISTER
Mon Jul 8 18:58:53 2013 [CTransaction::notifyDialog]
Mon Jul 8 18:58:53 2013 [CTransaction::notifyDialog] notify parent dialog
Mon Jul 8 18:58:53 2013 1508 [CDialog::onTransactionState(pTrans = 210f, previousState = null, currentState = Terminated, reason =
Timer K fires]
1373288333 -> Mon Jul 8 18:58:53 2013 1508 [CDialog::onTransactionState] dialog is terminated
Mon Jul 8 18:58:53 2013 1508 [CDialog::onTransactionState] onDialogClosed
Mon Jul 8 18:58:53 2013 1113 [CCall::onDialogClosed]
Mon Jul 8 18:58:53 2013 1113 [CCall::onDialogClosed] only initial *********
Mon Jul 8 18:58:53 2013 1113 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
Mon Jul 8 18:58:53 2013 1113 [CCall] closed
Mon Jul 8 18:58:53 2013 101 [CCallManager::onCallClosed]
Mon Jul 8 18:58:53 2013 101 [CCallManager::onCallClosed()] Call 1113 closed. Removing call 139395864 from the maps
Mon Jul 8 18:58:53 2013 [CMotorCallManager::onNotifyCallClosed] Call : 1a0692390be7787c61a3703c6b623889@10.110.32.90 is closed.
Mon Jul 8 18:58:53 2013 [CMotorCallManager::onNotifyCallClosed] Call : 1a0692390be7787c61a3703c6b623889@10.110.32.90 is closed.
Mon Jul 8 18:58:53 2013 [CMotorCallManager::onNotifyCallClosed] call processResponse.
Mon Jul 8 18:58:53 2013 1113[CMotorCall::chooseBestResponse] pForkedBranch=0x84e82f0 iResponseCode=2000.
Mon Jul 8 18:58:53 2013 1113[CMotorCall::chooseBestResponse] next branch.
Mon Jul 8 18:58:53 2013 1113[CMotorCall::processResponse] no response found.
Mon Jul 8 18:58:53 2013 [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
Mon Jul 8 18:58:53 2013 210F [~CTransaction] transaction 953736920 REGISTER
Mon Jul 8 18:58:53 2013 10 [~CResponse] resp(200) 1a0692390be7787c61a3703c6b623889@10.110.32.90 953736920 REGISTER
Mon Jul 8 18:58:53 2013 101 [CCallManager::removeGarbage] at index i = 0
1373288337 -> Mon Jul 8 18:58:53 2013 1113 [CMotorCall::clear_subscription] no subscription managed
Mon Jul 8 18:58:53 2013 1113[CMotorCall::~CMotorCall] Call : 1a0692390be7787c61a3703c6b623889@10.110.32.90 is being destructed.
Mon Jul 8 18:58:53 2013 1113 [CCall::~CCall]
Mon Jul 8 18:58:53 2013 1113 [CCall::removeGarbage]
Mon Jul 8 18:58:53 2013 1508 [CDialog::~CDialog]
Mon Jul 8 18:58:53 2013 10 [~CRequest] req 1a0692390be7787c61a3703c6b623889@10.110.32.90 953736920 REGISTER

Перейти

SIP trunk group: неправильный АОН

Seller_V

User

Сообщений: 424 Баллов: 677 Регистрация: 02.12.2009

05.07.2013 18:36:13

Цитата
error пишет: нда уж, ваш пров в глаза "играется"

Ка знать, может быть, и нет. Ведь то, что я приве, это трасса со станции. Это Я вижу то, что отправляется к прову. Доходит это до того места, где ловит он - большой вопрос.

А убежать от прова нет возможности - это наша же контора, только иногородний филиал.

Мы обречены бороться вечно...

Перейти