I am getting same problem that my voip line becomes unregistered and I have to click on "Register the line" in mange gateway settings. Seems to happen after few hours. I have time between registrations set to 600 seconds. Here are some log entries:
Firstly successful registration:
15:21:33.281|.\Registrar.cpp(537)|Log2|Registrar|ClientRegs::Register:[CM504003]: Sent registration request for 10001@voiptalk<br>
15:21:33.593|.\Registrar.cpp(360)|Log2|Registrar|ClientRegs:
nSuccess:[CM504004]: Registration succeeded for: 10001@voiptalk<br>
... log entries continue then:
09:55:15.546|.\Registrar.cpp(445)|Log5|Registrar|ClientRegs:
nRequestRetry:Registration attempt for L:10001(voiptalk) is scheduled in 20 sec.<br>
11:09:00.281|.\Registrar.cpp(445)|Log5|Registrar|ClientRegs:
nRequestRetry:Registration attempt for L:10001(voiptalk) is scheduled in 40 sec.<br>
11:28:12.156|.\SLServer.cpp(32)|Log5||ListenConnect:SL: connected bundus:0/PHPExtension_0 at [bundus]/PHPExtension_0<br>
12:51:51.703|.\Registrar.cpp(445)|Log5|Registrar|ClientRegs:
nRequestRetry:Registration attempt for L:10001(voiptalk) is scheduled in 60 sec.<br>
14:43:39.359|.\CallMgr.cpp(793)|Log2|CallManager|CallMgr:
nRemoveIPs:IP(s) removed:[192.168.0.12]<br>
14:43:39.359|.\CallMgr.cpp(758)|Log2|CallManager|CallMgr:
nAddIPs:IP(s) added:[169.254.162.45]<br>
looks like PC has lost its LAN ip address
14:43:39.843|.\Registrar.cpp(537)|Log2|Registrar|ClientRegs::Register:[CM504003]: Sent registration request for 10001@voiptalk<br>
14:43:39.859|TransportSelector.cxx(401)|Error2|Resip|::ResipLogger:Can't find source interface to use<br>
14:43:40.156|.\Registrar.cpp(384)|Log2|Registrar|ClientRegs:
nFailure:[CM504005]: Registration failed for: 10001@voiptalk; Cause: 503 No route to host; warning: ; internal<br>
14:43:40.156|.\Registrar.cpp(393)|Log2|Registrar|ClientRegs:
nFailure:[CM504007]: Next attempt to register 10001@voiptalk is scheduled in 10 minutes<br>
14:43:41.093|.\Registrar.cpp(384)|Log2|Registrar|ClientRegs:
nFailure:[CM504005]: Registration failed for: 10001@voiptalk; Cause: 503 Service Unavailable; warning: ; internal<br>
14:43:49.437|.\CallMgr.cpp(793)|Log2|CallManager|CallMgr:
nRemoveIPs:IP(s) removed:[169.254.162.45]<br>
14:43:49.437|.\CallMgr.cpp(758)|Log2|CallManager|CallMgr:
nAddIPs:IP(s) added:[192.168.0.12]<br>
but then gets it back
14:43:50.015|.\Registrar.cpp(537)|Log2|Registrar|ClientRegs::Register:[CM504003]: Sent registration request for 10001@voiptalk<br>
14:43:50.031|.\Registrar.cpp(384)|Log2|Registrar|ClientRegs:
nFailure:[CM504005]: Registration failed for: 10001@voiptalk; Cause: 503 Service Unavailable; warning: ; internal<br>
14:43:50.031|.\Registrar.cpp(393)|Log2|Registrar|ClientRegs:
nFailure:[CM504007]: Next attempt to register 10001@voiptalk is scheduled in 10 minutes<br>
14:53:50.312|.\Registrar.cpp(537)|Log2|Registrar|ClientRegs::Register:[CM504003]: Sent registration request for 10001@voiptalk<br>
14:53:50.578|.\Registrar.cpp(360)|Log2|Registrar|ClientRegs:
nSuccess:[CM504004]: Registration succeeded for: 10001@voiptalk<br>
registers OK, but then seems to keep trying to register again, when try to make a call it says no lines available
15:01:22.656|.\Registrar.cpp(445)|Log5|Registrar|ClientRegs:
nRequestRetry:Registration attempt for L:10001(voiptalk) is scheduled in 20 sec.<br>
15:11:04.921|.\Registrar.cpp(445)|Log5|Registrar|ClientRegs:
nRequestRetry:Registration attempt for L:10001(voiptalk) is scheduled in 40 sec.<br>
15:40:47.843|.\CallLeg.cpp(110)|Log5||CallLeg:
nNewCall:[CM500002]: Info on incoming INVITE:
INVITE sip:
[email protected];transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.50:5060;branch=z9hG4bK-d6c-346e44-3691db5f
Max-Forwards: 70
Contact: <sip:
[email protected]:5060;transport=UDP>
To: "02079342501"<sip:
[email protected]:5060>
From: "Dom desk"<sip:
[email protected]:5060>;tag=80ad2698-c0a80032-13c4-d6b-5a5103b1-d6b
Call-ID:
[email protected]
CSeq: 2 INVITE
Session-Expires: 120
Min-SE: 90
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp
Allow: INVITE, ACK, BYE, REFER, NOTIFY, CANCEL, INFO, OPTIONS
Proxy-Authorization: Digest username="11",realm="3CXPhoneSystem",nonce="12850414847:41eaebcab5b26127fa09eceee04c9e34",uri="sip:
[email protected];transport=UDP",response="1531a8093797b130a0ff387c4fd73c9a",algorithm=MD5
Supported: replaces, 100rel, timer
User-Agent: SpeedTouch 780
Content-Length: 0
X-Serialnumber: CP0737JT3MK
<br>
15:40:48.375|.\CallCtrl.cpp(100)|Log2||CallCtrl:
nIncomingCall:[CM503001]: Call(1): Incoming call from Ext.11 to "02079342501"<sip:
[email protected]:5060><br>
15:40:50.218|.\Extension.cpp(663)|Log3||Extension:
rintEndpointInfo:[CM505001]: Ext.11: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [SpeedTouch 780] Transport: [sip:192.168.0.12:5060]<br>
15:40:50.218|.\CallLeg.cpp(614)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:1.1<br>
15:40:50.234|.\CallCtrl.cpp(225)|Log3||CallCtrl:
nSelectRouteReq:[CM503010]: Making route(s) to "02079342501"<sip:
[email protected]:5060><br>
15:40:50.250|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210000] C:1.1:Offer received. RTP connection: 192.168.0.50:33382(33383)<br>
15:40:50.375|.\Line.cpp(637)|Error1||Line::Line:[CM303003]: There are no available outbound lines on gateway voiptalk at this time.<br>
15:40:50.375|.\CallCtrl.cpp(311)|Log2||CallCtrl:
nSelectRouteReq:[CM503015]: Call(1): Target is not registered: VoIPline:02079342501 dialed on (AnyLine@voiptalk)<br>
15:40:50.375|.\CallCtrl.cpp(305)|Log2||CallCtrl:
nSelectRouteReq:[CM503004]: Call(1): Calling: PSTNline:10000@[Dev:sip:
[email protected]:5161]<br>
15:40:50.390|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210006] C:1.2:Offer provided. Connection(by pass mode): 192.168.0.50:33382(33383)<br>
15:40:50.421|.\CallCtrl.cpp(145)|Log3||CallCtrl:
nAnsweredCall:[CM503002]: Call(1): Alerting sip:
[email protected]:5161<br>
15:40:50.421|.\Line.cpp(943)|Log2||Line:
rintEndpointInfo:[CM505002]: Gateway:[Mob home] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Linksys/SPA3102-3.3.6(GW)] Transport: [sip:192.168.0.12:5060]<br>
15:40:50.421|.\CallLeg.cpp(614)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:1.2<br>
15:40:50.421|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210001] C:1.2:Answer received. RTP connection: 192.168.0.40:16396(16397)<br>
15:40:50.437|.\CallCtrl.cpp(419)|Log2||CallCtrl:
nLegConnected:[CM503007]: Call(1): Device joined: sip:
[email protected]:5060<br>
15:40:50.437|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210007] C:1.1:Answer provided. Connection(by pass mode): 192.168.0.40:16396(16397)<br>
15:40:50.453|.\CallCtrl.cpp(419)|Log2||CallCtrl:
nLegConnected:[CM503007]: Call(1): Device joined: sip:
[email protected]:5161<br>
15:40:50.562|.\CallLeg.cpp(325)|Log5||CallLeg:
nConfirmed:Session 405 of leg C:1.1 is confirmed<br>
15:40:55.531|.\CallLeg.cpp(614)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:1.2<br>
15:40:55.531|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210000] C:1.2:Offer received. RTP connection: 192.168.0.40:16396(16397)<br>
15:40:55.531|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210006] C:1.1:Offer provided. Connection(by pass mode): 192.168.0.40:16396(16397)<br>
15:40:55.656|.\CallLeg.cpp(614)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:1.1<br>
15:40:55.656|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210001] C:1.1:Answer received. RTP connection: 192.168.0.50:33382(33383)<br>
15:40:55.656|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210007] C:1.2:Answer provided. Connection(by pass mode): 192.168.0.50:33382(33383)<br>
... log entries omitted as repeating
17:44:24.500|.\Registrar.cpp(537)|Log2|Registrar|ClientRegs::Register:[CM504003]: Sent registration request for 10001@voiptalk<br>
17:44:24.781|.\Registrar.cpp(360)|Log2|Registrar|ClientRegs:
nSuccess:[CM504004]: Registration succeeded for: 10001@voiptalk<br>
after the above manually forced registration it works ok via voip line
18:12:57.578|.\CallLeg.cpp(110)|Log5||CallLeg:
nNewCall:[CM500002]: Info on incoming INVITE:
INVITE sip:
[email protected];transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.50:5060;branch=z9hG4bK-3115-bfbd1e-5238bcf6
Max-Forwards: 70
Contact: <sip:
[email protected]:5060;transport=UDP>
To: "02079342501"<sip:
[email protected]:5060>
From: "Home phones"<sip:
[email protected]:5060>;tag=80ad2398-c0a80032-13c4-3115-28b552d0-3115
Call-ID:
[email protected]
CSeq: 2 INVITE
Session-Expires: 120
Min-SE: 90
Accept: application/dtmf-relay, x-application/dtmf-relay, application/sdp
Allow: INVITE, ACK, BYE, REFER, NOTIFY, CANCEL, INFO, OPTIONS
Proxy-Authorization: Digest username="10",realm="3CXPhoneSystem",nonce="12850423977:314f36c77a4b83f25505bbc5df36e054",uri="sip:
[email protected];transport=UDP",response="21b648214cfa9d6bf8dfce7deadd5e16",algorithm=MD5
Supported: replaces, 100rel, timer
User-Agent: SpeedTouch 780
Content-Length: 0
X-Serialnumber: CP0737JT3MK
<br>
18:12:57.593|.\CallCtrl.cpp(100)|Log2||CallCtrl:
nIncomingCall:[CM503001]: Call(4): Incoming call from Ext.10 to "02079342501"<sip:
[email protected]:5060><br>
18:12:57.968|.\Extension.cpp(663)|Log3||Extension:
rintEndpointInfo:[CM505001]: Ext.10: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [SpeedTouch 780] Transport: [sip:192.168.0.12:5060]<br>
18:12:57.968|.\CallLeg.cpp(614)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:4.1<br>
18:12:57.968|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210000] C:4.1:Offer received. RTP connection: 192.168.0.50:37036(37037)<br>
18:12:57.968|.\CallCtrl.cpp(225)|Log3||CallCtrl:
nSelectRouteReq:[CM503010]: Making route(s) to "02079342501"<sip:
[email protected]:5060><br>
18:12:58.000|.\CallCtrl.cpp(305)|Log2||CallCtrl:
nSelectRouteReq:[CM503004]: Call(4): Calling: VoIPline:10001@[Dev:sip:
[email protected]:5060]<br>
18:12:58.187|.\SLServer.cpp(320)|Error1|MediaServer|MediaServerReporting::STUN:[MS101003] C:4.2: Possible firewall problem. Address mapping failed on STUN server 192.168.0.12:3478 for local address ":9000"<br>
18:12:58.203|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210002] C:4.2:Offer provided. Connection(transcoding mode): 192.168.0.12:9000(9001)<br>
18:12:58.218|.\CallCtrl.cpp(305)|Log2||CallCtrl:
nSelectRouteReq:[CM503004]: Call(4): Calling: PSTNline:10000@[Dev:sip:
[email protected]:5161]<br>
18:13:02.703|.\CallCtrl.cpp(145)|Log3||CallCtrl:
nAnsweredCall:[CM503002]: Call(4): Alerting sip:
[email protected]:5060<br>
18:13:02.703|.\Line.cpp(946)|Log2||Line:
rintEndpointInfo:[CM505003]: Provider:[voiptalk] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [] Transport: [sip:192.168.0.12:5060]<br>
18:13:08.125|.\CallLeg.cpp(614)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:4.2<br>
18:13:08.125|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210001] C:4.2:Answer received. RTP connection: 217.14.138.177:11692(11693)<br>
18:13:08.125|.\SLServer.cpp(376)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210003] C:4.1:Answer provided. Connection(transcoding mode):192.168.0.12:7012(7013)<br>
18:13:08.140|.\CallCtrl.cpp(419)|Log2||CallCtrl:
nLegConnected:[CM503007]: Call(4): Device joined: sip:
[email protected]:5060<br>
18:13:08.156|.\CallCtrl.cpp(419)|Log2||CallCtrl:
nLegConnected:[CM503007]: Call(4): Device joined: sip:
[email protected]:5060<br>
18:13:08.250|.\CallLeg.cpp(325)|Log5||CallLeg:
nConfirmed:Session 465 of leg C:4.1 is confirmed<br>