SIP Trunks dropping out

[slamminhammo]

I have a customer with SIP trunks provided by Gamma.

The switch is a 500v2 at 6.1.5

Outgoing calls ok but incoming calls drop out after about 30 seconds.

Customer is using a Draytek Router, in Nat Mode.
Opened up ports 5060 -5080 TCP and UDP, 4000 - 60000 UDP, 3478 TCP/UDP going to Avaya IPO.

I am sure i have had this before in the dim distant past, but a senior moment does not allow me to remeber what the problem was.

Can anyone help please?

Thanks

 

[hairlessupportmonkey]

what does a monitor trace say?

ACSS - SME

 

[TheProvider]

Call cuts off are probably going to be related to connectivity qos issues. Or a common one we have is NAT refresh on the firewalls.
Where by the firewall only holds the connection open for a particular amount of time before shutting down.

As above though what is being sent or recieved from the provider.
Are you sending a bye or are you?

 

[amriddle01]

Best to use STUN too, opening/forwarding all those ports is crazy plus Drayteks are not the most reliable things in the world when it comes to port forwarding

ACSS (SME)
APSS (SME)

http://www.ipoffice.tel

"I'm just off to Hartlepool to buy some exploding trousers

 

[hairlessupportmonkey]

make sure the Draytek is on the latest firmware too. I have a 2820N at home and its rock solid.

We also have a number of them out there specifically for SIP trunks and generally dont have too many issues. just the occasional lock up.

ACSS - SME

 

[DBrewsky]

Make sure the NAT is a Static or Consistent NAT.

--DB

http://support.csctech.us

Remote Support Specialist

 

[slamminhammo]

Hi Guys

thank you very much for your help so far. Just received a trace from the client. Any comments on it would be appreciated

Thanks



UNICODE-UTF8
eng
49323mS SIP Rx: UDP 198.65.166.165:3478 -> 192.168.1.230:5060



49522mS SIP Rx: UDP 198.65.166.167:3479 -> 192.168.1.230:5060



49524mS PRN: ObjectMemoryPool: SSIComponentId, filled 5 times
49527mS PRN: Creating SIP Trunk with proxy: 83.245.6.81
49527mS PRN: SIPTrunk 83.245.6.81: proxy address change from [] to [83.245.6.81]
49655mS RES: Thu 3/2/2011 09:53:44 FreeMem=70711728(1) CMMsg=2 (2) Buff=200 960 1000 7479 5 Links=3000
49655mS RES2: IP 500 V2 6.1(5) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
50191mS PRN: Created WAV file MOH Source 1
50460mS PRN: Loading HoldMusic.wav from ipaddr=255.255.255.255
52511mS SIP Rx: UDP 83.245.6.81:5060 -> 192.168.1.230:5060
INVITE sip:01277282081@80.176.88.37 SIP/2.0
Max-Forwards: 69
Session-Expires: 3600;refresher=uac
Supported: timer, 100rel
To: <sip:01277282081@83.245.6.81:5060>
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
Remote-Party-Id: <sip:07970957140@83.245.6.81>;party=calling;screen=yes;privacy=off
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Allow: INVITE, BYE, OPTIONS, CANCEL, ACK, REGISTER, NOTIFY, INFO, REFER, SUBSCRIBE, PRACK, UPDATE
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
Contact: <sip:07970957140@83.245.6.81:5060>
Expires: 180
Call-Info: <sip:83.245.6.81>;method="NOTIFY;Event=telephone-event;Duration=1000"
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Length: 260

v=0
o=MSX12 2336 2726 IN IP4 83.245.6.81
s=sip call
c=IN IP4 83.245.6.82
t=0 0
m=audio 37612 RTP/AVP 8 0 100 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
52516mS CMCallEvt: 0.1002.0 -1 BaseEP: NEW CMEndpoint f54a4474 TOTAL NOW=1 CALL_LIST=0
52518mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
To: <sip:01277282081@83.245.6.81:5060>;tag=d9dca27f465b7c0e
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

52521mS CMCallEvt: CREATE CALL:1 (f549be20)
52521mS CMCallEvt: 0.1003.0 -1 BaseEP: NEW CMEndpoint f549aa0c TOTAL NOW=2 CALL_LIST=0
52523mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=1002 in=1
Called[01277282081] Type=Default (100) Reason=CMDRdirect SndComp Calling[07970957140@83.245.6.81] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6)
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07970957140@83.245.6.81
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceSIPTrunk type=0 number=17 channel=0 rx_gain=32 tx_gain=32 ep_callid=1002 ipaddr=192.168.1.230 apps=0
52523mS CD: CALL: 17.1002.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=01277282081 () CallingNum=07970957140@83.245.6.81 () Internal=0 Time=3 AState=Idle
52523mS CMCallEvt: 17.1002.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
52524mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: LOOKUP CALL ROUTE: type=100 called_party=01277282081 sub= calling=07970957140@83.245.6.81 dir=in complete=1 ses=0
52524mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: SET BESTMATCH: length 0 vs -1 match= dest=Main
52524mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: SET BESTMATCH: length 11 vs 0 match=01277282081 dest=Reception
52524mS CMCallEvt: Priority hike: call 1 priority 0->1
52524mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: LOOKUP ICR: DDI=01277282081 CGPN=07970957140@83.245.6.81 (Destination Reception ) => CDPN=Reception
52525mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: ADD TARGET (N): number=Reception type=100 depth=1 nobar=1 setorig=1 ses=0
52525mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: HG(Reception,401,192.168.1.230) Requires Routing To Master(1). IsLocalExecutive(1)
52525mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: HG call targeting occuring here
52525mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: PrimeForHGTarget: Reception setorig=1 recall=0 resetExtnVars 1
52526mS CMCallEvt: Priority hike: call 1 priority 1->5
52526mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: AddHGTarget Reception (depth=2) allowq=1 type=CMNTypeDefault
52526mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint f5493854 TOTAL NOW=3 CALL_LIST=1
52527mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: INITIAL TARGETING SUCCEEDED
52527mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: GetNoAnswerTimer:15
52527mS CMCallEvt: 17.1002.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSDialInitiated->CMCSDialled
52528mS CMLineTx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=1002 in=1
52528mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
52529mS CMCallEvt: 0.1004.0 1 ACDTep(Reception): StateChange: END=T CMCSIdle->CMCSOffering
52529mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
52530mS CMCallEvt: 0.1004.0 1 ACDTep(Reception): StateChange: END=T CMCSOffering->CMCSRinging
52530mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
52530mS CMCallEvt: 17.1002.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSDialled->CMCSRingBack
52531mS CMLineTx: v=0
CMAlerting
Line: type=SIPLine 17 Call: lid=17 id=1002 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Reception
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:100 N:100 R:4) number=401
52532mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
To: <sip:01277282081@83.245.6.81:5060>;tag=d9dca27f465b7c0e
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Contact: "01277282081" <sip:01277282081@192.168.1.230:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

52535mS CD: CALL: 17.1002.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 17" (0.0) Bend="Reception(401)" [] (0.0) CalledNum=401 (Reception) CallingNum=07970957140@83.245.6.81 () Internal=0 Time=15 AState=Ringing
52535mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: PrimeForHGTarget: Reception setorig=0 recall=0 resetExtnVars 1
52535mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: AddHGTarget Reception (depth=0) allowq=0 type=CMNTypeDefault
52536mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: AddHGTargetRingGroup Reception starting at 0
52536mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: ADD USER: ReceptionUser depth=1 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeDefault) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
52536mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint f548f7f4 TOTAL NOW=4 CALL_LIST=1
52536mS CMCallEvt: 0.1005.0 -1 ReceptionUser.-1: NEW CMExtnEndpoint f548f7f4, Name=ReceptionUser, Extn=523, Phys Extn=523
52537mS CMTARGET: 304.1005.0 1 ReceptionUser.0: ADD PRIMARY
52538mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: ADD USER: RebeccaS depth=1 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeDefault) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000
52538mS CMCallEvt: 0.1006.0 -1 BaseEP: NEW CMEndpoint f548c1c0 TOTAL NOW=5 CALL_LIST=1
52538mS CMCallEvt: 0.1006.0 -1 RebeccaS.-1: NEW CMExtnEndpoint f548c1c0, Name=RebeccaS, Extn=530, Phys Extn=530
52539mS CMTARGET: 253.1006.0 1 RebeccaS.0: ADD PRIMARY
52540mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: SelectTargetAvailable 2 targets
52541mS CMCallEvt: 304.1005.0 1 ReceptionUser.0: StateChange: END=T CMCSIdle->CMCSOffering
52541mS CMExtnEvt: ReceptionUser: CMExtnHandler::SetCurrent( id: 0->1005 )
52542mS CMExtnTx: v=523, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=304 id=1005 in=0
Called[523] Type=Default (100) Reason=CMDRdirect SndComp Calling[07970957140@83.245.6.81] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Reception
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Reception
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=401
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=401
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=01277282081
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07970957140@83.245.6.81
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceSIPTrunk type=0 number=17 channel=0 rx_gain=32 tx_gain=32 ep_callid=1002 ipaddr=192.168.1.230 apps=0
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIEFastStartInfoData (6)
IE CMIECallSignalChannelData (8) unknown
UUI type=User2User [401..] [0x34 0x30 0x31 0xff 0xff ]
Display [07970957140>Reception]
Timed: 03/02/11 09:53
Locale: eng
52542mS CMExtnRx: v=523, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=304 id=1005 in=0
52542mS CMCallEvt: 304.1005.0 1 ReceptionUser.0: StateChange: END=T CMCSOffering->CMCSRinging
52542mS CMExtnEvt: v=2 State, new=Ringing old=Idle,0,0,ReceptionUser
52544mS CMCallEvt: 253.1006.0 1 RebeccaS.0: StateChange: END=T CMCSIdle->CMCSOffering
52545mS CMExtnEvt: RebeccaS: CMExtnHandler::SetCurrent( id: 0->1006 )
52546mS CMExtnTx: v=530, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=253 id=1006 in=0
Called[530] Type=Default (100) Reason=CMDRdirect SndComp Calling[07970957140@83.245.6.81] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Reception
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Reception
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=401
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=401
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=01277282081
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07970957140@83.245.6.81
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceSIPTrunk type=0 number=17 channel=0 rx_gain=32 tx_gain=32 ep_callid=1002 ipaddr=192.168.1.230 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIEFastStartInfoData (6)
IE CMIECallSignalChannelData (8) unknown
UUI type=User2User [401..] [0x34 0x30 0x31 0xff 0xff ]
Display [07970957140>Reception]
Timed: 03/02/11 09:53
Locale: eng
52546mS CMExtnRx: v=530, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=253 id=1006 in=0
52546mS CMCallEvt: 253.1006.0 1 RebeccaS.0: StateChange: END=T CMCSOffering->CMCSRinging
52546mS CMExtnEvt: v=1 State, new=Ringing old=Idle,0,0,RebeccaS
52548mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: GetNoAnswerTimer:15
52549mS CD: CALL: 17.1002.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 17" (0.0) Bend="Reception(401)" [ReceptionUser(523),RebeccaS(530)] (0.0) CalledNum=401 (Reception) CallingNum=07970957140@83.245.6.81 () Internal=0 Time=29 AState=Ringing
54199mS CMExtnRx: v=530, p1=0
CMConnect
Line: type=IPLine 250 Call: lid=253 id=1006 in=0
IE CMIEFastStartInfoData (6)
IE CMIEFastStartInfoData (6)
54200mS CMCallEvt: 253.1006.0 1 RebeccaS.0: StateChange: END=T CMCSRinging->CMCSConnReq
54200mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 253.1006.0 1 RebeccaS.0
54201mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: Removed HG from Callinfo
54201mS CMCallEvt: 0.1004.0 1 ACDTep(Reception): StateChange: END=??? CMCSRinging->CMCSDelete
54201mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f5493854 TOTAL NOW=4 CALL_LIST=1
54202mS CMTARGET: 17.1002.1 1 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
54202mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint f549aa0c TOTAL NOW=3 CALL_LIST=1
54203mS CMExtnEvt: ReceptionUser: CALL LOST (CMCauseAnsweredByOther)
54204mS CMExtnEvt: ReceptionUser: Extn(523) Calling Party Number(07970957140@83.245.6.81) Type(CMNTypeUnknown)
54204mS CMCallEvt: 304.1005.0 -1 ReceptionUser.0: StateChange: END=X CMCSRinging->CMCSCompleted
54204mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Ringing,0,0,ReceptionUser
54205mS CMExtnTx: v=523, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=304 id=1005 in=0
Called[523] Type=Default (100) Reason=CMDRdirect Calling[07970957140@83.245.6.81] Type=Unknown Plan=Default
Cause=124, Answered By Other(IPO)
Timed: 03/02/11 09:53
54205mS CMExtnTx: v=523, p1=8053
CMFacility
Line: type=IPLine 250 Call: lid=304 id=2 in=1
IE CMIEFastStartInfoData (6)
54205mS CMExtnEvt: ReceptionUser: CMExtnHandler::SetCurrent( id: 1005->0 )
54206mS CMCallEvt: 304.1005.0 -1 ReceptionUser.-1: StateChange: END=X CMCSCompleted->CMCSDelete
54207mS CMCallEvt: 304.1005.0 -1 BaseEP: DELETE CMEndpoint f548f7f4 TOTAL NOW=2 CALL_LIST=1
54207mS CMExtnEvt: v=1 State, new=Connected old=Ringing,0,0,RebeccaS
54207mS CMCallEvt: 17.1002.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSRingBack->CMCSOGConnReq
54211mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=1002 in=1
IE CMIEFastStartInfoData (6)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=RebeccaS
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=RebeccaS
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=530
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceH323Phone type=61 number=8002 channel=0 rx_gain=32 tx_gain=32 ep_callid=1006 ipaddr=192.168.1.230 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Reception
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=401
54213mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
To: <sip:01277282081@83.245.6.81:5060>;tag=d9dca27f465b7c0e
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Contact: "01277282081" <sip:01277282081@192.168.1.230:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Min-SE: 3600
Session-Expires: 3600;refresher=uac
Content-Type: application/sdp
Content-Length: 205

v=0
o=UserA 1362682920 781386096 IN IP4 192.168.1.230
s=Session SDP
c=IN IP4 192.168.1.230
t=0 0
m=audio 49160 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
54214mS CMExtnTx: v=530, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=253 id=1006 in=0
Timed: 03/02/11 09:53
54217mS CD: CALL: 17.1002.1 BState=Connected Cut=3 Music=0.0 Aend="Line 17" (0.0) Bend="" [RebeccaS(530)] (0.0) CalledNum=401 (Reception) CallingNum=07970957140@83.245.6.81 () Internal=0 Time=1697 AState=Connected
54218mS CMMap: PCG::MapBChan pcp[91]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
54218mS H323Evt: SetRfc2833 (1): rx payload 101 tx payload 101
54219mS CMMap: PCG::MapBChan pcp[89]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
54220mS CMExtnEvt: ReceptionUser: Recover Timer reason=CMTRWrapUp
54220mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,ReceptionUser
54655mS RES: Thu 3/2/2011 09:53:49 FreeMem=69892932(1) CMMsg=4 (5) Buff=200 960 1000 7479 5 Links=2242
54655mS RES2: IP 500 V2 6.1(5) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=55 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
54698mS CMExtnTx: v=530, p1=8002
CMFacility
Line: type=IPLine 250 Call: lid=253 id=1 in=1
IE CMIEFastStartInfoData (6)
55946mS H323Evt: RTP(50r): 192.168.1.230/49160 83.245.6.82/37612 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=on AGE=1728 SENT=54 (avg size=160) RECV=50 (avg size=160)
55947mS H323Evt: RTP(50t): 192.168.1.230/49156 192.168.1.233/52382 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=1729 SENT=50 (avg size=160) RECV=54 (avg size=160)
56213mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
To: <sip:01277282081@83.245.6.81:5060>;tag=d9dca27f465b7c0e
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Contact: "01277282081" <sip:01277282081@192.168.1.230:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Min-SE: 3600
Session-Expires: 3600;refresher=uac
Content-Type: application/sdp
Content-Length: 205

v=0
o=UserA 1362682920 781386096 IN IP4 192.168.1.230
s=Session SDP
c=IN IP4 192.168.1.230
t=0 0
m=audio 49160 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
60213mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
To: <sip:01277282081@83.245.6.81:5060>;tag=d9dca27f465b7c0e
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Contact: "01277282081" <sip:01277282081@192.168.1.230:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Min-SE: 3600
Session-Expires: 3600;refresher=uac
Content-Type: application/sdp
Content-Length: 205

v=0
o=UserA 1362682920 781386096 IN IP4 192.168.1.230
s=Session SDP
c=IN IP4 192.168.1.230
t=0 0
m=audio 49160 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
64155mS RES: Thu 3/2/2011 09:53:59 FreeMem=69892544(1) CMMsg=5 (5) Buff=200 960 1000 7479 5 Links=2241
64155mS RES2: IP 500 V2 6.1(5) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=52 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
68213mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
To: <sip:01277282081@83.245.6.81:5060>;tag=d9dca27f465b7c0e
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Contact: "01277282081" <sip:01277282081@192.168.1.230:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Min-SE: 3600
Session-Expires: 3600;refresher=uac
Content-Type: application/sdp
Content-Length: 205

v=0
o=UserA 1362682920 781386096 IN IP4 192.168.1.230
s=Session SDP
c=IN IP4 192.168.1.230
t=0 0
m=audio 49160 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
77546mS H323Evt: Recv: RegistrationRequest 192.168.1.233; Endpoints registered: 2; Endpoints in registration: 0
78886mS H323Evt: Recv: RegistrationRequest 192.168.1.232; Endpoints registered: 2; Endpoints in registration: 0
79528mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
OPTIONS sip:Unknown@83.245.6.81 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.230:5060;rport;branch=z9hG4bK6da25dbb71ecc9471657776f8d56c302
From: <sip:Unknown@83.245.6.81>;tag=e740f9c12a481359
To: <sip:Unknown@83.245.6.81>
Call-ID: 2a118a00e875f6ea761bf4dc47878854@192.168.1.230
CSeq: 852481786 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

79570mS SIP Rx: UDP 83.245.6.81:5060 -> 192.168.1.230:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.230:5060;received=80.176.88.37;rport;branch=z9hG4bK6da25dbb71ecc9471657776f8d56c302
To: <sip:Unknown@83.245.6.81>;tag=3505715668-171109
From: <sip:Unknown@83.245.6.81>;tag=e740f9c12a481359
Call-ID: 2a118a00e875f6ea761bf4dc47878854@192.168.1.230
CSeq: 852481786 OPTIONS
Allow: INVITE, BYE, OPTIONS, CANCEL, ACK, REGISTER, NOTIFY, INFO, REFER, SUBSCRIBE, PRACK, UPDATE
Contact: <sip:Unknown@83.245.6.81:5060>
Content-Length: 0

79655mS RES: Thu 3/2/2011 09:54:14 FreeMem=69879880(1) CMMsg=5 (5) Buff=200 960 1000 7479 5 Links=2238
79655mS RES2: IP 500 V2 6.1(5) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=52 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
84213mS SIP Tx: UDP 192.168.1.230:5060 -> 83.245.6.81:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 83.245.6.81:5060;branch=z9hG4bK3ce8fe521feb967fb54c087695c2a7a6
From: <sip:07970957140@83.245.6.81>;tag=3505715641-111212
To: <sip:01277282081@83.245.6.81:5060>;tag=d9dca27f465b7c0e
Call-ID: 53063239-3505715641-111203@MSX12.gammatelecom.com
CSeq: 1 INVITE
Contact: "01277282081" <sip:01277282081@192.168.1.230:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Min-SE: 3600
Session-Expires: 3600;refresher=uac
Content-Type: application/sdp
Content-Length: 205

v=0
o=UserA 1362682920 781386096 IN IP4 192.168.1.230
s=Session SDP
c=IN IP4 192.168.1.230
t=0 0
m=audio 49160 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
84655mS RES: Thu 3/2/2011 09:54:19 FreeMem=69892456(1) CMMsg=5 (5) Buff=200 960 1000 7479 5 Links=2241
84655mS RES2: IP 500 V2 6.1(5) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
86213mS CMCallEvt: 17.1002.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSCompleted
86215mS CMExtnTx: v=530, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=253 id=1006 in=0
IE CMIEFastStartInfoData (6)
Timed: 03/02/11 09:54
86215mS CMExtnTx: v=530, p1=8002
CMFacility
Line: type=IPLine 250 Call: lid=253 id=1 in=1
IE CMIEFastStartInfoData (6)
86217mS CMLOGGING: CALL:2011/02/0309:53,00:00:00,001,07970957140@83.245.6.81,I,401,01277282081,,,,0,,""n/a,0
86217mS CD: CALL: 17.1002.1 BState=Connected Cut=2 Music=0.0 Aend="Line 17" (0.0) Bend="" [RebeccaS(530)] (0.0) CalledNum=401 (Reception) CallingNum=07970957140@83.245.6.81 () Internal=0 Time=33697 AState=Idle
86217mS CD: CALL: 17.1002.1 Deleted
86217mS CMCallEvt: 17.1002.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
86219mS CMExtnEvt: RebeccaS: CALL LOST (CMCauseNormal)
86219mS CMExtnEvt: RebeccaS: Extn(530) Calling Party Number(07970957140@83.245.6.81) Type(CMNTypeUnknown)
86219mS CMCallEvt: 253.1006.0 -1 RebeccaS.0: StateChange: END=X CMCSConnReq->CMCSCompleted
86219mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Connected,0,0,RebeccaS
86220mS CMExtnTx: v=530, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=253 id=1006 in=0
Called[530] Type=Default (100) Reason=CMDRdirect Calling[07970957140@83.245.6.81] Type=Unknown Plan=Default
Cause=16, Normal call clearing
Timed: 03/02/11 09:54
86220mS CMExtnTx: v=530, p1=8002
CMFacility
Line: type=IPLine 250 Call: lid=253 id=1 in=1
IE CMIEFastStartInfoData (6)
86220mS CMExtnEvt: RebeccaS: CMExtnHandler::SetCurrent( id: 1006->0 )
86221mS CMCallEvt: 253.1006.0 -1 RebeccaS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
86222mS CMCallEvt: 253.1006.0 -1 BaseEP: DELETE CMEndpoint f548c1c0 TOTAL NOW=1 CALL_LIST=0
86223mS CMCallEvt: END CALL:1 (f549be20)
86224mS CMTARGET: 17.1002.1 -1 BaseEP: ~CMTargetHandler f54a1210 ep f54a4474
86224mS CMCallEvt: 17.1002.1 -1 BaseEP: DELETE CMEndpoint f54a4474 TOTAL NOW=0 CALL_LIST=0
86225mS CMMap: PCG::UnmapBChan pcp[91]b0r1 cp_b 0 other_cp_b 0
86225mS CMMap: PCG::UnmapBChan pcp[89]b0r1 cp_b 0 other_cp_b 0
86226mS H323Evt: RTP(END): 192.168.1.230/49156 192.168.1.233/52382 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=32008 SENT=1563 RECV=1568 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
86226mS H323Evt: RTP(END): 192.168.1.230/49160 83.245.6.82/37612 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=on AGE=32008 SENT=1567 RECV=1563 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
88222mS CMExtnEvt: RebeccaS: Recover Timer reason=CMTRWrapUp
88222mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,RebeccaS
88223mS CMExtnTx: v=530, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[RebeccaS Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [RebeccaS Msgs=0]
Timed: 03/02/11 09:54
95655mS RES: Thu 3/2/2011 09:54:30 FreeMem=69964648(1) CMMsg=5 (5) Buff=200 959 1000 7479 5 Links=2359
95655mS RES2: IP 500 V2 6.1(5) Tasks=37 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
95729mS PRN: WAV Hold Music HoldMusic.wav failed to load
95730mS PRN: Deleted WAV file MOH Source 1

********** Warning: Logging to Screen Stopped **********

 

[hairlessupportmonkey]

check with gamma and ask what their session timers should be set to.

ACSS - SME