Solved: IP Phones TFTP error and registration issue

danijel_knezic · ‎01-20-2021

Hi

I have problem with my Cisco IP phone 7861 which cant registrant with SIP server and my WiFi 8821 phones which are connected to correct WiFi network but both have common error reported TFTP.

I am running Cisco ISR 4331 are voice router and SIP server and I am not sure what is missing in my configuration which could cause this kind of error.

Attached is my ISR 4331 configuration and photos of the my DHCP server config for voice vlan and phones reported errors.

IP Phone.jpg WiFi Phone.jpg

Roger Kallberg · ‎01-21-2021

For SSH see this post. https://community.cisco.com/t5/ip-telephony-and-phones/how-to-disable-ssh-on-a-phone-on-cucme/td-p/2083272

View solution in original post

Roger Kallberg · ‎01-22-2021

No problem. You should do this to enable the webUI on the phones.

telephony-service
 service phone webAccess 0
 create cnf-files

See this document for the full administration guide for CME. https://www.cisco.com/c/en/us/td/docs/voice_ip_comm/cucme/admin/configuration/manual/cmeadm.html

View solution in original post

Nithin Eluvathingal · ‎01-20-2021

Remove Username and password from Voice registrar Pool. its required when use authenticate register.

secondly check the firmware, sometime phones need firmware upgrade.

Roger Kallberg · ‎01-20-2021

Apart from what @Nithin Eluvathingal suggested change the option used in DHCP from 66 to 150 as the former is a legacy thing.

Nithin Eluvathingal · ‎01-20-2021

And Use DHCP option 150 instead of 66.

Adam Pawlowski · ‎01-20-2021

Hopefully as well that DHCP server implementation accepts a value for the option in that format. Some require it to be a packed hex value. But, just looking at the screenshot, it doesn't say "TFTP Timeout" or "TFTP Error" so it may have obtained the file and then has an issue from there. That would be more obvious in the phone's message log, I don't believe the status will say much otherwise.

danijel_knezic · ‎01-21-2021

Hi Guys,

First of all thanks for your inputs.

At moment status is:

I have tried removing the username and password but didn't help.

DHCP option 150 I don't have as option in my DHCP/Firewall (Kerio NG500) so I open the case with GFI company to see how I can sort this out.

I will try to download the latest phone firmware....

Do I need to do something on my WLC in regards the TFTP and Phones 8821?

danijel_knezic · ‎01-21-2021

My ISR 4331 tftp debug events:

*Jan 21 08:59:50.679: TFTP: Looking for SEPC064E4ECE0DE.cnf.xml
*Jan 21 08:59:50.692: TFTP: Opened flash:/its/SEPC064E4ECE0DE.cnf.xml, fd 0, size 4801 for process 595
*Jan 21 08:59:50.715: TFTP: Finished flash:/its/SEPC064E4ECE0DE.cnf.xml, time 00:00:00 for process 595
*Jan 21 08:59:50.734: TFTP: Looking for WLANRootCA.cer
*Jan 21 08:59:52.825: TFTP: Looking for 8821-WLAND4ADBD0C79BE.xml
*Jan 21 08:59:53.539: TFTP: Looking for AppDialRules.xml
*Jan 21 08:59:55.245: TFTP: Looking for 8821-WLANDefault.xml
*Jan 21 08:59:55.857: TFTP: Looking for WLANRootCA.cer
*Jan 21 08:59:57.175: TFTP: Looking for softkeyDefault_kpml.xml
*Jan 21 08:59:57.176: TFTP: Opened flash:/its/softkeyDefault_kpml.xml, fd 0, size 4846 for process 595
*Jan 21 08:59:57.199: TFTP: Finished flash:/its/softkeyDefault_kpml.xml, time 00:00:00 for process 595
*Jan 21 08:59:58.137: TFTP: Looking for 8821-WLANC064E4ECE0DE.xml
*Jan 21 09:00:00.339: TFTP: Looking for 8821-WLANDefault.xml
*Jan 21 09:00:02.491: TFTP: Looking for softkeyDefault_kpml.xml
*Jan 21 09:00:02.498: TFTP: Opened flash:/its/softkeyDefault_kpml.xml, fd 0, size 4846 for process 595
*Jan 21 09:00:02.531: TFTP: Finished flash:/its/softkeyDefault_kpml.xml, time 00:00:00 for process 595
*Jan 21 09:00:03.964: TFTP: Looking for CTLSEP5061BF3DF344.tlv
*Jan 21 09:00:04.850: TFTP: Looking for ITLSEP5061BF3DF344.tlv
*Jan 21 09:00:05.733: TFTP: Looking for ITLFile.tlv
*Jan 21 09:00:06.674: TFTP: Looking for SEP5061BF3DF344.cnf.xml
*Jan 21 09:00:06.688: TFTP: Opened flash:/its/SEP5061BF3DF344.cnf.xml, fd 0, size 4772 for process 595
*Jan 21 09:00:06.701: TFTP: Finished flash:/its/SEP5061BF3DF344.cnf.xml, time 00:00:00 for process 595
*Jan 21 09:00:12.591: TFTP: Looking for SIP_English_United_States/sp-sip.jar
*Jan 21 09:00:13.611: TFTP: Looking for SIP_United_Kingdom/g3-tones.xml
*Jan 21 09:00:15.475: TFTP: Looking for softkeyDefault_kpml.xml
*Jan 21 09:00:15.502: TFTP: Opened flash:/its/softkeyDefault_kpml.xml, fd 0, size 4846 for process 595
*Jan 21 09:00:15.515: TFTP: Finished flash:/its/softkeyDefault_kpml.xml, time 00:00:00 for process 595

danijel_knezic · ‎01-21-2021

And this is debug tftp packets:

MYALRT01#
*Jan 21 09:06:35.646: TFTP: Server request for port 49794, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:35.646: TFTP: read request from host 10.13.2.243(49794) via GigabitEthernet0/0/ 0
*Jan 21 09:06:35.646: TFTP: Looking for CTLSEPC064E4ECE0DE.tlv
*Jan 21 09:06:35.646: TFTP: Sending error 1 No such file
*Jan 21 09:06:38.026: TFTP: Server request for port 52054, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:38.026: TFTP: read request from host 10.13.2.243(52054) via GigabitEthernet0/0/ 0
*Jan 21 09:06:38.026: TFTP: Looking for ITLSEPC064E4ECE0DE.tlv
*Jan 21 09:06:38.026: TFTP: Sending error 1 No such file
*Jan 21 09:06:40.356: TFTP: Server request for port 49535, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:40.356: TFTP: read request from host 10.13.2.243(49535) via GigabitEthernet0/0/ 0
*Jan 21 09:06:40.356: TFTP: Looking for ITLFile.tlv
*Jan 21 09:06:40.356: TFTP: Sending error 1 No such file
*Jan 21 09:06:42.605: TFTP: Server request for port 50607, socket_id 0x7F397C71F200 for proce ss 448
*Jan 21 09:06:42.605: TFTP: read request from host 10.13.2.243(50607) via GigabitEthernet0/0/ 0
*Jan 21 09:06:42.605: TFTP: Looking for SEPC064E4ECE0DE.cnf.xml
*Jan 21 09:06:42.618: TFTP: Opened flash:/its/SEPC064E4ECE0DE.cnf.xml, fd 0, size 4801 for pr ocess 448
*Jan 21 09:06:42.618: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.622: TFTP: Received ACK for block 1, socket_id 0x7F397C71F200
*Jan 21 09:06:42.622: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.625: TFTP: Received ACK for block 2, socket_id 0x7F397C71F200
*Jan 21 09:06:42.625: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.628: TFTP: Received ACK for block 3, socket_id 0x7F397C71F200
*Jan 21 09:06:42.628: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.631: TFTP: Received ACK for block 4, socket_id 0x7F397C71F200
*Jan 21 09:06:42.631: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.634: TFTP: Received ACK for block 5, socket_id 0x7F397C71F200
*Jan 21 09:06:42.635: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.638: TFTP: Received ACK for block 6, socket_id 0x7F397C71F200
*Jan 21 09:06:42.638: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.642: TFTP: Received ACK for block 7, socket_id 0x7F397C71F200
*Jan 21 09:06:42.642: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.645: TFTP: Received ACK for block 8, socket_id 0x7F397C71F200
*Jan 21 09:06:42.645: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F397C71F200
*Jan 21 09:06:42.648: TFTP: Received ACK for block 9, socket_id 0x7F397C71F200
*Jan 21 09:06:42.648: TFTP: Sending block 10 (retry 0), len 193, socket_id 0x7F397C71F200
*Jan 21 09:06:42.651: TFTP: Received ACK for block 10, socket_id 0x7F397C71F200
*Jan 21 09:06:42.651: TFTP: Finished flash:/its/SEPC064E4ECE0DE.cnf.xml, time 00:00:00 for pr ocess 448
*Jan 21 09:06:43.786: TFTP: Server request for port 50089, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:43.786: TFTP: read request from host 10.13.2.240(50089) via GigabitEthernet0/0/ 0
*Jan 21 09:06:43.787: TFTP: Looking for CTLSEPD4ADBD0C79BE.tlv
*Jan 21 09:06:43.787: TFTP: Sending error 1 No such file
*Jan 21 09:06:45.306: TFTP: Server request for port 51460, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:45.306: TFTP: read request from host 10.13.2.243(51460) via GigabitEthernet0/0/ 0
*Jan 21 09:06:45.306: TFTP: Looking for AppDialRules.xml
*Jan 21 09:06:45.306: TFTP: Sending error 1 No such file
*Jan 21 09:06:45.736: TFTP: Server request for port 52962, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:45.736: TFTP: read request from host 10.13.2.240(52962) via GigabitEthernet0/0/ 0
*Jan 21 09:06:45.736: TFTP: Looking for ITLSEPD4ADBD0C79BE.tlv
*Jan 21 09:06:45.736: TFTP: Sending error 1 No such file
*Jan 21 09:06:47.646: TFTP: Server request for port 50256, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:47.646: TFTP: read request from host 10.13.2.243(50256) via GigabitEthernet0/0/ 0
*Jan 21 09:06:47.646: TFTP: Looking for WLANRootCA.cer
*Jan 21 09:06:47.646: TFTP: Sending error 1 No such file
*Jan 21 09:06:47.985: TFTP: Server request for port 51969, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:47.986: TFTP: read request from host 10.13.2.240(51969) via GigabitEthernet0/0/ 0
*Jan 21 09:06:47.986: TFTP: Looking for ITLFile.tlv
*Jan 21 09:06:47.986: TFTP: Sending error 1 No such file
*Jan 21 09:06:49.936: TFTP: Server request for port 52753, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:49.937: TFTP: read request from host 10.13.2.243(52753) via GigabitEthernet0/0/ 0
*Jan 21 09:06:49.937: TFTP: Looking for 8821-WLANC064E4ECE0DE.xml
*Jan 21 09:06:49.937: TFTP: Sending error 1 No such file
*Jan 21 09:06:49.962: TFTP: Server request for port 50030, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:49.962: TFTP: read request from host 10.13.2.240(50030) via GigabitEthernet0/0/ 0
*Jan 21 09:06:49.962: TFTP: Looking for SEPD4ADBD0C79BE.cnf.xml
*Jan 21 09:06:49.979: TFTP: Opened flash:/its/SEPD4ADBD0C79BE.cnf.xml, fd 0, size 4801 for pr ocess 448
*Jan 21 09:06:49.980: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:49.984: TFTP: Received ACK for block 1, socket_id 0x7F398E85E418
*Jan 21 09:06:49.984: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:49.987: TFTP: Received ACK for block 2, socket_id 0x7F398E85E418
*Jan 21 09:06:49.988: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:49.989: TFTP: Received ACK for block 3, socket_id 0x7F398E85E418
*Jan 21 09:06:49.990: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:49.993: TFTP: Received ACK for block 4, socket_id 0x7F398E85E418
*Jan 21 09:06:49.993: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:49.998: TFTP: Received ACK for block 5, socket_id 0x7F398E85E418
*Jan 21 09:06:49.998: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:50.003: TFTP: Received ACK for block 6, socket_id 0x7F398E85E418
*Jan 21 09:06:50.003: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:50.006: TFTP: Received ACK for block 7, socket_id 0x7F398E85E418
*Jan 21 09:06:50.006: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:50.009: TFTP: Received ACK for block 8, socket_id 0x7F398E85E418
*Jan 21 09:06:50.009: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:50.012: TFTP: Received ACK for block 9, socket_id 0x7F398E85E418
*Jan 21 09:06:50.013: TFTP: Sending block 10 (retry 0), len 193, socket_id 0x7F398E85E418
*Jan 21 09:06:50.016: TFTP: Received ACK for block 10, socket_id 0x7F398E85E418
*Jan 21 09:06:50.016: TFTP: Finished flash:/its/SEPD4ADBD0C79BE.cnf.xml, time 00:00:00 for pr ocess 448
*Jan 21 09:06:52.246: TFTP: Server request for port 51174, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:52.246: TFTP: read request from host 10.13.2.243(51174) via GigabitEthernet0/0/ 0
*Jan 21 09:06:52.246: TFTP: Looking for 8821-WLANDefault.xml
*Jan 21 09:06:52.246: TFTP: Sending error 1 No such file
*Jan 21 09:06:52.446: TFTP: Server request for port 52492, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:52.446: TFTP: read request from host 10.13.2.240(52492) via GigabitEthernet0/0/ 0
*Jan 21 09:06:52.446: TFTP: Looking for AppDialRules.xml
*Jan 21 09:06:52.446: TFTP: Sending error 1 No such file
*Jan 21 09:06:54.431: TFTP: Server request for port 51810, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:54.431: TFTP: read request from host 10.13.2.243(51810) via GigabitEthernet0/0/ 0
*Jan 21 09:06:54.431: TFTP: Looking for softkeyDefault_kpml.xml
*Jan 21 09:06:54.433: TFTP: Opened flash:/its/softkeyDefault_kpml.xml, fd 0, size 4846 for pr ocess 448
*Jan 21 09:06:54.433: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.436: TFTP: Received ACK for block 1, socket_id 0x7F398E85E418
*Jan 21 09:06:54.437: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.440: TFTP: Received ACK for block 2, socket_id 0x7F398E85E418
*Jan 21 09:06:54.440: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.443: TFTP: Received ACK for block 3, socket_id 0x7F398E85E418
*Jan 21 09:06:54.443: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.446: TFTP: Received ACK for block 4, socket_id 0x7F398E85E418
*Jan 21 09:06:54.447: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.449: TFTP: Received ACK for block 5, socket_id 0x7F398E85E418
*Jan 21 09:06:54.450: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.453: TFTP: Received ACK for block 6, socket_id 0x7F398E85E418
*Jan 21 09:06:54.453: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.456: TFTP: Received ACK for block 7, socket_id 0x7F398E85E418
*Jan 21 09:06:54.456: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.459: TFTP: Received ACK for block 8, socket_id 0x7F398E85E418
*Jan 21 09:06:54.460: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:06:54.462: TFTP: Received ACK for block 9, socket_id 0x7F398E85E418
*Jan 21 09:06:54.463: TFTP: Sending block 10 (retry 0), len 238, socket_id 0x7F398E85E418
*Jan 21 09:06:54.466: TFTP: Received ACK for block 10, socket_id 0x7F398E85E418
*Jan 21 09:06:54.466: TFTP: Finished flash:/its/softkeyDefault_kpml.xml, time 00:00:00 for pr ocess 448
*Jan 21 09:06:54.545: TFTP: Server request for port 50643, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:54.546: TFTP: read request from host 10.13.2.240(50643) via GigabitEthernet0/0/ 0
*Jan 21 09:06:54.546: TFTP: Looking for WLANRootCA.cer
*Jan 21 09:06:54.546: TFTP: Sending error 1 No such file
*Jan 21 09:06:56.646: TFTP: Server request for port 49472, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:56.646: TFTP: read request from host 10.13.2.240(49472) via GigabitEthernet0/0/ 0
*Jan 21 09:06:56.646: TFTP: Looking for 8821-WLAND4ADBD0C79BE.xml
*Jan 21 09:06:56.646: TFTP: Sending error 1 No such file
*Jan 21 09:06:57.371: TFTP: Server request for port 50102, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:57.371: TFTP: read request from host 10.13.2.242(50102) via GigabitEthernet0/0/ 0
*Jan 21 09:06:57.371: TFTP: Looking for CTLSEP5061BF3DF344.tlv
*Jan 21 09:06:57.371: TFTP: Sending error 1 No such file
*Jan 21 09:06:58.273: TFTP: Server request for port 53106, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:58.273: TFTP: read request from host 10.13.2.242(53106) via GigabitEthernet0/0/ 0
*Jan 21 09:06:58.274: TFTP: Looking for ITLSEP5061BF3DF344.tlv
*Jan 21 09:06:58.274: TFTP: Sending error 1 No such file
*Jan 21 09:06:58.656: TFTP: Server request for port 49280, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:58.656: TFTP: read request from host 10.13.2.240(49280) via GigabitEthernet0/0/ 0
*Jan 21 09:06:58.656: TFTP: Looking for 8821-WLANDefault.xml
*Jan 21 09:06:58.656: TFTP: Sending error 1 No such file
*Jan 21 09:06:59.177: TFTP: Server request for port 51970, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:06:59.177: TFTP: read request from host 10.13.2.242(51970) via GigabitEthernet0/0/ 0
*Jan 21 09:06:59.177: TFTP: Looking for ITLFile.tlv
*Jan 21 09:06:59.177: TFTP: Sending error 1 No such file
*Jan 21 09:07:00.505: TFTP: Server request for port 52293, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:00.505: TFTP: read request from host 10.13.2.242(52293) via GigabitEthernet0/0/ 0
*Jan 21 09:07:00.505: TFTP: Looking for SEP5061BF3DF344.cnf.xml
*Jan 21 09:07:00.506: TFTP: Opened flash:/its/SEP5061BF3DF344.cnf.xml, fd 0, size 4772 for pr ocess 448
*Jan 21 09:07:00.506: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.508: TFTP: Received ACK for block 1, socket_id 0x7F398E85E418
*Jan 21 09:07:00.509: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.510: TFTP: Received ACK for block 2, socket_id 0x7F398E85E418
*Jan 21 09:07:00.510: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.511: TFTP: Received ACK for block 3, socket_id 0x7F398E85E418
*Jan 21 09:07:00.512: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.513: TFTP: Received ACK for block 4, socket_id 0x7F398E85E418
*Jan 21 09:07:00.513: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.514: TFTP: Received ACK for block 5, socket_id 0x7F398E85E418
*Jan 21 09:07:00.514: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.516: TFTP: Received ACK for block 6, socket_id 0x7F398E85E418
*Jan 21 09:07:00.516: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.517: TFTP: Received ACK for block 7, socket_id 0x7F398E85E418
*Jan 21 09:07:00.518: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.519: TFTP: Received ACK for block 8, socket_id 0x7F398E85E418
*Jan 21 09:07:00.519: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.520: TFTP: Received ACK for block 9, socket_id 0x7F398E85E418
*Jan 21 09:07:00.520: TFTP: Sending block 10 (retry 0), len 164, socket_id 0x7F398E85E418
*Jan 21 09:07:00.522: TFTP: Received ACK for block 10, socket_id 0x7F398E85E418
*Jan 21 09:07:00.522: TFTP: Finished flash:/its/SEP5061BF3DF344.cnf.xml, time 00:00:00 for pr ocess 448
*Jan 21 09:07:00.746: TFTP: Server request for port 49857, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:00.746: TFTP: read request from host 10.13.2.240(49857) via GigabitEthernet0/0/ 0
*Jan 21 09:07:00.746: TFTP: Looking for softkeyDefault_kpml.xml
*Jan 21 09:07:00.752: TFTP: Opened flash:/its/softkeyDefault_kpml.xml, fd 0, size 4846 for pr ocess 448
*Jan 21 09:07:00.753: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.757: TFTP: Received ACK for block 1, socket_id 0x7F398E85E418
*Jan 21 09:07:00.757: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.760: TFTP: Received ACK for block 2, socket_id 0x7F398E85E418
*Jan 21 09:07:00.760: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.763: TFTP: Received ACK for block 3, socket_id 0x7F398E85E418
*Jan 21 09:07:00.763: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.766: TFTP: Received ACK for block 4, socket_id 0x7F398E85E418
*Jan 21 09:07:00.766: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.769: TFTP: Received ACK for block 5, socket_id 0x7F398E85E418
*Jan 21 09:07:00.770: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.774: TFTP: Received ACK for block 6, socket_id 0x7F398E85E418
*Jan 21 09:07:00.774: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.777: TFTP: Received ACK for block 7, socket_id 0x7F398E85E418
*Jan 21 09:07:00.777: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.780: TFTP: Received ACK for block 8, socket_id 0x7F398E85E418
*Jan 21 09:07:00.780: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:00.785: TFTP: Received ACK for block 9, socket_id 0x7F398E85E418
*Jan 21 09:07:00.786: TFTP: Sending block 10 (retry 0), len 238, socket_id 0x7F398E85E418
*Jan 21 09:07:00.789: TFTP: Received ACK for block 10, socket_id 0x7F398E85E418
*Jan 21 09:07:00.789: TFTP: Finished flash:/its/softkeyDefault_kpml.xml, time 00:00:00 for pr ocess 448
*Jan 21 09:07:07.838: TFTP: Server request for port 50815, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:07.838: TFTP: read request from host 10.13.2.242(50815) via GigabitEthernet0/0/ 0
*Jan 21 09:07:07.838: TFTP: Looking for SIP_English_United_States/sp-sip.jar
*Jan 21 09:07:07.838: TFTP: Sending error 1 No such file
MYALRT01#
MYALRT01#
MYALRT01#
MYALRT01#
*Jan 21 09:07:08.886: TFTP: Server request for port 51214, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:08.886: TFTP: read request from host 10.13.2.242(51214) via GigabitEthernet0/0/ 0
*Jan 21 09:07:08.886: TFTP: Looking for SIP_United_Kingdom/g3-tones.xml
*Jan 21 09:07:08.886: TFTP: Sending error 1 No such file
*Jan 21 09:07:10.134: TFTP: Server request for port 49555, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:10.134: TFTP: read request from host 10.13.2.242(49555) via GigabitEthernet0/0/ 0
*Jan 21 09:07:10.134: TFTP: Looking for softkeyDefault_kpml.xml
*Jan 21 09:07:10.139: TFTP: Opened flash:/its/softkeyDefault_kpml.xml, fd 0, size 4846 for pr ocess 448
*Jan 21 09:07:10.139: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.141: TFTP: Received ACK for block 1, socket_id 0x7F398E85E418
*Jan 21 09:07:10.142: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.143: TFTP: Received ACK for block 2, socket_id 0x7F398E85E418
*Jan 21 09:07:10.143: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.144: TFTP: Received ACK for block 3, socket_id 0x7F398E85E418
*Jan 21 09:07:10.145: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.146: TFTP: Received ACK for block 4, socket_id 0x7F398E85E418
*Jan 21 09:07:10.146: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.148: TFTP: Received ACK for block 5, socket_id 0x7F398E85E418
*Jan 21 09:07:10.148: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.149: TFTP: Received ACK for block 6, socket_id 0x7F398E85E418
*Jan 21 09:07:10.149: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.150: TFTP: Received ACK for block 7, socket_id 0x7F398E85E418
*Jan 21 09:07:10.151: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.152: TFTP: Received ACK for block 8, socket_id 0x7F398E85E418
*Jan 21 09:07:10.152: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:10.153: TFTP: Received ACK for block 9, socket_id 0x7F398E85E418
*Jan 21 09:07:10.154: TFTP: Sending block 10 (retry 0), len 238, socket_id 0x7F398E85E418
*Jan 21 09:07:10.155: TFTP: Received ACK for block 10, socket_id 0x7F398E85E418
*Jan 21 09:07:10.155: TFTP: Finished flash:/its/softkeyDefault_kpml.xml, time 00:00:00 for pr ocess 448
*Jan 21 09:07:13.847: TFTP: Server request for port 53029, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:13.847: TFTP: read request from host 10.13.2.242(53029) via GigabitEthernet0/0/ 0
*Jan 21 09:07:13.847: TFTP: Looking for CTLSEP5061BF3DF344.tlv
*Jan 21 09:07:13.847: TFTP: Sending error 1 No such file
*Jan 21 09:07:14.748: TFTP: Server request for port 53015, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:14.748: TFTP: read request from host 10.13.2.242(53015) via GigabitEthernet0/0/ 0
*Jan 21 09:07:14.748: TFTP: Looking for ITLSEP5061BF3DF344.tlv
*Jan 21 09:07:14.749: TFTP: Sending error 1 No such file
*Jan 21 09:07:15.641: TFTP: Server request for port 52902, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:15.641: TFTP: read request from host 10.13.2.242(52902) via GigabitEthernet0/0/ 0
*Jan 21 09:07:15.641: TFTP: Looking for ITLFile.tlv
*Jan 21 09:07:15.641: TFTP: Sending error 1 No such file
*Jan 21 09:07:16.600: TFTP: Server request for port 52304, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:16.601: TFTP: read request from host 10.13.2.242(52304) via GigabitEthernet0/0/ 0
*Jan 21 09:07:16.601: TFTP: Looking for SEP5061BF3DF344.cnf.xml
*Jan 21 09:07:16.616: TFTP: Opened flash:/its/SEP5061BF3DF344.cnf.xml, fd 0, size 4772 for pr ocess 448
*Jan 21 09:07:16.617: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.618: TFTP: Received ACK for block 1, socket_id 0x7F398E85E418
*Jan 21 09:07:16.619: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.620: TFTP: Received ACK for block 2, socket_id 0x7F398E85E418
*Jan 21 09:07:16.620: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.623: TFTP: Received ACK for block 3, socket_id 0x7F398E85E418
*Jan 21 09:07:16.623: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.624: TFTP: Received ACK for block 4, socket_id 0x7F398E85E418
*Jan 21 09:07:16.624: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.625: TFTP: Received ACK for block 5, socket_id 0x7F398E85E418
*Jan 21 09:07:16.626: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.627: TFTP: Received ACK for block 6, socket_id 0x7F398E85E418
*Jan 21 09:07:16.627: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.628: TFTP: Received ACK for block 7, socket_id 0x7F398E85E418
*Jan 21 09:07:16.628: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.630: TFTP: Received ACK for block 8, socket_id 0x7F398E85E418
*Jan 21 09:07:16.630: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:16.631: TFTP: Received ACK for block 9, socket_id 0x7F398E85E418
*Jan 21 09:07:16.631: TFTP: Sending block 10 (retry 0), len 164, socket_id 0x7F398E85E418
*Jan 21 09:07:16.632: TFTP: Received ACK for block 10, socket_id 0x7F398E85E418
*Jan 21 09:07:16.633: TFTP: Finished flash:/its/SEP5061BF3DF344.cnf.xml, time 00:00:00 for pr ocess 448
*Jan 21 09:07:22.885: TFTP: Server request for port 49549, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:22.886: TFTP: read request from host 10.13.2.242(49549) via GigabitEthernet0/0/ 0
*Jan 21 09:07:22.886: TFTP: Looking for SIP_English_United_States/sp-sip.jar
*Jan 21 09:07:22.886: TFTP: Sending error 1 No such file
*Jan 21 09:07:23.946: TFTP: Server request for port 50007, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:23.946: TFTP: read request from host 10.13.2.242(50007) via GigabitEthernet0/0/ 0
*Jan 21 09:07:23.946: TFTP: Looking for SIP_United_Kingdom/g3-tones.xml
*Jan 21 09:07:23.946: TFTP: Sending error 1 No such file
*Jan 21 09:07:25.180: TFTP: Server request for port 53141, socket_id 0x7F398E85E418 for proce ss 448
*Jan 21 09:07:25.180: TFTP: read request from host 10.13.2.242(53141) via GigabitEthernet0/0/ 0
*Jan 21 09:07:25.180: TFTP: Looking for softkeyDefault_kpml.xml
*Jan 21 09:07:25.195: TFTP: Opened flash:/its/softkeyDefault_kpml.xml, fd 0, size 4846 for pr ocess 448
*Jan 21 09:07:25.196: TFTP: Sending block 1 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.197: TFTP: Received ACK for block 1, socket_id 0x7F398E85E418
*Jan 21 09:07:25.197: TFTP: Sending block 2 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.199: TFTP: Received ACK for block 2, socket_id 0x7F398E85E418
*Jan 21 09:07:25.199: TFTP: Sending block 3 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.200: TFTP: Received ACK for block 3, socket_id 0x7F398E85E418
*Jan 21 09:07:25.201: TFTP: Sending block 4 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.202: TFTP: Received ACK for block 4, socket_id 0x7F398E85E418
*Jan 21 09:07:25.202: TFTP: Sending block 5 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.203: TFTP: Received ACK for block 5, socket_id 0x7F398E85E418
*Jan 21 09:07:25.204: TFTP: Sending block 6 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.205: TFTP: Received ACK for block 6, socket_id 0x7F398E85E418
*Jan 21 09:07:25.205: TFTP: Sending block 7 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.206: TFTP: Received ACK for block 7, socket_id 0x7F398E85E418
*Jan 21 09:07:25.207: TFTP: Sending block 8 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.208: TFTP: Received ACK for block 8, socket_id 0x7F398E85E418
*Jan 21 09:07:25.208: TFTP: Sending block 9 (retry 0), len 512, socket_id 0x7F398E85E418
*Jan 21 09:07:25.210: TFTP: Received ACK for block 9, socket_id 0x7F398E85E418
*Jan 21 09:07:25.210: TFTP: Sending block 10 (retry 0), len 238, socket_id 0x7F398E85E418
*Jan 21 09:07:25.211: TFTP: Received ACK for block 10, socket_id 0x7F398E85E418
*Jan 21 09:07:25.211: TFTP: Finished flash:/its/softkeyDefault_kpml.xml, time 00:00:00 for pr ocess 448

Roger Kallberg · ‎01-21-2021

From what I can tell all requests for files via TFTP completes with success from the perspective of your router. You'd need to look at the logs from the phone(s) to see what it complains about.

danijel_knezic · ‎01-21-2021

WiFi Phone.jpg

This is message status for the phones

Roger Kallberg · ‎01-21-2021

You do know I assume that you can get the logs from the phones if you access them via the webUI or via SSH?

danijel_knezic · ‎01-21-2021

I have tried both web GUI and SSH but I can't access the phones. IPs I can ping but access denied.

Roger Kallberg · ‎01-21-2021

Both options are off from default. You need to enable it and for SSH you'd have to define a user name and password.

Roger Kallberg · ‎01-21-2021

For webUI see this post for how to enable it. https://community.cisco.com/t5/ip-telephony-and-phones/enable-web-access-on-cme-for-ip-phones/td-p/1767728

danijel_knezic · ‎01-21-2021

Sorry if I am asking basic but I am new into VoIP. Do I need to do this config for webGUI in my ISR 4331

Telephony-service 0

Service phone webAccess 1

Create cnf-files