SIP with 6.0

ceejcast · Oct 14, 2010

I programmed a sip line on an IP500 V1 running realease 6.0.18. After rebooting, I get the confirmation from the sip provider and able to make a call to the pbx. When I try to make another call and any call after, it rings busy.
The monitor trace shows that the pbx is trying to authenticate with the sip provider but never receives confirmation from the provider so the pbx does not know where to route the call and returns a busy signal while the sip provider sees confimation on their side and says everything looks good and is therefore blaming it on my end. I had to reboot again to be able to make another call and the problem reoccurs thereafter. Has anyone experienced this with 6.0 and is there a possible solution? The sip provider is not supported by Avaya TSO.

hairlessupportmonkey · Oct 14, 2010

can you post some monitor traces?

ceejcast · Oct 14, 2010

AFTER REBOOT - First Call Successful
Some changes made for security purposes

39976mS PRN: Sending Config out to f557f4bc finished error=0
39976mS PRN: Config Write Completed
40987mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: TimerExpired cause=CMTCNoAnswerTimeout
40987mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: Retarget NOANSWER EXCEPTED=00000001 ValidTargets=1
40987mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: Retarget on target_cfg_user=Extn22
40988mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: ADD USER: Extn22 depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x1) excpt(0x1), allow_redir(1) remote=00000000
40988mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: SELECT: TRY VOICEMAIL orig_hg() orig_user(22)
40988mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: ADD VM TARGET
40988mS CMTARGET: **** 17.1003.1 1 SIPTrunk Endpoint: MakeVoicemailTarget pbx=<null> local=1 type=1
40989mS CMCallEvt: 0.1007.0 -1 BaseEP: NEW CMEndpoint f54cdb98 TOTAL NOW=5 CALL_LIST=1
40989mS CMTARGET: 0.1007.0 1 RAS.0: ADD PRIMARY
40989mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
40989mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: GetNoAnswerTimer:15
40990mS CMCallEvt: 0.1007.0 1 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
40990mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1007 )
40991mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#Extn22] Type=Voicemail (102) Reason=107 SndComp Calling[caller number@domain name] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Extn22
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Extn22
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=incoming number
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Extn22
IE CMIEOriginalCalledPartyKName (237)(Type=CMNameDefault) name=Extn22
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:3) number=22
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn22
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Extn22
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=22
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=caller number@domain name
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceSIPTrunk type=0 number=17 channel=0 rx_gain=32 tx_gain=32 ep_callid=1003 ipaddr=ip office ip apps=0
Display [caller number>Extn22]
Timed: 12/10/10 17:00
Locale: enu
40991mS CMMap: a=21.42 b=1.254 T
40992mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Extn22
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1007 ipaddr=ip office ip apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn22
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Extn22
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=22
40992mS CMCallEvt: 0.1007.0 1 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
40993mS CMCallEvt: 0.1004.0 1 TargetingEP: RequestEnd 0.1007.0 1 RAS.0
40993mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
40994mS CMExtnEvt: Extn22: CALL LOST (CMCauseForwardToVoicemail)
40994mS CMExtnEvt: Extn22: Extn(22) Calling Party Number(caller number@domain name) Type(CMNTypeUnknown)
40994mS CMCallEvt: 0.1005.0 -1 Extn22.0: StateChange: END=X CMCSRinging->CMCSCompleted
40994mS CMExtnEvt: v=5 State, new=PortRecoverDelay old=Ringing,0,0,Extn22
40995mS CMExtnTx: v=22, p1=0
CMReleaseComp
Line: type=DigitalExtn 3 Call: lid=0 id=1005 in=0
Called[22] Type=Default (100) Reason=CMDRdirect Calling[caller number@domain name] Type=Unknown Plan=Default
Cause=123, Forward To Voicemail(IPO)
Timed: 12/10/10 17:00
40995mS CMExtnEvt: Extn22: CMExtnHandler::SetCurrent( id: 1005->0 )
40995mS CMCallEvt: 0.1005.0 -1 Extn22.-1: StateChange: END=X CMCSCompleted->CMCSDelete
40995mS CMCallEvt: 0.1005.0 -1 BaseEP: DELETE CMEndpoint f5586588 TOTAL NOW=4 CALL_LIST=1
40996mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
40996mS CMCallEvt: 17.1003.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSRingBack->CMCSOGConnReq
40998mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=1003 in=1
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Extn22
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1007 ipaddr=ip office ip apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn22
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Extn22
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=22
IE CMIEFastStartInfoData (6)
40998mS Sip: 17.1003.1 1 SIPTrunk Endpoint(f55f9eb0) received CMConnect
41000mS SIP Call Tx: 17
SIP/2.0 200 Ok
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bKbf67.a3e50d41.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c80d9-4cb4cc6f-411a0e64-7664af9f
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 1 INVITE
Contact: "incoming number" <sip:incoming number@ip office ip:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Min-SE: 1800
Session-Expires: 1800;refresher=uac
Content-Type: application/sdp
Content-Length: 228

v=0
o=UserA 3572065438 721258346 IN IP4 ip office ip
s=Session SDP
c=IN IP4 ip office ip
t=0 0
m=audio 49152 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
41001mS SIP Tx: UDP ip office ip:5060 -> sip provider ip:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bKbf67.a3e50d41.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c80d9-4cb4cc6f-411a0e64-7664af9f
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 1 INVITE
Contact: "incoming number" <sip:incoming number@ip office ip:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Min-SE: 1800
Session-Expires: 1800;refresher=uac
Content-Type: application/sdp
Content-Length: 228

v=0
o=UserA 3572065438 721258346 IN IP4 ip office ip
s=Session SDP
c=IN IP4 ip office ip
t=0 0
m=audio 49152 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
41002mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f558c510 TOTAL NOW=3 CALL_LIST=1
41003mS CD: CALL: 17.1003.1 BState=Connected Cut=3 Music=0.0 Aend="Line 17" (0.0) Bend="Extn22(22)" [VoiceMail] (21.42) CalledNum=#Extn22 (Extn22) CallingNum=caller number@domain name () Internal=0 Time=15022 AState=Connected
41004mS H323Evt: SetRfc2833 (1): rx payload 101 tx payload 101
41004mS CMMap: PCG::MapBChan pcp[108]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
41004mS CMMap: PCGS CPReserveCodec for the other end (pcp[108]b0r1) true
41004mS CMMap: PCG::MapBChan pcp[44]b1r0 cp_b f5a49e90 other_cp_b f5a52d34 type CGTypeSimple
41004mS CMMap: a=4.1 b=21.42 M1
41005mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[108]b0r1 echo_cancellation set to 0
41005mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[108]b0r1 Configure 4.1
41005mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[108]b0r1 ConnectIndication 4.1
41138mS SIP Rx: UDP sip provider ip:5060 -> ip office ip:5060
ACK sip:incoming number@ip office ip:5060;transport=udp SIP/2.0
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 1 ACK
Via: SIP/2.0/UDP sip provider ip:5060;branch=0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c8251-4cb4cc7e-411a49ad-785fa59c
Max-Forwards: 16
Contact: <sip:caller number@domain ip:5060;maddr=domain ip;transport=udp>
Content-Length: 0

41141mS SIP Call Rx: 17
ACK sip:incoming number@ip office ip:5060;transport=udp SIP/2.0
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 1 ACK
Via: SIP/2.0/UDP sip provider ip:5060;branch=0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c8251-4cb4cc7e-411a49ad-785fa59c
Max-Forwards: 16
Contact: <sip:caller number@domain ip:5060;maddr=domain ip;transport=udp>
Content-Length: 0

41142mS CMLineRx: v=0
CMConnectAck
Line: type=SIPLine 17 Call: lid=17 id=1003 in=1
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=caller number@domain name
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceSIPTrunk type=0 number=17 channel=0 rx_gain=32 tx_gain=32 ep_callid=1003 ipaddr=ip office ip apps=0
41142mS CMCallEvt: 17.1003.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSConnected
41142mS CMTARGET: 17.1003.1 1 SIPTrunk Endpoint: ~CMTargetHandler f558ed40 ep f55f9eb0
41143mS CMCallEvt: 0.1007.0 1 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
41144mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=caller number@domain name
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceSIPTrunk type=0 number=17 channel=0 rx_gain=32 tx_gain=32 ep_callid=1003 ipaddr=ip office ip apps=0
42153mS H323Evt: RTP(50t): ip office ip/49152 domain ip/25794 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=1149 SENT=50 (avg size=20) RECV=51 (avg size=20)
42431mS RES: Tue 12/10/2010 17:00:48 FreeMem=70366636(1) CMMsg=4 (5) Buff=200 949 1000 7447 5 Links=3037
42431mS RES2: IP 500 6.0(18) Tasks=33 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 CF=UMNT
42902mS SIP Reg/Opt Tx: 17
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK35cc14d2d70310e1cf8f76295ccde16f
From: <sip:Unknown@sip provider>;tag=ef9062f3e1616119
To: <sip:Unknown@sip provider>
Call-ID: 1836bb64500e8ccacccce6bd02d9c866@ip office ip
CSeq: 1614750809 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

42902mS SIP Tx: UDP ip office ip:5060 -> sip provider ip:5060
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK35cc14d2d70310e1cf8f76295ccde16f
From: <sip:Unknown@sip provider>;tag=ef9062f3e1616119
To: <sip:Unknown@sip provider>
Call-ID: 1836bb64500e8ccacccce6bd02d9c866@ip office ip
CSeq: 1614750809 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

42995mS CMExtnEvt: Extn22: Recover Timer reason=CMTRWrapUp
42995mS CMExtnEvt: v=5 State, new=Idle old=PortRecoverDelay,0,0,Extn22
44096mS CD: CALLSYNC: cs02
50902mS SIP Reg/Opt Tx: 17
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK35cc14d2d70310e1cf8f76295ccde16f
From: <sip:Unknown@sip provider>;tag=ef9062f3e1616119
To: <sip:Unknown@sip provider>
Call-ID: 1836bb64500e8ccacccce6bd02d9c866@ip office ip
CSeq: 1614750809 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

50902mS SIP Tx: UDP ip office ip:5060 -> sip provider ip:5060
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK35cc14d2d70310e1cf8f76295ccde16f
From: <sip:Unknown@sip provider>;tag=ef9062f3e1616119
To: <sip:Unknown@sip provider>
Call-ID: 1836bb64500e8ccacccce6bd02d9c866@ip office ip
CSeq: 1614750809 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

50931mS RES: Tue 12/10/2010 17:00:56 FreeMem=70376396(1) CMMsg=4 (5) Buff=200 949 1000 7447 5 Links=3036
50931mS RES2: IP 500 6.0(18) Tasks=33 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 CF=UMNT
62579mS SIP Rx: UDP sip provider ip:5060 -> ip office ip:5060
BYE sip:incoming number@ip office ip:5060;transport=udp SIP/2.0
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 2 BYE
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bK8f67.1e167f36.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c84a4-4cb4cc94-411a9d6c-1b8d33cd
Max-Forwards: 16
Content-Length: 0

62582mS SIP Call Rx: 17
BYE sip:incoming number@ip office ip:5060;transport=udp SIP/2.0
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 2 BYE
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bK8f67.1e167f36.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c84a4-4cb4cc94-411a9d6c-1b8d33cd
Max-Forwards: 16
Content-Length: 0

62584mS SIP Call Tx: 17
SIP/2.0 200 Ok
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bK8f67.1e167f36.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c84a4-4cb4cc94-411a9d6c-1b8d33cd
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 2 BYE
Supported: timer
Content-Length: 0

62584mS SIP Tx: UDP ip office ip:5060 -> sip provider ip:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bK8f67.1e167f36.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c84a4-4cb4cc94-411a9d6c-1b8d33cd
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc6f-411a0e64-5b4ee53e
To: <sip:incoming number@sip provider ip:5060>;tag=38f208c04788dcc0
Call-ID: CXC-318-66912e70-a9d5ed0-13c4-4cb4cc6f-411a0e64-5ba700c0@domain ip
CSeq: 2 BYE
Supported: timer
Content-Length: 0

62585mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=1003 in=1
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=caller number@domain name
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=6 class=CMDeviceSIPTrunk type=0 number=17 channel=0 rx_gain=32 tx_gain=32 ep_callid=1003 ipaddr=ip office ip apps=0
Cause=16, Normal call clearing
62585mS CMCallEvt: 17.1003.1 1 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
62587mS CMLOGGING: CALL:2010/10/1217:00,00:00:21,015,caller number@domain name,I,22,incoming number,,,,0,,""n/a,0
62587mS CD: CALL: 17.1003.1 BState=Connected Cut=2 Music=0.0 Aend="Line 17" (0.0) Bend="Extn22(22)" [VoiceMail] (21.42) CalledNum=#Extn22 (Extn22) CallingNum=caller number@domain name () Internal=0 Time=36607 AState=Idle
62588mS CD: CALL: 17.1003.1 Deleted
62588mS CMCallEvt: 17.1003.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
62589mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
62589mS CMCallEvt: 0.1007.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
62589mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
62589mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#Extn22] Type=Voicemail (102) Reason=107 Calling[caller number@domain name] Type=Unknown Plan=Default
BChan: slot=21 chan=42
Cause=16, Normal call clearing
62590mS CMMap: a=21.42 b=0.0 T0
62591mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1007->0 )
62591mS CMCallEvt: 0.1007.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
62591mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
62591mS CMCallEvt: 0.1007.0 -1 BaseEP: DELETE CMEndpoint f54cdb98 TOTAL NOW=2 CALL_LIST=0
62591mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=16, Normal call clearing
62592mS CMCallEvt: END CALL:1 (f558d91c)
62594mS CMCallEvt: 17.1003.1 -1 BaseEP: DELETE CMEndpoint f55f9eb0 TOTAL NOW=1 CALL_LIST=0
62595mS CMMap: PCG::UnmapBChan pcp[44]b1r0 cp_b f5a49e90 other_cp_b f5a52d34
62595mS CMMap: a=4.1 b=21.42 M0
62596mS CMMap: PCG::UnmapBChan pcp[108]b0r1 cp_b 0 other_cp_b 0
62596mS H323Evt: RTP(END): ip office ip/49152 domain ip/25794 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=21592 SENT=1071 RECV=1071 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
62596mS CMMap: a=4.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 32
62599mS CD: CALLSYNC: cs02
62755mS PRN: Sending body size=97
62931mS RES: Tue 12/10/2010 17:01:08 FreeMem=70390672(1) CMMsg=4 (5) Buff=200 949 1000 7454 5 Links=2896
62931mS RES2: IP 500 6.0(18) Tasks=33 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=47 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 CF=UMNT

ceejcast · Oct 14, 2010

SUBSEQUENT CALLS - All Calls Fail
Some changes made for security purposes

66902mS SIP Reg/Opt Tx: 17
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK35cc14d2d70310e1cf8f76295ccde16f
From: <sip:Unknown@sip provider>;tag=ef9062f3e1616119
To: <sip:Unknown@sip provider>
Call-ID: 1836bb64500e8ccacccce6bd02d9c866@ip office ip
CSeq: 1614750809 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

66902mS SIP Tx: UDP ip office ip:5060 -> sip provider ip:5060
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK35cc14d2d70310e1cf8f76295ccde16f
From: <sip:Unknown@sip provider>;tag=ef9062f3e1616119
To: <sip:Unknown@sip provider>
Call-ID: 1836bb64500e8ccacccce6bd02d9c866@ip office ip
CSeq: 1614750809 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

66930mS ERR: PCVMail: Stored VPIM Address change = smtp.emailsrvr.com
66932mS PRN: Config Write Wake Up
67432mS PRN: Sorting User List...
67432mS PRN: User List Sorted
67433mS PRN: Check for duplicates took 0 ms
67433mS PRN: Updates IO list size 1 updated list size 1
67433mS PRN: Sending Updates out to f557f4bc started
67433mS PRN: Sending Updates out to f557f4bc finished
67434mS PRN: Config Write Completed
67931mS RES: Tue 12/10/2010 17:01:13 FreeMem=70397520(1) CMMsg=4 (5) Buff=200 949 1000 7454 5 Links=2894
67931mS RES2: IP 500 6.0(18) Tasks=33 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 CF=UMNT
68902mS CMCallEvt: 0.1006.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
68903mS CMCallEvt: 0.1006.0 -1 BaseEP: DELETE CMEndpoint f5581d34 TOTAL NOW=0 CALL_LIST=0
68912mS CMCallEvt: 0.1008.0 -1 BaseEP: NEW CMEndpoint f5581d34 TOTAL NOW=1 CALL_LIST=0
68913mS SIP Reg/Opt Tx: 17
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK128abe7f2dd8c6ca8d7b2bbde2efd0eb
From: <sip:Unknown@sip provider>;tag=a5a1e5400e96ada2
To: <sip:Unknown@sip provider>
Call-ID: f7b262aac349ff5c9bb049cf9e72048f@ip office ip
CSeq: 1940875990 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

68914mS SIP Tx: UDP ip office ip:5060 -> sip provider ip:5060
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK128abe7f2dd8c6ca8d7b2bbde2efd0eb
From: <sip:Unknown@sip provider>;tag=a5a1e5400e96ada2
To: <sip:Unknown@sip provider>
Call-ID: f7b262aac349ff5c9bb049cf9e72048f@ip office ip
CSeq: 1940875990 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

70914mS SIP Reg/Opt Tx: 17
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK128abe7f2dd8c6ca8d7b2bbde2efd0eb
From: <sip:Unknown@sip provider>;tag=a5a1e5400e96ada2
To: <sip:Unknown@sip provider>
Call-ID: f7b262aac349ff5c9bb049cf9e72048f@ip office ip
CSeq: 1940875990 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

70914mS SIP Tx: UDP ip office ip:5060 -> sip provider ip:5060
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK128abe7f2dd8c6ca8d7b2bbde2efd0eb
From: <sip:Unknown@sip provider>;tag=a5a1e5400e96ada2
To: <sip:Unknown@nexvortex.com>
Call-ID: f7b262aac349ff5c9bb049cf9e72048f@ip office ip
CSeq: 1940875990 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

72818mS SIP Rx: UDP sip provider ip:5060 -> ip office ip:5060
INVITE sip:incoming number@ip office ip;transport=udp SIP/2.0
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc9e-411ac560-4606b917;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc9e-411ac560-4606b917
To: <sip:incoming number@sip provider ip:5060>
Call-ID: CXC-43-668e93b0-a9d5ed0-13c4-4cb4cc9e-411ac560-2b52e0b1@domain ip
CSeq: 1 INVITE
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bK358e.ea123c73.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c85a9-4cb4cc9e-411ac560-5c0c0104
Max-Forwards: 16
Supported: 100rel
Remote-Party-ID: <sip:caller number@domain name:5060>;id-type=subscriber;privacy=off;screen=no
Content-Disposition: session;handling=required
Contact: <sip:caller number@domain ip:5060;maddr=domain ip;transport=udp>
Min-SE: 900
Session-Expires: 1800
Content-Type: application/sdp
Content-Length: 238

v=0
o=Acme_UAS 0 1 IN IP4 domain ip
s=SIP Media Capabilities
c=IN IP4 domain ip
t=0 0
m=audio 21022 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=maxptime:20
a=sendrecv
72823mS SIP Call Rx: 17
INVITE sip:incoming number@ip office ip;transport=udp SIP/2.0
Record-Route: <sip:incoming number@sip provider ip:5060;nat=yes;ftag=a9d5ed0-13c4-4cb4cc9e-411ac560-4606b917;lr=on>
From: <sip:caller number@domain ip:5060>;tag=a9d5ed0-13c4-4cb4cc9e-411ac560-4606b917
To: <sip:incoming number@sip provider ip:5060>
Call-ID: CXC-43-668e93b0-a9d5ed0-13c4-4cb4cc9e-411ac560-2b52e0b1@domain ip
CSeq: 1 INVITE
Via: SIP/2.0/UDP sip provider ip:5060;branch=z9hG4bK358e.ea123c73.0
Via: SIP/2.0/UDP domain ip:5060;branch=z9hG4bK-c85a9-4cb4cc9e-411ac560-5c0c0104
Max-Forwards: 16
Supported: 100rel
Remote-Party-ID: <sip:caller number@domain name:5060>;id-type=subscriber;privacy=off;screen=no
Content-Disposition: session;handling=required
Contact: <sip:caller number@domain ip:5060;maddr=domain ip;transport=udp>
Min-SE: 900
Session-Expires: 1800
Content-Type: application/sdp
Content-Length: 238

v=0
o=Acme_UAS 0 1 IN IP4 domain ip
s=SIP Media Capabilities
c=IN IP4 domain ip
t=0 0
m=audio 21022 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=maxptime:20
a=sendrecv
72823mS Sip: Cannot accept call: reason=503 (Service Unavailable)

hairlessupportmonkey · Oct 14, 2010

how have you got this routing out to the real world?

NAT? Firewall?

Im wondering if there is some NAT time out going on? 503 service unavailable is about all i can see obvious. Ill take a peek in the morning when I can focus!

tlpeter · Oct 14, 2010

Is there a SIP URI configured ?

Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

Sarcasm, it's only one of the services I offer.

Bas1234 · Oct 14, 2010

^ check you SIP URI, your sending UNKNOWN
Try to set the outgoing line id to the uri that shows the "Authentication Name"

66902mS SIP Reg/Opt Tx: 17
OPTIONS sip:Unknown@sip provider SIP/2.0
Via: SIP/2.0/UDP ip office ip:5060;rport;branch=z9hG4bK35cc14d2d70310e1cf8f76295ccde16f
From: <sip:Unknown@sip provider>;tag=ef9062f3e1616119
To: <sip:Unknown@sip provider>
Call-ID: 1836bb64500e8ccacccce6bd02d9c866@ip office ip
CSeq: 1614750809 OPTIONS
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0

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

Dain Bramaged
___________________________________________

ceejcast · Oct 14, 2010

SIP URI is configured and outgoing line id is set. I am testing in calls so outgoing line id is not yet an issue.

laimonas · Oct 18, 2010

Same problem here. With v5 all works fine.

Bas1234 · Oct 18, 2010

Did you check the "Sip Credentials" (see SIP line)?
These username/password are gone when you upgrade from V5 > V6

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

Dain Bramaged
___________________________________________

hairlessupportmonkey · Oct 18, 2010

good point Bas - was just about to say!

hairlessupportmonkey · Oct 18, 2010

infact, Ive have to delete out our SIP trunk and re-create it after upgrading our in house IPO.

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!

SIP with 6.0

ceejcast

Technical User

hairlessupportmonkey

IS-IT--Management

ceejcast

Technical User

ceejcast

Technical User

hairlessupportmonkey

IS-IT--Management

tlpeter

Programmer

Bas1234

Technical User

ceejcast

Technical User

laimonas

Programmer

Bas1234

Technical User

hairlessupportmonkey

IS-IT--Management

hairlessupportmonkey

IS-IT--Management

Similar threads

Part and Inventory Search

Sponsor