Re: Problemas de registro de un telefono ip 7940

clara260912 · ‎12-06-2013

Buenos dias, tengo un telefono de voz ip 7940 unido a un switch que además tiene call manager (2811), firewall 5505 y router 2901 (todos en la misma LAN) y todo esto sale a la parte WAN con el mismo router 2901.

El teléfono está constantemente registrandose y desregistrandose.

En algunos registros funciona la comunicacion de voz en los dos sentidos con otros telefono ip de la wan y en otros solo en el sentido de salida.

En el 2811 (call manager) sale de forma periódica este mensaje;

Dec 13 17:41:52: %IPPHONE-6-REG_ALARM: Name=SEP000D28AF15C6 Load=CP79020101SCCP030610A.zup Last=Initialized

Dec 13 17:41:52: %IPPHONE-6-REGISTER_NEW: ephone-7:SEP000D28AF15C6 IP:10.60.0.10 Socket:2 DeviceType:Phone has registered.

Dec 13 17:42:33: %IPPHONE-6-REG_ALARM: Name=SEP0016C8D52EB6 Load=CP79020101SCCP030610A.zup Last=CM-aborted-TCP

Dec 13 17:42:33: %IPPHONE-6-REGISTER_NEW: ephone-6:SEP0016C8D52EB6 IP:10.60.0.14 Socket:3 DeviceType:Phone has registered.

Dec 13 17:42:43: %IPPHONE-6-REG_ALARM: Name=SEP0011BB248B80 Load=CP79020101SCCP030610A.zup Last=CM-aborted-TCP

Dec 13 17:42:43: %IPPHONE-6-REGISTER_NEW: ephone-4:SEP0011BB248B80 IP:10.60.0.7 Socket:4 DeviceType:Phone has registered.

Dec 13 17:43:48: %IPPHONE-6-UNREGISTER_ABNORMAL: ephone-2:SEP000D28AF15D0 IP:10.60.0.13 Socket:1 DeviceType:Phone has unregistered abnormally.

Dec 13 17:43:48: %IPPHONE-6-UNREGISTER_ABNORMAL: ephone-5:SEP000D28AF164D IP:10.60.0.12 Socket:5 DeviceType:Phone has unregistered abnormally.

Hay alguna solución para que no suceda esto?, gracias

Luis Segnini Salas · ‎12-18-2013

Hola Pepe,

Espero te encuentres bien. Me parece, que con la descripción que das, que el problema que tienes es debido a conectividad entre el IP Phone y el CME en tu router 2811. De casualidad, el tráfico que el IP Phone genera, siempre viaja atraves del Firewall 5505 que tienes?

Saludos,

Felipe Segnini

clara260912 · ‎12-18-2013

Sí, pero sin establecerse ningún tipo de llamada, cada cinco minutos más o menos, el teléfono se desregistra y registra, ese es el principal problema, no sé si tendré que configurar algo en el call manager?

Luis Segnini Salas · ‎12-19-2013

Gracias por la información Pepe. Me parece que tienes algún tipo de problema de red entre tu IP Phone y CME. Muy probablemente los keepalives entre tu IP Phone y CME no estan siendo intercambiados de forma correcta.

Por defecto, los keepalives son intercambiados entre el IP Phone y CME cada 30 segundos; si despues de 3 intentos, el IP Phone no recibe una respuesta al keepalive por parte del CME, el IP Phone se desregistra.

Te recomiendo que obtengas capturas sniffers desde el IP Phone que presenta este problema y el CME, con el fin de ver si los keepalives se intercambian de forma correcta. Si no es así, muy probablemente, algo en tu red, posiblemente el firewall, esta bloqueando este trafico. Los keepalives viajan utilizando el puerto TCP 2000. Valdría la pena revisar si alguna parte de tu red que restringa estos mensajes.

Espero que esta información te sea de ayuda.

Saludos,

Felipe Segnini

clara260912 · ‎12-19-2013

Te doy más datos, apago el router call manager y lanzo ping al telefono desde una máquina remota y responde durante 5 minutos y luego deja de responder, ya que se reinicia, y así sucesivamente. El puerto TCP 2000 está abierto. No sé si puedes darme otra solución, gracias.

Luis Segnini Salas · ‎12-19-2013

Adicional a los sniffer traces tanto del IP Phone como el CME, te recomiendo obtener los siguientes debugs al momento de que un IP Phone presenta la falla y compartirlo en este foro:

debug ephone detail mac-address xxxx.xxxx.xxxx

debug ip tcp transactions

debug ip dhcp

Saludos,

Felipe Segnini

clara260912 · ‎12-23-2013

10.100.50.2 es el telefono ip, 10.100.50.1 es el call manager, activando el debug ip transactions y el debug ephone detail mac-address, el resultado del logging es el siguiente:

3: Dec 23 14:18:27.300: TCP0: Connection to 10.100.50.1:52125, advertising MSS 1400

008344: Dec 23 14:18:27.300: TCP0: bad seg from 10.100.50.1 -- bad sequence number: port 2000 seq 3501725696 ack 0 rcvnxt 3501725697 rcvwnd 4128 len 0

008345: Dec 23 14:18:27.896: TCP0: bad seg from 10.100.50.1 -- bad sequence number: port 2000 seq 3501725696 ack 0 rcvnxt 3501725697 rcvwnd 4128 len 0

008346: Dec 23 14:18:28.696: TCP0: bad seg from 10.100.50.1 -- bad sequence number: port 2000 seq 3501725696 ack 0 rcvnxt 3501725697 rcvwnd 4128 len 0

008347: Dec 23 14:18:28.840: 10.100.50.2:2000 <---> 10.100.50.1:52127 congestion window changes

008348: Dec 23 14:18:28.840: cwnd from 1400 to 1400, ssthresh from 65535 to 2800

008349: Dec 23 14:18:28.840: TCP0: timeout #1 - timeout is 4000 ms, seq 4129341694

008350: Dec 23 14:18:28.840: TCP: (2000) -> 10.100.50.1(52127)

008351: Dec 23 14:18:29.300: 10.100.50.2:2000 <---> 10.100.50.1:52125 congestion window changes

008352: Dec 23 14:18:29.300: cwnd from 1400 to 1400, ssthresh from 65535 to 2800

008353: Dec 23 14:18:29.300: TCP0: timeout #1 - timeout is 4000 ms, seq 1716102479

008354: Dec 23 14:18:29.300: TCP: (2000) -> 10.100.50.1(52125)

008355: Dec 23 14:18:29.896: TCP0: bad seg from 10.100.50.1 -- bad sequence number: port 2000 seq 3501725696 ack 0 rcvnxt 3501725697 rcvwnd 4128 len 0

008356: Dec 23 14:18:32.840: TCP0: timeout #2 - timeout is 4000 ms, seq 4129341694

008357: Dec 23 14:18:32.840: TCP: (2000) -> 10.100.50.1(52127)

008358: Dec 23 14:18:33.300: TCP0: timeout #2 - timeout is 4000 ms, seq 1716102479

008359: Dec 23 14:18:33.300: TCP: (2000) -> 10.100.50.1(52125)

008360: Dec 23 14:18:36.840: TCP0: state was SYNRCVD -> CLOSED [2000 -> 10.100.50.1(52127)]

008361: Dec 23 14:18:36.840: TCB 0x465D3AC0 destroyed

008362: Dec 23 14:18:37.300: TCP0: state was SYNRCVD -> CLOSED [2000 -> 10.100.50.1(52125)]

008363: Dec 23 14:18:37.300: TCB 0x4596B4C8 destroyed

008364: Dec 23 14:19:16.388: TCB465D3AC0 created

008365: Dec 23 14:19:16.388: TCP0: state was LISTEN -> SYNRCVD [2000 -> 10.100.50.1(52129)]

008366: Dec 23 14:19:16.388: TCP: tcb 465D3AC0 connection to 100.100.50.1:52129, peer MSS 1400, MSS is 516

008367: Dec 23 14:19:16.388: TCP: sending SYN, seq 233249918, ack 3511863297

008368: Dec 23 14:19:16.388: TCP0: Connection to 10.100.50.1:52129, advertising MSS 1400

008369: Dec 23 14:19:16.396: TCP0: state was SYNRCVD -> ESTAB [2000 -> 100.100.50.1(52129)]

008370: Dec 23 14:19:16.400: TCB4642214C accepting 465D3AC0 from 10.100.50.1.52129

008371: Dec 23 14:19:16.400: TCB465D3AC0 setting property TCP_PID (8) 46E960A4

008372: Dec 23 14:19:16.400: TCB465D3AC0 setting property TCP_NO_DELAY (1) 46E960A8

008373: Dec 23 14:19:16.400: TCB465D3AC0 setting property unknown (13) 46E961BC

008374: Dec 23 14:19:16.400: TCB465D3AC0 setting property TCP_NONBLOCKING_WRITE (10) 46E961BC

008375: Dec 23 14:19:16.400: TCB465D3AC0 setting property TCP_NONBLOCKING_READ (14) 46E961BC

008376: Dec 23 14:19:16.400: TCB465D3AC0 setting property TCP_ALWAYSPUSH (17) 46E961BC

008377: Dec 23 14:19:16.400: TCB465D3AC0 setting property TCP_NO_DELAY (1) 46E961BC

008378: Dec 23 14:19:16.400: TCB465D3AC0 setting property TCP_TOS (11) 46E961B0

008379: Dec 23 14:19:16.400: TCB463F8604 setting property unknown (13) 46E960CC

008380: Dec 23 14:19:16.400: TCB463F8604 setting property TCP_NONBLOCKING_WRITE (10) 46E960CC

008381: Dec 23 14:19:16.400: TCB463F8604 setting property TCP_NONBLOCKING_READ (14) 46E960CC

008382: Dec 23 14:19:16.400: TCB463F8604 setting property TCP_ALWAYSPUSH (17) 46E960CC

008383: Dec 23 14:19:16.400: TCB463F8604 setting property TCP_NO_DELAY (1) 46E960CC

008384: Dec 23 14:19:16.400: TCB463F8604 setting property TCP_TOS (11) 46E960C0

008385: Dec 23 14:19:16.516: Skinny Is Ready

008386: Dec 23 14:19:16.516: %IPPHONE-6-REG_ALARM: Name=SEP001301E7766D Load=3.1(MF.G2) Last=TCP-timeout

008387: Dec 23 14:19:16.516: %IPPHONE-6-REGISTER: ephone-1:SEP001301E7766D IP:10.100.50.1 Socket:1 DeviceType:Phone has registered.

008388: Dec 23 14:19:27.624: SkinnySyncDnCFA for DN 1 for 1 phones

Luis Segnini Salas · ‎12-24-2013

Hola Pepe,

008344: Dec 23 14:18:27.300: TCP0: bad seg from 10.100.50.1 -- bad sequence number: port 2000 seq 3501725696 ack 0 rcvnxt 3501725697 rcvwnd 4128 len 0

008345: Dec 23 14:18:27.896: TCP0: bad seg from 10.100.50.1 -- bad sequence number: port 2000 seq 3501725696 ack 0 rcvnxt 3501725697 rcvwnd 4128 len 0

Este mensaje es una indicación de que el dispositivo ha recibido el SYN, envió el SYN / ACK, y se encuentra en espera del ACK, pero este nunca llega al IP Phone. Esto nos indica que la comunicación TCP no se está estableciendo de forma correcta.

Lamentablemente no soy un experto en lo que es seguridad y firewalls, pero te recomendaria revisar la configuración de este dispositivo ya que muy probablemente el tráfico esta siendo bloqueado.

Saludos,

Felipe Segnini

clara260912 · ‎12-30-2013

Buenas, desconecto el firewall y todo sigue igual, la version del call manager es 3.3 y el firmware del telefono es `P0030301MFG2 , será ese el problema?

Luis Segnini Salas · ‎12-30-2013

Hola Pepe,

Pues si, tu versión de CUCM ya esta bastante obsoleta y el firmware de igual forma. Te recomiendo que le actualices el firmware al IP Phone a una versión mas reciente, puedes descargar el firmware de Cisco.com. Adicional, te sugiero que consideres la actualización de tu CallManager.

Adicional a esto, ¿Cuales dispositivos son los que estan entre el IP Phone y el CME? Esto con el fin de ver si el trafico es bloqueado por alguno de estos dispositivos.

Saludos,

Felipe Segnini

clara260912 · ‎12-31-2013

He dejado telefono ip 7940 con router callmanager 3.3 2811 y sigue sin funcionar.

clara260912 · ‎01-03-2014

Actualicé el call manager a la version 7, y siguió sin funcionar. Pero al cambiar el teléfono por un 7962 en las mismas condiciones funciona, osea que es un problema del telefono 7940 o del firmware.

Luis Segnini Salas · ‎01-03-2014

Gracias por la información Pepe. Sería bueno actualizar el firmware del IP Phone tambien y realizar pruebas. La versión de firmware que tienes esta obsoleta.

Saludos,

Felipe Segnini

clara260912 · ‎01-05-2014

He actualizado la version del telefono 7940 a la 8-0-7 desde el tftp del router y sucede lo siguiente:

1) dejo el tftp activo con el load 7940 a la P00308000700 en el cme. -> en este caso funciona

2) desactivo el tftp y el load 7940 en el cme. -> en este caso no funciona