New Phones not getting EPM config

Unfortunately, no way of configuring their router for dhcp option 66 as it is managed by third party… Manually entered fqdn of the FreePBX Server in the Grandstream 1615’s, 1630’s and 2160’s. After rebooting the phones a few times, the 2160’s and 1630’s were able to get their config file from the remote FreePBX Server using tftp.

The 1615’s for the most part do not get their config file. Not sure what log might show any info if it is an error in the configuring of EPM for these phone.

The configuration looks straight forward enough and I’ve read the guide to try to make sure errors are avoided.

Thinking I may have messed up the template for the GS 1615’s, deleted the template and started afresh. Mapped the 1615’s to the right extensions. Rebooted a few 1615’s but no luck. They don’t get their config and they obviously don’t show up in cli.

Disabled iptables just in case tftp was being blocked. Also tried http and https. Everything tried fails.

Perhaps someone has experienced this and can provide some help.
Thanks.

You can enable tftp logging with the instructions here:
https://wiki.freepbx.org/display/PPS/Enable+TFTP+verbose+logging

Once that is done, you can tail/grep/etc /var/log/messages for tftp traffic.

Thanks for the pointer Igaetz. Nothing from tfpd is showing in /var/log/messages but I will use EPG to try again to get phones to get their config and then see if anything shows up.

Now seeing some phones REQ files. However, some of the phone are not.
Installed several 1615’s that have MACs ending DA2 to DA9. Only DA5 and DA6 show up:

May 12 19:21:48 host1-pbx in.tftpd[25303]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5
May 12 19:21:48 host1-pbx in.tftpd[25304]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6
May 12 19:21:49 host1-pbx in.tftpd[25305]: RRQ from xx.xxx.165.203 filename gxp1600fw.bin
May 12 19:21:50 host1-pbx in.tftpd[25306]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5
May 12 19:21:50 host1-pbx in.tftpd[25307]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6
May 12 19:21:51 host1-pbx in.tftpd[25308]: RRQ from xx.xxx.165.203 filename gxp1600fw.bin
May 12 19:21:52 host1-pbx in.tftpd[25309]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5
May 12 19:21:52 host1-pbx in.tftpd[25310]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6
May 12 19:21:53 host1-pbx in.tftpd[25311]: RRQ from xx.xxx.165.203 filename gxp1600fw.bin
May 12 19:21:54 host1-pbx in.tftpd[25312]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5
May 12 19:21:54 host1-pbx in.tftpd[25313]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6
May 12 19:21:55 host1-pbx in.tftpd[25314]: RRQ from xx.xxx.165.203 filename gxp1600fw.bin
May 12 19:21:56 host1-pbx in.tftpd[25315]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5
May 12 19:21:56 host1-pbx in.tftpd[25316]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6
May 12 19:21:57 host1-pbx in.tftpd[25317]: RRQ from xx.xxx.165.203 filename gxp1600fw.bin
May 12 19:21:58 host1-pbx in.tftpd[25318]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5.xml
May 12 19:21:58 host1-pbx in.tftpd[25319]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6.xml
May 12 19:21:59 host1-pbx in.tftpd[25320]: RRQ from xx.xxx.165.203 filename gxp1600fw.bin
May 12 19:22:00 host1-pbx in.tftpd[25321]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5.xml
May 12 19:22:00 host1-pbx in.tftpd[25322]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6.xml
May 12 19:22:01 host1-pbx in.tftpd[25347]: RRQ from xx.xxx.165.203 filename ring1.bin
May 12 19:22:02 host1-pbx in.tftpd[25348]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5.xml
May 12 19:22:02 host1-pbx in.tftpd[25349]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6.xml
May 12 19:22:03 host1-pbx in.tftpd[25350]: RRQ from xx.xxx.165.203 filename ring1.bin
May 12 19:22:04 host1-pbx in.tftpd[25351]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5.xml
May 12 19:22:04 host1-pbx in.tftpd[25352]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6.xml
May 12 19:22:05 host1-pbx in.tftpd[25353]: RRQ from xx.xxx.165.203 filename ring1.bin
May 12 19:22:06 host1-pbx in.tftpd[25354]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5.xml
May 12 19:22:06 host1-pbx in.tftpd[25355]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6.xml
May 12 19:22:07 host1-pbx in.tftpd[25356]: RRQ from xx.xxx.165.203 filename ring1.bin
May 12 19:22:08 host1-pbx in.tftpd[25357]: RRQ from xx.xxx.165.203 filename cfg000b82a96da5.xml
May 12 19:22:08 host1-pbx in.tftpd[25358]: RRQ from xx.xxx.165.203 filename cfg000b82a96da6.xml
May 12 19:22:09 host1-pbx in.tftpd[25379]: RRQ from xx.xxx.165.203 filename ring1.bin
May 12 19:22:10 host1-pbx in.tftpd[25380]: RRQ from xx.xxx.165.203 filename cfg.xml
May 12 19:22:10 host1-pbx in.tftpd[25381]: RRQ from xx.xxx.165.203 filename cfg.xml
May 12 19:22:11 host1-pbx in.tftpd[25382]: RRQ from xx.xxx.165.203 filename ring1.bin
May 12 19:22:12 host1-pbx in.tftpd[25383]: RRQ from xx.xxx.165.203 filename cfg.xml
May 12 19:22:13 host1-pbx in.tftpd[25384]: RRQ from xx.xxx.165.203 filename cfg.xml
May 12 19:22:13 host1-pbx in.tftpd[25385]: RRQ from xx.xxx.165.203 filename ring2.bin
May 12 19:22:14 host1-pbx in.tftpd[25386]: RRQ from xx.xxx.165.203 filename cfg.xml
May 12 19:22:14 host1-pbx in.tftpd[25387]: RRQ from xx.xxx.165.203 filename cfg.xml
May 12 19:22:15 host1-pbx in.tftpd[25388]: RRQ from xx.xxx.165.203 filename ring2.bin

Not sure why anything is grabbing gxp1600fw.bin since we do not have any 1600 phones

Thanks.

What I am seeing in /var/log/messages is requests for config files but they don’t seem to be sent:

May 15 16:06:39 host1-oemet in.tftpd[4729]: RRQ from xx.xxx.165.203 filename cfg000b82a9695b
May 15 16:06:41 host1-oemet in.tftpd[4730]: RRQ from xx.xxx.165.203 filename cfg000b82a9695b
May 15 16:06:43 host1-oemet in.tftpd[4731]: RRQ from xx.xxx.165.203 filename cfg000b82a9695b
May 15 16:06:45 host1-oemet in.tftpd[4732]: RRQ from xx.xxx.165.203 filename cfg000b82a9695b
May 15 16:06:47 host1-oemet in.tftpd[4735]: RRQ from xx.xxx.165.203 filename cfg000b82a9695b
May 15 16:06:49 host1-oemet in.tftpd[4736]: RRQ from xx.xxx.165.203 filename cfg000b82a9695b
May 15 16:06:51 host1-oemet in.tftpd[4737]: RRQ from xx.xxx.165.203 filename cfg000b82a9695b.xml

The file is present:
-rwxr-xr-x 1 asterisk asterisk 3240 May 15 16:03 cfg000b82a9695b

This file is NOT present:
tftpboot]# ls cfg000b82a9695b.xml
ls: cannot access cfg000b82a9695b.xml: No such file or directory

What might be causing this??

Thanks.

It doesn’t look like the file is getting sent, which can mean lots of things, from the TFTP port not being open to TFTP not being enabled. Turn on logging and make sure that TFTP is configured to run. Also, in the xinetd.conf (there are other places as well) there is a setting that limits the number of file requests per minute. Make sure you aren’t exceeding that - logging should help you with that as well.

Hi Dave,
I am turning on one phone at a time. Can’t see how that would cause tftp to fail.
Just tried another phone, Upgraded firmware by pointing the phone to firmware.grandstream.com, now the phone is at latest firmware.

Rebooted phone from phone GUI,

Watching /var/log/messages again all that is displayed is:

May 15 17:15:54 host1-oemet in.tftpd[8454]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3
May 15 17:15:56 host1-oemet in.tftpd[8455]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3
May 15 17:15:58 host1-oemet in.tftpd[8456]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3
May 15 17:16:02 host1-oemet in.tftpd[8482]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3
May 15 17:16:04 host1-oemet in.tftpd[8483]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3
May 15 17:16:06 host1-oemet in.tftpd[8484]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3.xml
May 15 17:16:08 host1-oemet in.tftpd[8485]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3.xml
May 15 17:16:10 host1-oemet in.tftpd[8486]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3.xml
May 15 17:16:12 host1-oemet in.tftpd[8487]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3.xml
May 15 17:16:14 host1-oemet in.tftpd[8488]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3.xml
May 15 17:16:16 host1-oemet in.tftpd[8489]: RRQ from xx.xxx.165.203 filename cfg000b82a96da3.xml
May 15 17:16:18 host1-oemet in.tftpd[8493]: RRQ from xx.xxx.165.203 filename cfg.xml
May 15 17:16:20 host1-oemet in.tftpd[8514]: RRQ from xx.xxx.165.203 filename cfg.xml
May 15 17:16:22 host1-oemet in.tftpd[8515]: RRQ from xx.xxx.165.203 filename cfg.xml
May 15 17:16:24 host1-oemet in.tftpd[8517]: RRQ from xx.xxx.165.203 filename cfg.xml
May 15 17:16:26 host1-oemet in.tftpd[8518]: RRQ from xx.xxx.165.203 filename cfg.xml
May 15 17:16:28 host1-oemet in.tftpd[8519]: RRQ from xx.xxx.165.203 filename cfg.xml
May 15 17:16:30 host1-oemet in.tftpd[8520]: RRQ from xx.xxx.165.203 filename cfggxp1615.xml
May 15 17:16:32 host1-oemet in.tftpd[8521]: RRQ from xx.xxx.165.203 filename cfggxp1615.xml
May 15 17:16:34 host1-oemet in.tftpd[8522]: RRQ from xx.xxx.165.203 filename cfggxp1615.xml
May 15 17:16:36 host1-oemet in.tftpd[8523]: RRQ from xx.xxx.165.203 filename cfggxp1615.xml
May 15 17:16:38 host1-oemet in.tftpd[8524]: RRQ from xx.xxx.165.203 filename cfggxp1615.xml
May 15 17:16:40 host1-oemet in.tftpd[8525]: RRQ from xx.xxx.165.203 filename cfggxp1615.xml

Nothing else is displayed related to tftp

As for files, not sure if they are supposed to be in /tftpboot but:
tftpboot]# ls cfggxp1615.xml
ls: cannot access cfggxp1615.xml: No such file or directory

tftpboot]# ls cfg.xml
ls: cannot access cfg.xml: No such file or directory

tftpboot]# ls cfg000b82a96da3.xml
ls: cannot access cfg000b82a96da3.xml: No such file or directory

tftpboot]# ll cfg000b82a96da3
-rwxr-xr-x 1 asterisk asterisk 3246 May 15 17:05 cfg000b82a96da3

/etc/xinetd.d/tftp has:
service tftp
{
disable = no
socket_type = dgram
protocol = udp
wait = yes
user = root
server = /usr/sbin/in.tftpd
server_args = -s /tftpboot --verbose
per_source = 11
cps = 100 2
flags = IPv4

Try “service restart xinetd” and see if that helps. If I remember correctly, you should be seeing the files getting sent as well as requested.

The config file is there, so it shouldn’t be requesting it five times. For whatever reason, tftpd is not responding correctly.