Re: [OSL | CCIE_Voice] PSTN issues
Hi Leslie, Try removing any policy map commands if they exist and make sure the Vlans are in place. You can enter the policy map commands if any later if the phones get registered. Regards Gurpreet On Fri, Jun 8, 2012 at 12:56 AM, Leslie Meade leslie.me...@lvs1.com wrote: I have a strange issue that I think I know the issue but do not know how to fix it. I have a test lab and today I fired up my PSTN router a 3745, and for the past year it was worked with out an issue. But today i am getting the phone hanging at requesting Softkey Template then it will cycle through again There has been no changes to the router. When i do a debug tftp events i get the following Jun 7 21:35:57.695: New Skinny socket accepted [1] (1 active) Jun 7 21:35:57.695: sin_family 2, sin_port 50667, in_addr 10.10.200.21 Jun 7 21:35:57.695: skinny_add_socket 1 10.10.200.21 50667 Jun 7 21:35:57.703: %IPPHONE-6-REG_ALARM: 17: Name=SEP0014F26A78CA Load=8.0(9.0) Last=KeepaliveTO Jun 7 21:35:57.703: ephone-(3)[2] StationRegisterMessage (1/1/5) from 10.10.200.21 Jun 7 21:35:57.703: ephone-(3)[2] Register StationIdentifier DeviceName SEP0014F26A78CA Jun 7 21:35:57.703: ephone-(3)[2] StationIdentifier Instance 1 deviceType 7 Jun 7 21:35:57.703: ephone-3[1]:stationIpAddr 10.10.200.21 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:maxStreams 0 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:From Phone raw protocol Ver 0x856B Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:protocol Ver 0x856B Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:phone-size 5480 dn-size 688 Jun 7 21:35:57.703: ephone-(3) Allow any Skinny Server IP address 10.10.250.2 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:Found entry 2 for 0014F26A78CA Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:socket change 1 to 2 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:DisAssociate: Closed socket 1 while REGISTERED Jun 7 21:35:57.707: %IPPHONE-6-UNREGISTER_ABNORMAL: ephone-3:SEP0014F26A78CA IP:10.10.200.21 Socket:1 DeviceType:Phone has unregistered abnormally. Jun 7 21:35:57.707: ephone-3[-1][SEP0014F26A78CA]:FAILED: CLOSED old socket -1 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:***Force device subtype to 0 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:phone SEP0014F26A78CA re-associate OK on socket [2] Jun 7 21:35:57.707: %IPPHONE-6-REGISTER: ephone-3:SEP0014F26A78CA IP:10.10.200.21 Socket:2 DeviceType:Phone has registered. Jun 7 21:35:57.707: Phone 2 socket 2 Jun 7 21:35:57.707: Skinny Local IP address = 10.10.250.2 on port 2000 Jun 7 21:35:57.707: Skinny Phone IP address = 10.10.200.21 50667 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:Signal protocol ver 8 to phone with ver 11 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:Date Format M/D/Y Jun 7 21:35:57.707: ephone-3[2]:RegisterAck sent to sockettype ephone socket 2: keepalive period 30 use sccp-version 8 Jun 7 21:35:57.707: ephone-3[2]:CapabilitiesReq sent Jun 7 21:35:57.715: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.715: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.719: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.755: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.759: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:CapabilitiesRes received Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Caps list 8 WideBand_256K 120 ms G711Ulaw64k 40 ms G711Alaw64k 40 ms G729AnnexB 60 ms G729AnnexAwAnnexB 60 ms G729 60 ms G729AnnexA 60 ms Unrecognized Media Type 257 4 ms Jun 7 21:35:57.919: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:ButtonTemplateReqMessage Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:StationButtonTemplateReqMessage set max presentation to 6 Jun 7 21:35:57.919: ephone-3[2]:CheckAutoReg Jun 7 21:35:57.919: ephone-3[2]:AutoReg is disabled Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Setting 6 lines 0 speed-dials on phone (max_line 6) Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:First Speed Dial Button location is 0 (0) Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Configured 0 speed dial buttons Jun 7 21:35:57.919: ephone-3[2]:ButtonTemplate lines=6 speed=0 buttons=6 offset=0 Jun 7 21:35:57.927: ephone-3[2]:StationSoftKeyTemplateReqMessage Jun 7 21:35:57.927: ephone-3[2]:StationSoftKeyTemplateResMessage -- This is where the Requesting Softkey Template hangs Jun 7 21:35:58.115: Bring up DN 1 by SkinnyCheckDnStatus Jun 7 21:35:58.591: TFTP: Finished system:/its/XMLDefault7960.cnf.xml, time 00:00:25 for process 154 Jun 7 21:35:59.115: Bring up DN 2 by SkinnyCheckDnStatus Jun 7 21:36:00.115: Bring up DN 3 by SkinnyCheckDnStatus Jun 7 21:36:01.115: Bring up DN 4 by SkinnyCheckDnStatus Jun 7 21:36:02.115: Bring up DN 5 by SkinnyCheckDnStatus Jun 7 21:36:02.623: TFTP: Finished
[OSL | CCIE_Voice] PSTN issues
I have a strange issue that I think I know the issue but do not know how to fix it. I have a test lab and today I fired up my PSTN router a 3745, and for the past year it was worked with out an issue. But today i am getting the phone hanging at requesting Softkey Template then it will cycle through again There has been no changes to the router. When i do a debug tftp events i get the following Jun 7 21:35:57.695: New Skinny socket accepted [1] (1 active) Jun 7 21:35:57.695: sin_family 2, sin_port 50667, in_addr 10.10.200.21 Jun 7 21:35:57.695: skinny_add_socket 1 10.10.200.21 50667 Jun 7 21:35:57.703: %IPPHONE-6-REG_ALARM: 17: Name=SEP0014F26A78CA Load=8.0(9.0) Last=KeepaliveTO Jun 7 21:35:57.703: ephone-(3)[2] StationRegisterMessage (1/1/5) from 10.10.200.21 Jun 7 21:35:57.703: ephone-(3)[2] Register StationIdentifier DeviceName SEP0014F26A78CA Jun 7 21:35:57.703: ephone-(3)[2] StationIdentifier Instance 1deviceType 7 Jun 7 21:35:57.703: ephone-3[1]:stationIpAddr 10.10.200.21 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:maxStreams 0 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:From Phone raw protocol Ver 0x856B Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:protocol Ver 0x856B Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:phone-size 5480 dn-size 688 Jun 7 21:35:57.703: ephone-(3) Allow any Skinny Server IP address 10.10.250.2 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:Found entry 2 for 0014F26A78CA Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:socket change 1 to 2 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:DisAssociate: Closed socket 1 while REGISTERED Jun 7 21:35:57.707: %IPPHONE-6-UNREGISTER_ABNORMAL: ephone-3:SEP0014F26A78CA IP:10.10.200.21 Socket:1 DeviceType:Phone has unregistered abnormally. Jun 7 21:35:57.707: ephone-3[-1][SEP0014F26A78CA]:FAILED: CLOSED old socket -1 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:***Force device subtype to 0 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:phone SEP0014F26A78CA re-associate OK on socket [2] Jun 7 21:35:57.707: %IPPHONE-6-REGISTER: ephone-3:SEP0014F26A78CA IP:10.10.200.21 Socket:2 DeviceType:Phone has registered. Jun 7 21:35:57.707: Phone 2 socket 2 Jun 7 21:35:57.707: Skinny Local IP address = 10.10.250.2 on port 2000 Jun 7 21:35:57.707: Skinny Phone IP address = 10.10.200.21 50667 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:Signal protocol ver 8 to phone with ver 11 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:Date Format M/D/Y Jun 7 21:35:57.707: ephone-3[2]:RegisterAck sent to sockettype ephone socket 2: keepalive period 30 use sccp-version 8 Jun 7 21:35:57.707: ephone-3[2]:CapabilitiesReq sent Jun 7 21:35:57.715: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.715: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.719: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.755: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.759: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:CapabilitiesRes received Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Caps list 8 WideBand_256K 120 ms G711Ulaw64k 40 ms G711Alaw64k 40 ms G729AnnexB 60 ms G729AnnexAwAnnexB 60 ms G729 60 ms G729AnnexA 60 ms Unrecognized Media Type 257 4 ms Jun 7 21:35:57.919: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:ButtonTemplateReqMessage Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:StationButtonTemplateReqMessage set max presentation to 6 Jun 7 21:35:57.919: ephone-3[2]:CheckAutoReg Jun 7 21:35:57.919: ephone-3[2]:AutoReg is disabled Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Setting 6 lines 0 speed-dials on phone (max_line 6) Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:First Speed Dial Button location is 0 (0) Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Configured 0 speed dial buttons Jun 7 21:35:57.919: ephone-3[2]:ButtonTemplate lines=6 speed=0 buttons=6 offset=0 Jun 7 21:35:57.927: ephone-3[2]:StationSoftKeyTemplateReqMessage Jun 7 21:35:57.927: ephone-3[2]:StationSoftKeyTemplateResMessage -- This is where the Requesting Softkey Template hangs Jun 7 21:35:58.115: Bring up DN 1 by SkinnyCheckDnStatus Jun 7 21:35:58.591: TFTP: Finished system:/its/XMLDefault7960.cnf.xml, time 00:00:25 for process 154 Jun 7 21:35:59.115: Bring up DN 2 by SkinnyCheckDnStatus Jun 7 21:36:00.115: Bring up DN 3 by SkinnyCheckDnStatus Jun 7 21:36:01.115: Bring up DN 4 by SkinnyCheckDnStatus Jun 7 21:36:02.115: Bring up DN 5 by SkinnyCheckDnStatus Jun 7 21:36:02.623: TFTP: Finished system:/its/XMLDefault7960.cnf.xml, time 00:00:25 for process 156 Jun 7 21:36:03.115: Bring up DN 6 by SkinnyCheckDnStatus Jun 7 21:36:06.619: TFTP: Finished system:/its/XMLDefault7960.cnf.xml, time 00:00:25 for process 171 Jun 7 21:36:07.115: ephone-3[2]:SkinnyCompleteRegistration Jun 7 21:36:10.619: TFTP: Finished system:/its/XMLDefault7960.cnf.xml, time 00:00:25 for
Re: [OSL | CCIE_Voice] PSTN issues
What is the phone type? You might try doing a hard reset of the phone it's self. Press and hold # while you power cycle, when lights start flashing input 123456789*0# let it sit. Note make sure you have your dhcp and TFTP server still reachable at this point. Just wait and let the phone do its thing. I have seen in the past where some of my phones act a little screwy like this every now and again, sometimes this reset straightens out. Thanks, Justin McIntyre -Original Message- Message: 3 Date: Fri, 8 Jun 2012 04:56:40 + From: Leslie Meade leslie.me...@lvs1.com To: ccie_voice@onlinestudylist.com ccie_voice@onlinestudylist.com Subject: [OSL | CCIE_Voice] PSTN issues Message-ID: f64719604b4e6f41bdbb2af38e7609f4181f3...@lvscgyex03.longviewsystems.com Content-Type: text/plain; charset=iso-8859-1 I have a strange issue that I think I know the issue but do not know how to fix it. I have a test lab and today I fired up my PSTN router a 3745, and for the past year it was worked with out an issue. But today i am getting the phone hanging at requesting Softkey Template then it will cycle through again There has been no changes to the router. When i do a debug tftp events i get the following Jun 7 21:35:57.695: New Skinny socket accepted [1] (1 active) Jun 7 21:35:57.695: sin_family 2, sin_port 50667, in_addr 10.10.200.21 Jun 7 21:35:57.695: skinny_add_socket 1 10.10.200.21 50667 Jun 7 21:35:57.703: %IPPHONE-6-REG_ALARM: 17: Name=SEP0014F26A78CA Load=8.0(9.0) Last=KeepaliveTO Jun 7 21:35:57.703: ephone-(3)[2] StationRegisterMessage (1/1/5) from 10.10.200.21 Jun 7 21:35:57.703: ephone-(3)[2] Register StationIdentifier DeviceName SEP0014F26A78CA Jun 7 21:35:57.703: ephone-(3)[2] StationIdentifier Instance 1deviceType 7 Jun 7 21:35:57.703: ephone-3[1]:stationIpAddr 10.10.200.21 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:maxStreams 0 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:From Phone raw protocol Ver 0x856B Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:protocol Ver 0x856B Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:phone-size 5480 dn-size 688 Jun 7 21:35:57.703: ephone-(3) Allow any Skinny Server IP address 10.10.250.2 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:Found entry 2 for 0014F26A78CA Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:socket change 1 to 2 Jun 7 21:35:57.703: ephone-3[1][SEP0014F26A78CA]:DisAssociate: Closed socket 1 while REGISTERED Jun 7 21:35:57.707: %IPPHONE-6-UNREGISTER_ABNORMAL: ephone-3:SEP0014F26A78CA IP:10.10.200.21 Socket:1 DeviceType:Phone has unregistered abnormally. Jun 7 21:35:57.707: ephone-3[-1][SEP0014F26A78CA]:FAILED: CLOSED old socket -1 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:***Force device subtype to 0 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:phone SEP0014F26A78CA re-associate OK on socket [2] Jun 7 21:35:57.707: %IPPHONE-6-REGISTER: ephone-3:SEP0014F26A78CA IP:10.10.200.21 Socket:2 DeviceType:Phone has registered. Jun 7 21:35:57.707: Phone 2 socket 2 Jun 7 21:35:57.707: Skinny Local IP address = 10.10.250.2 on port 2000 Jun 7 21:35:57.707: Skinny Phone IP address = 10.10.200.21 50667 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:Signal protocol ver 8 to phone with ver 11 Jun 7 21:35:57.707: ephone-3[2][SEP0014F26A78CA]:Date Format M/D/Y Jun 7 21:35:57.707: ephone-3[2]:RegisterAck sent to sockettype ephone socket 2: keepalive period 30 use sccp-version 8 Jun 7 21:35:57.707: ephone-3[2]:CapabilitiesReq sent Jun 7 21:35:57.715: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.715: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.719: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.755: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.759: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:CapabilitiesRes received Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Caps list 8 WideBand_256K 120 ms G711Ulaw64k 40 ms G711Alaw64k 40 ms G729AnnexB 60 ms G729AnnexAwAnnexB 60 ms G729 60 ms G729AnnexA 60 ms Unrecognized Media Type 257 4 ms Jun 7 21:35:57.919: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:MediaPathEventMessage Jun 7 21:35:57.919: ephone-3[2]:ButtonTemplateReqMessage Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:StationButtonTemplateReqMessage set max presentation to 6 Jun 7 21:35:57.919: ephone-3[2]:CheckAutoReg Jun 7 21:35:57.919: ephone-3[2]:AutoReg is disabled Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Setting 6 lines 0 speed-dials on phone (max_line 6) Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:First Speed Dial Button location is 0 (0) Jun 7 21:35:57.919: ephone-3[2][SEP0014F26A78CA]:Configured 0 speed dial buttons Jun 7 21:35:57.919: ephone-3[2]:ButtonTemplate lines=6 speed=0 buttons=6 offset=0 Jun 7 21:35:57.927: ephone-3[2]:StationSoftKeyTemplateReqMessage Jun 7 21:35:57.927: ephone-3[2]:StationSoftKeyTemplateResMessage -- This is where