Problem getting phone to register

[weaver4]

I have a few Linksys SPA941 telephones.

For the User ID I am using: t001a and the password is zebra. I set this up correctly, with ext 211, but the phones won't register. The phones are on the same subnet as the server and the are making contact, but they fail registration.

Here is my log file.

Please help.

======================================

10:56:01.937|.\CallMgr.cpp(365)|Log2||LoadLicenceInfo:Licence loading error
10:56:01.953|.\CallMgr.cpp(69)|Log2|CallManager|CallMgr::Initialize:Start 3CX PhoneSystem Call Manager
10:56:01.953|.\CallMgr.cpp(70)|Log2|CallManager|CallMgr::Initialize:Version: 3.0.1928.0
10:56:02.171|.\CallMgr.cpp(78)|Log2|CallManager|CallMgr::Initialize:** Initializing SIP stack **
10:56:02.203|.\CM_Stack.cpp(24)|Log2|SipStack|CallMgr::Stack::Initialize:** Adding transports **
10:56:02.203|UdpTransport.cxx(34)|Trace5|Resip|>>:Creating UDP transport host= port=5060 ipv4=1
10:56:02.218|TcpTransport.cxx(27)|Trace5|Resip|>>:Creating TCP transport host= port=5060 ipv4=1
10:56:02.218|DnsUtil.cxx(140)|Trace5|Resip|>>:local hostname does not contain a domain part
10:56:02.218|.\CallMgr.cpp(84)|Trace5|CallManager|CallMgr::Initialize:My address: "3cxPbx"<sip:[email protected]:5060>
10:56:02.234|.\CallMgr.cpp(190)|Log2|DUM|CallMgr:umThread::thread:** Enter DUM Thread **
10:56:02.312|.\DBAccessor.cpp(69)|Log2||DBA:** Database connection Ok **
10:56:02.437|.\CM_Stack.cpp(85)|Log2|SipStack|CallMgr::Stack::thread:** Enter Stack Loop **
10:56:02.546|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
10:56:05.531|.\SLServer.cpp(21)|Log2||ListenConnect:SL: connected 3cx-test:0/MediaServer at [3cx-test:0]/MediaServer
10:56:05.531|.\MSInterface.cpp(637)|Log2||MediaServerConnected:Media Server is connected
10:56:05.531|.\MSInterface.cpp(640)|Trace5||MediaServerConnected:New MS Factory created: 11775080
10:56:05.531|.\MSInterface.cpp(646)|Trace5||MediaServerConnected:Factory is added
10:56:05.609|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
10:56:05.718|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
10:56:06.546|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:06.546|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':IVR not found on 127.0.0.1:5483
10:56:07.546|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:07.546|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':*** Connecting to [127.0.0.1:5483] ***
10:56:08.515|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:08.515|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':IVR not found on 127.0.0.1:5483
10:56:08.781|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
10:56:08.890|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
10:56:09.515|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:09.515|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':*** Connecting to [127.0.0.1:5483] ***
10:56:10.593|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:10.593|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':IVR not found on 127.0.0.1:5483
10:56:11.593|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:11.593|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':*** Connecting to [127.0.0.1:5483] ***
10:56:11.953|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
10:56:12.062|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
10:56:12.562|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:12.562|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':IVR not found on 127.0.0.1:5483
10:56:12.625|.\SLServer.cpp(21)|Log2||ListenConnect:SL: connected 3cx-test:0/VoiceBoxManagerService at [3cx-test:0]/VoiceBoxManagerService
10:56:13.234|.\SLServer.cpp(21)|Log2||ListenConnect:SL: connected 3cx-test:5483/IVRServer at [3cx-test:5483]/IVRServer
10:56:13.234|.\IVRInterface.cpp(249)|Log2||IVRConnected:IVR Server is connected
10:56:13.234|.\IVRInterface.cpp(252)|Trace5||IVRConnected:New MS Factory created: 11775816
10:56:13.234|.\IVRInterface.cpp(258)|Trace5||IVRConnected:Factory is added
10:56:13.562|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:13.562|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':*** Connecting to [127.0.0.1:5483] ***
10:56:13.562|.\MSInterface.cpp(540)|Trace5||??:MSLogReports
10:56:13.562|.\SLServer.cpp(289)|Log2|MediaServer|MediaServerReporting::Service:from '3cx-test:0/MediaServer':*** Connected to 3cx-test:5483/IVRServer at 127.0.0.1:5483 ***
10:56:15.125|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
10:56:15.125|.\CallMgr.cpp(244)|Error1|CallManager|CallMgr::failResolveExtIp:Failed to resolve external IP!
10:57:02.265|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
10:58:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
10:59:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:00:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:01:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:02:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:03:02.218|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:04:02.265|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:05:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:06:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:07:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:08:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:08:18.859|.\SLServer.cpp(21)|Log2||ListenConnect:SL: connected 3cx-test:0/PHPExtension_0 at [3cx-test:0]/PHPExtension_0
11:09:02.218|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:10:02.218|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:11:02.265|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:12:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:13:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:14:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:15:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:16:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:16:15.328|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:16:18.390|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:16:18.500|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:16:21.562|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:16:21.671|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:16:24.734|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:16:24.843|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:16:27.906|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:16:27.906|.\CallMgr.cpp(244)|Error1|CallManager|CallMgr::failResolveExtIp:Failed to resolve external IP!
11:17:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:18:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:19:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:20:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:21:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:22:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:23:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:24:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:25:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:25:38.906|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-ef50f548 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:25:38.937|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-ad237f64 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:26:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:26:21.578|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-b1eb1f8c cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:26:21.593|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-5bd3bb34 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:27:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:27:34.203|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-dfc82556 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:27:34.218|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-7ef85ff0 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:28:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:29:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:30:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:30:35.781|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-8d9489be cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:30:35.812|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-50ad06a8 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:31:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:32:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:33:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:33:50.484|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-37accb5e cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:33:50.500|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-ca48b188 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:34:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:34:24.109|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-8df5503 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:34:24.125|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-8ca8ac1a cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:35:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:36:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:36:28.015|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:36:31.078|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:36:31.187|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:36:34.250|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:36:34.359|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:36:37.421|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:36:37.531|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:36:40.593|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:36:40.593|.\CallMgr.cpp(244)|Error1|CallManager|CallMgr::failResolveExtIp:Failed to resolve external IP!
11:37:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:38:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:39:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:40:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:40:15.109|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-519d104c cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:40:15.125|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-f6fc8033 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:40:46.718|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-95e0d329 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:40:46.734|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-fbddd2fb cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:41:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:42:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:43:02.265|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:44:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:44:33.984|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-e3bdc8c3 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:44:34.015|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-1556ca29 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:45:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:46:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:46:37.140|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-1e035d45 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:46:37.156|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.10.4 tid=-28d170c3 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:47:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:48:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:49:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:50:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:51:02.281|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:51:04.812|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-5f66efe4 cseq=REGISTER [email protected]:5060 / 2027 from(wire)
11:51:04.828|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-440eab73 cseq=REGISTER [email protected]:5061 / 2027 from(wire)
11:51:53.781|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-fd7bd639 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:51:53.812|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-fe802c67 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:52:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:53:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:54:02.234|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:54:06.453|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-f8d7520d cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:54:06.468|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-befcc29a cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:55:02.218|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:55:32.218|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-7e21b460 cseq=REGISTER [email protected]:5060 / 1 from(wire)
11:55:32.234|ServerAuthManager.cxx(50)|Trace5|Resip|>>:ServerAuth rejected request SipReq: REGISTER 173.100.11.65 tid=-4b080c55 cseq=REGISTER [email protected]:5061 / 1 from(wire)
11:56:02.218|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:56:40.718|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:56:43.781|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:56:43.906|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:56:46.968|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:56:47.078|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:56:50.140|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:56:50.250|.\StunClient.cpp(57)|Log2|STUN|StunClient:rocess:Send initial STUN request to 194.221.62.209
11:56:53.312|.\StunClient.cpp(84)|Error1|STUN|StunClient:rocess:STUN failed to resolve external IP using server 194.221.62.209
11:56:53.312|.\CallMgr.cpp(244)|Error1|CallManager|CallMgr::failResolveExtIp:Failed to resolve external IP!
11:57:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:58:02.265|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
11:59:02.203|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
12:00:02.250|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
12:01:02.296|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message

 

[N_G]

This is not the server status log. Please send us the server status log, i.e go to phone system > server status and clear the log and then try to register with a phone.

P.S. We have tested with those linksys phones and they work fine.

 

[archie]

I have a few Linksys SPA941 telephones.

For the User ID I am using: t001a

Please, set user ID to be 211 (if you want it to be ext. 211)

 

[weaver4]

Changing the User ID worked. Thanks!

I could use a different ID on asterisk so that is how I got confused.

 

[archie]

You can use different IDs on 3CX either. But don't mix User ID and Authentication ID.
1. User ID of the phone should match 'Extension number' in extension's settings, and should be of digits only, no letters.
2. Auth ID should match ID in Authentication section of the settings, and could be any alphanumeric string you want.