Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

Register Log in
  • Congratulations John Tel on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

E1 R2 Dropping calls

Status
Not open for further replies.
Guilherme1000

Guilherme1000

Technical User
Apr 17, 2012
270
BR
Hello all,
Please, help me!
I have a simple E1 R2 set up with a local carrier dropping calls intermittently.
I have changed the control unit, PRI card, upgraded to 9.1.400, configured from the start, but the calls keep dropping randomly.
It seems a carrier issue but the carrier says it's a PABX problem, and I have to be sure it's not a PABX issue.

Here is a monitor trace from a call(extn 921) dropped:

********** Warning: Missed 207 packet(s) Total late 0 packet(s) **********
09:37:14 614767466mS CMExtnRx: v=RAS, p1=0
CMTransfer
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[900] Type=Default (100) Reason=CMDRdirect
09:37:14 614767466mS CMCallEvt: c0a82a010000451c 0.17692.0 5104 RAS.0: Transfer CMCauseTransfer
09:37:14 614767467mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
09:37:14 614767467mS CD: CALL: 5.17690.1 BState=Connected Cut=3 Music=0.0 Aend="Line 5" (3.3) Bend="#AA:Atendedor(#AA:Atendedor)" [VoiceMail] (21.11) CalledNum=#AA:Atendedor () CallingNum=6232714952 () Internal=0 Time=10107 AState=Connected
09:37:14 614767467mS CMTARGET: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: ADD TARGET (N): number=900 type=100 depth=1 nobar=0 setorig=1 ses=0
09:37:14 614767468mS CMTARGET: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: SET USER: Recepcao orig=1
09:37:14 614767468mS CMTARGET: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: ADD USER: Recepcao depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
09:37:14 614767468mS CMCallEvt: 0000000000000000 0.17696.0 -1 BaseEP: NEW CMEndpoint f170a460 TOTAL NOW=11 CALL_LIST=5
09:37:14 614767468mS CMCallEvt: 0000000000000000 0.17696.0 -1 Recepcao.-1: NEW CMExtnEndpoint f170a460, Name=Recepcao, Extn=900, Phys Extn=900
09:37:14 614767470mS CMTARGET: c0a82a0100004520 251.17696.0 5104 Recepcao.1: ADD PRIMARY
09:37:14 614767470mS CMTARGET: FoundKnownSystemTargets ICR cache hit
09:37:14 614767471mS CMTARGET: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: PrepareTransferTargets Found 1 target
09:37:14 614767471mS CMTARGET: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: GetNoAnswerTimer:15
09:37:14 614767471mS CMCallEvt: 0000000000000000 0.17697.0 -1 BaseEP: NEW CMEndpoint f16fda74 TOTAL NOW=12 CALL_LIST=5
09:37:14 614767473mS CMExtnEvt: RAS: CALL LOST (CMCauseTransfer)
09:37:14 614767473mS CMCallEvt: c0a82a010000451c 0.17692.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
09:37:14 614767473mS CMExtnEvt: v=1012 State, new=PortRecoverDelay old=Connected,0,0,RAS
09:37:14 614767473mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[#AA:Atendedor] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[6232714952] Type=Unknown Plan=Default Pres=Allowed (0)
BChan: slot=21 chan=11
Cause=126, Transfer(IPO)
09:37:14 614767473mS CMMap: a=21.11 b=0.0 T0
09:37:14 614767474mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 17692->0 )
09:37:14 614767474mS CMCallEvt: c0a82a010000451c 0.17692.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:37:14 614767474mS CMExtnEvt: v=1012 State, new=Idle old=PortRecoverDelay,0,0,RAS
09:37:14 614767475mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
BChan: slot=21 chan=11
Cause=126, Transfer(IPO)
09:37:14 614767475mS CMCallEvt: 0000000000000000 0.17697.0 5104 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
09:37:14 614767476mS CMCallEvt: c0a82a0100004520 251.17696.0 5104 Recepcao.1: StateChange: END=T CMCSIdle->CMCSOffering
09:37:14 614767478mS CMExtnCopyProcessCallMsg: v=4
CMSetup
Line: type=NoLine 0 Call: lid=251 id=17696 in=0
Called[900] Type=Default (100) Reason=CMDRdirect SndComp Calling[6232714952] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 8 item(s)
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Recepcao
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=6232714952
IE CMIEDeviceDetail (231) c0a82a010000451a LOCALE=ptb HW=15 VER=9 class=CMDeviceR2Trunk type=0 number=5 channel=2 features=0x20 rx_gain=32 tx_gain=32 ep_callid=17690 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
Display [6232714952(#AA:Atendedor)>Recepca]
Cause=126, Transfer(IPO)
Timed: 29/02/16 09:37
Locale: ptb
09:37:14 614767478mS CMExtnTx: v=900, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=251 id=17696 in=0
Called[900] Type=Default (100) Reason=CMDRdirect SndComp Calling[6232714952] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 8 item(s)
IE CMIECallSignalChannelData (8) unknown
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Recepcao
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=6232714952
IE CMIEDeviceDetail (231) c0a82a010000451a LOCALE=ptb HW=15 VER=9 class=CMDeviceR2Trunk type=0 number=5 channel=2 features=0x20 rx_gain=32 tx_gain=32 ep_callid=17690 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
Display [6232714952(#AA:Atendedor)>Recepca]
Cause=126, Transfer(IPO)
Timed: 29/02/16 09:37
Locale: ptb
09:37:14 614767478mS CMExtnRx: v=900, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=251 id=17696 in=0
09:37:14 614767478mS CMExtnCopyProcessMsg: v=4
CMAlerting
Line: type=IPLine 250 Call: lid=251 id=17696 in=0
09:37:14 614767479mS CMCallEvt: c0a82a0100004520 251.17696.0 5104 Recepcao.1: StateChange: END=T CMCSOffering->CMCSRinging
09:37:14 614767479mS CMCallEvt: 0000000000000000 0.17697.0 5104 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
09:37:14 614767482mS CMExtnTx: v=900, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=17696 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6232714952] Type=Unknown Plan=Default Pres=Allowed (0)
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=6232714952
IE CMIEDeviceDetail (231) c0a82a010000451a LOCALE=ptb HW=15 VER=9 class=CMDeviceR2Trunk type=0 number=5 channel=2 features=0x20 rx_gain=32 tx_gain=32 ep_callid=17690 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
Timed: 29/02/16 09:37
09:37:14 614767482mS CMLineTx: v=5
CMFacility
Line: type=R2Line 5 Call: lid=5 id=17690 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Recepcao
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=900
Cause=126, Transfer(IPO)

********** Warning: Missed 261 packet(s) Total late 0 packet(s) **********
09:37:21 614774343mS R2DSP: PRIU DSP 2: read tone, port 0, channel 4, tone 0x01

********** Warning: Missed 59 packet(s) Total late 0 packet(s) **********
09:37:21 614774552mS R2DSP: PRIU DSP 2: read tone, port 0, channel 4, tone 0x02
09:37:21 614774553mS R2Dialler: R2 Dialler: R2_BRA_5.4: State: Bwd 3 RX Fwd MFC: I.2 - "2"
09:37:21 614774553mS R2Channel: R2_BRA_5.4: EventTx: Dialling State: Dialling
09:37:21 614774553mS R2Dialler: R2 Dialler: R2_BRA_5.4: State: Bwd 3 TX Bwd MFC: A.5 - SendNextANI
09:37:21 614774553mS R2DSP: PRIU DSP 2: channel 4 (timeslot 8), send digit 5 (tone 37)
09:37:21 614774553mS R2Line: line: 5.4: State= IncomingGuard RxMessage: ReceivedDigit
09:37:21 614774553mS R2Line: line: 5.4: ANI Digit Received 2
09:37:21 614774653mS R2DSP: PRIU DSP 2: read tone, port 0, channel 4, tone 0x10
09:37:21 614774653mS R2DSP: PRIU DSP 2: channel 4 (timeslot 8), send digit 16 (tone 32)
09:37:21 614774694mS CMExtnEvt: Aluízio: CMExtnHandler::SetCurrent( id: 17677->0 )
09:37:21 614774694mS CMLineTx: v=11
CMFacility
Line: type=AnalogueLine 11 Call: lid=0 id=17680 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
HoldNotific.Invoke.CodePageUnknown
invokeId 16859
09:37:21 614774695mS CMExtnEvt: v=5 State, new=PortRecoverDelay old=Connected,0,0,Aluízio
09:37:21 614774696mS CMCallEvt: 0000000000000000 0.17703.0 -1 BaseEP: NEW CMEndpoint f16f5914 TOTAL NOW=11 CALL_LIST=4
09:37:21 614774696mS CMCallEvt: 0000000000000000 0.17703.0 -1 Aluízio.-1: NEW CMExtnEndpoint f16f5914, Name=Aluízio, Extn=956, Phys Extn=956
09:37:21 614774696mS CMExtnEvt: Aluízio: CMExtnHandler::SetCurrent( id: 0->17703 )
09:37:21 614774696mS CMExtnEvt: v=5 State, new=Idle old=PortRecoverDelay,0,0,Aluízio
09:37:21 614774696mS CMCallEvt: c0a82a0100004527 0.17703.0 -1 Aluízio.1: StateChange: END=X CMCSIdle->CMCSCompletedTone
09:37:21 614774697mS CMExtnEvt: v=5 State, new=CMESCompleted old=Idle,0,0,Aluízio
09:37:21 614774697mS CMCallEvt: 0000000000000000 0.17704.0 -1 BaseEP: NEW CMEndpoint f16f2838 TOTAL NOW=12 CALL_LIST=4
09:37:21 614774697mS CMCallEvt: 0000000000000000 0.17704.0 -1 Aluízio.-1: NEW CMExtnEndpoint f16f2838, Name=Aluízio, Extn=956, Phys Extn=956
09:37:21 614774697mS CMCallEvt: CREATE CALL:5108 (f16f22ac)
09:37:21 614774698mS CMCallEvt: 0000000000000000 0.17705.0 -1 BaseEP: NEW CMEndpoint f1688b64 TOTAL NOW=13 CALL_LIST=4
09:37:21 614774700mS CMExtnEvt: Aluízio: CMExtnHandler::SetCurrent( id: 17703->17704 )
09:37:21 614774700mS CMCallEvt: c0a82a0100004527 0.17703.0 -1 Aluízio.-1: StateChange: END=X CMCSCompletedTone->CMCSDelete
09:37:21 614774700mS CMCallEvt: c0a82a0100004527 0.17703.0 -1 BaseEP: DELETE CMEndpoint f16f5914 TOTAL NOW=12 CALL_LIST=5
09:37:21 614774700mS CMExtnEvt: v=5 State, new=Idle old=CMESCompleted,0,0,Aluízio
09:37:21 614774701mS CMExtnRx: v=956, p1=0
CMSetup
Line: type=AnalogueExtn 7 Call: lid=0 id=5 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[2997795796] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Aluízio
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=956
IE CMIEDeviceDetail (231) c0a82a0100004528 LOCALE=ptb HW=15 VER=9 class=CMDeviceStdPhone type=3 number=205 channel=0 features=0x10000000 rx_gain=32 tx_gain=32 ep_callid=17704 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
IE CMIEMohSourceId (247) MOH Source = 255
Locale: ptb
09:37:21 614774701mS CMCallEvt: c0a82a0100004528 0.17704.0 5108 Aluízio.1: StateChange: END=A CMCSIdle->CMCSDialInitiated
09:37:21 614774701mS CMExtnEvt: v=5 State, new=Dialling old=Idle,0,0,Aluízio
09:37:21 614774702mS CMTARGET: c0a82a0100004528 0.17704.0 5108 Aluízio.1: LOOKUP CALL ROUTE: GID=0 type=100 called_party= sub= calling=2997795796 calling_sub= dir=out complete=0 ses=0
09:37:21 614774702mS CMTARGET: c0a82a0100004528 0.17704.0 5108 Aluízio.1: ADD TARGET (N): number= type=100 depth=1 nobar=0 setorig=1 ses=0
09:37:21 614774702mS CMCallEvt: c0a82a0100004528 0.17704.0 5108 Aluízio.1: StateChange: END=A CMCSDialInitiated->CMCSDialling
09:37:21 614774703mS CMExtnTx: v=956, p1=0
CMSetupAck
Line: type=AnalogueExtn 7 Call: lid=0 id=5 in=0
BChan: slot=9 chan=7
UUI type=Local [......2Pd...] [0x01 0x0b 0x00 0x04 0x01 0x02 0x32 0x50 0x64 0x00 0x00 0x00 ]
Timed: 29/02/16 09:37
09:37:21 614774703mS CD: CALL: 0.17677.0 BState=Connected Cut=2 Music=0.1 Aend="Aluízio(956)" (9.7) Bend="Line 11" [Line 11] (5.3) CalledNum=997795796 () CallingNum=956 (Aluízio) Internal=0 Time=94177 AState=Connected
09:37:21 614774704mS CD: CALL: 0.17704.0 BState=Idle Cut=0 Music=3.0 Aend="Aluízio(956)" (9.7) Bend="" [] (0.0) CalledNum= () CallingNum=2997795796 () Internal=1 Time=6 AState=Dialling
09:37:21 614774705mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f55f5934 other_cp_b f5599aac

********** Warning: Missed 268 packet(s) Total late 0 packet(s) **********
09:37:30 614783800mS CMCallEvt: 0000000000000000 0.17711.0 -1 BaseEP: NEW CMEndpoint f1706198 TOTAL NOW=12 CALL_LIST=5
09:37:30 614783805mS CMCallEvt: CREATE CALL:5111 (f16f22ac)
09:37:30 614783805mS CMCallEvt: 0000000000000000 0.17712.0 -1 BaseEP: NEW CMEndpoint f17a3a54 TOTAL NOW=13 CALL_LIST=5
09:37:30 614783808mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=17711 in=1
Called[929] Type=Default (100) Reason=CMDRdirect SndComp Calling[700@192.168.0.185] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=700@192.168.0.185
IE CMIEDeviceDetail (231) c0a82a010000452f LOCALE=ptb HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x0 rx_gain=32 tx_gain=32 ep_callid=17711 ipaddr=192.168.42.1 apps=0 loc=999 em_loc=999 features2=0x0 is_spcall=1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=929
09:37:30 614783808mS CD: CALL: 17.17711.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=929 () CallingNum=700@192.168.0.185 () Internal=0 Time=3 AState=Idle
09:37:30 614783808mS CMCallEvt: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
09:37:30 614783808mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: LOOKUP CALL ROUTE: GID=555 type=100 called_party=929 sub= calling=700@192.168.0.185 calling_sub= dir=in complete=1 ses=0
09:37:30 614783808mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: SET BESTMATCH: length 0 vs -1 match= dest=.
09:37:30 614783808mS CMCallEvt: Priority hike: call 5111 priority 0->1
09:37:30 614783808mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: LOOKUP ICR: DDI=929 CGPN=700@192.168.0.185 (Destination . ) => CDPN=929
09:37:30 614783809mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: ADD TARGET (N): number=929 type=100 depth=1 nobar=1 setorig=1 ses=0
09:37:30 614783809mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: SET USER: Eduardo Rocha orig=1
09:37:30 614783809mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: ADD USER: Eduardo Rocha depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
09:37:30 614783809mS CMTARGET: FoundKnownSystemTargets ICR cache hit
09:37:30 614783809mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: NO INITIAL TARGETS: Eduardo Rocha
09:37:30 614783810mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: ADD USER: Eduardo Rocha depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x40) excpt(0x40), allow_redir(1) remote=00000000 simult 0 (0)
09:37:30 614783810mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: SELECT: TRY VOICEMAIL orig_hg() orig_user(929)
09:37:30 614783810mS CMTARGET: c0a82a010000452f 17.17711.1 5111 SIPTrunk Endpoint: Fallback() targeting failed
09:37:30 614783812mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
09:37:30 614783813mS CMLOGGING: CALL:2016/02/2909:37,00:00:00,000,700@192.168.0.185,I,929,929,,,,0,,"",0,n/a
09:37:30 614783813mS CD: CALL: 17.17711.1 BState=Idle Cut=0 Music=0.0 Aend="Line 17" (0.0) Bend="Eduardo Rocha(929)" [] (0.0) CalledNum=929 (Eduardo Rocha) CallingNum=700@192.168.0.185 () Internal=0 Time=8 AState=Dialling
09:37:30 614783813mS CD: CALL: 17.17711.1 Deleted
09:37:30 614783814mS CMLineTx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=17711 in=1
Cause=17, User busy
09:37:30 614783816mS CMCallEvt: c0a82a010000452f 17.17711.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSDialInitiated->CMCSDelete
09:37:30 614783816mS CMCallEvt: 0000000000000000 0.17712.0 -1 BaseEP: DELETE CMEndpoint f17a3a54 TOTAL NOW=12 CALL_LIST=5
09:37:30 614783816mS CMCallEvt: END CALL:5111 (f16f22ac)
09:37:30 614783817mS CMTARGET: c0a82a010000452f 17.17711.1 -1 BaseEP: ~CMTargetHandler f16fc088 ep f1706198
09:37:30 614783817mS CMCallEvt: c0a82a010000452f 17.17711.1 -1 BaseEP: DELETE CMEndpoint f1706198 TOTAL NOW=11 CALL_LIST=5
09:37:30 614783823mS PRN: Config Write Wake Up
09:37:31 614784323mS PRN: Updates IO list size 0 updated list size 1
09:37:31 614784323mS PRN: Config Write Completed
09:37:31 614784835mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,900: Feature Key Pressed 4:DCPLFeatureXfer
09:37:31 614784835mS CMExtnRx: v=900, p1=0
CMTransfer
Line: type=IPLine 250 Call: lid=251 id=17696 in=0
09:37:31 614784835mS CMExtnCopyProcessMsg: v=4
CMTransfer
Line: type=IPLine 250 Call: lid=251 id=17696 in=0
09:37:31 614784837mS CMExtnCopyProcessMsg: v=4
CMFacility
Line: type=NoLine 0 Call: lid=251 id=17696 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
09:37:31 614784838mS CMExtnTx: v=900, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=17696 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 29/02/16 09:37
09:37:31 614784838mS CMExtnTx: v=900, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=4 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
09:37:31 614784838mS CMExtnEvt: Recepcao: CMExtnHandler::SetCurrent( id: 17696->0 )
09:37:31 614784839mS CMLineTx: v=5
CMFacility
Line: type=R2Line 5 Call: lid=5 id=17690 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
HoldNotific.Invoke.CodePageUnknown
invokeId 16860
09:37:31 614784839mS R2Line: line: 5.2: State: Active CMMessage: CMFacility
09:37:31 614784839mS CMExtnTxP: v=900
CMCallStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6232714952] Type=Unknown Plan=Default Pres=Allowed (0)
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Recepcao
State=90, HeldCall
09:37:31 614784841mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Connected,0,0,Recepcao
09:37:31 614784841mS CMCallEvt: 0000000000000000 0.17713.0 -1 BaseEP: NEW CMEndpoint f16f2838 TOTAL NOW=12 CALL_LIST=5
09:37:31 614784841mS CMCallEvt: 0000000000000000 0.17713.0 -1 Recepcao.-1: NEW CMExtnEndpoint f16f2838, Name=Recepcao, Extn=900, Phys Extn=900
09:37:31 614784842mS CMCallEvt: CREATE CALL:5112 (f16f22ac)
09:37:31 614784842mS CMCallEvt: 0000000000000000 0.17714.0 -1 BaseEP: NEW CMEndpoint f17a3a54 TOTAL NOW=13 CALL_LIST=5
09:37:31 614784843mS CMExtnEvt: Recepcao: CMExtnHandler::SetCurrent( id: 0->17713 )
09:37:31 614784844mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Recepcao
09:37:31 614784845mS CMExtnRx: v=900, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=251 id=17713 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6232714952] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 10 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Recepcao
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=900
IE CMIEDeviceDetail (231) c0a82a0100004531 LOCALE=ptb HW=15 VER=9 class=CMDeviceH323Phone type=87 number=8000 channel=0 features=0x10000400 rx_gain=32 tx_gain=32 ep_callid=17713 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall
=1
IE CMIEMohSourceId (247) MOH Source = 1
Locale: ptb
09:37:31 614784846mS CMExtnCopyProcessMsg: v=4
CMSetup
Line: type=IPLine 250 Call: lid=251 id=17713 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6232714952] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 10 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Recepcao
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=900
IE CMIEDeviceDetail (231) c0a82a0100004531 LOCALE=ptb HW=15 VER=9 class=CMDeviceH323Phone type=87 number=8000 channel=0 features=0x10000400 rx_gain=32 tx_gain=32 ep_callid=17713 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall
=1
IE CMIEMohSourceId (247) MOH Source = 1
Locale: ptb

********** Warning: Missed 208 packet(s) Total late 0 packet(s) **********
09:37:38 614791815mS CMExtnRx: v=921, p1=0
CMConnect
Line: type=AnalogueExtn 3 Call: lid=0 id=6 in=0
09:37:38 614791815mS CMCallEvt: c0a82a0100004535 0.17717.0 5104 Aline.0: StateChange: END=T CMCSRinging->CMCSConnReq
09:37:38 614791815mS CMCallEvt: 0000000000000000 0.17714.0 5104 TargetingEP: RequestEnd c0a82a0100004535 0.17717.0 5104 Aline.0
09:37:38 614791815mS CMTARGET: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: CancelTimer CMTCCoverageTimeout
09:37:38 614791816mS CMCallEvt: 0000000000000000 0.17714.0 -1 BaseEP: DELETE CMEndpoint f17a3a54 TOTAL NOW=9 CALL_LIST=4
09:37:38 614791817mS CMExtnEvt: v=6 State, new=Connected old=Ringing,0,0,Aline
09:37:38 614791818mS CMCallEvt: c0a82a0100004535 0.17717.0 5104 Aline.0: StateChange: END=B CMCSConnReq->CMCSConnected
09:37:38 614791818mS CMTARGET: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: ~CMTargetHandler f16fc088 ep f16c0558
09:37:38 614791819mS CMExtnTx: v=921, p1=0
CMConnectAck
Line: type=AnalogueExtn 3 Call: lid=0 id=6 in=0
BChan: slot=2 chan=14
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=6232714952
IE CMIEDeviceDetail (231) c0a82a010000451a LOCALE=ptb HW=15 VER=9 class=CMDeviceR2Trunk type=0 number=5 channel=2 features=0x20 rx_gain=32 tx_gain=32 ep_callid=17690 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
Timed: 29/02/16 09:37
09:37:38 614791820mS CMLineTx: v=5
CMFacility
Line: type=R2Line 5 Call: lid=5 id=17690 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Aline
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=921
IE CMIEDeviceDetail (231) c0a82a0100004535 LOCALE=ptb HW=15 VER=9 class=CMDeviceStdPhone type=3 number=30 channel=0 features=0x10000000 rx_gain=32 tx_gain=32 ep_callid=17717 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
Cause=126, Transfer(IPO)
09:37:38 614791820mS R2Line: line: 5.2: State: Active CMMessage: CMFacility
09:37:38 614791820mS CMLineTx: v=5
CMFacility
Line: type=R2Line 5 Call: lid=5 id=17690 in=1
BChan: slot=2 chan=14
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Aline
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=921
IE CMIEDeviceDetail (231) c0a82a0100004535 LOCALE=ptb HW=15 VER=9 class=CMDeviceStdPhone type=3 number=30 channel=0 features=0x10000000 rx_gain=32 tx_gain=32 ep_callid=17717 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
Cause=126, Transfer(IPO)
09:37:38 614791820mS R2Line: line: 5.2: State: Active CMMessage: CMFacility
09:37:38 614791820mS CD: CALL: 5.17690.1 BState=Connected Cut=3 Music=0.0 Aend="Line 5" (3.3) Bend="Aline(921)" [Aline(921)] (2.14) CalledNum=900 (Recepcao) CallingNum=6232714952 () Internal=0 Time=34460 AState=Connected
09:37:38 614791822mS CMMap: a=3.3 b=0.0 R0
09:37:38 614791822mS CMMap: PCG::MapBChan pcp[90]b1r0 cp_b f560f7b8 other_cp_b 0 type CGTypeSimple
09:37:38 614791822mS CMMap: PCG::MapBChan pcp[137]b1r0 cp_b f55d8950 other_cp_b f560f7b8 type CGTypeSimple
09:37:38 614791822mS CMMap: a=3.3 b=2.14 M12
09:37:39 614792774mS CMExtnEvt: Aluízio: Recover Timer reason=CMTRWrapUp
09:37:39 614792774mS CMExtnEvt: v=5 State, new=Idle old=PortRecoverDelay,0,0,Aluízio
09:37:41 614794976mS PRN: R2 CAS: R2_BRA_5.4:: Receive: 0001 -> 1001
09:37:41 614794976mS R2Channel: R2_BRA_5.4: EventRx: Clear Forward State: Answer
09:37:42 614795196mS R2Channel: R2_BRA_5.4: EventTx: Idle State: Answer
09:37:42 614795196mS PRN: R2 CAS: R2_BRA_5.4:: Transmit: 0101 -> 1001
09:37:42 614795196mS R2Channel: R2_BRA_5.4: State Change Answer -> Idle
09:37:42 614795196mS R2Channel: R2_BRA_5.4: EventTx: Idle State: Idle
09:37:42 614795197mS R2Line: line: 5.4: State= Active RxMessage: Idle
09:37:42 614795197mS R2Line: line: 5.4: State Change Active -> Idle
09:37:42 614795197mS CMLineRx: v=5
CMReleaseComp
Line: type=R2Line 5 Call: lid=5 id=17706 in=1
Cause=16, Normal call clearing
09:37:42 614795197mS CMCallEvt: c0a82a010000452a 5.17706.1 5109 R2 Trunk:5 CHAN=4: StateChange: END=A CMCSConnected->CMCSCompleted
09:37:42 614795199mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
09:37:42 614795200mS CMLOGGING: CALL:2016/02/2909:37,00:00:14,004,81981296085,I,907,3907,,,,0,,"",0,n/a
09:37:42 614795200mS CD: CALL: 5.17706.1 BState=Connected Cut=2 Music=0.0 Aend="Line 5" (3.5) Bend="Fabiana Menezes(907)" [Fabiana Menezes(907)] (9.5) CalledNum=907 (Fabiana Menezes) CallingNum=81981296085 () Internal=0 Time=19179 AState=Idle
09:37:42 614795200mS CD: CALL: 5.17706.1 Deleted
09:37:42 614795200mS CMCallEvt: c0a82a010000452a 5.17706.1 -1 R2 Trunk:5 CHAN=4: StateChange: END=X CMCSCompleted->CMCSDelete
09:37:42 614795202mS CMExtnEvt: Fabiana Menezes: CALL LOST (CMCauseNormal)
09:37:42 614795202mS CMExtnEvt: Fabiana Menezes: Extn(907) Calling Party Number(81981296085) Type(CMNTypeUnknown)
09:37:42 614795202mS CMCallEvt: c0a82a010000452c 0.17708.0 -1 Fabiana Menezes.0: StateChange: END=X CMCSConnected->CMCSCompletedTone
09:37:42 614795202mS CMExtnEvt: v=3 State, new=CMESCompleted old=Connected,0,0,Fabiana Menezes
09:37:42 614795203mS CMCallEvt: END CALL:5109 (f16a56e0)
09:37:42 614795204mS CMCallEvt: c0a82a010000452a 5.17706.1 -1 BaseEP: DELETE CMEndpoint f168ec78 TOTAL NOW=8 CALL_LIST=3
09:37:42 614795204mS R2Line: line: 5.4: Clear Call
09:37:42 614795205mS CMMap: PCG::UnmapBChan pcp[166]b1r0 cp_b f559c5d4 other_cp_b f560eac0
09:37:42 614795205mS CMMap: a=3.5 b=9.5 M02
09:37:42 614795206mS CMMap: PCG::UnmapBChan pcp[92]b1r0 cp_b f560eac0 other_cp_b 0
09:37:42 614795208mS CMMap: a=9.5 b=0.0 B1
09:37:42 614795240mS CMExtnRx: v=910, p1=0
CMConnect
Line: type=AnalogueExtn 3 Call: lid=0 id=3 in=0
09:37:42 614795240mS CMCallEvt: c0a82a0100004526 0.17702.0 5103 Neide.0: StateChange: END=T CMCSRinging->CMCSConnReq
09:37:42 614795240mS CMCallEvt: 0000000000000000 0.17699.0 5103 TargetingEP: RequestEnd c0a82a0100004526 0.17702.0 5103 Neide.0
09:37:42 614795240mS CMTARGET: c0a82a0100004519 0.17689.0 5103 R2 Trunk:5 CHAN=1: CancelTimer CMTCNoAnswerTimeout
09:37:42 614795240mS CMCallEvt: 0000000000000000 0.17699.0 -1 BaseEP: DELETE CMEndpoint f171936c TOTAL NOW=7 CALL_LIST=3
09:37:42 614795242mS CMExtnEvt: v=3 State, new=Connected old=Ringing,0,0,Neide
09:37:42 614795242mS CMCallEvt: c0a82a0100004526 0.17702.0 5103 Neide.0: StateChange: END=B CMCSConnReq->CMCSConnected
09:37:42 614795242mS CMTARGET: c0a82a0100004519 0.17689.0 5103 R2 Trunk:5 CHAN=1: ~CMTargetHandler f1701d70 ep f171d6b4
09:37:42 614795244mS CMExtnTx: v=910, p1=0
CMConnectAck
Line: type=AnalogueExtn 3 Call: lid=0 id=3 in=0
BChan: slot=2 chan=11
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=034650225
IE CMIEDeviceDetail (231) c0a82a0100004519 LOCALE=ptb HW=15 VER=9 class=CMDeviceR2Trunk type=0 number=5 channel=1 features=0x0 rx_gain=32 tx_gain=32 ep_callid=17689 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=1
Timed: 29/02/16 09:37
09:37:42 614795244mS CMLineTx: v=5
CMFacility
Line: type=R2Line 5 Call: lid=0 id=17689 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Neide
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=910
IE CMIEDeviceDetail (231) c0a82a0100004526 LOCALE=ptb HW=15 VER=9 class=CMDeviceStdPhone type=3 number=27 channel=0 features=0x10000000 rx_gain=32 tx_gain=32 ep_callid=17702 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
Cause=126, Transfer(IPO)
09:37:42 614795244mS R2Line: line: 5.1: State: Active CMMessage: CMFacility
09:37:42 614795244mS CMLineTx: v=5
CMFacility
Line: type=R2Line 5 Call: lid=0 id=17689 in=0
BChan: slot=2 chan=11
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Neide
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=910
IE CMIEDeviceDetail (231) c0a82a0100004526 LOCALE=ptb HW=15 VER=9 class=CMDeviceStdPhone type=3 number=27 channel=0 features=0x10000000 rx_gain=32 tx_gain=32 ep_callid=17702 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
Cause=126, Transfer(IPO)
09:37:42 614795244mS R2Line: line: 5.1: State: Active CMMessage: CMFacility
09:37:42 614795245mS CD: CALL: 0.17687.0 BState=Connected Cut=3 Music=0.0 Aend="Line 5" (3.2) Bend="Neide(910)" [Neide(910)] (2.11) CalledNum=034650225 () CallingNum=910 (Neide) Internal=0 Time=52051 AState=Connected
09:37:42 614795246mS CMMap: a=3.2 b=0.0 R0
09:37:42 614795246mS CMMap: PCG::MapBChan pcp[89]b1r0 cp_b f560fe34 other_cp_b 0 type CGTypeSimple
09:37:42 614795246mS CMMap: PCG::MapBChan pcp[134]b1r0 cp_b f55dca38 other_cp_b f560fe34 type CGTypeSimple
09:37:42 614795246mS CMMap: a=3.2 b=2.11 M12
09:37:42 614795307mS RES: Mon 29/2/2016 09:37:42 FreeMem=53127888 Heap=52464248(1) Cache=663640 MemObjs=20355(21824) CMMsg=8(10) ASN=0 Buff=5200 1364 1000 7159 6 Links=63122(63756) BTree=1472(3143) CPU=11.55% CPUStats=08.42%/1/6/536/10256/11287/00.29%/0
/02.82% MCR=0 MCW=0

********** Warning: Missed 15 packet(s) Total late 0 packet(s) **********
09:37:55 614807775mS CMExtnEvt: Aluízio: Recover Timer reason=CMTRHoldTimeout
09:37:55 614807775mS CMExtnEvt: Aluízio: CMExtnHandler::SetCurrent( id: 0->17677 )
09:37:55 614807775mS CMExtnEvt: v=5 State, new=RingRecover old=Idle,0,0,Aluízio
09:37:55 614807776mS CMCallEvt: c0a82a010000450d 0.17677.0 5100 Aluízio.0: REVCALL INIT display=<
09:37:55 614807776mS CMExtnTx: v=956, p1=0
CMSetup
Line: type=AnalogueExtn 7 Call: lid=0 id=5 in=0
Called[956] Type=Reminder (123) Reason=CMDRdirect Calling[2997795796] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=9 chan=7
UUI type=Local [......2Pd...] [0x01 0x03 0x00 0x04 0x01 0x02 0x32 0x50 0x64 0x00 0x00 0x00 ]
Display [<Externa]
Cause=120, Reminder(IPO)
Timed: 29/02/16 09:37
09:37:55 614807821mS RES: Mon 29/2/2016 09:37:55 FreeMem=53135356 Heap=52464248(1) Cache=671108 MemObjs=20352(21824) CMMsg=8(10) ASN=0 Buff=5200 1364 1000 7159 6 Links=63116(63756) BTree=1472(3143) CPU=09.10% CPUStats=08.68%/1/7/536/10273/11287/00.32%/0
/02.85% MCR=0 MCW=0
09:37:55 614807821mS RES2: IP 500 V2 9.1.4.0 build 137 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=72 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=44(TLS=3) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:37:55 614807821mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
09:37:55 614807821mS RES5: CLog MemObjs=1515 FreePoolMem(Objs)=4420(85) TotalMem=264500
09:37:55 614807868mS CMExtnRx: v=956, p1=0
CMAlerting
Line: type=AnalogueExtn 7 Call: lid=0 id=5 in=0
09:38:02 614814530mS CMExtnRx: v=956, p1=0
CMConnect
Line: type=AnalogueExtn 7 Call: lid=0 id=5 in=0
09:38:02 614814531mS CMCallEvt: c0a82a010000450d 0.17677.0 5100 Aluízio.0: REVCALL COMPLETE state=1 substate=2
09:38:02 614814531mS CMLineTx: v=11
CMFacility
Line: type=AnalogueLine 11 Call: lid=0 id=17680 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RetrieveNotific.Invoke.CodePageUnknown
invokeId 16862
09:38:02 614814531mS CMLineTx: v=11
CMFacility
Line: type=AnalogueLine 11 Call: lid=0 id=17680 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[956] Type=Internal Plan=Default
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Aluízio
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Aluízio
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=956
IE CMIEDeviceDetail (231) c0a82a010000450d LOCALE=ptb HW=15 VER=9 class=CMDeviceStdPhone type=3 number=205 channel=0 features=0x10000020 rx_gain=32 tx_gain=32 ep_callid=17677 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
09:38:02 614814532mS CMExtnEvt: v=5 State, new=Connected old=RingRecover,0,0,Aluízio
09:38:02 614814533mS CMExtnTx: v=956, p1=0
CMConnectAck
Line: type=AnalogueExtn 7 Call: lid=0 id=5 in=0
Called[2997795796] Type=Default (100) Reason=CMDRdirect Calling[956] Type=Internal Plan=Default
BChan: slot=9 chan=7
Cause=120, Reminder(IPO)
Timed: 29/02/16 09:38
09:38:02 614814533mS CD: CALL: 0.17677.0 BState=Connected Cut=3 Music=0.0 Aend="Aluízio(956)" (9.7) Bend="Line 11" [Line 11] (5.3) CalledNum=997795796 () CallingNum=956 (Aluízio) Internal=0 Time=134007 AState=Connected
09:38:02 614814534mS CMMap: a=5.3 b=0.0 H0
09:38:02 614814534mS CMMap: PCG::MapBChan pcp[122]b1r0 cp_b f55f5934 other_cp_b 0 type CGTypeSimple
09:38:02 614814535mS CMMap: PCG::MapBChan pcp[168]b1r0 cp_b f5599aac other_cp_b f55f5934 type CGTypeSimple
09:38:02 614814535mS CMMap: a=5.3 b=9.7 M12
09:38:02 614814821mS RES: Mon 29/2/2016 09:38:02 FreeMem=53134904 Heap=52464248(1) Cache=670656 MemObjs=20355(21824) CMMsg=8(10) ASN=0 Buff=5200 1364 1000 7160 6 Links=63121(63756) BTree=1472(3143) CPU=08.67% CPUStats=09.45%/3/9/536/10188/11287/00.34%/0
/02.83% MCR=0 MCW=0
09:38:02 614814821mS RES2: IP 500 V2 9.1.4.0 build 137 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=67 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=44(TLS=3) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:38:02 614814821mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
09:38:02 614814822mS RES5: CLog MemObjs=1515 FreePoolMem(Objs)=4420(85) TotalMem=264500
09:38:07 614819453mS CMExtnRx: v=956, p1=0
CMReleaseComp
Line: type=AnalogueExtn 7 Call: lid=0 id=5 in=0
09:38:07 614819453mS CMCallEvt: Cannot complete the transfer - off-switch transfer is inhibited
09:38:07 614819453mS CMCallEvt: c0a82a010000450d 0.17677.0 5100 Aluízio.0: StateChange: END=A CMCSConnected->CMCSCompleted
09:38:07 614819454mS CMExtnEvt: v=5 State, new=PortRecoverDelay old=Connected,0,0,Aluízio
09:38:07 614819455mS PRN: CDR - ResetQueueSize=3000
09:38:07 614819456mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
09:38:07 614819456mS ERR: CDRServer discarding - framecount=3000
09:38:07 614819457mS CMLOGGING: CALL:2016/02/2909:35,00:02:01,000,956,O,997795796,2997795796,Aluízio,,,0,,"",0,n/a
09:38:07 614819457mS CD: CALL: 0.17677.0 BState=Connected Cut=2 Music=0.0 Aend="Aluízio(956)" (9.7) Bend="Line 11" [Line 11] (5.3) CalledNum=997795796 () CallingNum=956 (Aluízio) Internal=0 Time=138931 AState=Idle
09:38:07 614819457mS CD: CALL: 0.17677.0 Deleted
09:38:07 614819457mS CMExtnEvt: Aluízio: CALL LOST (CMCauseNormal)
09:38:07 614819458mS CMExtnEvt: Aluízio: Extn(956) Calling Party Number(956) Type(CMNTypeInternal)
09:38:07 614819458mS CMExtnEvt: Aluízio: CMExtnHandler::SetCurrent( id: 17677->0 )
09:38:07 614819458mS CMCallEvt: c0a82a010000450d 0.17677.0 -1 Aluízio.-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:38:07 614819459mS CMCallEvt: c0a82a0100004510 0.17680.0 -1 Alog Trunk:11: StateChange: END=X CMCSConnected->CMCSDelete
09:38:07 614819460mS CMCallEvt: c0a82a0100004510 0.17680.0 -1 BaseEP: DELETE CMEndpoint f16b956c TOTAL NOW=5 CALL_LIST=2
09:38:07 614819460mS CMCallEvt: END CALL:5100 (f1709ed4)
09:38:07 614819461mS CMCallEvt: c0a82a010000450d 0.17677.0 -1 BaseEP: DELETE CMEndpoint f169f01c TOTAL NOW=4 CALL_LIST=2
09:38:07 614819463mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f55f5934 other_cp_b f5599aac
09:38:07 614819463mS CMMap: a=9.7 b=5.3 M02
09:38:07 614819463mS CMMap: PCG::UnmapBChan pcp[168]b1r0 cp_b f5599aac other_cp_b 0
09:38:07 614819829mS RES: Mon 29/2/2016 09:38:07 FreeMem=53159276 Heap=52464248(1) Cache=695028 MemObjs=20307(21824) CMMsg=8(10) ASN=0 Buff=5200 1362 1000 7161 6 Links=63108(63756) BTree=1470(3143) CPU=08.50% CPUStats=09.32%/9/15/536/10205/11287/00.31%/
0/02.84% MCR=0 MCW=0
09:38:07 614819829mS RES2: IP 500 V2 9.1.4.0 build 137 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=70 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=44(TLS=3) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:38:07 614819829mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
09:38:07 614819829mS RES5: CLog MemObjs=1515 FreePoolMem(Objs)=4420(85) TotalMem=264500
09:38:09 614821458mS CMExtnEvt: Aluízio: Recover Timer reason=CMTRWrapUp
09:38:09 614821458mS CMExtnEvt: v=5 State, new=Idle old=PortRecoverDelay,0,0,Aluízio
09:38:10 614822643mS R2DSP: PRIU DSP 2: is alive
09:38:14 614826661mS PRN: R2 CAS: R2_BRA_5.2:: Receive: 0001 -> 1001
09:38:14 614826661mS R2Channel: R2_BRA_5.2: EventRx: Clear Forward State: Answer
09:38:14 614826882mS R2Channel: R2_BRA_5.2: EventTx: Idle State: Answer
09:38:14 614826882mS PRN: R2 CAS: R2_BRA_5.2:: Transmit: 0101 -> 1001
09:38:14 614826882mS R2Channel: R2_BRA_5.2: State Change Answer -> Idle
09:38:14 614826882mS R2Channel: R2_BRA_5.2: EventTx: Idle State: Idle
09:38:14 614826883mS R2Line: line: 5.2: State= Active RxMessage: Idle
09:38:14 614826883mS R2Line: line: 5.2: State Change Active -> Idle
09:38:14 614826883mS CMLineRx: v=5
CMReleaseComp
Line: type=R2Line 5 Call: lid=5 id=17690 in=1
Cause=16, Normal call clearing
09:38:14 614826884mS CMCallEvt: c0a82a010000451a 5.17690.1 5104 R2 Trunk:5 CHAN=2: StateChange: END=A CMCSConnected->CMCSCompleted
09:38:14 614826885mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
09:38:14 614826887mS CMLOGGING: CALL:2016/02/2909:37,00:00:57,011,6232714952,I,900,3900,,,,0,,"",0,n/a
09:38:14 614826887mS CD: CALL: 5.17690.1 BState=Connected Cut=2 Music=0.0 Aend="Line 5" (3.3) Bend="Aline(921)" [Aline(921)] (2.14) CalledNum=900 (Recepcao) CallingNum=6232714952 () Internal=0 Time=69527 AState=Idle


10:23:29 617541943mS R 10:29:16 617888844mS PRN: +------------------------------------------------------------
10:29:16 617888844mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
10:29:17 617889630mS CMExtnEvt: Iracema: Recover Timer reason=CMTRWrapUp
10:29:17 617889630mS CMExtnEvt: v=27 State, new=Idle old=PortRecoverDelay,0,0,Iracema
 
Sort by date Sort by votes
09:38:07 614819453mS CMCallEvt: Cannot complete the transfer - off-switch transfer is inhibited

How's your car?
 
Upvote 0 Downvote
but that event seems to be from another call, the call dropped is from the extension 921(aline)

no transfer are made, the calls drops while in speech.
 
Upvote 0 Downvote
There is an awful lot of packet loss in that trace, if that's on the same network that's not good :)

 
Upvote 0 Downvote
Ok you've got a cause code 16. If you read the monitor manual (there is one), i think you'll find this is a normal hangup signal.
Check your clocking is set to network.
Check your line type is set correctly. We use ETSI in my locale.
Check system status for red alarms against the line as these events occur.
Reboot the NTU.


How's your car?
 
Upvote 0 Downvote
amriddlle, it's a TDM line. can the network be affecting?

Holdmusic34, clock is set to network, line type correct, no red alarms occured

 
Upvote 0 Downvote
Make and break a normal call having the external party hang up. Stare and compare a trace versus a dropped call. See the difference. Prob both cause 16.
Look for sameness outside the ip office. Are these all calls to the same mobile carrier for example?

How's your car?
 
Upvote 0 Downvote
Kick it back to the line provider, they always say it's our problem and in most cases it turns out to be on their end
 
Upvote 0 Downvote
The carrier will install an equipament called analizer between the IP office and the modem and will be testing for 24h. Let's see what we got, thank you all anyway!
 
Upvote 0 Downvote
Status
Not open for further replies.

Similar threads

AndoverService
  • Question
Echo on incoming calls analog lines 2
Replies
3
Views
192
AndoverService
AndoverService
rsv23
  • Locked
  • Question
VMpro stops recording calls
Replies
3
Views
435
derfloh
derfloh
marianoabdala
  • Locked
  • Question
Make Avaya Calls you 1
Replies
4
Views
394
SoftwareRT
SoftwareRT
NetEngBB
  • Locked
  • Question
Forward unconditional not working with external calls through VMPro
Replies
11
Views
867
NetEngBB
NetEngBB
Nikos Koulioumbis
  • Locked
  • Question
VM PRO on IPO500 9.0.12 does not route calls to menu selection 1
Replies
6
Views
454
TouchToneTommy
TouchToneTommy

Part and Inventory Search

Sponsor

Back
Top