Начинаю знакомство с софтом РТУ Город, возникла проблема с переадресацией.
Все входящие звонки на номер 7495xxx7427 поступают на абонента 00102, если, он не доступен, то вызов переадресовывается на 7903xxx5535. По факту, при вызове в трубке слышен один КПВ, на мобильный звонок "долетает", но обрывается. В трубке приятный голос сообщает, что "Соединение невозможно. Перезвоните позднее". Собственно сам лог звонка:
- Код: Выделить всё
10.10.2011 12:08:52.500664 0009 DBG Switch("Switch_1").EndPoint("h323"). Call created, call id=17799510f31711e0a44e001517adc1c6. Total call count 12.
10.10.2011 12:08:52.500718 0009 INF c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): *******************************************************
10.10.2011 12:08:52.500741 0009 INF c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): Incoming call src=74957484420 address=x.x.x.x dst=7495xxx7427
10.10.2011 12:08:52.500767 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): Tries to retrieve inbound terminal with params:
10.10.2011 12:08:52.500783 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): type=H323
10.10.2011 12:08:52.500815 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): source=74957484420
10.10.2011 12:08:52.500830 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): destination=7495xxx7427
10.10.2011 12:08:52.500845 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): addr=x.x.x.x
10.10.2011 12:08:52.500860 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): port=39027
10.10.2011 12:08:52.500878 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): registrationId=
10.10.2011 12:08:52.500892 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): externalRegistrationId=
10.10.2011 12:08:52.500906 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): zone=voip
10.10.2011 12:08:52.500921 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): CPC=0 Space=None
10.10.2011 12:08:52.500947 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): DomainId=ROOT
10.10.2011 12:08:52.500961 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): address=x.x.x.x
10.10.2011 12:08:52.500986 0009 DBG Registrar: FindStaticTerminal(): Entry guid=1061a712-9b66-4be2-8cc0-a132d6695d7d found in cache_address2entry_
10.10.2011 12:08:52.501006 0009 INF c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): Terminal type=Gateway, id=xxx
10.10.2011 12:08:52.591116 0009 DBG Switch("Switch_1"). Conference created, conference id=c865884983e16ef3477214cb98b893d9. Total conference count 12.
10.10.2011 12:08:52.591193 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): cpc_db_id=-1
10.10.2011 12:08:52.591343 0009 DBG c865884983e16ef3477214cb98b893d9 Conference.OnRegisterCall(): Incoming call registered (src="74957484420", dst="7495xxx7427")
10.10.2011 12:08:52.591431 0009 DBG c865884983e16ef3477214cb98b893d9 Call accepted.
10.10.2011 12:08:52.591457 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall: Terminal=xxx capacity: total_occ_all=0/infinity out_occ_all=0/infinity increment
10.10.2011 12:08:52.591484 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall(): protocolConfId=ed032da7f24d11e0a1ad00070e58957a
10.10.2011 12:08:52.598401 0009 INF c865884983e16ef3477214cb98b893d9 Conference.OnCapabilitiesCall() incoming >>> Caps (DB): (PCMA, SUPERCODEC.G.723.1, G.729), ()
10.10.2011 12:08:52.598422 0009 INF c865884983e16ef3477214cb98b893d9 Conference.OnCapabilitiesCall() incoming >>> Caps (device): (PCMA, PCMU, G.729B, G.729, G.723.1, G.723.1)
10.10.2011 12:08:52.598493 0009 INF c865884983e16ef3477214cb98b893d9 RoutingProcessorRule.GetRoute(): Rule description: incoming_call: Rule RegExp: True
10.10.2011 12:08:52.598523 0009 INF c865884983e16ef3477214cb98b893d9 DomainRoute: .ctor(): --->>> Follow to DOMAIN route src=74957484420, domain=Root domain, dst=7495xxx7427, domain=xxxx.ru
10.10.2011 12:08:52.598539 0009 INF RoutingProcessor: GetRules(3553376c-27e6-48c7-ae42-62ff08a0212a): Rules cache is not valid. Update from DB
10.10.2011 12:08:52.624943 0009 INF c865884983e16ef3477214cb98b893d9 RoutingProcessorRule.GetRoute(): Rule description: xxxx_incall: Rule RegExp: True
10.10.2011 12:08:52.624977 0009 INF c865884983e16ef3477214cb98b893d9 UserRoute initialize. Follow to USER route src=74957484420, dst=000102
10.10.2011 12:08:52.625017 0009 INF c865884983e16ef3477214cb98b893d9 Conference.OnCapabilitiesCall(): Caps1: (PCMA)
10.10.2011 12:08:52.625037 0009 INF c865884983e16ef3477214cb98b893d9 Conference: OnCapabilitiesCall(): incoming >>> Only one codec presents. Confirming caps=(PCMA)
10.10.2011 12:08:52.625098 0009 INF ForwardProcessor: .ctor(): No forward record specified for Unconditional
10.10.2011 12:08:52.625974 0009 INF ForwardProcessor: GetForwardTo(): Unreachable forward to 7903xxx5535
10.10.2011 12:08:52.626764 0009 INF ForwardProcessor: GetForwardTo(): Unreachable forward to 7903xxx5535
10.10.2011 12:08:52.626781 0009 INF c865884983e16ef3477214cb98b893d9 Conference: NotAvailableForward(): Making 'NOT AVAILABLE' forward: 74957484420 -> 7903xxx5535
10.10.2011 12:08:52.626907 0009 INF c865884983e16ef3477214cb98b893d9 RoutingProcessorRule.GetRoute(): Rule description: DialOut: Rule RegExp: True
10.10.2011 12:08:52.626934 0009 INF c865884983e16ef3477214cb98b893d9 GwRoute initialized. this.options.alias_group=none call.RemoteTerminal.Id=xxxx
10.10.2011 12:08:52.626951 0009 INF c865884983e16ef3477214cb98b893d9 GwRoute initialized. Follow to GATEWAY route src=000102, dst=7903xxx5535, gw=fbe08fda-029e-48e4-a069-944a6135fa57
10.10.2011 12:08:52.627051 0009 DBG c865884983e16ef3477214cb98b893d9 Conference("c865884983e16ef3477214cb98b893d9") ChangeDialRoute() Delete previous dial route "incall". New dial route "DialOut"
10.10.2011 12:08:52.627072 0009 INF c865884983e16ef3477214cb98b893d9 Conference.OnCapabilitiesCall(): Caps1: (PCMA)
10.10.2011 12:08:52.627087 0009 INF c865884983e16ef3477214cb98b893d9 Conference: OnCapabilitiesCall(): incoming >>> Only one codec presents. Confirming caps=(PCMA)
10.10.2011 12:08:52.627102 0009 INF c865884983e16ef3477214cb98b893d9 Conference: DialOutgoingTerminal(): Dial: 000102 -> XXX
10.10.2011 12:08:52.627146 0009 DBG Switch("Switch_1").EndPoint("h323"). Call created, call id=2ab525d30ca7b8bb4d2c24f4c2e893dc. Total call count 13.
10.10.2011 12:08:52.627183 0009 INF c865884983e16ef3477214cb98b893d9 RegisterOutgoingLeg(): Caps: (FAX.PCMA)
10.10.2011 12:08:52.627286 0009 DBG c865884983e16ef3477214cb98b893d9 ChangePrivacyInfo: Remote terminal type 'MeraSystems.VoIP.Phoenix.Gateway', source '000102', CallingDisplayName: ''
10.10.2011 12:08:52.627313 0009 DBG c865884983e16ef3477214cb98b893d9 ChangePrivacyInfo: Incoming PI 'Allowed'
10.10.2011 12:08:52.627351 0009 DBG c865884983e16ef3477214cb98b893d9 Conference: CreateOutgoingCall(): Call to static gateway(type=H323 addr=x.x.x.x:1720)
10.10.2011 12:08:52.646836 0009 INF c865884983e16ef3477214cb98b893d9 Conference <-- CtrlMsg.OnMediaStartCall (Incoming call id="17799510f31711e0a44e001517adc1c6", src=74957484420, dst=7495xxx7427, CapsStart=(PCMA))
10.10.2011 12:08:52.657981 0009 DBG c865884983e16ef3477214cb98b893d9 Conference.OnRegisterCall(): Outgoing call registered (src="000102", dst="7903xxx5535")
10.10.2011 12:08:52.658023 0009 DBG c865884983e16ef3477214cb98b893d9 Call: OnRegisterCall: Terminal=XXX capacity: total_occ_all=0/infinity in_occ_all=0/infinity increment
10.10.2011 12:08:52.699293 0009 INF c865884983e16ef3477214cb98b893d9 Conference <-- CtrlMsg.OnMediaStartCall (Outgoing call id="2ab525d30ca7b8bb4d2c24f4c2e893dc", src=000102, dst=7903xxx5535, CapsStart=(FAX.PCMA, RFC2833DTMF))
10.10.2011 12:08:52.699329 0009 DBG c865884983e16ef3477214cb98b893d9 MediaProcessor SetMediaMode(): capsStart1=(PCMA) capsStart2=(FAX.PCMA, RFC2833DTMF)!
10.10.2011 12:08:52.699352 0009 INF c865884983e16ef3477214cb98b893d9 MediaProcessor.SwitchTwoLegs() Switch media: Proxy and Proxy
10.10.2011 12:08:52.699377 0009 INF c865884983e16ef3477214cb98b893d9 MediaProcessor.SwitchTwoLegs() Stop playing prompts.
10.10.2011 12:08:52.699400 0009 DBG c865884983e16ef3477214cb98b893d9 MediaConference("4b7a5a563a3f26a8a46a2a57d0f893dd") add iCall("17799510f31711e0a44e001517adc1c6") (proxyDtmf=True, exclusion="*#!") Total call count 1
10.10.2011 12:08:52.699426 0009 DBG c865884983e16ef3477214cb98b893d9 MediaConference("4b7a5a563a3f26a8a46a2a57d0f893dd") add oCall("2ab525d30ca7b8bb4d2c24f4c2e893dc") (proxyDtmf=True, exclusion="*#!") Total call count 2
10.10.2011 12:08:52.699454 0009 DBG c865884983e16ef3477214cb98b893d9 MediaConference("4b7a5a563a3f26a8a46a2a57d0f893dd") DoRegister (isDirect=False). Call count 2
10.10.2011 12:08:52.699475 0009 INF c865884983e16ef3477214cb98b893d9 MediaProcessor.SwitchTwoLegs() Media conference registered (isDirect=False)
10.10.2011 12:08:59.973449 0009 INF c865884983e16ef3477214cb98b893d9 Conference.OnAlertingCall(): Outgoing Call src=000102 dst=7903xxx5535 alerted.
10.10.2011 12:08:59.973539 0009 DBG c865884983e16ef3477214cb98b893d9 MediaProcessor SetMediaMode(): capsStart1=(PCMA) capsStart2=(FAX.PCMA, RFC2833DTMF)!
10.10.2011 12:08:59.973566 0009 INF c865884983e16ef3477214cb98b893d9 MediaProcessor.SwitchTwoLegs() Switch media: Proxy and Proxy
10.10.2011 12:08:59.973599 0009 INF c865884983e16ef3477214cb98b893d9 MediaProcessor.SwitchTwoLegs() Stop playing prompts.
10.10.2011 12:08:59.980663 0009 INF c865884983e16ef3477214cb98b893d9 Conference.OnUnregisterCall() Outgoing src=000102 dst=7903xxx5535 universal_code=65544 call=2ab525d30ca7b8bb4d2c24f4c2e893dc
10.10.2011 12:08:59.980756 0009 DBG c865884983e16ef3477214cb98b893d9 Conference.RemoveCall() Calls in conference for monitor=2
10.10.2011 12:08:59.980778 0009 DBG c865884983e16ef3477214cb98b893d9 RemoveCall: CALL STATE = CLEARING
10.10.2011 12:08:59.980803 0009 DBG c865884983e16ef3477214cb98b893d9 oCall("2ab525d30ca7b8bb4d2c24f4c2e893dc") Unregistered. Src=000102, Dst=7903xxx5535.
10.10.2011 12:08:59.980827 0009 DBG oCall("2ab525d30ca7b8bb4d2c24f4c2e893dc") Switch("Switch_1").EndPoint("h323"). Call deleted, call id=2ab525d30ca7b8bb4d2c24f4c2e893dc. Total call count 12.
10.10.2011 12:08:59.980857 0009 INF c865884983e16ef3477214cb98b893d9 Conference: OnConnectedCall(): incoming >>> call=17799510f31711e0a44e001517adc1c6.
10.10.2011 12:09:04.008468 0009 INF c865884983e16ef3477214cb98b893d9 Conference.OnUnregisterCall() Incoming src=74957484420 dst=7495xxx7427 universal_code=65544 call=17799510f31711e0a44e001517adc1c6
10.10.2011 12:09:04.008529 0009 DBG c865884983e16ef3477214cb98b893d9 Conference.RemoveCall() Calls in conference for monitor=1
10.10.2011 12:09:04.008548 0009 DBG c865884983e16ef3477214cb98b893d9 Conference.RemoveCall() Remove conference from monitor. Id=c865884983e16ef3477214cb98b893d9
10.10.2011 12:09:04.008571 0009 DBG c865884983e16ef3477214cb98b893d9 Conference.RemoveCall() Conference is removed from monitor. Id=c865884983e16ef3477214cb98b893d9
10.10.2011 12:09:04.008589 0009 DBG c865884983e16ef3477214cb98b893d9 RemoveCall: CALL STATE = WAITING
10.10.2011 12:09:04.008628 0009 DBG c865884983e16ef3477214cb98b893d9 iCall("17799510f31711e0a44e001517adc1c6") Unregistered. Src=74957484420, Dst=7495xxx7427.
10.10.2011 12:09:04.008655 0009 DBG oCall("17799510f31711e0a44e001517adc1c6") Switch("Switch_1").EndPoint("h323"). Call deleted, call id=17799510f31711e0a44e001517adc1c6. Total call count 11.
10.10.2011 12:09:04.008685 0009 DBG c865884983e16ef3477214cb98b893d9 MediaConference("4b7a5a563a3f26a8a46a2a57d0f893dd") DoUnregister
В cdr отмечается дисконнект: TS, 8 - [H.323] Procedure failure .
Собственно непонятно кто же отрубвает звонок, провайдер или РТУ? И конечно, непонятна причина.
