Hi Mohammad,
Please refer the details belw
Problem Description:
Calls from Branch office Analog phones to Huawei IP Phones in KL Office are failing intermittently. Users get busy tone when they try to dial IP Phone in KL Office. Issue occurs consistently with concurrent calls.
Call Flow
Analog Phone ----- Avaya PABX ------- Voice GW----------CUCM ------- SIP Trunk ---------- Huawei PBX ---------IP Phone
We collected the debugs on the GW and traces on CUCM and found below.
GW : Debugs:
Feb 12 06:38:33.858: vsacount in free is 6
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/CCAPI/ccCallDisconnect:
Cause Value=47, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/CCAPI/ccCallDisconnect:
Cause Value=47, Call Entry(Responsed=TRUE, Cause Value=47)
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/vtsp_process_event:
[state:S_PROCEEDING, event:E_CC_DISCONNECT]
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/act_pre_con_disconnect:
Cause Value=47, Hairpin=FALSE
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/vtsp_timer_stop:
Timer Stop Time=-1271588220
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/vtsp_ring_noan_timer_stop:
Timer Stop Time=-1271588220
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/act_pre_con_disconnect:
[Fast Busy]-Tone Played In Direction [Network]
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/vtsp_timer_stop:
Timer Stop Time=-1271588220
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/CCAPI/cc_api_call_disc_cause_update:
Cause Value=16
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/CCAPI/cc_api_call_disc_cause_update:
Call Entry(Disconnect Cause=16)
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/vtsp_play_busy_timer_start:
Timer Start Time=-1271588220, Busy Timer Value=30(ms)
Feb 12 06:38:33.858: //3293903/1C2EA8B29B62/VTSP:(0/0/0:1):-1:1:4/act_pre_con_disconnect:
Tone=Fast Busy, Tone Timeout=30(s)
01740516.000 |14:30:09.485 |SdlSig |MediaDisconnectRequest |wait |ConnectionManager(1,100,219,1) |MatrixControl(1,100,146,467737) |1,100,14,3151800.3^10.89.28.70^* |[R:N-H:0,N:3,L:0,V:0,Z:0,D:0] CI1=26983686 CI2=26983687 sc=0 disconnType=1 ssReason=0 clearType=0 IF1Created=F IF2Created=F party1.mMediaCoordinatorNodeId=0 party2.mMediaCoordinatorNodeId=0 party1.dtmCall= dtm.mode=0 dtm.CI=0 dtm.MTPForDTMF=F party2.dtmCall= dtm.mode=0 dtm.CI=0 dtm.MTPForDTMF=F reconnectPending=F forceStopCI=0
01740516.001 |14:30:09.485 |AppInfo |!!ERROR!! -ConnectionManager- wait_MediaDisconnectRequest :CI NOT FOUND IN TABLE,CI(26983686,26983687),discType=1,IFCreated(0,0),PID(0-0,0-0),IFHandling(0,0),MCNode(0,0)
01740517.000 |14:30:09.486 |SdlSig |CcDisconnReq |restart0 |H225D(1,100,199,31) |Cdcc(1,100,224,463213) |1,100,14,3151800.3^10.89.28.70^* |[R:N-H:0,N:3,L:0,V:0,Z:0,D:0] CI=26983686 CI.branch=0 clearType=0 c.l=1 c.cid=8 c.cs=0 c.lc=0 c.r=0 cv=47 FDataType=0 opId=0 ssType=0 SsKey=0 invokeId=0 resultExp=F bpda=F OnBehalf=Media rfr=0 localPatternUsage=2 connectedPatternUsage=5 rdDestPart= rdDestPatt= rdDestCdpn=pi=0si1 lrnCepn= redDestName=locale: 1 Name: UnicodeName: pi: 0 annId=0 lHPMemCEPN= cHPMemCEPN= dtmCall=F dtmDisconn=F dtmMcNodeId=0 forcedClear=F TransparentData=null CanSupportSIPTandN=true TransId=0 AllowBitMask=0x0 UserAgentOrServer= OrigDDName=locale: 1 Name: UnicodeName: pi: 0 mCallerId= mCallerName= mediaCause=1 CAL={v=ffffffff, m=ffffffff, tDev=F, res=F, devType=0} CAL={v=fffffff6, m=ffffffff, tDev=F, res=F, devType=0}
01740518.000 |14:30:09.486 |SdlSig |CcDisconnReq |restart0 |SIPD(1,100,84,9) |Cdcc(1,100,224,463213) |1,100,14,3151800.3^10.89.28.70^* |[R:N-H:0,N:3,L:0,V:0,Z:0,D:0] CI=26983687 CI.branch=0 clearType=0 c.l=1 c.cid=8 c.cs=0 c.lc=0 c.r=0 cv=47 FDataType=0 opId=0 ssType=0 SsKey=0 invokeId=0 resultExp=F bpda=F OnBehalf=Media rfr=0 localPatternUsage=2 connectedPatternUsage=5 rdDestPart= rdDestPatt= rdDestCdpn=pi=0si1 lrnCepn= redDestName=locale: 1 Name: UnicodeName: pi: 0 annId=0 lHPMemCEPN= cHPMemCEPN= dtmCall=F dtmDisconn=F dtmMcNodeId=0 forcedClear=F TransparentData=null CanSupportSIPTandN=false TransId=0 AllowBitMask=0x0 UserAgentOrServer= OrigDDName=locale: 1 Name: UnicodeName: pi: 0 mCallerId= mCallerName= mediaCause=1 CAL={v=ffffffff, m=ffffffff, tDev=F, res=F, devType=0} CAL={v=fffffff6, m=ffffffff, tDev=F, res=F, devType=0}
01740518.001 |14:30:09.486 |AppInfo |SIPD(9) - getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 26983687 mapKey is 26983687
01740518.002 |14:30:09.486 |AppInfo |SIPD(9) - getCdpcPid: found Cdpc Pid (1,100,85,132130) for mapKey 26983687
Kindly suggest the way forward.
Thanks
Vipin
