- Joined
- Jul 31, 2008
- Messages
- 19
- Reaction score
- 0
I have my 3CX system installed now and I can receive inbound calls with one slight hitch. I can hear the external caller talking but they cannot hear me. I can make outbound calls perfectly. I couldn't see anything obvious in the 3CX server status screen so I turned on verbose logging and done a quick test and this is the result. I am using VOIPon by the way and Linksys SPA942 handsets. I thought it might be the firmware on the SPA942 which was v5.1.15(a) so I upgraded them to the latest version without any change.
14:01:22.167 Call::Terminate [CM503008]: Call(485): Call is terminated
14:01:22.167 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:01:22.167 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:01:12.011 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:01:12.011 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:01:12.011 Call::Terminate [CM503008]: Call(485): Call is terminated
14:01:12.011 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:01:11.995 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:50.026 CallCtrl:
nLegConnected [CM503007]: Call(485): Device joined: sip:[email protected]:5060
14:00:50.026 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:00:50.026 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:49.995 CallCtrl:
nLegConnected [CM503007]: Call(485): Device joined: sip:[email protected]:5060
14:00:49.870 MediaServerReporting::SetRemoteParty [MS210003] C:485.1:Answer provided. Connection(transcoding mode):82.108.156.100:9016(9017)
14:00:49.870 MediaServerReporting::SetRemoteParty [MS210001] C:485.5:Answer received. RTP connection: 192.168.1.48:16476(16477)
14:00:49.870 Extension:
rintEndpointInfo [CM505001]: Ext.1462: Device info: Device Identified: [Man: Linksys;Mod: SPA-941;Rev: General] Capabilities:[reinvite, no-replaces, able-no-sdp, recvonly] UserAgent: [Linksys/SPA942-5.2.8] Transport: [sip:192.168.1.225:5060]
14:00:49.870 CallLeg::setRemoteSdp Remote SDP is set for legC:485.5
14:00:49.870 CallCtrl:
nAnsweredCall [CM503002]: Call(485): Alerting sip:[email protected]:5060
14:00:48.401 CallLeg:
nFailure [CM503003]: Call(485): Call to sip:[email protected] has failed; Cause: 486 Busy Here; from IP:192.168.1.38:5060
14:00:48.386 MediaServerReporting::SetRemoteParty [MS210002] C:485.9:Offer provided. Connection(transcoding mode): 192.168.1.225:7204(7205)
14:00:48.386 CallLeg:
nFailure [CM503003]: Call(485): Call to sip:[email protected] has failed; Cause: 486 Busy Here; from IP:192.168.1.49:5060
14:00:48.354 MediaServerReporting::SetRemoteParty [MS210002] C:485.8:Offer provided. Connection(transcoding mode): 192.168.1.225:7202(7203)
14:00:48.323 MediaServerReporting::SetRemoteParty [MS210002] C:485.7:Offer provided. Connection(transcoding mode): 192.168.1.225:7200(7201)
14:00:48.307 MediaServerReporting::SetRemoteParty [MS210002] C:485.6:Offer provided. Connection(transcoding mode): 192.168.1.225:7198(7199)
14:00:48.276 MediaServerReporting::SetRemoteParty [MS210002] C:485.5:Offer provided. Connection(transcoding mode): 192.168.1.225:7196(7197)
14:00:48.261 MediaServerReporting::SetRemoteParty [MS210002] C:485.4:Offer provided. Connection(transcoding mode): 192.168.1.225:7194(7195)
14:00:48.229 MediaServerReporting::SetRemoteParty [MS210002] C:485.3:Offer provided. Connection(transcoding mode): 192.168.1.225:7192(7193)
14:00:48.214 MediaServerReporting::SetRemoteParty [MS210002] C:485.2:Offer provided. Connection(transcoding mode): 192.168.1.225:7190(7191)
14:00:48.182 CallCtrl:
nSelectRouteReq [CM503004]: Call(485): Calling: RingAll8000:1459Ext.14591460Ext.14601461Ext.14611462Ext.14621467Ext.14671470Ext.14701455Ext.14551454Ext.14541452Ext.1452@[Dev:sip:[email protected]:5060, Dev:sip:[email protected]:5060, Dev:sip:[email protected]:5060, Dev:sip:[email protected]:5060, Dev:sip:[email protected]:5060, Dev:sip:[email protected]:5060, Dev:sip:[email protected]:5060, Dev:sip:[email protected]:5060]
14:00:48.182 CallCtrl:
nSelectRouteReq [CM503010]: Making route(s) to [sip:[email protected]:5060]
14:00:48.182 MediaServerReporting::SetRemoteParty [MS210000] C:485.1:Offer received. RTP connection: 192.168.1.1:10250(10251)
14:00:48.182 CallLeg::setRemoteSdp Remote SDP is set for legC:485.1
14:00:48.182 Line:
rintEndpointInfo [CM505003]: Provider:[VOIPon] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Asterisk PBX] Transport: [sip:192.168.1.225:5060]
14:00:48.182 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:00:48.182 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:48.151 CallCtrl:
nIncomingCall [CM503001]: Call(485): Incoming call from 07970206489@(Ln.10000@VOIPon) to [sip:[email protected]:5060]
14:00:46.917 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:00:46.901 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:46.901 CallLeg:
nNewCall [CM500002]: Info on incoming INVITE:
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.1.1:5060;branch=z9hG4bK7d7bce07;rport=5060
Max-Forwards: 70
Contact: [sip:[email protected]:5060]
To: [sip:[email protected]]
From: "07970206489"[sip:[email protected]];tag=as1caa5e2c
Call-ID: [email protected]
CSeq: 102 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Date: Tue, 26 Aug 2008 13:54:18 GMT
Supported: replaces
User-Agent: Asterisk PBX
Content-Length: 0
I also changed the extensions and put a tick in the 'pbx delivers audio' section without any change.
14:01:22.167 Call::Terminate [CM503008]: Call(485): Call is terminated
14:01:22.167 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:01:22.167 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:01:12.011 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:01:12.011 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:01:12.011 Call::Terminate [CM503008]: Call(485): Call is terminated
14:01:12.011 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:01:11.995 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:50.026 CallCtrl:
14:00:50.026 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:00:50.026 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:49.995 CallCtrl:
14:00:49.870 MediaServerReporting::SetRemoteParty [MS210003] C:485.1:Answer provided. Connection(transcoding mode):82.108.156.100:9016(9017)
14:00:49.870 MediaServerReporting::SetRemoteParty [MS210001] C:485.5:Answer received. RTP connection: 192.168.1.48:16476(16477)
14:00:49.870 Extension:
14:00:49.870 CallLeg::setRemoteSdp Remote SDP is set for legC:485.5
14:00:49.870 CallCtrl:
14:00:48.401 CallLeg:
14:00:48.386 MediaServerReporting::SetRemoteParty [MS210002] C:485.9:Offer provided. Connection(transcoding mode): 192.168.1.225:7204(7205)
14:00:48.386 CallLeg:
14:00:48.354 MediaServerReporting::SetRemoteParty [MS210002] C:485.8:Offer provided. Connection(transcoding mode): 192.168.1.225:7202(7203)
14:00:48.323 MediaServerReporting::SetRemoteParty [MS210002] C:485.7:Offer provided. Connection(transcoding mode): 192.168.1.225:7200(7201)
14:00:48.307 MediaServerReporting::SetRemoteParty [MS210002] C:485.6:Offer provided. Connection(transcoding mode): 192.168.1.225:7198(7199)
14:00:48.276 MediaServerReporting::SetRemoteParty [MS210002] C:485.5:Offer provided. Connection(transcoding mode): 192.168.1.225:7196(7197)
14:00:48.261 MediaServerReporting::SetRemoteParty [MS210002] C:485.4:Offer provided. Connection(transcoding mode): 192.168.1.225:7194(7195)
14:00:48.229 MediaServerReporting::SetRemoteParty [MS210002] C:485.3:Offer provided. Connection(transcoding mode): 192.168.1.225:7192(7193)
14:00:48.214 MediaServerReporting::SetRemoteParty [MS210002] C:485.2:Offer provided. Connection(transcoding mode): 192.168.1.225:7190(7191)
14:00:48.182 CallCtrl:
14:00:48.182 CallCtrl:
14:00:48.182 MediaServerReporting::SetRemoteParty [MS210000] C:485.1:Offer received. RTP connection: 192.168.1.1:10250(10251)
14:00:48.182 CallLeg::setRemoteSdp Remote SDP is set for legC:485.1
14:00:48.182 Line:
14:00:48.182 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:00:48.182 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:48.151 CallCtrl:
14:00:46.917 LineCfg::getInboundTarget [CM503012]: Inbound office hours' rule for LN:10000 forwards to DN:8000
14:00:46.901 LineCfg::getInboundTarget Looking for inbound target: called=08713091822; caller=07970206489
14:00:46.901 CallLeg:
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.1.1:5060;branch=z9hG4bK7d7bce07;rport=5060
Max-Forwards: 70
Contact: [sip:[email protected]:5060]
To: [sip:[email protected]]
From: "07970206489"[sip:[email protected]];tag=as1caa5e2c
Call-ID: [email protected]
CSeq: 102 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Date: Tue, 26 Aug 2008 13:54:18 GMT
Supported: replaces
User-Agent: Asterisk PBX
Content-Length: 0
I also changed the extensions and put a tick in the 'pbx delivers audio' section without any change.