OSS Endpoint : Polycom Provisioning Issue

General Help
1

It appears that it is reading the files in the tftpboot directory, but it the phone remains unprovisioned. I have almost tried everything.

Here is what I see in the log of IP 450,

001203.268|app1 ||03|Manual Reboot
001203.648|so ||03|SoNcasC::procMsg: Client service shutdown complete
001207.148|wdog ||03|Watchdog Expired: tSupObjs
000004.784|log ||03|---------- Initial log entry ----------
000004.786|so ||03|Platform: Model=SoundPoint IP 450, Assembly=2345-12450-001 Rev=B Region=
000004.786|so ||03|Platform: Interface eth0 MAC=0004f22f38c2
000004.786|so ||03|Platform: BootBlock=3.0.2.0024 (12450-001) 30-Nov-10 14:59
000004.786|so ||03|Platform: Updater=5.0.3.1667 24-Aug-12 11:19
000004.786|so ||03|Application, main: Label=SIP, Version=Mink 4.0.4.2906 17-Apr-13 23:40
000004.786|so ||03|Application, main: P/N=3150-11530-404
000004.786|rdisk||03|RAM disk created, size: 4,194,304 bytes
000004.786|ocsp ||03|O.C.S.P. Enabled = 0
000004.788|tls ||03|Initial log entry. Current logging level 4
000004.824|pmt ||03|Initial log entry. Current logging level 4
000004.826|wdog ||03|Initial log entry. Current logging level 4
000004.826|ethf ||03|Initial log entry. Current logging level 4
000004.826|hw ||03|Initial log entry. Current logging level 4
000004.826|ares ||03|Initial log entry. Current logging level 4
000004.826|dns ||03|Initial log entry. Current logging level 4
000004.826|cfg ||03|Initial log entry. Current logging level 4
000004.828|dot1x||03|Initial log entry. Current logging level 4
000004.832|cfg |5|03|RT|Error installing DHCP event hook.
000004.888|lic ||03|Initial log entry. Current logging level 4
000005.404|curl ||03|Initial log entry. Current logging level 4
000005.404|utilm||03|Initial log entry. Current logging level 4
000005.404|copy ||03|Initial log entry. Current logging level 4
000005.404|rtos ||03|Initial log entry. Current logging level 4
000005.406|sec ||03|Initial log entry. Current logging level 4
000005.406|so ||03|Configuration files:
000005.416|log ||03|Log render level set to 0
000005.434|srtp ||03|Initial log entry. Current logging level 4
000005.436|res ||03|Initial log entry. Current logging level 4
000005.438|httpa||03|Initial log entry. Current logging level 4
000005.438|lldp ||03|Initial log entry. Current logging level 4
000005.438|cdp ||03|Initial log entry. Current logging level 4
000005.440|sys ||03|Initial log entry. Current logging level 4
000005.586|ssps ||03|Initial log entry. Current logging level 4
000007.906|so ||03|System Info Reports:
000007.906|so ||03| CPU is TNETV1055/C55x, rev 2 running at 150.000MHz with memory at 125.000MHz.
000007.906|so ||03| Board is identified as PolycomSoundPointIP-SPIP_450.
000007.906|so ||03| DRAM_LO: 0x94000000. DRAM_SIZE: 32 MB
000007.906|so ||03| Clocks are VBUSP: 125.000MHz, VBUS: 75.000MHz, USB: 25.000MHz, LCD: 20.833MHz, DSP: 100.000MHz.
000007.906|so ||03| NOR flash detected is 12
000007.906|key ||03|Initial log entry. Current logging level 4
000007.908|httpd||03|Initial log entry. Current logging level 4
000007.908|poll ||03|Initial log entry. Current logging level 4
000007.910|push ||03|Initial log entry. Current logging level 4
000007.910|poll ||03|Initial log entry. Current logging level 4
000007.910|poll ||03|Initial log entry. Current logging level 4
000007.910|poll ||03|Initial log entry. Current logging level 4
000007.910|poll ||03|Initial log entry. Current logging level 4
000007.910|poll ||03|Initial log entry. Current logging level 4
000007.910|ht ||03|Initial log entry. Current logging level 4
000007.910|dbuf ||03|Initial log entry. Current logging level 4
000007.914|ssps ||03|Application, comp. 1: Label=PolyDSP Titan Mem1 FS5, Version=6.1.1.0010 11-Jul-12 19:00
000007.914|ssps ||03|Application, comp. 1: P/N=3150-11580-611.
000007.914|pps ||03|Initial log entry. Current logging level 4
000007.914|sip ||03|Initial log entry. Current logging level 4
000007.916|ptt ||03|Initial log entry. Current logging level 4
000008.164|so ||03|Using TCP IP Media Port, configured initial RTP port 2222
000008.182|sshc ||03|Initial log entry. Current logging level 4
000008.186|cmp ||03|Initial log entry. Current logging level 4
000008.186|wmgr ||03|Initial log entry. Current logging level 4
000008.192|slog ||03|Initial log entry. Current logging level 3
000008.388|app1 ||03|Initial log entry. Current logging level 4
000008.398|app1 |4|03|StatusBar is disabled
000008.404|app1 |4|03|mb.main.home parameter is empty
000008.418|mb ||03|Initial log entry. Current logging level 4
000008.836|ldap ||03|Initial log entry. Current logging level 4
000008.836|ldap |4|03|cDynamicData::cDynamicData:cDynamicData:Failed
000008.858|efk ||03|Initial log entry. Current logging level 4
000009.252|app1 |4|03|[AppUserLoginC::Init]:flushing user cache file failed
000009.296|app1 |4|03|Observable::notifyAll 1
000009.298|app1 |4|03|getCfgParams
000009.300|utilm|4|03|uBLFCompressed: File /ffs0/local/local-directory_xml.zzz does not exist or is empty
000019.714|cfg ||03|RT|cfgRtNetInterfaceUpdate: Network not initialized
000024.222|cfg ||03|RT| Phone IP address is 192.168.2.187.
000024.224|cfg ||03|RT| Subnet mask is 255.255.255.0.
000024.224|cfg ||03|RT| Gateway address is 192.168.2.1.
000024.224|cfg ||03|RT|DHCP: Alternate DNS server is not specified
000024.224|cfg ||03|RT| DNS server is 192.168.2.1.
000024.224|cfg ||03|RT| DNS domain is bcsnj.local.
000024.226|dns ||03|DNS resolver servers are ‘192.168.2.1’ '0.0.0.0’
000024.226|dns ||03|DNS resolver search domain is 'bcsnj.local’
000024.228|cfg ||03|RT|Primary IP changed to 192.168.2.187 subnet mask 255.255.255.0
000024.228|cfg ||03|RT|cfgRtNetInterfaceUpdate: bfeng – calling network status callback
000024.228|cfg ||03|RT|cfgRtNetInterfaceUpdate: bfeng – calling network status callback
000024.258|sys ||03|0x95886b20 (tDhcpcStateTask): arp_check: No reply, addr not used
000024.310|so ||03|Network initialized. Starting network tasks.
000024.310|log ||03|Install file upload callback for ‘so’

000024.324|cfg |5|03|Prm|Parameter acd.reg requested type 0 but is of type 2
000024.324|sip ||03|Fast Boot Measurement Point: Ready for Call, uptime: 24.324 sec.
000024.328|app1 ||03|Ctx [0] Registered [false]
000024.442|copy ||03|Server ‘54.187.75.81’ said ‘0004f22f38c2-directory.xml’ is not present
000024.446|utilm|4|03|uBLFCompressed: File /ffs0/local/local-directory_xml.zzz does not exist or is empty
000024.464|res |4|03|[ResFinderC]: Download - Failed to download file Leaf256x116.jpg, errno 0x380003.
000024.466|app1 |4|03|Background processing failed: (Leaf256x116.jpg): (pResult->m_result != ResFinderFound): 1.
000024.560|utilm|4|03|uBLFCompressed: File /ffs0/local/local-directory_xml.zzz does not exist or is empty
000024.598|res |4|03|[ResFinderC]: Download - Failed to download file Sailboat256x116.jpg, errno 0x380003.
000024.600|app1 |4|03|Background processing failed: (Sailboat256x116.jpg): (pResult->m_result != ResFinderFound): 1.
000024.734|res |4|03|[ResFinderC]: Download - Failed to download file Beach256x116.jpg, errno 0x380003.
000024.736|app1 |4|03|Background processing failed: (Beach256x116.jpg): (pResult->m_result != ResFinderFound): 1.
000024.836|cfg |4|03|Prov|Download of master configuration file failed
000024.836|cfg |4|03|Prov|Trying to boot from existing configuration
000024.840|cfg |4|03|Prov|Provisioning failed
000024.846|cfg ||03|Prov|Finished updating configuration
000024.864|res |4|03|[ResFinderC]: Download - Failed to download file Palm256x116.jpg, errno 0x380003.
000024.864|app1 |4|03|Background processing failed: (Palm256x116.jpg): (pResult->m_result != ResFinderFound): 1.
000024.970|copy ||03|Server ‘54.187.75.81’ said ‘0004f22f38c2-boot.log’ is not present
000024.970|log |4|03|UtilLogC::uploadFifoLog: upload error. protocol 0 result = -1
000024.974|log |4|03|Failed to upload boot log on start up.
000024.988|res |4|03|[ResFinderC]: Download - Failed to download file Jellyfish256x116.jpg, errno 0x380003.
000024.988|app1 |4|03|Background processing failed: (Jellyfish256x116.jpg): (pResult->m_result != ResFinderFound): 1.
000025.082|copy ||03|Server ‘54.187.75.81’ said ‘0004f22f38c2-app.log’ is not present
000025.084|log |4|03|UtilLogC::uploadFifoLog: upload error. protocol 0 result = -1
000025.128|res |4|03|[ResFinderC]: Download - Failed to download file Mountain256x116.jpg, errno 0x380003.
000025.130|app1 |4|03|Background processing failed: (Mountain256x116.jpg): (pResult->m_result != ResFinderFound): 1.

2

I have FreePBX 2.11 on Centos 6.5 with Asterisk 11.7

I am trying to TFTP provision Polycom IP 331 and 450 phones using the free version of the Endpoint Manager.

3

Do you have your DHCP server setup to send option 66 to the phones?

4

Nopes , I have set it to static.

With tcpdump, I can see TFTP hits coming on to my server. I also see that it is reading the TFTP directory.

16:24:13.371086 IP (tos 0x0, ttl 43, id 21578, offset 0, flags [none], proto UDP (17), length 76)
96.56.215.58.47589 > 10.0.0.156.tftp: [udp sum ok] 48 RRQ “0004f22f38c2-directory.xml” octet blksize 4096
16:24:13.386645 IP (tos 0x0, ttl 43, id 25425, offset 0, flags [none], proto UDP (17), length 65)
96.56.215.58.32477 > 10.0.0.156.tftp: [udp sum ok] 37 RRQ “Leaf256x116.jpg” octet blksize 4096
16:24:13.483797 IP (tos 0x0, ttl 43, id 29272, offset 0, flags [none], proto UDP (17), length 76)
96.56.215.58.24946 > 10.0.0.156.tftp: [udp sum ok] 48 RRQ “000000000000-directory.xml” octet blksize 4096
16:24:13.507077 IP (tos 0x0, ttl 43, id 36966, offset 0, flags [none], proto UDP (17), length 69)
96.56.215.58.50182 > 10.0.0.156.tftp: [udp sum ok] 41 RRQ “Sailboat256x116.jpg” octet blksize 4096
16:24:13.628519 IP (tos 0x0, ttl 43, id 48507, offset 0, flags [none], proto UDP (17), length 66)
96.56.215.58.63080 > 10.0.0.156.tftp: [udp sum ok] 38 RRQ “0004f22f38c2.cfg” octet blksize 4096
16:24:13.656899 IP (tos 0x0, ttl 43, id 52354, offset 0, flags [none], proto UDP (17), length 66)
96.56.215.58.19136 > 10.0.0.156.tftp: [udp sum ok] 38 RRQ “Beach256x116.jpg” octet blksize 4096
16:24:13.761209 IP (tos 0x0, ttl 43, id 60048, offset 0, flags [none], proto UDP (17), length 66)
96.56.215.58.44080 > 10.0.0.156.tftp: [udp sum ok] 38 RRQ “000000000000.cfg” octet blksize 4096
16:24:13.776001 IP (tos 0x0, ttl 43, id 2207, offset 0, flags [none], proto UDP (17), length 65)
96.56.215.58.28619 > 10.0.0.156.tftp: [udp sum ok] 37 RRQ “Palm256x116.jpg” octet blksize 4096
16:24:13.898759 IP (tos 0x0, ttl 43, id 9901, offset 0, flags [none], proto UDP (17), length 71)
96.56.215.58.53665 > 10.0.0.156.tftp: [udp sum ok] 43 WRQ “0004f22f38c2-boot.log” octet blksize 4096
16:24:13.901388 IP (tos 0x0, ttl 43, id 17595, offset 0, flags [none], proto UDP (17), length 70)
96.56.215.58.61293 > 10.0.0.156.tftp: [udp sum ok] 42 RRQ “Jellyfish256x116.jpg” octet blksize 4096
16:24:14.011845 IP (tos 0x0, ttl 43, id 21442, offset 0, flags [none], proto UDP (17), length 70)
96.56.215.58.39363 > 10.0.0.156.tftp: [udp sum ok] 42 WRQ “0004f22f38c2-app.log” octet blksize 4096
16:24:14.033630 IP (tos 0x0, ttl 43, id 29136, offset 0, flags [none], proto UDP (17), length 69)
96.56.215.58.22834 > 10.0.0.156.tftp: [udp sum ok] 41 RRQ “Mountain256x116.jpg” octet blksize 4096

5

I am putting in the provisioning server from the web admin.

Setting --> Provisioning server

In the boot log, what does this really mean, I know provisioning is failed, what is really causing it,

000024.836|cfg |4|03|Prov|Download of master configuration file failed
000024.836|cfg |4|03|Prov|Trying to boot from existing configuration
000024.840|cfg |4|03|Prov|Provisioning failed
000024.846|cfg |*|03|Prov|Finished updating configuration