ARS with SIP Trunk Overflow

Post by **cavagnaro** » 28 Jan 2011 18:35

Hi guys,
I'm setting up a SIP Trunk on my OXE, now I'd like that if the 2nd access I have on the SIP Gateway are busy (GSM GATEWAY) OXE goes to my ISDN PRI channel or my analog trunks. I have created my ARS and it does work dialing using the SIP EXT GW but on 3rd call I only get a busy signal on OXE and SIP messages say BUSY but does not overflow, is this possible to do? Any special configuration?
Thanks!

006-02-06-00-00) time(1296289809) ****
932 <SIP 37> : Receive INVITE Request
933 <NetCon 37> : Found inbound voip peer(0) result(2) peer->fixedPatter nSize(0) mostMatchingSize(-1)
934 <NetCon 37> : Found inbound voip peer by dest-pattern id(0)
935 <Call 37> : From Net - calledParty(956757712) callingParty(5076)
936 <Call 37> : MatchedAll
937 <Call 37> : MatchAllProcess After Sorted
<0> id(50) dest(T) prefer(0) selected(25)
<1> id(51) dest(T) prefer(0) selected(2)
938 <Call 37> : Initiate callee with dial-peer(T) status(CalleeDetermi nedAll) id(00000000-0000-0000-0000-000000000000)
939 <Call 37> : Fail by Channel Busy
940 <Call 37> : Initiate callee with dial-peer(T) status(CalleeDetermi nedAll) id(00000000-0000-0000-0000-000000000000)
941 <Call 37> : Fail by Channel Busy
942 <Call 37> : Terminated from(fffffff7) this(Local:PortBusy) before( Local:PortBusy) forced(0) time(1296289809)
943 <NetEP 37> : Call FROM <Espinoza Cristia> terminated reason(Local:P ortBusy)
944 <SIP 37> : Receive ACK Request
945 <SIP 37> : Set Terminated Success for 425256168 INVITE
946 <Call 38> : ****** Call Created status(InitiatedByNet) ver(8.28:2 006-02-06-00-00) time(1296289834) ****¨

1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest] *pcszNameToFind : 956757
712
1296257639 -> [isUserDeclared] 956757712@192.168.100.61
1296257639 -> isUserDeclared] NOT found in the dictionnary.
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest]-->extGw=11
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest] nb available licenses=99
999 .
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest] req_Uri_OK=0
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest]-->pSipAddSpec->getHost()
=192.168.100.61
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest] user= 956757712
1296257639 -> [CGWMngData::isMainServer] CPU is MAIN.
1296257639 -> [CGWMngData::isMainServer] checkwith PCS = 0
1296257639 -> 11a9 [CCall::makeGenericRequest] INVITE
1296257639 -> 11a9 [CCall::getDialog] Confirmed Dialog is not found (ID =
1296257639 -> 11a9 [CCall::getDialog] Initial Dialog Client not found
1296257639 -> 151e [CDialog::createRequest]
1296257639 -> 151e [CDialog::buildServicesForAllRequest]
1296257639 -> 151e [CDialog::createInviteRequest]
1296257639 -> 210f [CTransaction::changeState] STATE CHANGED TO INITIAL
1296257639 -> 210f [CTransaction::changeState] notifying the parent dialog
1296257639 -> 151e [CDialog::onTransactionState(pTrans = 210f, previousState = T
erminated, currentState = Initial, reason = None]
1296257639 -> 118F [CRequest::CRequest] Creation of a request
1296257639 -> 118F [CMessage::send] sip_sendMessage (192.168.100.61:5060)
1296257639 -> SEND MESSAGE TO NETWORK (192.168.100.61:5060 [UDP]) (BUFF LEN = 11
55)
----------------------utf8-----------------------
INVITE sip:956757712@192.168.100.61;user=phone SIP/2.0
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDAT
E, INFO
Supported: replaces,timer,100rel
User-Agent: OmniPCX Enterprise R9.1 i1.605.16
Session-Expires: 1800;refresher=uac
Min-SE: 900
P-Asserted-Identity: "Espinoza Cristia" <sip:5076@192.168.50.4;user=phone>
Content-Type: application/sdp
To: <sip:956757712@192.168.100.61;user=phone>
From: "Espinoza Cristia" <sip:5076@192.168.50.4>;tag=8e91f9b289c2e3dc62dee0c70c2
04edb
Contact: <sip:5076@192.168.50.4;transport=UDP>
Call-ID: 6471e00175a2a81d01f2e2d44d51d118@192.168.50.4
CSeq: 1351003573 INVITE
Via: SIP/2.0/UDP 192.168.50.4;branch=z9hG4bK048134eb6ad4d80e346fed43516530f4
Max-Forwards: 70
Content-Length: 390

v=0
o=OXE 1296257639 1296257639 IN IP4 192.168.50.4
s=abs
c=IN IP4 192.168.50.7
t=0 0
m=audio 32696 RTP/AVP 8 0 18 4 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20
a=maxptime:40
a=rtpmap:4 G723/8000
a=ptime:30
a=maxptime:30
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1296257639 -> 210f [CTransInitialState::createRequest] Transaction changes to Ca
lling state
1296257639 -> 210f [CTransaction::changeState] STATE CHANGED TO CALLING
1296257639 -> 210f [CTransaction::changeState] notifying the parent dialog
1296257639 -> 151e [CDialog::onTransactionState(pTrans = 210f, previousState = I
nitial, currentState = Calling, reason = Request creation]
1296257639 -> 210f [CTransaction::startTimer] Timer A is started (delay = 500 ms
)
1296257639 -> 210f [CTransaction::startTimer] Timer B is started (delay = 8000 m
s)
1296257639 -> 151e [CDialog::addTransaction] added transaction 210f with branch
z9hG4bK048134eb6ad4d80e346fed43516530f4, with CSeq 1351003573
1296257639 -> [CSessionTimerContext::CSessionTimerContext] New CSessionTimerCont
ext from request (Client, UA)
1296257639 -> [CSessionTimerContext::updateAfterRefreshCreation] Update CSession
TimerContext (refresh creation)
1296257639 -> [CSessionTimerContext::updateSupported] Request timer supported
1296257639 -> [CSessionTimerContext::updateMinSE] Min-SE updated : 900
1296257639 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1296257639 -> [CSessionTimerContext::updateSessionExpires] updated
1296257639 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1296257639 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updat
ed : 1800
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest] nb contact=1.
1296257639 -> [CForkedBranch::CForkedBranch] context = 0x402247d0.
1296257639 -> 11a9[CMotorCall::branchAllocation] built branch number=1.
1296257639 -> 11a9[CMotorCall::branchAllocation] pForkedBranch = 0x40220790.
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest] reqUri duplication pFor
kedBranch = 0x40220790.
1296257639 -> 11a9[CMotorCall::makeInitialMotorRequest] reqUri duplication2.
1296257639 -> [CForkedBranch::reqUriDuplication] reqUri :sip:956757712@1
92.168.100.61;user=phone
1296257639 -> [onIncomingEvent] CallID must be inserted.
1296257639 -> [onIncomingEvent] CallID inserted.
1296257639 -> [exec_ipc] in end 21257.
1296257639 -> RECEIVE MESSAGE FROM NETWORK (192.168.100.61:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.50.4;branch=z9hG4bK048134eb6ad4d80e346fed43516530f4
From: "Espinoza Cristia" <sip:5076@192.168.50.4>;tag=8e91f9b289c2e3dc62dee0c70c2
04edb
To: <sip:956757712@192.168.100.61;user=phone>
Call-ID: 6471e00175a2a81d01f2e2d44d51d118@192.168.50.4
CSeq: 1351003573 INVITE
User-Agent: AddPac SIP Gateway
Content-Length: 0

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

1296257639 -> [CIOCom::receiveResponse] CResponse 1404 ref=1
1296257639 -> [CDispatcher::onIncomingResponse]
1296257639 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1296257639 -> 102 [CCallManager::onIncomingResponse]
1296257639 -> 11a9 [CCall::receiveResponse] 100
1296257639 -> 11a9 [CCall::receiveResponse]Trying to find the right dialog
1296257639 -> 11a9 [CCall::getDialog] Confirmed Dialog is not found (ID = 8e91f9
b289c2e3dc62dee0c70c204edb;)
1296257639 -> 11a9 [CCall::getDialog] Returning Initial Dialog Client
1296257639 -> 11a9 [CCall::receiveResponse]One dialog found
1296257639 -> 151e [CDialog::receiveResponse]
1296257639 -> 210f [CTransCallingState::receiveResponse] Provisional : Transacti
on changes to Proceeding state
1296257639 -> 210f [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1296257639 -> 210f [CTransaction::changeState] notifying the parent dialog
1296257639 -> 151e [CDialog::onTransactionState(pTrans = 210f, previousState = C
alling, currentState = Proceeding, reason = 1xx response reception]
1296257639 -> 210f [CTransaction::freeTimerToken] Timer A is freed
1296257639 -> 210f [CTransaction::freeTimerToken] Timer B is freed
1296257639 -> 11a9[CMotorCall::searchForkedBranch]pForkedBranch=0x40220790.
1296257639 -> [receiveInformationalMessage] Call: 6471e00175a2a81d01f2e2d44d51d1
18@192.168.50.4 eqt: 1574 PROCEEDING_STATE received a message.
1296257639 -> 104 [CCallManager::removeGarbage] at index i = 0
1296257639 -> 11a7 [CCall::~CCall]
1296257639 -> 11a7 [CCall::removeGarbage]
1296257639 -> 151b [CDialog::~CDialog]
1296257639 -> 13FF [~CResponse] resp(200) 521144115@teamwork.ebdperu.com 1 REGIS
TER
1296257639 -> 118B [~CRequest] req 521144115@teamwork.ebdperu.com 1 REGISTER
1296257639 -> RECEIVE MESSAGE FROM NETWORK (192.168.100.61:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 486 Busy Here
Via: SIP/2.0/UDP 192.168.50.4;branch=z9hG4bK048134eb6ad4d80e346fed43516530f4
From: "Espinoza Cristia" <sip:5076@192.168.50.4>;tag=8e91f9b289c2e3dc62dee0c70c2
04edb
To: <sip:956757712@192.168.100.61;user=phone>;tag=f14d8528a4
Call-ID: 6471e00175a2a81d01f2e2d44d51d118@192.168.50.4
CSeq: 1351003573 INVITE
User-Agent: AddPac SIP Gateway
Content-Length: 0

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

1296257639 -> [CIOCom::receiveResponse] CResponse 1405 ref=1
1296257639 -> [CDispatcher::onIncomingResponse]
1296257639 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1296257639 -> 102 [CCallManager::onIncomingResponse]
1296257639 -> 11a9 [CCall::receiveResponse] 486
1296257639 -> 11a9 [CCall::receiveResponse]Trying to find the right dialog
1296257639 -> 11a9 [CCall::getDialog] Confirmed Dialog is not found (ID = 8e91f9
b289c2e3dc62dee0c70c204edb;f14d8528a4)
1296257639 -> 11a9 [CCall::getDialog] Returning Initial Dialog Client
1296257639 -> 11a9 [CCall::receiveResponse]One dialog found
1296257639 -> 151e [CDialog::receiveResponse]
1296257639 -> 1404 [~CResponse] resp(100) 6471e00175a2a81d01f2e2d44d51d118@192.1
68.50.4 1351003573 INVITE
1296257639 -> 210f [CTransProceedingState::receiveResponse] Final : Transaction
changes to Completed state
1296257639 -> 210f [CTransaction::changeState] STATE CHANGED TO COMPLETED
1296257639 -> 210f [CTransaction::changeState] notifying the parent dialog
1296257639 -> 151e [CDialog::onTransactionState(pTrans = 210f, previousState = P
roceeding, currentState = Completed, reason = Final resp reception]
1296257639 -> 1190 [CRequest::CRequest] Creation of a request
1296257639 -> 1190 [CMessage::send] sip_sendMessage (192.168.100.61:5060)
1296257639 -> SEND MESSAGE TO NETWORK (192.168.100.61:5060 [UDP]) (BUFF LEN = 37
9)
----------------------utf8-----------------------
ACK sip:956757712@192.168.100.61;user=phone SIP/2.0
Call-ID: 6471e00175a2a81d01f2e2d44d51d118@192.168.50.4
From: "Espinoza Cristia" <sip:5076@192.168.50.4>;tag=8e91f9b289c2e3dc62dee0c70c2
04edb
To: <sip:956757712@192.168.100.61;user=phone>;tag=f14d8528a4
Via: SIP/2.0/UDP 192.168.50.4;branch=z9hG4bK048134eb6ad4d80e346fed43516530f4
CSeq: 1351003573 ACK
Content-Length: 0

-------------------------------------------------
1296257639 -> 210f [CTransaction::startTimer] Timer D is started (delay = 32077
ms)
1296257639 -> 11a9[CMotorCall::searchForkedBranch]pForkedBranch=0x40220790.
1296257639 -> [CForkedBranch::setFinalResponse] pResponse=0x4021a700.
1296257639 -> [CForkedBranch::setFinalResponse] response=486.
1296257639 -> 11a9[CMotorCall::responseToInviteOrCancel] no response to the CH m
_cancelSent=0.
1296257639 -> 11a9[CMotorCall::responseToInviteOrCancel] all responses are recei
ved 1.
1296257639 -> 11a9[CMotorCall::responseToInviteOrCancel] call chooseBestResponse
.
1296257639 -> 11a9[CMotorCall::chooseBestResponse] pForkedBranch=0x40220790 iRes
ponseCode=2000.
1296257639 -> [CForkedBranch::getResponseCode] response=486.
1296257639 -> [CForkedBranch::getResponseCode] response=486.
1296257639 -> 11a9[CMotorCall::chooseBestResponse] last iResponseCode=486.
1296257639 -> [CForkedBranch::setFinalResponse] pResponse=0x4021a700.
1296257639 -> 11a9[CMotorCall::chooseBestResponse] next branch.
1296257639 -> 11a9[CMotorCall::responseToInviteOrCancel] inviteResponse => updat
e the lastInviteContext.
1296257639 -> [receiveRequestFailureMessage] Call: 6471e00175a2a81d01f2e2d44d51d
118@192.168.50.4 eqt: 1574 PROCEEDING_STATE received a message.
1296257639 -> 11a9[emitEventToMonitel] content size (lginfo): 656
1296257639 -> 11a9[emitEventToMonitel] CAC = 0 eqtCAC = -1
1296257639 -> 11a9[emitEventToMonitel] CAC = 0
1296257639 -> [ipc_thread] IPC Thread : Ipc reception 21258.
1296257639 -> [ipc_thread] IPC Thread : TCL thread signaled.
1296257639 -> [sendLgEvtSip] Event sent on eqt : 1574 Id :-1
1296257639 -> [display_ipc_out] ------------ Begin ---------------
1296257639 -> Id : -1
1296257639 -> REQUEST FAILURE
1296257639 -> xx : 86
1296257639 -> RELATIVE REQUEST : INVITE
1296257639 -> [display_ipc_out] ------------- End ----------------
1296257639 -> 11a9[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancel
ableForkedBranch.
1296257639 -> 11a9[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
1296257639 -> [CForkedBranch::getResponseCode] response=486.
1296257639 -> [CForkedBranch::getResponseCode] response=486.
1296257639 -> [exec_ipc] in 21257.
1296257639 -> [display_ipc_in] ------------ Begin ---------------
1296257639 -> neqt : 1574 Id : -1
1296257639 -> SIP EQT RELEASED
1296257639 -> [display_ipc_in] ------------- End ----------------
1296257639 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the
eqt 1574--1.
1296257639 -> [CMotorCallManager::onIncomingEvent] The call with eqt: 1574 has r
eleased its equipment.
1296257639 -> 11a9[CMotorCall::emitSipEqtReleaseAck] SIP_EQT_RELEASE_ACK
1296257639 -> 11a9[emitEventToMonitel] content size (lginfo): 656
1296257639 -> 11a9[emitEventToMonitel] CAC = 0 eqtCAC = -1
1296257639 -> 11a9[emitEventToMonitel] CAC = 0
1296257639 -> [sendLgEvtSip] Event sent on eqt : 1574 Id :-1
1296257639 -> [display_ipc_out] ------------ Begin ---------------
1296257639 -> Id : -1
1296257639 -> UNKNOWN MESSAGE
1296257639 -> [display_ipc_out] ------------- End ----------------
1296257639 -> 11a9[CMotorCall::unRegister] Remove eqt : 1574 diag : -1 from the
map.
1296257639 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 1574 erased.
1296257639 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1296257639 -> [CMotorCallManager::onIncomingEvent] killSession.
1296257639 -> 11a9 [CCall::killSession]
1296257639 -> 210f [CTransaction::changeState] STATE CHANGED TO TERMINATED
1296257639 -> 210f [CTransaction::freeTimerToken] Timer D is freed
1296257639 -> 210f [CTransaction::changeState] no transaction state, terminated
reason : None
1296257639 -> 11a9 [CCall::onKill Session Call manager close is called]
1296257639 -> 11a9 [CCall::killSession] notifying the call manager
1296257639 -> 102 [CCallManager::onCallClosed]
1296257639 -> 102 [CCallManager::onCallClosed()] Call 11a9 closed. Removing cal
l 1075987360 from the maps
1296257639 -> [CMotorCallManager::onNotifyCallClosed] Call : 6471e00175a2a81d01f
2e2d44d51d118@192.168.50.4 is closed.
1296257639 -> [CMotorCallManager::onNotifyCallClosed] Call : 6471e00175a2a81d01f
2e2d44d51d118@192.168.50.4 is closed.
1296257639 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1296257639 -> [CCall::onKill] set callmanager to null
1296257639 -> [exec_ipc] in end 21257.
1296257639 -> [CTransaction::notifyDialog]
1296257639 -> [CTransaction::notifyDialog] notify parent dialog
1296257639 -> 151e [CDialog::onTransactionState(pTrans = 210f, previousState = n
ull, currentState = Terminated, reason = None]
1296257639 -> 151e [CDialog::onTransactionState] dialog is terminated
1296257639 -> 151e [CDialog::onTransactionState] onDialogClosed
1296257639 -> 11a9 [CCall::onDialogClosed]
1296257639 -> 11a9 [CCall::onDialogClosed] only initial *********
1296257639 -> 11a9 [CCall::onDialogClosed] All the initial dialogs in the Call a
re closed
1296257639 -> 210F [~CTransaction] transaction 1351003573 INVITE
1296257639 -> 1190 [~CRequest] req 6471e00175a2a81d01f2e2d44d51d118@192.168.50.4
1351003573 ACK
1296257642 -> 102 [CCallManager::removeGarbage] at index i = 0
1296257642 -> 11a9[CMotorCall::~CMotorCall] Call : 6471e00175a2a81d01f2e2d44d51d
118@192.168.50.4 is being destructed.
1296257642 -> 1405 [~CResponse] resp(486) 6471e00175a2a81d01f2e2d44d51d118@192.1
68.50.4 1351003573 INVITE
1296257642 -> 11a9 [CCall::~CCall]
1296257642 -> 11a9 [CCall::removeGarbage]
1296257642 -> 151e [CDialog::~CDialog]
1296257642 -> [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerCo
ntext
1296257642 -> 118F [~CRequest] req 6471e00175a2a81d01f2e2d44d51d118@192.168.50.4
1351003573 INVITE

Thanks!

alex · Post by **alex** » 29 Jan 2011 12:51

Cav, try

Code: Select all

actdbg all=off
actdbg ars=on
mtracer

and check differencies between 2nd and 3rd call.

Post by **cavagnaro** » 29 Jan 2011 20:21

Great, will do! Thanks

Post by **cavagnaro** » 31 Jan 2011 20:00

what am I supposed to see on screen?? I see nothing... :S

alex · Post by **alex** » 03 Feb 2011 06:27

May be mtracer is running on other device. Start trace with "tuner km" command.

luis.molinas · Post by **luis.molinas** » 14 Dec 2011 19:09

Hi Cav,

You could solve this problem?

Regards

Post by **cavagnaro** » 14 Dec 2011 22:28

Actually is a pending task...got extremely busy so no time for labs

luis.molinas · Post by **luis.molinas** » 15 Dec 2011 06:31

ok! I am in the same situation as your.

I'll be seeing how to solve this.

Post by **cavagnaro** » 15 Dec 2011 12:24

if you have the answer please share

root · Post by **root** » 22 Dec 2011 19:33

try this dirty workarund....

SIP / SIPResponsetoCHmapping

translate SIP Busy Response (486) to CH Cause 41 (Temp Failure) then ARS should do the wanted overflow,
but keep in mind that it is a system wide change!

The root issue is the bad response of you GSM gateway not the OXE behavior.

Alcatel Unleashed

ARS with SIP Trunk Overflow

ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow

Re: ARS with SIP Trunk Overflow