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 strongm on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

Upgrade to 5.0.26 causes SIP issue's

Status
Not open for further replies.
djuplift

djuplift

IS-IT--Management
Jan 9, 2008
573
US
I just upgraded 2 IP500 V1's that are on an SCN sharing SIP trunks to 5.0.26. Following the upgrade calls coming into users on the remote system do cannot reach voicemail nor any device a user is forwarded to. The caller hears ringing and system status says the call went to voicemail or to the forwarded device.

Keep in mind the remote system is using the SIP trunks on the Main system to receive inbound calls and make outbound. There is an IP trunks between.

Here is some SYS mon output:
a=x-cxc:aT0wNGMzZjg0ODVjYWIwNzZiLTA7Yj02NC4xOTguMTQ5LjEzNztkPTIxNi40My4yLjU6MjQ3NDI7dD1pbnRlcm5hbDthPTEwLjI1NS4wLjU6MjEzOTI7
a=sendrecv
61979845mS Sip: Find End Point 25.1737.0 213 SIPTrunk Endpoint (f52da1b4) Sip CallId 6cb6a31fdd7c4efa0473bb2eed3e7350@192.168.43.1
61979845mS Sip: 25.1737.0 213 SIPTrunk Endpoint(f52d90e0) Process SIP response dialog f52d90e0, method INVITE, CodeNum 183 in state SIPDialog::INV_PROV_RESP_RCVD(5)
61979846mS Sip: 25.1737.0 213 SIPTrunk Endpoint(f52d90e0) UpdateSIPCallState SIPDialog::INV_PROV_RESP_RCVD(5) -> SIPDialog::SESSION_IN_PROGRESS(27)
61979846mS Sip: 25.1737.0 213 SIPTrunk Endpoint(f52d90e0) UpdateSDPState SIPDialog::OFFER_SENT(1) -> SIPDialog::ANSWER_RCVD(4)
61979846mS Sip: 25.1737.0 213 SIPTrunk Endpoint(f52d90e0) ModifyFirewallHoles open 1 192.168.43.1:49166 -> 10.255.0.5:21392
61979847mS CMLineRx: v=0
CMAlerting
Line: type=IPLine 25 Call: lid=25 id=1737 in=0
Called[3193628336] Type=Default (100) Reason=CMDRdirect
IE CMIEFastStartInfoData (6)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=93633789
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceSIPTrunk type=0 number=25 channel=0 rx_gain=32 tx_gain=32 ep_callid=1737 ipaddr=10.0.0.211 apps=0
61979847mS CMCallEvt: 25.1737.0 213 SIPTrunk Endpoint: StateChange: END=B CMCSAccept->CMCSRinging
61979847mS CMExtnCutEvt: 252.1733.0 213 Terminal Info 1.0: AudioConnectionChange to CMLocalTonePending SSOperationInProgress 0 MEDIA_FLAG_ACC_ENTRY 0
61979848mS CMCallEvt: 252.1733.0 213 Terminal Info 1.0: StateChange: END=A CMCSDialled->CMCSRingBack
61979848mS CMExtnCutEvt: 252.1733.0 213 Terminal Info 1.0: AudioConnectionChange to CMLocalTonePending SSOperationInProgress 0 MEDIA_FLAG_ACC_ENTRY 0
61979849mS CMExtnEvt: v=3 State, new=Alerting old=Proceeding,0,0,Terminal Info 1
61979849mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: ProcessInboundMsg --- msg: CMAlerting f52f3704 fs f52c5d90 h245 0 reinvite 0 fs size 2
61979850mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: ProcessInboundFastStart, state is LCFSInitialize
61979850mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::SetMediaChannelAddr RX --- rtp 192.168.43.1
61979850mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::OnFastStart, TX, fse.IsRxLCParaDefined() 1, fse.IsTxLCParaDefined() 0 lc 1 change_state 1
61979850mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::UpdateInfo set lc num 1 LC_INCOMING
61979850mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: UpdateInfo RX (lc:1 lc_tx:TX) rtp: 10.255.0.5:21393 (RX:192.168.43.1:49166 TX:0.0.0.0:0) update 0
61979851mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::SetMediaState id 1 Rx LCSE_STATE_RELEASED -> LCSE_STATE_ESTABLISHED (payload is 12 num 1)
61979851mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::SetMediaChannelAddr RX --- rtp 192.168.43.1
61979851mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::OnFastStart, TX, fse.IsRxLCParaDefined() 0, fse.IsTxLCParaDefined() 1 lc 7 change_state 1
61979851mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::UpdateInfo set lc num 7 LC_OUTGOING
61979852mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::SetMediaChannelAddr TX --- rtp 10.255.0.5
61979852mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: UpdateInfo TX (lc:7 lc_tx:TX) rtp: 10.255.0.5:21393 (RX:192.168.43.1:49166 TX:10.255.0.5:21392) update 1
61979852mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::SetMediaState id 1 Tx LCSE_STATE_RELEASED -> LCSE_STATE_ESTABLISHED (payload is 12 num 7)
61979852mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::ConfigureConnection, type 6 packet size 20 silence_suppression 0 fax 3
61979853mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: SetLocalState LCFSInitialize -> LCMediaConnected
61979853mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: ESH225 registered for the following events:
61979853mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: ESRenego registered for the following events: TCS_Empty TCS_Full
61979853mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: SessId 1: createReplacementFastStart for local
61979854mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::Map(to tone) --- tone: CMLocalToneNone CP(RTP: local(192.168.43.1 49166); remote(10.255.0.5 21392)) tones supported 1
61979854mS CH : CHand::JoinGroup, cp[531]b0r1 (current group: g null), group g null
61979854mS CMExtnCutEvt: 25.1737.0 213 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneNone play_tapi_moh 0
61979855mS MH : 25.1737.0 213 SIPTrunk Endpoint LCMH Tone CMLocalToneNone(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is not changed (lcmh) renego 0
61979855mS MH : 25.1737.0 213 SIPTrunk Endpoint LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneNone) in parent state CMCSRinging action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61979855mS CMExtnCutEvt: 252.1733.0 213 Terminal Info 1.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneNone play_tapi_moh 0
61979856mS MH : 252.1733.0 213 Terminal Info 1.0 LCMH Tone CMLocalToneNone(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is changed (lcmh) renego 1
61979856mS MH : 252.1733.0 213 Terminal Info 1.0 LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneNone) in parent state CMCSRingBack action 1 renegotiate 1 voip_negotiator->processing_inbound 0
61979856mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: OnRenegotiate to stack in local state LCRPaused, remote state LCRIdle, pause is 0 (fs 0, size 0), AllowRenegotiation 1 mh_changed 1 IsRTPRelayRequired 1 respond 0
61979857mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: OnMHChanged, UnsetLcmhChanged processing_cmd 9 processing_inbound 0
61979857mS MH : 252.1733.0 213 Terminal Info 1.0 UnsetLcmhChanged other_mh f52f3564 lcmh 1
61979857mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: SendReplacementFS
61979857mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: SessId 1: createReplacementFastStart for remote
61979858mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: ProcessInboundCCMsg, delay the message, size 1
61979858mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: Ignore play local tone, other mh will give us the data...
61979858mS CMExtnCutEvt: 25.1737.0 213 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61979858mS MH : 25.1737.0 213 SIPTrunk Endpoint LCMH Tone CMLocalToneCutThru(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is not changed (lcmh) renego 0
61979859mS MH : 25.1737.0 213 SIPTrunk Endpoint LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneNone) in parent state CMCSRinging action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61979859mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::IsMappable to other media session rx: c0a82b01 tx: aff0005
61979859mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::IsMappable both are opened
61979859mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::Map(to media session) Not mappable
61979859mS CH : CHand::JoinGroup, cp[531]b0r1 (current group: g null), group g null
61979860mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: ProcessOutboundCCMsg --- msg: CMFacility f53c305c fs f531df28 h245 0 fs size 2
61979860mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: AssertRTPRelay fs
61979860mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: AssertRTPRelayData fs
61979860mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: ProcessOutboundFastStart, state is LCMediaConnected
61979861mS CMLineTx: v=0
CMFacility
Line: type=IPLine 25 Call: lid=25 id=1737 in=0
61979861mS Sip: 25.1737.0 213 SIPTrunk Endpoint(f52da1b4) received CMFacility
61979861mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::IsMappable to other media session rx: c0a82b01 tx: aff0005
61979861mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::IsMappable both are opened
61979861mS VOIP: (Out) 25.1737.0 213 SIPTrunk Endpoint: CMMediaSession::Map(to media session) Not mappable
61979862mS CH : CHand::JoinGroup, cp[531]b0r1 (current group: g null), group g null
61979862mS CMExtnCutEvt: 252.1733.0 213 Terminal Info 1.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61979862mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: ProcessOutboundCCMsg --- msg: CMAlerting f52f3704 fs f52c5d90 h245 0 fs size 2
61979862mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: AssertRTPRelay fs
61979863mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: AssertRTPRelayData fs
61979863mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: ProcessOutboundFastStart, state is LCMediaConnected
61979863mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: OnRenegotiate to stack in local state LCRPaused, remote state LCRIdle, pause is 0 (fs f52c5d90, size 2), AllowRenegotiation 1 mh_changed 0 IsRTPRelayRequired 1 respond 0
61979864mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: IsRenegoFSCompatible HasPriority 0 session f52b8e60 size 2
61979864mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: IsRenegoFSCompatible: has priority false, set data_type to 18
61979864mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: ResumeMediaConnection lcmh->IsOtherEndLCMH() f52f3564 from_stack 0 ie_fs f52c5d90 mh_changed 0 respond 0
61979864mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::OnFastStart, TX, fse.IsRxLCParaDefined() 0, fse.IsTxLCParaDefined() 1 lc 1 change_state 1
61979865mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::UpdateInfo set lc num 1 LC_INCOMING
61979865mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: UpdateInfo RX (lc:1 lc_tx:TX) rtp: 10.0.0.211:49159 (RX:10.0.0.211:49158 TX:10.0.0.29:49486) update 0
61979865mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::SetMediaState id 1 Rx LCSE_STATE_RELEASED -> LCSE_STATE_ESTABLISHED (payload is 12 num 1)
61979865mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::OnFastStart, TX, fse.IsRxLCParaDefined() 1, fse.IsTxLCParaDefined() 0 lc 7 change_state 1
61979866mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::UpdateInfo set lc num 7 LC_OUTGOING
61979866mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::SetMediaChannelAddr RX --- rtp 10.0.0.211
61979866mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: UpdateInfo TX (lc:7 lc_tx:TX) rtp: 10.0.0.211:49159 (RX:10.0.0.211:49158 TX:10.0.0.29:49486) update 1
61979866mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::SetMediaState id 1 Tx LCSE_STATE_RELEASED -> LCSE_STATE_ESTABLISHED (payload is 12 num 7)
61979866mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::ConfigureConnection, type 6 packet size 20 silence_suppression 0 fax 3
61979867mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::OnFastStart, TX, fse.IsRxLCParaDefined() 0, fse.IsTxLCParaDefined() 1 lc 1 change_state 1
61979867mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: UpdateInfo RX (lc:1 lc_tx:TX) rtp: 10.0.0.211:49159 (RX:10.0.0.211:49158 TX:10.0.0.29:49486) update 0
61979867mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::SetMediaState id 1 Rx LCSE_STATE_ESTABLISHED -> LCSE_STATE_ESTABLISHED (payload is 12 num 1)
61979868mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::ConfigureConnection, type 6 packet size 20 silence_suppression 0 fax 3
61979868mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::OnFastStart, TX, fse.IsRxLCParaDefined() 1, fse.IsTxLCParaDefined() 0 lc 7 change_state 1
61979868mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::SetMediaChannelAddr RX --- rtp 10.0.0.211
61979868mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: UpdateInfo TX (lc:7 lc_tx:TX) rtp: 10.0.0.211:49159 (RX:10.0.0.211:49158 TX:10.0.0.29:49486) update 1
61979869mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::SetMediaState id 1 Tx LCSE_STATE_ESTABLISHED -> LCSE_STATE_ESTABLISHED (payload is 12 num 7)
61979869mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::ConfigureConnection, type 6 packet size 20 silence_suppression 0 fax 3
61979869mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: SetRenegotiateState local LCRPaused -> LCRIdle
61979869mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::IsMappable to other media session rx: a0000d3 tx: a00001d
61979870mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::IsMappable both are opened
61979870mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: CMMediaSession::Map(to media session) --- session-id: 1 use_codecs 0
61979870mS CH : CHand::Map rx cp[528]b0r1 (g:g null) tx cp[531]b0r1 (g:g null)
61979870mS CH : Allocate CGTypeSimple group g G[202]
61979870mS CH : CHand::JoinGroup, cp[528]b0r1 (current group: g null), group g G[202]
61979870mS CH : CG::AddConnection, cp[528]b0r1 to group g G[202]
61979871mS CH : CHand::JoinGroup, cp[531]b0r1 (current group: g null), group g G[202]
61979871mS CH : CG::AddConnection, cp[531]b0r1 to group g G[202]
61979871mS VOIP: (In) 252.1733.0 213 Terminal Info 1.0: ESRenego registered for the following events: TCS_Empty TCS_Full
61979872mS CMExtnTx: v=6723, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=252 id=1733 in=0
Called[3193628336] Type=Default (100) Reason=CMDRdirect
IE CMIEFastStartInfoData (6)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=93633789
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceSIPTrunk type=0 number=25 channel=0 rx_gain=32 tx_gain=32 ep_callid=1737 ipaddr=10.0.0.211 apps=0
Timed: 10/05/11 09:18
61979872mS CMExtnTx: v=6723, p1=8001
CMFacility
Line: type=IPLine 250 Call: lid=252 id=3 in=1
IE CMIEFastStartInfoData (6)
61979873mS Sip: 25.1737.0 213 SIPTrunk Endpoint(f52d90e0) UpdateSDPState SIPDialog::ANSWER_RCVD(4) -> SIPDialog::COMPLETE(7)
61979877mS H323Evt: SetRfc2833 (1): rx payload 101 tx payload 101
61979877mS CMMap: PCG::MapBChan pcp[531]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
61979878mS CMMap: PCG::MapBChan pcp[528]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
61979938mS CD: CALL: 252.1733.0 BState=Ringing Cut=3 Music=0.0 Aend="Terminal Info 1(6723)" (0.0) Bend="Line 25" [Line 25] (0.0) CalledNum=3633789@bwas4.global.voip.mcleodusa.net () CallingNum=6723 (Terminal Info 1) Internal=0 Time=11594 AState=Ringing
61981020mS H323Evt: RTP(50t): 192.168.43.1/49166 10.255.0.5/21392 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=1143 SENT=50 (avg size=20) RECV=55 (avg size=20)
61983404mS CMCallEvt: 0.1738.0 -1 BaseEP: NEW CMEndpoint f52fee84 TOTAL NOW=9 CALL_LIST=3
61983404mS CMCallEvt: 0.1738.0 -1 American Eagle.-1: NEW CMExtnEndpoint f52fee84, Name=American Eagle, Extn=3226, Phys Extn=3226
61983404mS CMCallEvt: CREATE CALL:214 (f53acff8)
61983405mS CMCallEvt: 0.1739.0 -1 BaseEP: NEW CMEndpoint f53abdb4 TOTAL NOW=10 CALL_LIST=3
61983408mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: SetLocalTone CMLocalToneNone->CMLocalToneCutThru
61983408mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneUnCut active=CMLocalToneNone play_tapi_moh 0
61983408mS MH : 0.1738.0 214 American Eagle.0: FCMH PlayLocalTone CMLocalToneNone->CMLocalToneNone, generate 1
61983409mS CMExtnEvt: American Eagle: CMExtnHandler::SetCurrent( id: 0->1738 )
61983409mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneUnCut active=CMLocalToneNone play_tapi_moh 0
61983409mS MH : 0.1738.0 214 American Eagle.0: FCMH PlayLocalTone CMLocalToneNone->CMLocalToneUnCut, generate 1
61983409mS CH : CHand::JoinGroup, cp[152]b1r0 (current group: g null), group g null
61983409mS CMExtnRx: v=3226, p1=0
CMSetup
Line: type=AnalogueExtn 6 Call: lid=0 id=11 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[3226] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61983410mS CMCallEvt: 0.1738.0 214 American Eagle.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
61983410mS CMExtnEvt: v=11 State, new=Connected old=Idle,0,0,American Eagle
61983411mS CMTARGET: 0.1738.0 214 American Eagle.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=3226 dir=out complete=0 ses=0
61983411mS CMTARGET: 0.1738.0 214 American Eagle.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
61983411mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: SetLocalTone CMLocalToneCutThru->CMLocalToneDial
61983411mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: AudioConnectionChange current=CMLocalToneDial new=CMLocalToneDial active=CMLocalToneUnCut play_tapi_moh 0
61983412mS MH : 0.1738.0 214 American Eagle.0: FCMH PlayLocalTone CMLocalToneUnCut->CMLocalToneDial, generate 1
61983412mS CH : CHand::JoinGroup, cp[152]b1r0 (current group: g null), group g D1[1]
61983412mS CH : CG::AddConnection, cp[152]b1r0 to group g D1[1]
61983412mS CMCallEvt: 0.1738.0 214 American Eagle.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
61983412mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: SetLocalTone CMLocalToneDial->CMLocalToneDial
61983413mS CMExtnEvt: v=11 State, new=Dialling old=Connected,0,0,American Eagle
61983413mS CMExtnTx: v=3226, p1=0
CMSetupAck
Line: type=AnalogueExtn 6 Call: lid=0 id=11 in=0
BChan: slot=8 chan=13
UUI type=Local [......2Pd.] [0x00 0x02 0x00 0x00 0x01 0x02 0x32 0x50 0x64 0x00 ]
Timed: 10/05/11 09:18
61983414mS CD: CALL: 0.1738.0 BState=Idle Cut=1 Music=3.0 Aend="American Eagle(3226)" (8.13) Bend="" [] (0.0) CalledNum= () CallingNum=3226 (American Eagle) Internal=1 Time=10 AState=DialInitiated
61983415mS CMMap: a=8.13 b=0.0 D1
61984899mS CMExtnRx: v=3226, p1=0
CMInformation
Line: type=AnalogueExtn 6 Call: lid=0 id=11 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
61984900mS CMTARGET: 0.1738.0 214 American Eagle.0: Setting Hard Timer 4000
61984900mS CMTARGET: 0.1738.0 214 American Eagle.0: LOOKUP CALL ROUTE: type=100 called_party=3 sub= calling=3226 dir=out complete=0 ses=0
61984900mS CMTARGET: 0.1738.0 214 American Eagle.0: ADD TARGET (N): number=3 type=100 depth=1 nobar=1 setorig=1 ses=0
61984901mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: SetLocalTone CMLocalToneDial->CMLocalToneCutThru
61984901mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneUnCut active=CMLocalToneDial play_tapi_moh 0
61984901mS MH : 0.1738.0 214 American Eagle.0: FCMH PlayLocalTone CMLocalToneDial->CMLocalToneUnCut, generate 1
61984901mS CH : CHand::JoinGroup, cp[152]b1r0 (current group: g D1[1]), group g null
61984901mS CH : CG::RemoveConnection, cp[152]b1r0 group g D1[1]
61984903mS CMMap: a=8.13 b=0.0 D0
61985095mS CMExtnRx: v=3226, p1=0
CMInformation
Line: type=AnalogueExtn 6 Call: lid=0 id=11 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
61985096mS CMTARGET: 0.1738.0 214 American Eagle.0: Setting Hard Timer 4000
61985096mS CMTARGET: 0.1738.0 214 American Eagle.0: LOOKUP CALL ROUTE: type=100 called_party=32 sub= calling=3226 dir=out complete=0 ses=0
61985096mS CMTARGET: 0.1738.0 214 American Eagle.0: ADD TARGET (N): number=32 type=100 depth=1 nobar=1 setorig=1 ses=0
61985386mS CMExtnRx: v=3226, p1=0
CMInformation
Line: type=AnalogueExtn 6 Call: lid=0 id=11 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
61985386mS CMTARGET: 0.1738.0 214 American Eagle.0: Setting Hard Timer 4000
61985386mS CMTARGET: 0.1738.0 214 American Eagle.0: LOOKUP CALL ROUTE: type=100 called_party=327 sub= calling=3226 dir=out complete=0 ses=0
61985386mS CMTARGET: 0.1738.0 214 American Eagle.0: ADD TARGET (N): number=327 type=100 depth=1 nobar=1 setorig=1 ses=0
61985573mS CMExtnRx: v=3226, p1=0
CMInformation
Line: type=AnalogueExtn 6 Call: lid=0 id=11 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
61985574mS CMTARGET: 0.1738.0 214 American Eagle.0: Setting Hard Timer 4000
61985574mS CMTARGET: 0.1738.0 214 American Eagle.0: LOOKUP CALL ROUTE: type=100 called_party=3274 sub= calling=3226 dir=out complete=0 ses=0
61985574mS CMTARGET: 0.1738.0 214 American Eagle.0: ADD TARGET (N): number=3274 type=100 depth=1 nobar=1 setorig=1 ses=0
61985575mS CMTARGET: 0.1738.0 214 American Eagle.0: SET USER: Landmark orig=1
61985575mS CMTARGET: 0.1738.0 214 American Eagle.0: ADD USER: Landmark depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
61985575mS CMCallEvt: 0.1740.0 -1 BaseEP: NEW CMEndpoint f52b5668 TOTAL NOW=11 CALL_LIST=4
61985575mS CMCallEvt: 0.1740.0 -1 Landmark.-1: NEW CMExtnEndpoint f52b5668, Name=Landmark, Extn=3274, Phys Extn=3274
61985577mS CMTARGET: 0.1740.0 214 Landmark.0: ADD PRIMARY
61985578mS CMTARGET: 0.1738.0 214 American Eagle.0: CancelTimer CMTCDelayedProcessing
61985578mS CMTARGET: 0.1738.0 214 American Eagle.0: INITIAL TARGETING SUCCEEDED
61985578mS CMTARGET: 0.1738.0 214 American Eagle.0: GetNoAnswerTimer:15
61985579mS CMCallEvt: 0.1739.0 214 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
61985580mS CMCallEvt: 0.1740.0 214 Landmark.0: StateChange: END=T CMCSIdle->CMCSOffering
61985580mS CMExtnEvt: Landmark: CMExtnHandler::SetCurrent( id: 0->1740 )
61985580mS CMExtnTx: v=3274, p1=0
CMSetup
Line: type=AnalogueExtn 7 Call: lid=0 id=29 in=0
Called[3274] Type=Default (100) Reason=CMDRdirect Calling[3226] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=9 chan=31
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=American Eagle
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=American Eagle
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=3226
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceStdPhone type=3 number=111 channel=0 rx_gain=32 tx_gain=32 ep_callid=1738 ipaddr=10.0.0.211 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Landmark
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=American Eagle
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=American Eagle
IE CMIEMohSourceId (247) MOH Source = 255
UUI type=Local [......2Pd.] [0x00 0x02 0x00 0x00 0x01 0x02 0x32 0x50 0x64 0x00 ]
Display [American Eagle]
Timed: 10/05/11 09:18
Locale: enu
61985581mS CD: CALL: 0.1738.0 BState=Idle Cut=0 Music=0.0 Aend="American Eagle(3226)" (8.13) Bend="Landmark(3274)" [Landmark(3274)] (0.0) CalledNum=3274 (Landmark) CallingNum=3226 (American Eagle) Internal=1 Time=2177 AState=Dialling
61985630mS RES: Tue 10/5/2011 09:18:17 FreeMem=69701680(1) CMMsg=6 (7) Buff=200 951 997 7454 4 Links=16590
61985630mS RES2: IP 500 5.0(26) Tasks=34 RTEngine=0 CMRTEngine=0 Timer=62 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2
61985666mS CMExtnRx: v=3274, p1=0
CMAlerting
Line: type=AnalogueExtn 7 Call: lid=0 id=29 in=0
61985666mS CMCallEvt: 0.1740.0 214 Landmark.0: StateChange: END=T CMCSOffering->CMCSRinging
61985666mS CMExtnCutEvt: 0.1738.0 214 American Eagle.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneRingBack active=CMLocalToneUnCut play_tapi_moh 0
61985667mS MH : 0.1738.0 214 American Eagle.0: FCMH PlayLocalTone CMLocalToneUnCut->CMLocalToneRingBack, generate 1
61985667mS CH : CHand::JoinGroup, cp[152]b1r0 (current group: g null), group g R1[3]
61985667mS CH : CG::AddConnection, cp[152]b1r0 to group g R1[3]
61985667mS CMExtnEvt: v=29 State, new=Ringing old=Idle,0,0,Landmark
61985668mS CMCallEvt: 0.1739.0 214 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
61985668mS CMCallEvt: 0.1738.0 214 American Eagle.0: StateChange: END=A CMCSDialling->CMCSRingBack
61985669mS CMExtnEvt: v=11 State, new=Alerting old=Dialling,0,0,American Eagle
61985669mS CMExtnTx: v=3226, p1=0
CMAlerting
Line: type=AnalogueExtn 6 Call: lid=0 id=11 in=0
BChan: slot=8 chan=13
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Landmark
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=3274
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceStdPhone type=3 number=229 channel=0 rx_gain=32 tx_gain=32 ep_callid=1740 ipaddr=10.0.0.211 apps=0
Timed: 10/05/11 09:18
61985670mS CD: CALL: 0.1738.0 BState=Ringing Cut=1 Music=2.0 Aend="American Eagle(3226)" (8.13) Bend="Landmark(3274)" [Landmark(3274)] (0.0) CalledNum=3274 (Landmark) CallingNum=3226 (American Eagle) Internal=1 Time=2266 AState=Ringing
61985672mS CMMap: a=8.13 b=0.0 R1
61986828mS EVENT: ev=380,v=(null),p1=0,p2=0,p3=0,p4=0,s1=AVRIP receive from pbx 10.0.0.212 count = 0
61987003mS H323Evt: v=0 stacknum=20 State, new=NullState, old=NullState id=-1
61987004mS H323Evt: v=0 stacknum=20 State, new=Present, old=NullState id=69
61987006mS CMLineRx: v=20
CMSetup
Line: type=IPLine 20 Call: lid=20 id=69 in=1
Called[#Kathy Bell] Type=Voicemail (102) Reason=107 SndComp Calling[3193652296@10.255.0.5] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 14748
user 'CHOICE SOLUTION' presentation Allowed
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Kathy Bell
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=CHOICE SOLUTION
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=3197315714@192.168.43.1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Kathy Bell
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=CHOICE SOLUTION
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=3193652296@10.255.0.5
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceSIPTrunk type=0 number=25 channel=0 rx_gain=32 tx_gain=32 ep_callid=1727 ipaddr=10.0.0.211 apps=0
61987006mS CMCallEvt: 0.1741.0 -1 BaseEP: NEW CMEndpoint f52b99e0 TOTAL NOW=12 CALL_LIST=4
61987007mS CH : CHand::AllocateIPConnectionPoint cp[532]b0r0[TOTAL CP:238]
61987007mS CMCallEvt: CREATE CALL:215 (f52cb354)
61987007mS CMCallEvt: 0.1742.0 -1 BaseEP: NEW CMEndpoint f53c0120 TOTAL NOW=13 CALL_LIST=4
61987011mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: SetLocalTone CMLocalToneNone->CMLocalToneCutThru
61987011mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneUnCut active=CMLocalToneNone play_tapi_moh 0
61987011mS MH : 20.69.1 215 H323TrunkEP LCMH Tone CMLocalToneNone(CMLocalToneNone)->CMLocalToneUnCut, other side's media handler is not changed (not lcmh) renego 0
61987011mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneUnCut (current CMLocalToneUnCut, prev CMLocalToneNone) in parent state CMCSIdle action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61987012mS MH : 20.69.1 215 H323TrunkEP ProcessInboundMsg, set incoming_leg to true
61987012mS CD: CALL: 20.69.1 BState=Idle Cut=1 Music=0.0 Aend="Line 20" (270.2) Bend="" [] (0.0) CalledNum=#Kathy Bell (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=5 AState=Idle
61987013mS CMCallEvt: 20.69.1 215 H323TrunkEP: StateChange: END=A CMCSIdle->CMCSDialInitiated
61987013mS CMTARGET: 20.69.1 215 H323TrunkEP: LOOKUP CALL ROUTE: type=102 called_party=#Kathy Bell sub= calling=3193652296@10.255.0.5 dir=out complete=1 ses=0
61987014mS CMTARGET: 20.69.1 215 H323TrunkEP: ADD TARGET (N): number=#Kathy Bell type=102 depth=1 nobar=1 setorig=1 ses=0
61987014mS CMTARGET: 20.69.1 215 H323TrunkEP: ADD VM TARGET
61987014mS CMTARGET: 20.69.1 215 H323TrunkEP: Voicemail for remote user Kathy Bell locally handled.
61987014mS CMTARGET: **** 20.69.1 215 H323TrunkEP: MakeVoicemailTarget pbx=<null> local=0 type=1
61987015mS CMMap: a=4.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 16
61987015mS CMCallEvt: 0.1743.0 -1 BaseEP: NEW CMEndpoint f52fda68 TOTAL NOW=14 CALL_LIST=5
61987015mS CMTARGET: 0.1743.0 215 RAS.0: ADD PRIMARY
61987015mS CMTARGET: 20.69.1 215 H323TrunkEP: ADD VM TARGET: SUCCEEDED
61987016mS CMTARGET: 20.69.1 215 H323TrunkEP: INITIAL TARGETING SUCCEEDED
61987016mS CMTARGET: 20.69.1 215 H323TrunkEP: SetNoAnswerTimer Not Starting: 5714
61987016mS CMCallEvt: 20.69.1 215 H323TrunkEP: StateChange: END=A CMCSDialInitiated->CMCSDialled
61987017mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessInboundMsg --- msg: CMSetup f52f3704 fs 0 h245 0 reinvite 0 fs size 0
61987017mS VOIP: (In) 20.69.1 215 H323TrunkEP: SetLocalState LCIdle -> LCFSDisabled
61987017mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessOutboundCCMsg --- msg: CMProceeding f52f8c38 fs 0 h245 0 fs size 0
61987017mS CMLineTx: v=20
CMProceeding
Line: type=IPLine 20 Call: lid=20 id=69 in=1
61987018mS CMCallEvt: 0.1742.0 215 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
61987019mS CMCallEvt: 0.1743.0 215 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
61987019mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1743 )
61987019mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#Kathy Bell] Type=Voicemail (102) Reason=107 SndComp Calling[3193652296@10.255.0.5] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 14748
user 'CHOICE SOLUTION' presentation Allowed
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Kathy Bell
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=CHOICE SOLUTION
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=3197315714@192.168.43.1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Kathy Bell
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=CHOICE SOLUTION
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=3193652296@10.255.0.5
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceSIPTrunk type=0 number=25 channel=0 rx_gain=32 tx_gain=32 ep_callid=1727 ipaddr=10.0.0.211 apps=0
Display [CHOICE SOLUTION>Kathy Bell]
Timed: 10/05/11 09:18
Locale: enu
61987020mS CMMap: a=21.42 b=1.254 T
61987021mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Kathy Bell
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1743 ipaddr=10.0.0.211 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
61987022mS CMCallEvt: 0.1743.0 215 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
61987022mS CMCallEvt: 0.1742.0 215 TargetingEP: RequestEnd 0.1743.0 215 RAS.0
61987023mS CMExtnCutEvt: 0.1743.0 215 RAS.0: SetLocalTone CMLocalToneNone->CMLocalToneCutThru
61987023mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneUnCut play_tapi_moh 0
61987024mS MH : 20.69.1 215 H323TrunkEP LCMH Tone CMLocalToneUnCut(CMLocalToneUnCut)->CMLocalToneCutThru, other side's media handler is not changed (not lcmh) renego 0
61987024mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneUnCut) in parent state CMCSDialled action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61987024mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
61987024mS CMCallEvt: 20.69.1 215 H323TrunkEP: StateChange: END=A CMCSDialled->CMCSOGConnReq
61987025mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987025mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSOGConnReq action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61987025mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessOutboundCCMsg --- msg: CMConnect f52c44d4 fs 0 h245 0 fs size 0
61987026mS CMLineTx: v=20
CMConnect
Line: type=IPLine 20 Call: lid=20 id=69 in=1
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Kathy Bell
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1743 ipaddr=10.0.0.211 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageUtf8String
invokeId 14695
user '#Kathy Bell' presentation Allowed
IE CMIEPrimitiveData (7)
61987027mS CMCallEvt: 0.1742.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
61987027mS CMCallEvt: 0.1742.0 -1 BaseEP: DELETE CMEndpoint f53c0120 TOTAL NOW=13 CALL_LIST=5
61987028mS CD: CALL: 20.69.1 BState=Connected Cut=3 Music=0.0 Aend="Line 20" (270.2) Bend="Kathy Bell(5714)" [VoiceMail] (21.42) CalledNum=#Kathy Bell (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=21 AState=Connected
61987031mS H323Evt: v=0 stacknum=20 State, new=ICProceeding, old=Present id=69
61987032mS H323Evt: v=0 stacknum=20 State, new=Active, old=ICProceeding id=69
61987035mS CMLineRx: v=20
CMConnectAck
Line: type=IPLine 20 Call: lid=20 id=69 in=1
61987035mS CMCallEvt: 20.69.1 215 H323TrunkEP: StateChange: END=A CMCSOGConnReq->CMCSConnected
61987035mS CMTARGET: 20.69.1 215 H323TrunkEP: ~CMTargetHandler f52d28b0 ep f52b99e0
61987036mS CMCallEvt: 0.1743.0 215 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
61987037mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987037mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSConnected action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987038mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessInboundMsg --- msg: CMConnectAck f52c44d4 fs 0 h245 0 reinvite 0 fs size 0
61987038mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[3193652296@10.255.0.5] Type=Unknown Plan=Default Pres=Allowed (0)
BChan: slot=21 chan=42
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=CHOICE SOLUTION
61987046mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
IE CMIEPrimitiveData (7)
61987047mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987047mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSConnected action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987047mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessInboundMsg --- msg: CMFacility f52f3704 fs 0 h245 f531df28 reinvite 0 fs size 0
61987048mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987048mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSConnected action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987048mS VOIP: (In) 20.69.1 215 H323TrunkEP: SetLocalState LCFSDisabled -> LCH245Nego
61987048mS VOIP: (In) 20.69.1 215 H323TrunkEP: ESH225 registered for the following events: OLC_Finished
61987049mS CMExtnCutEvt: 0.1743.0 215 RAS.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneNone play_tapi_moh 0
61987049mS MH : 0.1743.0 215 RAS.0: FCMH PlayLocalTone CMLocalToneNone->CMLocalToneCutThru, generate 0
61987049mS CH : CHand::Map rx cp[76]b1r0 (g:g null) tx cp[532]b0r0 (g:g null)
61987049mS CH : Allocate CGTypeSimple group g G[203]
61987050mS CH : CHand::JoinGroup, cp[76]b1r0 (current group: g null), group g G[203]
61987050mS CH : CG::AddConnection, cp[76]b1r0 to group g G[203]
61987050mS CH : CHand::JoinGroup, cp[532]b0r0 (current group: g null), group g G[203]
61987050mS CH : CG::AddConnection, cp[532]b0r0 to group g G[203]
61987050mS CMExtnTx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
61987051mS CMMap: PCG::MapBChan pcp[76]b1r0 cp_b f59091f4 other_cp_b 0 type CGTypeSimple
61987058mS H323Evt: v=0 stacknum=20 State, new=Active, old=Delivered id=1729
61987063mS CMLineRx: v=20
CMConnect
Line: type=IPLine 20 Call: lid=0 id=1729 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14749
user '#Kathy Bell' presentation Allowed
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Kathy Bell
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1743 ipaddr=10.0.0.211 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIEPrimitiveData (7)
61987064mS MH : 0.1729.0 211 H323TrunkEP ProcessInboundMsg, set incoming_leg to false
61987064mS CMCallEvt: 0.1729.0 211 H323TrunkEP: StateChange: END=B CMCSRinging->CMCSConnReq
61987064mS CMCallEvt: 25.1727.1 211 SIPTrunk Endpoint: StateChange: END=A CMCSRingBack->CMCSOGConnReq
61987064mS CMExtnCutEvt: 0.1729.0 211 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneNone play_tapi_moh 0
61987065mS MH : 0.1729.0 211 H323TrunkEP LCMH Tone CMLocalToneNone(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is not changed (lcmh) renego 0
61987065mS MH : 0.1729.0 211 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneNone) in parent state CMCSConnReq action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987065mS MH : 0.1729.0 211 H323TrunkEP LCMH Tone CMLocalToneCutThru(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is not changed (lcmh) renego 0
61987065mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: ProcessInboundMsg --- msg: CMConnect f52c44d4 fs 0 h245 f531df28 reinvite 0 fs size 0
61987066mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: SetLocalState LCFSInitialize -> LCFSDisabled
61987066mS CMExtnCutEvt: 0.1729.0 211 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987066mS MH : 0.1729.0 211 H323TrunkEP LCMH Tone CMLocalToneCutThru(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is not changed (lcmh) renego 0
61987066mS MH : 0.1729.0 211 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneNone) in parent state CMCSConnReq action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987067mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: SetLocalState LCFSDisabled -> LCH245Nego
61987067mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: ESH225 registered for the following events: OLC_Finished
61987068mS CMExtnCutEvt: 25.1727.1 211 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneRingBack play_tapi_moh 0
61987068mS MH : 25.1727.1 211 SIPTrunk Endpoint LCMH Tone CMLocalToneNone(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is changed (lcmh) renego 0
61987068mS MH : 25.1727.1 211 SIPTrunk Endpoint LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneNone) in parent state CMCSOGConnReq action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61987068mS CMExtnCutEvt: 25.1727.1 211 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987069mS MH : 25.1727.1 211 SIPTrunk Endpoint PlayPendingLocalTone lcmh_media_handler_changed is still true...
61987069mS MH : 25.1727.1 211 SIPTrunk Endpoint LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSOGConnReq action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61987069mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: ProcessOutboundCCMsg --- msg: CMConnect f52c44d4 fs 0 h245 f531df28 fs size 0
61987069mS MH : 25.1727.1 211 SIPTrunk Endpoint PlayPendingLocalTone lcmh_media_handler_changed is still true...
61987070mS MH : 25.1727.1 211 SIPTrunk Endpoint UnsetLcmhChanged other_mh f53b7ec8 lcmh 1
61987070mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: ProcessInboundCCMsg, delay the message, size 1
61987071mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: CMVoipNegotiator::processOutboundCCMsg, suppress CMConnect
61987071mS CMLineTx: v=0
CMFacility
Line: type=IPLine 25 Call: lid=25 id=1727 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14749
user '#Kathy Bell' presentation Allowed
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Kathy Bell
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1743 ipaddr=10.0.0.211 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIEPrimitiveData (7)
61987071mS Sip: 25.1727.1 211 SIPTrunk Endpoint(f52c2468) received CMFacility
61987072mS CMExtnCutEvt: 0.1729.0 211 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987072mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: ProcessOutboundCCMsg --- msg: CMFacility f5304578 fs 0 h245 f5315bf4 fs size 0
61987073mS CMLineTx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=0 id=1729 in=0
IE CMIEPrimitiveData (7)
61987074mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallTransferIdentity.Invoke.CodePageUnknown
invokeId 14750
call identity 0 redirection number ''
61987074mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987074mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSConnected action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987075mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessInboundMsg --- msg: CMFacility f53c305c fs 0 h245 0 reinvite 0 fs size 0
61987075mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessOutboundCCMsg --- msg: CMFacility f52f8c38 fs 0 h245 0 fs size 0
61987075mS CMLineTx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallTransferIdentity.ReturnResult.CodePageUtf8String
invokeId 14750
call identity 1016 redirection number ''
61987076mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[3193652296@10.255.0.5] Type=Unknown Plan=Default Pres=Allowed (0)
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=CHOICE SOLUTION
61987076mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987077mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSConnected action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987077mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessInboundMsg --- msg: CMFacility f53c2bf0 fs 0 h245 0 reinvite 0 fs size 0
61987077mS CMExtnTx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[3193652296@10.255.0.5] Type=Unknown Plan=Default Pres=Allowed (0)
BChan: slot=21 chan=42
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=CHOICE SOLUTION
61987078mS CD: CALL: 25.1727.1 BState=Connected Cut=3 Music=0.0 Aend="Line 25" (0.0) Bend="Kathy Bell(5714)" [Line 20] (270.1) CalledNum=5714 (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=25114 AState=Connected
61987078mS CD: CALL: 20.69.1 BState=Connected Cut=3 Music=0.0 Aend="Line 20" (270.2) Bend="Kathy Bell(5714)" [VoiceMail] (21.42) CalledNum=#Kathy Bell (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=71 AState=Connected
61987086mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=0 id=1729 in=0
IE CMIEPrimitiveData (7)
61987087mS CMExtnCutEvt: 0.1729.0 211 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987087mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: ProcessInboundMsg --- msg: CMFacility f53c305c fs 0 h245 f531df28 reinvite 0 fs size 0
61987087mS CMExtnCutEvt: 25.1727.1 211 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987088mS CMExtnCutEvt: 25.1727.1 211 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987088mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: ProcessOutboundCCMsg --- msg: CMFacility f53c305c fs 0 h245 f531df28 fs size 0
61987088mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: SetLocalState LCFSResponse -> LCH245Nego
61987088mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: ESH225 registered for the following events: OLC_Finished
61987089mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: ProcessInboundCCMsg, delay the message, size 1
61987089mS CMExtnCutEvt: 0.1729.0 211 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987090mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: ProcessOutboundCCMsg --- msg: CMFacility f53c2bf0 fs 0 h245 f52f25e8 fs size 0
61987090mS VOIP: (Out) 0.1729.0 211 H323TrunkEP: CMVoipNegotiator::processOutboundCCMsg, reset h245 count_loops from 1 to 0
61987090mS CMLineTx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=0 id=1729 in=0
IE CMIEPrimitiveData (7)
61987091mS CD: CALL: 25.1727.1 BState=Connected Cut=3 Music=0.0 Aend="Line 25" (0.0) Bend="Kathy Bell(5714)" [Line 20] (270.1) CalledNum=5714 (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=25127 AState=Connected
61987097mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
IE CMIEPrimitiveData (7)
61987098mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987098mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSConnected action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987098mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessInboundMsg --- msg: CMFacility f53c305c fs 0 h245 f52f25e8 reinvite 0 fs size 0
61987099mS CMLineTx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
IE CMIEPrimitiveData (7)
61987099mS CMExtnTx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
61987100mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
IE CMIEPrimitiveData (7)
61987100mS CMExtnCutEvt: 20.69.1 215 H323TrunkEP: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987100mS MH : 20.69.1 215 H323TrunkEP LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSConnected action 1 renegotiate 0 voip_negotiator->processing_inbound 1
61987101mS VOIP: (In) 20.69.1 215 H323TrunkEP: ProcessInboundMsg --- msg: CMFacility f52c44d4 fs 0 h245 f531df28 reinvite 0 fs size 0
61987101mS CMLineTx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=20 id=69 in=1
IE CMIEPrimitiveData (7)
61987102mS CMExtnTx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
61987102mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=0 id=1729 in=0
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallTransferInitiate.Invoke.CodePageUnknown
invokeId 14751
call identity 1016 redirection number ''
61987102mS CMTARGET: TransferProposal: X_call: ID=211 Aend=3193652296@10.255.0.5 ->
61987103mS CMTARGET: TransferProposal: Y_call: ID=215 Aend=3193652296@10.255.0.5 ->
61987103mS CMTARGET: Surviving Call=211
61987103mS CMTARGET: Goodbying Call=215
61987103mS CMTARGET: AEND 3193652296@10.255.0.5 -> BEND
61987107mS CMCallEvt: 0.1729.0 211 H323TrunkEP: CompleteTransfer to 20.69.1 (20.69.1 215 H323TrunkEP) (Return @ 0ms -> null) ssdata 0
61987108mS CMLineTx: v=20
CMReleaseComp
Line: type=IPLine 20 Call: lid=0 id=1729 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallTransferInitiate.ReturnResult.CodePageUtf8String
invokeId 14751
call identity 0 redirection number ''
Cause=126, Transfer(IPO)
61987108mS CMCallEvt: 0.1729.0 -1 H323TrunkEP: StateChange: END=X CMCSConnReq->CMCSDelete
61987108mS CMExtnCutEvt: 0.1729.0 -1 H323TrunkEP: SetLocalTone CMLocalToneCutThru->CMLocalToneNone
61987108mS CMExtnCutEvt: 0.1729.0 -1 H323TrunkEP: AudioConnectionChange current=CMLocalToneNone new=CMLocalToneNone active=CMLocalToneCutThru play_tapi_moh 0
61987109mS CMExtnCutEvt: 0.1743.0 211 RAS.0: SetLocalTone CMLocalToneCutThru->CMLocalToneCutThru
61987109mS CMExtnCutEvt: 25.1727.1 211 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987109mS MH : 25.1727.1 211 SIPTrunk Endpoint LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSOGConnReq action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61987110mS CMExtnCutEvt: 0.1743.0 211 RAS.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneNone active=CMLocalToneCutThru play_tapi_moh 0
61987110mS MH : 0.1743.0 211 RAS.0: FCMH PlayLocalTone CMLocalToneCutThru->CMLocalToneNone, generate 0
61987110mS CH : CHand::JoinGroup, cp[76]b1r0 (current group: g G[203]), group g null
61987110mS CH : CG::RemoveConnection, cp[76]b1r0 group g G[203]
61987111mS CMExtnTx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=CHOICE SOLUTION
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=3193652296@10.255.0.5
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceSIPTrunk type=0 number=25 channel=0 rx_gain=32 tx_gain=32 ep_callid=1727 ipaddr=10.0.0.211 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
Cause=126, Transfer(IPO)
61987111mS CMExtnTx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=CHOICE SOLUTION
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=3193652296@10.255.0.5
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceSIPTrunk type=0 number=25 channel=0 rx_gain=32 tx_gain=32 ep_callid=1727 ipaddr=10.0.0.211 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallTransferComplete.Invoke.CodePageUnknown
invokeId 14696
redirection name 'CHOICE SOLUTION' redirection number '3193652296' primary end answered
61987112mS CMExtnCutEvt: 25.1727.1 211 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61987112mS MH : 25.1727.1 211 SIPTrunk Endpoint PlayPendingLocalTone lcmh_media_handler_changed is still true...
61987113mS MH : 25.1727.1 211 SIPTrunk Endpoint LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneCutThru) in parent state CMCSOGConnReq action 1 renegotiate 0 voip_negotiator->processing_inbound 0
61987113mS VOIP: (In) 25.1727.1 211 SIPTrunk Endpoint: ProcessOutboundCCMsg --- msg: CMFacility f52c44d4 fs 0 h245 0 fs size 0
61987113mS MH : 25.1727.1 211 SIPTrunk Endpoint PlayPendingLocalTone lcmh_media_handler_changed is still true...
61987113mS CMLineTx: v=0
CMFacility
Line: type=IPLine 25 Call: lid=25 id=1727 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Kathy Bell
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1743 ipaddr=10.0.0.211 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Kathy Bell
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=5714
Cause=126, Transfer(IPO)
61987114mS Sip: 25.1727.1 211 SIPTrunk Endpoint(f52c2468) received CMFacility
61987114mS CD: CALL: 25.1727.1 BState=Connected Cut=3 Music=0.0 Aend="Line 25" (0.0) Bend="Kathy Bell(5714)" [VoiceMail] (21.42) CalledNum=#Kathy Bell (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=25150 AState=Connected
61987115mS CMLOGGING: CALL:2011/05/1009:18,00:00:01,000,3193652296@10.255.0.5,I,#KathyBell,#KathyBell,CHOICESOLUTION,,,0,,""n/a,0
61987116mS CD: CALL: 25.1727.1 BState=Idle Cut=3 Music=0.0 Aend="Line 20" (270.2) Bend="" [] (0.0) CalledNum=#Kathy Bell (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=108 AState=Connected
61987116mS CD: CALL: 25.1727.1 Deleted
61987117mS CMLineTx: v=20
CMReleaseComp
Line: type=IPLine 20 Call: lid=20 id=69 in=1
Cause=126, Transfer(IPO)
61987117mS CMCallEvt: 20.69.1 -1 H323TrunkEP: StateChange: END=X CMCSConnected->CMCSDelete
61987117mS CMExtnCutEvt: 20.69.1 -1 H323TrunkEP: SetLocalTone CMLocalToneCutThru->CMLocalToneNone
61987118mS CMExtnCutEvt: 20.69.1 -1 H323TrunkEP: AudioConnectionChange current=CMLocalToneNone new=CMLocalToneNone active=CMLocalToneCutThru play_tapi_moh 0
61987118mS CMCallEvt: END CALL:215 (f52cb354)
61987123mS CH : CHand::FreeConnectionPoint cp[532]b0r0[TOTAL CP:237]
61987123mS CMCallEvt: 20.69.1 -1 BaseEP: DELETE CMEndpoint f52b99e0 TOTAL NOW=12 CALL_LIST=4
61987124mS CH : CHand::FreeConnectionPoint cp[525]b0r0[TOTAL CP:236]
61987125mS CMCallEvt: 0.1729.0 -1 BaseEP: DELETE CMEndpoint f5316b28 TOTAL NOW=11 CALL_LIST=4
61987125mS CD: CALL: 25.1727.1 BState=Connected Cut=3 Music=0.0 Aend="Line 25" (0.0) Bend="Kathy Bell(5714)" [VoiceMail] (21.42) CalledNum=#Kathy Bell (Kathy Bell) CallingNum=3193652296@10.255.0.5 (CHOICE SOLUTION) Internal=0 Time=25161 AState=Connected
61987127mS CMMap: PCG::UnmapBChan pcp[76]b1r0 cp_b f59091f4 other_cp_b 0
61987128mS CH : CG::RemoveConnection, cp[532]b0r0 group g G[203]
61987128mS CMMap: a=4.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 0, total 16
61987128mS CH : Free CGTypeSimple group g G[203]
61987130mS H323Evt: v=0 stacknum=20 State, new=ReleaseReq, old=Active id=1729
61987131mS H323Evt: v=0 stacknum=20 State, new=NullState, old=ReleaseReq id=1729
61987132mS H323Evt: v=0 stacknum=20 State, new=ReleaseReq, old=Active id=69
61987132mS H323Evt: v=0 stacknum=20 State, new=NullState, old=ReleaseReq id=69
61994051mS PRN: 6713: Digit Pressed 2
61994052mS CMExtnRx: v=6713, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=260 id=1724 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
61994053mS CMExtnCutEvt: CMMediaEndpoint::playTune 260.1724.0 210 Roger Weisenbec.0 2 inbound 1 bothway 0 continuous 0
61994053mS CMExtnCutEvt: 260.1724.0 210 Roger Weisenbec.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61994053mS CMExtnCutEvt: 25.1736.0 210 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61994053mS CH : DtmfPlayer created f52f4fe4(36) callback f5f8602c dtmfs size 0
61994054mS CH : CC::GenerateTones ok cp[522]b0r1 tone 2, dtmfs size 0 dtmf_player f52f4fe4 dtmf_player_unique_id 36
61994054mS CMExtnCutEvt: 260.1724.0 210 Roger Weisenbec.0: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61994054mS VOIP: (In) 260.1724.0 210 Roger Weisenbec.0: ProcessInboundMsg --- msg: CMInformation f52f8c38 fs 0 h245 0 reinvite 0 fs size 0
61994055mS CMExtnCutEvt: 25.1736.0 210 SIPTrunk Endpoint: AudioConnectionChange current=CMLocalToneCutThru new=CMLocalToneCutThru active=CMLocalToneCutThru play_tapi_moh 0
61994055mS VOIP: (Out) 25.1736.0 210 SIPTrunk Endpoint: ProcessOutboundCCMsg --- msg: CMInformation f52f8c38 fs 0 h245 0 fs size 0
61994055mS CMLineTx: v=0
CMInformation
Line: type=IPLine 25 Call: lid=25 id=1736 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Roger Weisenbec
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Roger Weisenbeck
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=6713
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=5 class=CMDeviceH32
 
Sort by date Sort by votes
I am having very similar issues on 6.1 and 7.0

Kevin Wing
ACSS Small and Medium Enterprise (SME) Communications
ACS- Implement IP Office
ACA- Implement IP Office
Carousel Industries
 
Upvote 0 Downvote
Do you guys get silence perhaps?

BAZINGA!

I'm not insane, my mother had me tested!
 
Upvote 0 Downvote
On which end peter?

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
Caller or both sides.


BAZINGA!

I'm not insane, my mother had me tested!
 
Upvote 0 Downvote
No I have not tried that..

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
61983415mS CMMap: a=8.13 b=0.0 D1
61984903mS CMMap: a=8.13 b=0.0 D0
61985672mS CMMap: a=8.13 b=0.0 R1
61987015mS CMMap: a=4.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 16

Looks like a VCM/licence problem?!

-D1 indicates that DIAL TONE has just been turned ON on the channel.
-D0 indicates that DIAL TONE has just been turned OFF on the channel.
-R1 indicates that RINGING TONE has just been turned ON on the channel.


Avaya_Red.gif

___________________________________________
It works! Now if only I could remember what I did...

Dain Bramaged (Avaya Search tool )
______________________________________
 
Upvote 0 Downvote
Both systems have 16 VCM channels and Voicemail licenses. Not seeing any congestion's.

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
What's this about?

2851684mS Sip: Unsupported sip incoming method on trunk interface <ACK> - ignoring
2851684mS Sip: Cannot accept call: reason=405 (Method Not Allowed)

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
Did you try to recreate the trunk?

busy 1, total 16 <<< so i think it means 1 of 16 is busy. So a licence is not the issue i guess. A bad VCM could be a second option.

Avaya_Red.gif

___________________________________________
It works! Now if only I could remember what I did...

Dain Bramaged (Avaya Search tool )
______________________________________
 
Upvote 0 Downvote
I will try and recreate the trunk and get back to you shortly..


ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
Try to add 3 *'s on the SIP URI? Just aad a new SIP URI and instead of Use User Data put a * in all 3 pulldown menu's.

Avaya_Red.gif

___________________________________________
It works! Now if only I could remember what I did...

Dain Bramaged (Avaya Search tool )
______________________________________
 
Upvote 0 Downvote
Rebuilding the trunk did not help.

What is the purpose in changing the URI data to *'s?

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
IP Office 5.0+: If the wildcard * is used in the SIP trunk's Local URI, Contact and Display fields, that SIP trunk will accept any incoming SIP call. The incoming call routing is still performed by the system incoming call routes based on matching the values received with the call or the URI's incoming group setting. Outgoing calls using the trunk will need to use another URI set against the trunk.

Avaya_Red.gif

___________________________________________
It works! Now if only I could remember what I did...

Dain Bramaged (Avaya Search tool )
______________________________________
 
Upvote 0 Downvote
The system wont accept any calls to the URI using the *'s. I created new routes for it and pointed them at local and remote users no luck..

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
I also noticed these calls are generating PRI License errors? There is no pRI or licenses.

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
Downgraded the firmware and it is still not working.

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
IP office is directly connected to the Paetec router via the WAN port no firewall.

ACA - IP Office
ACSS - SMEC (IP Office)
APSS - SMEC (IP Office)
JNCIA-EX
JNCIA-ER
JNCIA-FWV
Convergence+
 
Upvote 0 Downvote
Status
Not open for further replies.

Similar threads

sheldoom
  • Question
No response to SIP Options Messages
Replies
1
Views
173
sheldoom
sheldoom
Diya Freahat
  • Locked
  • Question
SIP trunk - no audio
Replies
9
Views
633
derfloh
derfloh
Westi
  • Locked
  • Question
upgrade path to R12.0 for IP500V2 or IP500V2A 1
Replies
13
Views
945
Westi
Westi
joestar
  • Question
Caller ID (CLID) Not Passing to Twinned Phone with SIP
Replies
7
Views
450
Menniss
Menniss
MartinDT
  • Locked
  • Question
Upgrade R11 to R12 License
Replies
8
Views
360
sizbut
sizbut

Part and Inventory Search

Sponsor

Back
Top