Hola Ernesto
En mi post anterior indiqué que entre las extensiones locales (LAN 3CX) y la extensión remota (distinta ip pública), la comunicación era correcta y que el problema que tenía era que esa extensión remota no recibía llamadas por PSTN.
Eso es incorrecto ya que la extensión remota funciona perfectamente cuando sale, tanto por VoIp como por PSTN, pero no es capaz de recibir llamadas.
Revisé los puertos del router de la línea de la extensión remota y abrí y mapeé los mismos puertos que en el router de la línea de la 3CX probé cambiando la configuarción del Stun y el RPT, pero nada cambia.
Puede ser que haya puertos mal configuarados en el START y END?
Cuando se hace una llamada desde una extensión local a la extensión remota, esta suena y al descolgar el auricular no establece la comunicación y no se escucha nada en ninguna de las dos extensiones hasta que en la extensión local atiende la recepcionista digital y finaliza la llamada, la extensión remota continúa sonando un tiempo largo hasta que corta.
La extensión remota está configurada para que "Sin respuesta" atienda la recepcionista digital.
Para hacer estas pruebas utilizé la extensión local 10, que funciona perfectamente y la extensión remota 11
La ip pública de la extensión 10 local es 79.148.231.179, línea ADSL de Telefónica 6MB
La ip pública de la extensión 11 remota es 80.37.242.23 línea ADSL de Telefónica 3 MB
El log siguiente corresponde a esa llamada fallida
20:30:29.104 Call::Terminate [CM503008]: Call(15): Call is terminated
20:30:29.104 Call::Terminate [CM503008]: Call(15): Call is terminated
20:30:03.832 CallLeg:
nConfirmed Session 336 of leg C:15.1 is confirmed
20:30:03.457 CallCtrl:
nLegConnected [CM503007]: Call(15): Device joined: sip:
[email protected]:40600;rinstance=ab64e15346445d68
20:30:03.457 CallCtrl:
nLegConnected [CM503007]: Call(15): Device joined: sip:
[email protected]:2048;line=y1c70nao
20:30:03.457 MediaServerReporting::SetRemoteParty [MS210005] C:15.1:Answer provided. Connection(proxy mode):192.168.2.2:7032(7033)
20:30:03.457 MediaServerReporting::SetRemoteParty [MS210001] C:15.4:Answer received. RTP connection: 127.0.0.1:40616(40617)
20:30:03.457 CallLeg::setRemoteSdp Remote SDP is set for legC:15.4
20:30:03.442 Extension:
rintEndpointInfo [CM505001]: Ext.81: Device info: Device Identified: [Man: 3CX Ltd.;Mod: 3CX IVR;Rev: 1] Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CX IVR] Transport: [sip:127.0.0.1:5060]
20:30:03.442 CallCtrl:
nAnsweredCall [CM503002]: Call(15): Alerting sip:
[email protected]:40600;rinstance=ab64e15346445d68
20:30:02.927 MediaServerReporting::SetRemoteParty [MS210004] C:15.4:Offer provided. Connection(proxy mode): 127.0.0.1:7034(7035)
20:30:02.927 CallCtrl:
nRerouteReq [CM503005]: Call(15): Forwarding: Ext:Ext.81@[Dev:sip:
[email protected]:40600;rinstance=ab64e15346445d68]
20:29:47.910 MediaServerReporting::SetRemoteParty [MS210002] C:15.3:Offer provided. Connection(transcoding mode): 79.148.231.179:9014(9015)
20:29:47.739 MediaServerReporting::SetRemoteParty [MS210002] C:15.2:Offer provided. Connection(transcoding mode): 79.148.231.179:9012(9013)
20:29:47.520 CallCtrl:
nSelectRouteReq [CM503004]: Call(15): Calling: Shared:Ext.11@[Dev:sip:
[email protected]:2048;line=fsdj1npb, Dev:sip:
[email protected]:2051;line=pe3xgzmp]
20:29:47.520 CallCtrl:
nSelectRouteReq [CM503010]: Making route(s) to [sip:
[email protected]:5060;user=phone]
20:29:47.520 MediaServerReporting::SetRemoteParty [MS210000] C:15.1:Offer received. RTP connection: 192.168.2.50:59112(59113)
20:29:47.520 CallLeg::setRemoteSdp Remote SDP is set for legC:15.1
20:29:47.520 Extension:
rintEndpointInfo [CM505001]: Ext.10: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [snom300/7.3.7] Transport: [sip:192.168.2.2:5060]
20:29:47.505 CallCtrl:
nIncomingCall [CM503001]: Call(15): Incoming call from Ext.10 to [sip:
[email protected]:5060;user=phone]
20:29:47.505 CallLeg:
nNewCall [CM500002]: Info on incoming INVITE:
INVITE sip:
[email protected]:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.2.50:2048;branch=z9hG4bK-dxjhuxoo607n;rport=2048
Max-Forwards: 70
Contact: [sip:
[email protected]:2048;line=y1c70nao];reg-id=1
To: [sip:
[email protected]:5060;user=phone]
From: "HKS DIRECCION"[sip:
[email protected]:5060];tag=50z9f1cd20
Call-ID: 3c2f6039ef3d-cllnh72qfidv
CSeq: 2 INVITE
Session-Expires: 3600;refresher=uas
Min-SE: 90
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO
Proxy-Authorization: Digest username="10",realm="3CXPhoneSystem",nonce="12869609387:8d5f1caccf861e2376e410a028907a88",uri="sip:
[email protected]:5060;user=phone",response="1b0fc90a407da869bd7d5f8b7049dd06",algorithm=MD5
Supported: timer, 100rel, replaces, from-change
User-Agent: snom300/7.3.7
Allow-Events: talk, hold, refer, call-info
Content-Length: 0
P-Key-Flags: keys="3"
Este otro log corresponde a una llamada desde la extensión local a la remota, cortando desde la extensión local la llamada.
La extensión remota continúa sonando a pesar de haber cortado en la extensión local, lo he dejado sonar hasta que se ha cortado la llamada.
20:14:26.413 StunClient:
nInitTests [CM506001]: STUN request to resolve SIP external IP
ort mapping is sent to STUN server 75.101.138.128:3478 over Transport 192.168.2.2:5060
20:12:09.941 Call::Terminate [CM503008]: Call(10): Call is terminated
20:11:55.074 MediaServerReporting::SetRemoteParty [MS210002] C:10.2:Offer provided. Connection(transcoding mode): 79.148.231.179:9002(9003)
20:11:54.856 CallCtrl:
nSelectRouteReq [CM503004]: Call(10): Calling: Ext:Ext.11@[Dev:sip:
[email protected]:2051;line=pe3xgzmp]
20:11:54.856 CallCtrl:
nSelectRouteReq [CM503010]: Making route(s) to [sip:
[email protected]:5060;user=phone]
20:11:54.856 MediaServerReporting::SetRemoteParty [MS210000] C:10.1:Offer received. RTP connection: 192.168.2.50:50470(50471)
20:11:54.856 CallLeg::setRemoteSdp Remote SDP is set for legC:10.1
20:11:54.856 Extension:
rintEndpointInfo [CM505001]: Ext.10: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [snom300/7.3.7] Transport: [sip:192.168.2.2:5060]
20:11:54.840 CallCtrl:
nIncomingCall [CM503001]: Call(10): Incoming call from Ext.10 to [sip:
[email protected]:5060;user=phone]
20:11:54.840 CallLeg:
nNewCall [CM500002]: Info on incoming INVITE:
INVITE sip:
[email protected]:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.2.50:2048;branch=z9hG4bK-q9quztzfuph0;rport=2048
Max-Forwards: 70
Contact: [sip:
[email protected]:2048;line=y1c70nao];reg-id=1
To: [sip:
[email protected]:5060;user=phone]
From: "HKS DIRECCION"[sip:
[email protected]:5060];tag=52eqy08jgw
Call-ID: 3c2f5c09074d-0ybe9oarft1m
CSeq: 2 INVITE
Session-Expires: 3600;refresher=uas
Min-SE: 90
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO
Proxy-Authorization: Digest username="10",realm="3CXPhoneSystem",nonce="12869608314:295145a99f4580481a2f66bbc335f84f",uri="sip:
[email protected]:5060;user=phone",response="f60ef77b28b66ec82999c40464e7e2e2",algorithm=MD5
Supported: timer, 100rel, replaces, from-change
User-Agent: snom300/7.3.7
Allow-Events: talk, hold, refer, call-info
Content-Length: 0
P-Key-Flags: keys="3"
Este otro log corresponde a una llamada hecha desde la extensión local a la remota, la extensión remota no suena y atiende luego de unos segundos la recepcionista digital en la extensión local.
20:18:13.881 Call::Terminate [CM503008]: Call(11): Call is terminated
20:18:13.881 Call::Terminate [CM503008]: Call(11): Call is terminated
20:17:48.577 CallLeg:
nConfirmed Session 245 of leg C:11.1 is confirmed
20:17:48.203 CallCtrl:
nLegConnected [CM503007]: Call(11): Device joined: sip:
[email protected]:40600;rinstance=ab64e15346445d68
20:17:48.203 CallCtrl:
nLegConnected [CM503007]: Call(11): Device joined: sip:
[email protected]:2048;line=y1c70nao
20:17:48.203 MediaServerReporting::SetRemoteParty [MS210005] C:11.1:Answer provided. Connection(proxy mode):192.168.2.2:7022(7023)
20:17:48.203 MediaServerReporting::SetRemoteParty [MS210001] C:11.3:Answer received. RTP connection: 127.0.0.1:40612(40613)
20:17:48.203 Extension:
rintEndpointInfo [CM505001]: Ext.81: Device info: Device Identified: [Man: 3CX Ltd.;Mod: 3CX IVR;Rev: 1] Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CX IVR] Transport: [sip:127.0.0.1:5060]
20:17:48.203 CallCtrl:
nAnsweredCall [CM503002]: Call(11): Alerting sip:
[email protected]:40600;rinstance=ab64e15346445d68
20:17:48.203 CallLeg::setRemoteSdp Remote SDP is set for legC:11.3
20:17:47.719 MediaServerReporting::SetRemoteParty [MS210004] C:11.3:Offer provided. Connection(proxy mode): 127.0.0.1:7024(7025)
20:17:47.719 CallCtrl:
nRerouteReq [CM503005]: Call(11): Forwarding: Ext:Ext.81@[Dev:sip:
[email protected]:40600;rinstance=ab64e15346445d68]
20:17:32.656 MediaServerReporting::SetRemoteParty [MS210002] C:11.2:Offer provided. Connection(transcoding mode): 79.148.231.179:9004(9005)
20:17:32.469 CallCtrl:
nSelectRouteReq [CM503004]: Call(11): Calling: Ext:Ext.11@[Dev:sip:
[email protected]:2051;line=pe3xgzmp]
20:17:32.469 CallCtrl:
nSelectRouteReq [CM503010]: Making route(s) to [sip:
[email protected]:5060;user=phone]
20:17:32.453 MediaServerReporting::SetRemoteParty [MS210000] C:11.1:Offer received. RTP connection: 192.168.2.50:51172(51173)
20:17:32.453 CallLeg::setRemoteSdp Remote SDP is set for legC:11.1
20:17:32.453 Extension:
rintEndpointInfo [CM505001]: Ext.10: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [snom300/7.3.7] Transport: [sip:192.168.2.2:5060]
20:17:32.453 CallCtrl:
nIncomingCall [CM503001]: Call(11): Incoming call from Ext.10 to [sip:
[email protected]:5060;user=phone]
20:17:32.453 CallLeg:
nNewCall [CM500002]: Info on incoming INVITE:
INVITE sip:
[email protected]:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.2.50:2048;branch=z9hG4bK-wh5wdb4wipo1;rport=2048
Max-Forwards: 70
Contact: [sip:
[email protected]:2048;line=y1c70nao];reg-id=1
To: [sip:
[email protected]:5060;user=phone]
From: "HKS DIRECCION"[sip:
[email protected]:5060];tag=7wxbazkkoo
Call-ID: 3c2f5d5d1380-og7nt9xxat3l
CSeq: 2 INVITE
Session-Expires: 3600;refresher=uas
Min-SE: 90
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO
Proxy-Authorization: Digest username="10",realm="3CXPhoneSystem",nonce="12869608652:c458df1943d4cdf069351eae65023762",uri="sip:
[email protected]:5060;user=phone",response="7a8dd423322e1aee169de98984eaafb9",algorithm=MD5
Supported: timer, 100rel, replaces, from-change
User-Agent: snom300/7.3.7
Allow-Events: talk, hold, refer, call-info
Content-Length: 0
P-Key-Flags: keys="3"
Cuando reinicio el SNOM, la extensión remota, tarda unos 20 segundos en registrarse.
Cuando se están haciendo las pruebas, en el estado de la línea la extensión remota siempre aparece en verde como "no ocupado", a pesar de que está sonando.
Este otro log corresponde al Registro del SNOM, la extensión remota, durante esas llamadas fallidas
[5]24/12/2001 00:00:12: read_xml_settings: found dial-plan XML header
[5]24/12/2001 00:00:20: Setting server was already set: http://provisioning.snom.com/snom300/snom300.php?mac={mac}
[5]24/12/2001 00:00:20: Fetching URL: http://provisioning.snom.com/snom300/snom300.php?mac=000413283F2D
[2]24/12/2001 01:00:30: start_dst(985485600) end_dst(1004238000) offset_dst(3600) offset_utc(3600)
[2]24/12/2001 01:00:30: start DST: 03/25/2001 02:00:00 (985485600)
[2]24/12/2001 01:00:30: end DST: 10/28/2001 03:00:00 (1004238000)
[0]24/12/2001 01:00:31: allocated local SIP tcp port 2048![5]24/12/2001 01:00:31: read_xml_settings: found phone-book XML header
[5]24/12/2001 01:00:32: Opening TCP socket on port 8081
[5]24/12/2001 01:00:32: Opening TCP socket on port 443
[0]24/12/2001 01:00:32: Could not bind socket to port 2048
[2]27/10/2008 20:21:42: start_dst(1206842400) end_dst(1224990000) offset_dst(3600) offset_utc(3600)
[2]27/10/2008 20:21:42: start DST: 03/30/2008 02:00:00 (1206842400)
[2]27/10/2008 20:21:42: end DST: 10/26/2008 03:00:00 (1224990000)
[5]27/10/2008 20:22:03: Settings applied!
[3]27/10/2008 20:22:17: DNS: Timeout on provisioning.snom.com
[0]27/10/2008 20:22:17: Webclient: Could not find host provisioning.snom.com:80
[5]27/10/2008 20:22:17: Fetching URL: http: //127.0.0.1/dummy.htm
[5]27/10/2008 20:22:17: Fetching FW URL: http://provisioning.snom.com/update6to7/firmware.php
[1]27/10/2008 20:22:17: Webclient: DNS resolution for provisioning.snom.com:80 failed
[2]27/10/2008 20:22:17: start_dst(1206842400) end_dst(1224990000) offset_dst(3600) offset_utc(3600)
[2]27/10/2008 20:22:17: start DST: 03/30/2008 02:00:00 (1206842400)
[2]27/10/2008 20:22:17: end DST: 10/26/2008 03:00:00 (1224990000)
[0]27/10/2008 20:22:17: allocated local SIP tcp port 2048![5]27/10/2008 20:22:39: Settings applied!
[2]27/10/2008 20:22:42: Sending post request host = , file =
[2]27/10/2008 20:22:51: Transport Error: Pending packet 1000000: generating fake
[2]27/10/2008 20:22:51: Registrar 11 @sip.hks.es timed out
[5]27/10/2008 20:23:51: sip:
rocess_auth:Match challenge for user=11, realm=3CXPhoneSystem
[2]27/10/2008 20:23:52: Registered at registrar as 11 @sip.hks.es (Expires: 1800 secs)
[5]27/10/2008 20:23:52: sip:
rocess_auth:Match challenge for user=11, realm=3CXPhoneSystem
[5]27/10/2008 20:23:52: sip:
rocess_auth:Match challenge for user=11, realm=3CXPhoneSystem
[5]27/10/2008 20:24:47: Dialog -2/1 going to early
[5]27/10/2008 20:24:47: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:24:47: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:24:48: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:24:48: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:24:49: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:24:49: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:24:51: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:24:51: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:24:54: Dialog -2/1 going to confirmed
[5]27/10/2008 20:29:48: Dialog -4/3 going to early
[5]27/10/2008 20:29:48: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:29:48: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:29:48: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:29:48: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:29:49: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:29:49: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:29:51: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:29:51: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:29:55: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:29:55: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:30:03: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:30:03: timeout::callback: Registering with timeout of 0 ms
[5]27/10/2008 20:30:19: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:30:19: timeout::callback: Registering with timeout of 0 ms
[2]27/10/2008 20:31:48: Denying call id=-4 reason=user-busy
[5]27/10/2008 20:31:48: Dialog -4/3 going to terminated
[5]27/10/2008 20:31:48: Add dirty host: udp/192.168.2.2/5060
[5]27/10/2008 20:31:48: timeout::callback: Registering with timeout of 0 ms
[1]27/10/2008 20:32:23: sip::ack_timeout: entered for connection 3
[1]27/10/2008 20:32:23: sip::ack_timeout: deleting connection 3 because of missing ACK
Tengo algunas traces que he hecho con el ethereal, no corresponden a estas llamadas, son de ayer, pero con el mismo problema.
No conozco ese sofware y no sé como postearlas.
En esas capturas, observo como datos de interés 3 líneas "Unowkn WCCP message" desde la ip pública de la 3CX a la ip lan del SNOM y 4 líneas desde una ip extraña 10.4.116.1 a la ip del SNOM que informan "Destination unreacheable".
Observo una línea que indica desde la ip lan del router (la puerta de enlace del SNOM) a la destination 224.0.0.1 la información "V2 Membership Query".
Si me dices como posteo los resultados del ethereal vuelvo a hacer pruebas con ese software y te envío los resultados.
Muchas Gracias
Saludos cordiales.
Alejandro HKS