IPO not sending SIP REGISTER message

[crankyralph]

IPO 8.1
SIP trunk is setup, and I've confirmed traffic to and from the provider, but the trunk never registers because the ipo never sends a registration request. OPTIONS requests are frequently sent to keep-alive a trunk the IPO never asked to go live in the first place. I've confirmed authentication name / password etc.

I also notice in the 8.1 release notes you can send a Register request instead of Options request as a keep-alive, but of course I can't find any mention in the documentation of how the @#$%&*?!!! to do it!

Save me Tek-Tips, you are my only hope....

 

[crankyralph]

Further info...
IPO using LAN2 / open internet
Traffic ends with SIP/2.0 403 Forbidden - not registered?

 

[amriddle01]

Do you have the credentials added and "Registration required" ticked? If so post a monitor trace



"No problem monkey socks

 

[crankyralph]

Thanks amriddle01.
I confirmed credentials / password with provider, and registration is selected. Trace is below:

IPO LAN2 - 10.208.250.86
Provider - 76.11.36.40
9024318099 - sip line number
dialed # - 8775998 with 6 as short code to dial out



2013-09-30T10:30:58 219913240mS CMTARGET: 0.1365.0 47 TechsHfx.0: Setting Hard Timer 4000
2013-09-30T10:30:58 219913240mS CMTARGET: 0.1365.0 47 TechsHfx.0: LOOKUP CALL ROUTE: type=100 called_party=68775998 sub= calling=2388 dir=out complete=0 ses=0
2013-09-30T10:30:58 219913240mS CMTARGET: 0.1365.0 47 TechsHfx.0: ADD TARGET (N): number=68775998 type=100 depth=1 nobar=1 setorig=1 ses=0
2013-09-30T10:30:58 219913351mS Sip: SIPDialog f4dfde94 destroyed, size 0
2013-09-30T10:31:03 219917240mS CMTARGET: 0.1365.0 47 TechsHfx.0: TimerExpired cause=CMTCDelayedProcessing
2013-09-30T10:31:03 219917240mS CMTARGET: 0.1365.0 47 TechsHfx.0: LOOKUP CALL ROUTE: type=100 called_party=68775998 sub= calling=2388 dir=out complete=1 ses=0
2013-09-30T10:31:03 219917240mS CMTARGET: 0.1365.0 47 TechsHfx.0: ADD TARGET (N): number=68775998 type=100 depth=1 nobar=1 setorig=1 ses=0
2013-09-30T10:31:03 219917241mS CMTARGET: 0.1365.0 47 TechsHfx.0: SYS SC: 68775998 3 8775998 sc=type=Dial code=6N;, num=N"@10.208.250.86:5060" callinfop->sending_complete=1 secondary_dialtone=
2013-09-30T10:31:03 219917242mS Sip: License, Valid 1, Available 30, Consumed 0
2013-09-30T10:31:03 219917243mS CMCallEvt: 0.1367.0 -1 BaseEP: NEW CMEndpoint f4d9d970 TOTAL NOW=5 CALL_LIST=2
2013-09-30T10:31:03 219917243mS Sip: SIPDialog f4d9c694 created, size 1
2013-09-30T10:31:03 219917243mS CH : CHand::AllocateIPConnectionPoint cp[177]b0r0[TOTAL CP:136]
2013-09-30T10:31:03 219917245mS CMTARGET: DIAL LINE: 47 GROUP = 30 SUCCESS = f4d9d970
2013-09-30T10:31:03 219917245mS CMTARGET: 0.1365.0 47 TechsHfx.0: INITIAL TARGETING SUCCEEDED
2013-09-30T10:31:03 219917245mS CMTARGET: 0.1365.0 47 TechsHfx.0: GetNoAnswerTimer:20
2013-09-30T10:31:03 219917245mS CMCallEvt: 0.1365.0 47 TechsHfx.0: StateChange: END=A CMCSDialling->CMCSDialled
2013-09-30T10:31:03 219917245mS CMExtnEvt: v=3 State, new=Proceeding old=Dialling,0,0,TechsHfx
2013-09-30T10:31:03 219917246mS CMExtnTx: v=2388, p1=0
CMProceeding
Line: type=DigitalExtn 4 Call: lid=0 id=1365 in=0
Timed: 30/09/13 10:31
2013-09-30T10:31:03 219917246mS CMCallEvt: 0.1366.0 47 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
2013-09-30T10:31:03 219917247mS CMCallEvt: 30.1367.0 47 SIPTrunk Endpoint: StateChange: END=T CMCSIdle->CMCSOffering
2013-09-30T10:31:03 219917247mS SS : 30.1367.0 47 SIPTrunk Endpoint TxSSMsg, adding SSMsgData::CallingName (CMSetup)
2013-09-30T10:31:03 219917247mS MH : 30.1367.0 47 SIPTrunk Endpoint: ProcessOutboundMsg, set incoming_leg to false
2013-09-30T10:31:03 219917247mS VOIP: (Out) 30.1367.0 47 SIPTrunk Endpoint: ProcessOutboundCCMsg --- msg: CMSetup f4d42a80 fs 0 h245 0 fs size 0 security size 0
2013-09-30T10:31:03 219917247mS VOIP: (Out) 30.1367.0 47 SIPTrunk Endpoint: other_lcmh 0 lcmh->CanMediaWaitForConnect() 1 is_line 1 is_original_target 1 aend ghost 0
2013-09-30T10:31:03 219917248mS VOIP: (Out) 30.1367.0 47 SIPTrunk Endpoint: SetLocalState LCIdle -> LCFSInitialize
2013-09-30T10:31:03 219917248mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 30 Call: lid=30 id=1367 in=0
Called[8775998@10.208.250.86:5060] Type=Default (100) Reason=CMDRdirect SndComp Calling[2388] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=TechsHfx
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=TechsHfx
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=TechsHfx
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=TechsHfx
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2388
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceStdPhone type=99 number=51 channel=0 rx_gain=32 tx_gain=32 ep_callid=1365 ipaddr=192.168.25.15 apps=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 14648
user 'TechsHfx' presentation Allowed
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIESecurityData (253) 2 item(s)
Display [TechsHfx>8775998]
Timed: 30/09/13 10:31
Locale: enu
2013-09-30T10:31:03 219917249mS Sip: 30.1367.0 47 SIPTrunk Endpoint(f4d9d970) received CMSetup
2013-09-30T10:31:03 219917250mS Sip: 30.1367.0 47 SIPTrunk Endpoint(f4d9c694) SetLocalRTPAddress to 10.208.250.86:49152 (index 0)
2013-09-30T10:31:03 219917251mS SIP Call Tx: 30
INVITE sip:8775998@10.208.250.86:5060 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK56dc15713fba421b1e8eecd05338d43c
From: "TechsHfx" <sip:9024318099@10.208.250.86>;tag=e9de4dee5693b320
To: <sip:8775998@10.208.250.86:5060>
Call-ID: cd1efae684260f473b819c37b8da7fb8
CSeq: 1696870511 INVITE
Contact: "TechsHfx" <sip:9024318099@10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: timer,100rel
User-Agent: IP Office 8.1 (63)
Content-Length: 253

v=0
o=UserA 3907685283 3041976976 IN IP4 10.208.250.86
s=Session SDP
c=IN IP4 10.208.250.86
t=0 0
m=audio 49152 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2013-09-30T10:31:03 219917251mS SIP Tx: UDP 10.208.250.86:5060 -> 76.11.36.40:5060
INVITE sip:8775998@10.208.250.86:5060 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK56dc15713fba421b1e8eecd05338d43c
From: "TechsHfx" <sip:9024318099@10.208.250.86>;tag=e9de4dee5693b320
To: <sip:8775998@10.208.250.86:5060>
Call-ID: cd1efae684260f473b819c37b8da7fb8
CSeq: 1696870511 INVITE
Contact: "TechsHfx" <sip:9024318099@10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: timer,100rel
User-Agent: IP Office 8.1 (63)
Content-Length: 253

v=0
o=UserA 3907685283 3041976976 IN IP4 10.208.250.86
s=Session SDP
c=IN IP4 10.208.250.86
t=0 0
m=audio 49152 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2013-09-30T10:31:03 219917252mS CD: CALL: 0.1365.0 BState=Idle Cut=1 Music=0.0 Aend="TechsHfx(2388)" (4.9) Bend="Line 30" [Line 30] (0.0) CalledNum=68775998 () CallingNum=2388 (TechsHfx) Internal=0 Time=6802 AState=Dialled
2013-09-30T10:31:03 219917253mS CD2: CALL:S 0.1365.0,0.1366.0,8,0,1,0,0,0,TechsHfx(2388),,Line 30,4.9,0.0,100.100,68775998,100.101,2388,,,100,68775998,100,,0,16,0,1,0.0,,,,,,,0,6802,0,0,0,0,enu,,0,,0,0,0,0,,47,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2013-09-30T10:31:03 219917254mS SIP Rx: UDP 76.11.36.40:5060 -> 10.208.250.86:5060
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bK56dc15713fba421b1e8eecd05338d43c
From: "TechsHfx" <sip:9024318099@10.208.250.86>;tag=e9de4dee5693b320
Call-ID: cd1efae684260f473b819c37b8da7fb8
CSeq: 1696870511 INVITE
To: <sip:8775998@10.208.250.86:5060>;tag=3589536671-23728
Content-Length: 0

2013-09-30T10:31:03 219917256mS SIP Call Rx: 30
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bK56dc15713fba421b1e8eecd05338d43c
From: "TechsHfx" <sip:9024318099@10.208.250.86>;tag=e9de4dee5693b320
Call-ID: cd1efae684260f473b819c37b8da7fb8
CSeq: 1696870511 INVITE
To: <sip:8775998@10.208.250.86:5060>;tag=3589536671-23728
Content-Length: 0

2013-09-30T10:31:03 219917258mS SIP Call Tx: 30
ACK sip:8775998@10.208.250.86:5060 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK56dc15713fba421b1e8eecd05338d43c
From: "TechsHfx" <sip:9024318099@10.208.250.86>;tag=e9de4dee5693b320
To: <sip:8775998@10.208.250.86:5060>;tag=3589536671-23728
Call-ID: cd1efae684260f473b819c37b8da7fb8
CSeq: 1696870511 ACK
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T10:31:03 219917258mS SIP Tx: UDP 10.208.250.86:5060 -> 76.11.36.40:5060
ACK sip:8775998@10.208.250.86:5060 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK56dc15713fba421b1e8eecd05338d43c
From: "TechsHfx" <sip:9024318099@10.208.250.86>;tag=e9de4dee5693b320
To: <sip:8775998@10.208.250.86:5060>;tag=3589536671-23728
Call-ID: cd1efae684260f473b819c37b8da7fb8
CSeq: 1696870511 ACK
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T10:31:03 219917259mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 30 Call: lid=30 id=1367 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=68775998
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=30 channel=1 rx_gain=32 tx_gain=32 ep_callid=1367 ipaddr=192.168.25.15 apps=0
Cause=21, Call rejected
2013-09-30T10:31:03 219917259mS CMCallEvt: 0.1366.0 47 TargetingEP: RequestEnd 30.1367.0 47 SIPTrunk Endpoint
2013-09-30T10:31:03 219917260mS CMTARGET: 0.1365.0 47 TechsHfx.0: CancelTimer CMTCNoAnswerTimeout
2013-09-30T10:31:03 219917260mS CMCallEvt: 0.1366.0 -1 BaseEP: DELETE CMEndpoint f555161c TOTAL NOW=4 CALL_LIST=2
2013-09-30T10:31:03 219917260mS MH : 0.1365.0 47 TechsHfx.0: FCMH: PlayLocalTone CMLocalToneUnCut->CMLocalToneNone, generate 1
2013-09-30T10:31:03 219917260mS CH : CHand::JoinGroup, cp[124]b1r0 (current group: g null), group g null
2013-09-30T10:31:03 219917261mS MH : 30.1367.0 47 SIPTrunk Endpoint: LCMH Tone CMLocalToneNone(CMLocalToneNone)->CMLocalToneCutThru, other side's media handler is not changed (not lcmh) renego 0
2013-09-30T10:31:03 219917261mS MH : 30.1367.0 47 SIPTrunk Endpoint: LCMH PlayLocalTone CMLocalToneCutThru (current CMLocalToneCutThru, prev CMLocalToneNone) in parent state CMCSOffering action 1 renegotiate 0 voip_negotiator->processing_inbound 1
2013-09-30T10:31:03 219917261mS CMCallEvt: 30.1367.0 47 SIPTrunk Endpoint: StateChange: END=B CMCSOffering->CMCSCompleted
2013-09-30T10:31:03 219917261mS VOIP: (Out) 30.1367.0 47 SIPTrunk Endpoint: ProcessInboundMsg --- msg: CMReleaseComp f5570c3c fs 0 h245 0 reinvite 0 fs size 0 security size 0
2013-09-30T10:31:03 219917262mS CMLOGGING: CALL:2013/09/3010:30,00:00:00,000,2388,O,8775998@10.208.250.86:5060,68775998,TechsHfx,,,0,,""n/a,0
2013-09-30T10:31:03 219917262mS CD: CALL: 0.1365.0 BState=Disconnecting Cut=1 Music=0.0 Aend="TechsHfx(2388)" (4.9) Bend="Line 30" [Line 30] (0.0) CalledNum=8775998@10.208.250.86:5060 () CallingNum=2388 (TechsHfx) Internal=0 Time=6812 AState=Dialled
2013-09-30T10:31:03 219917263mS CD2: CALL:S 0.1365.0,0.1366.0,8,3,1,0,0,0,TechsHfx(2388),Line 30,,4.9,0.0,100.100,8775998@10.208.250.86:5060,100.101,2388,,,100,68775998,100,,0,16,0,1,0.0,,,,,,,0,6812,0,0,0,0,enu,,0,,0,0,0,0,,47,,,,,,,,,,,,,,,,,,,,,,,,,,
,,
2013-09-30T10:31:03 219917263mS CD: CALL: 0.1365.0 Deleted
2013-09-30T10:31:03 219917263mS CD2: CALL 0.1365.0,0.1366.0,47,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
2013-09-30T10:31:03 219917263mS CMExtnEvt: TechsHfx: CALL LOST (CMCauseCallRejected)
2013-09-30T10:31:03 219917263mS CMExtnEvt: TechsHfx: Extn(2388) Calling Party Number(2388) Type(CMNTypeInternal)
2013-09-30T10:31:03 219917264mS CMCallEvt: 0.1365.0 -1 TechsHfx.0: StateChange: END=X CMCSDialled->CMCSCompletedTone
2013-09-30T10:31:03 219917264mS MH : 0.1365.0 -1 TechsHfx.0: FCMH: PlayLocalTone CMLocalToneNone->CMLocalToneNU, generate 1
2013-09-30T10:31:03 219917264mS CH : CHand::JoinGroup, cp[124]b1r0 (current group: g null), group g E1[7]
2013-09-30T10:31:03 219917264mS CH : CG::AddConnection, cp[124]b1r0 to group g E1[7]
2013-09-30T10:31:03 219917264mS CMExtnEvt: v=3 State, new=CMESCompleted old=Proceeding,0,0,TechsHfx
2013-09-30T10:31:03 219917265mS Sip: SIPDialog f4d9c694 destroyed, size 0
2013-09-30T10:31:03 219917265mS CMCallEvt: 30.1367.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
2013-09-30T10:31:03 219917265mS CMCallEvt: END CALL:47 (f4d1b1b4)
2013-09-30T10:31:03 219917266mS CMCallEvt: 30.1367.0 -1 BaseEP: DELETE CMEndpoint f4d9d970 TOTAL NOW=3 CALL_LIST=1
2013-09-30T10:31:03 219917268mS CH : CHand::FreeConnectionPoint cp[177]b0r0[TOTAL CP:135]
2013-09-30T10:31:03 219917614mS PRN: TechsHfx: Feature Key Pressed 6CPLFeatureDrop
2013-09-30T10:31:03 219917614mS CMExtnEvt: TechsHfx: CMExtnHandler::SetCurrent( id: 1365->0 )
2013-09-30T10:31:03 219917614mS MH : 0.1365.0 -1 TechsHfx.0: FCMH: PlayLocalTone CMLocalToneNU->CMLocalToneNone, generate 1
2013-09-30T10:31:03 219917614mS CH : CHand::JoinGroup, cp[124]b1r0 (current group: g E1[7]), group g null
2013-09-30T10:31:03 219917614mS CH : CG::RemoveConnection, cp[124]b1r0 group g E1[7]
2013-09-30T10:31:03 219917614mS CMExtnTx: v=2388, p1=0
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=1365 in=0
Called[68775998] Type=Default (100) Reason=CMDRdirect Calling[2388] Type=Internal Plan=Default
Cause=2, No route to specific transit network/(5ESS)Calling party off hold
Timed: 30/09/13 10:31
2013-09-30T10:31:03 219917615mS CMCallEvt: 0.1365.0 -1 TechsHfx.-1: StateChange: END=X CMCSCompletedTone->CMCSDelete
2013-09-30T10:31:03 219917615mS CMExtnEvt: v=3 State, new=PortRecoverDelay old=CMESCompleted,0,0,TechsHfx
2013-09-30T10:31:03 219917615mS CMTARGET: 0.1365.0 -1 BaseEP: ~CMTargetHandler f554b108 ep f4d490b8
2013-09-30T10:31:03 219917616mS CMCallEvt: 0.1365.0 -1 BaseEP: DELETE CMEndpoint f4d490b8 TOTAL NOW=2 CALL_LIST=1
2013-09-30T10:31:04 219918827mS ERR: TFTP Write:: Too many retries (from 192.168.20.35, 25579, to 4100) file=nasystem
2013-09-30T10:31:05 219919615mS CMExtnEvt: TechsHfx: Recover Timer reason=CMTRWrapUp
2013-09-30T10:31:05 219919615mS CMExtnEvt: v=3 State, new=Idle old=PortRecoverDelay,0,0,TechsHfx
2013-09-30T10:31:05 219919616mS CMExtnTx: v=2388, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 4 Call: lid=0 id=-1 in=0
Called[TechsHfx Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [TechsHfx Msgs=0]
Timed: 30/09/13 10:31
2013-09-30T10:31:10 219924103mS Sip: SIPDialog f4da6098 created, size 1
2013-09-30T10:31:10 219924105mS SIP Reg/Opt Tx: 30
OPTIONS sip:10.208.250.86 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK5aa21dcaf23f8a58793ec05d1a330fa4
From: <sip:10.208.250.86>;tag=e5b8f4330cae2b3e
To: <sip:10.208.250.86>
Call-ID: 7de54df23f0b58ae75f937b648dd1bfb
CSeq: 1407631270 OPTIONS
Contact: <sip:10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T10:31:10 219924105mS SIP Tx: UDP 10.208.250.86:5060 -> 76.11.36.40:5060
OPTIONS sip:10.208.250.86 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK5aa21dcaf23f8a58793ec05d1a330fa4
From: <sip:10.208.250.86>;tag=e5b8f4330cae2b3e
To: <sip:10.208.250.86>
Call-ID: 7de54df23f0b58ae75f937b648dd1bfb
CSeq: 1407631270 OPTIONS
Contact: <sip:10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T10:31:10 219924107mS SIP Rx: UDP 76.11.36.40:5060 -> 10.208.250.86:5060
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bK5aa21dcaf23f8a58793ec05d1a330fa4
From: <sip:10.208.250.86>;tag=e5b8f4330cae2b3e
Call-ID: 7de54df23f0b58ae75f937b648dd1bfb
CSeq: 1407631270 OPTIONS
To: <sip:10.208.250.86>;tag=3589536677-876492
Content-Length: 0

2013-09-30T10:31:10 219924109mS SIP Reg/Opt Rx: 30
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bK5aa21dcaf23f8a58793ec05d1a330fa4
From: <sip:10.208.250.86>;tag=e5b8f4330cae2b3e
Call-ID: 7de54df23f0b58ae75f937b648dd1bfb
CSeq: 1407631270 OPTIONS
To: <sip:10.208.250.86>;tag=3589536677-876492
Content-Length: 0

 

[hairlessupportmonkey]

delete the trunk and re-create it.

ACSS - SME
General Geek

 

[amriddle01]

How often does it re-register? You need to trace after a reboot to see it send a register request (takes about 30secs - 1 min sometimes) unless it re-registers often enough that you see it trying while running monitor for a brief period



"No problem monkey socks

 

[crankyralph]

Thanks again for the attention amriddle01.
In the SIP Credentials of Line 30 [ my sip trunk ] I have expiry set to 1min and Reg Required is checked. The monitor traces repeat as shown below...

2013-09-30T12:34:09 227304129mS SIP Reg/Opt Tx: 30
OPTIONS sip:10.208.250.86 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK8e79449094e48a32075aeae1a20c20bc
From: <sip:10.208.250.86>;tag=2e999128fa184863
To: <sip:10.208.250.86>
Call-ID: ae8a1eba21defc9dd58f29cff271b615
CSeq: 684920401 OPTIONS
Contact: <sip:10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T12:34:09 227304129mS SIP Tx: UDP 10.208.250.86:5060 -> 76.11.36.40:5060
OPTIONS sip:10.208.250.86 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bK8e79449094e48a32075aeae1a20c20bc
From: <sip:10.208.250.86>;tag=2e999128fa184863
To: <sip:10.208.250.86>
Call-ID: ae8a1eba21defc9dd58f29cff271b615
CSeq: 684920401 OPTIONS
Contact: <sip:10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T12:34:09 227304131mS SIP Rx: UDP 76.11.36.40:5060 -> 10.208.250.86:5060
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bK8e79449094e48a32075aeae1a20c20bc
From: <sip:10.208.250.86>;tag=2e999128fa184863
Call-ID: ae8a1eba21defc9dd58f29cff271b615
CSeq: 684920401 OPTIONS
To: <sip:10.208.250.86>;tag=3589544057-746199
Content-Length: 0

2013-09-30T12:34:09 227304133mS SIP Reg/Opt Rx: 30
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bK8e79449094e48a32075aeae1a20c20bc
From: <sip:10.208.250.86>;tag=2e999128fa184863
Call-ID: ae8a1eba21defc9dd58f29cff271b615
CSeq: 684920401 OPTIONS
To: <sip:10.208.250.86>;tag=3589544057-746199
Content-Length: 0

2013-09-30T12:34:14 227309132mS Sip: SIPDialog f4da6098 destroyed, size 0

### then ###

2013-09-30T12:35:09 227364127mS Sip: SIPDialog f4da6098 created, size 1
2013-09-30T12:35:09 227364128mS SIP Reg/Opt Tx: 30
OPTIONS sip:10.208.250.86 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bKc3cd973e6783adffd0f8fb67f898bbfa
From: <sip:10.208.250.86>;tag=323c76766508b94b
To: <sip:10.208.250.86>
Call-ID: 501bb4349332fc6eeee1cbbaf72da522
CSeq: 62993945 OPTIONS
Contact: <sip:10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T12:35:09 227364129mS SIP Tx: UDP 10.208.250.86:5060 -> 76.11.36.40:5060
OPTIONS sip:10.208.250.86 SIP/2.0
Via: SIP/2.0/UDP 10.208.250.86:5060;rport;branch=z9hG4bKc3cd973e6783adffd0f8fb67f898bbfa
From: <sip:10.208.250.86>;tag=323c76766508b94b
To: <sip:10.208.250.86>
Call-ID: 501bb4349332fc6eeee1cbbaf72da522
CSeq: 62993945 OPTIONS
Contact: <sip:10.208.250.86:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
User-Agent: IP Office 8.1 (63)
Content-Length: 0

2013-09-30T12:35:09 227364131mS SIP Rx: UDP 76.11.36.40:5060 -> 10.208.250.86:5060
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bKc3cd973e6783adffd0f8fb67f898bbfa
From: <sip:10.208.250.86>;tag=323c76766508b94b
Call-ID: 501bb4349332fc6eeee1cbbaf72da522
CSeq: 62993945 OPTIONS
To: <sip:10.208.250.86>;tag=3589544117-744816
Content-Length: 0

2013-09-30T12:35:09 227364133mS SIP Reg/Opt Rx: 30
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.208.250.86:5060;rport=5060;received=10.208.250.86;branch=z9hG4bKc3cd973e6783adffd0f8fb67f898bbfa
From: <sip:10.208.250.86>;tag=323c76766508b94b
Call-ID: 501bb4349332fc6eeee1cbbaf72da522
CSeq: 62993945 OPTIONS
To: <sip:10.208.250.86>;tag=3589544117-744816
Content-Length: 0

2013-09-30T12:35:14 227369132mS Sip: SIPDialog f4da6098 destroyed, size 0

This repeats every minute. I've dug through the notes for several providers, but I never get the line to register with the Provider.

??????? Why didn't I become a carpenter like my mother wanted....

 

[amriddle01]

I would delete and recreate the line as per Sean's suggestion, or failing that post your config somewhere (with anything sensitive changed i.e passwords) and I'll take a gander



"No problem monkey socks

 

[crankyralph]

Thanks amriddle01 and h-s-monkey!!!
Deleted and recreated the sip line and it registered. My incoming calls work like a charm.
Outgoing goes to dead air ( goes out properly on sip line, and I don't see any rejection but hear no ringback and the dialed number does not ring ) so I've got a call in to the provider to go over what they see from the IPO; whether it's a routing issue on their end or I'm not sending something they need. [ SIP newb, but learning... ]

I'll update with resolution, and thanks again for your help!!