Polycom + FusionPBx Provisioning Headache

Status
Not open for further replies.

zanderb

New Member
Sep 18, 2020
2
0
1
35
Hello,

I've scoured the forums extensively before posting this thread as to follow proper forum etiquette.

I will explain the issue to the best of my knowledge and do know that this is a multi-part question.

Part One - Section One - Description of PBX Purpose
We are looking at setting up fusionPBX for multi-tenancy usage for our business purposes.

Part Two - Section One - Provisioning: Setting up testing domain for testing purposes
1. We've set up and configured our fusionPBX using vmware and everything has been running smoothly. We were able to create a domain and subdomain without issue.
2. Previous to the issue, we had everything working as expected using a "test.domain.com" domain.
3. We were able to provision a Polycom VVX300 phone using the polycom_provision_url without issue using our testing extension
4. After doing a little research on multi-tenancy, we created another subdomain labeled "company.domain.com" and have configured our secondary testing DID to send and receive calls.
5. Before making any changes to the "company.domain.com" tenant, we verified everything was working as expected, which it was. We were able to provision the polycom phone as expected using the polycom_provision_url.
6. We were able to make and receive calls from the device to outside numbers

Part Two - Section Two - When things went wrong
In an effort to start building out the system for multi-tenancy use, we watched the video that DigitalDaz provided in "tips, tricks" on setting domain specific settings vs. global settings.

1. We copied the polycom_provision_url over to the "company.domain.com" domain settings section
2. We copied the http_auth_username and http_auth_password
3. We copied the enabled
4. We copied the admin_name
5. We copied the admin_password
6. We then updated the "default settings" polycom_provision_url" to false
7. For testing purposes, the username/password and admin/password information was kept the same.
8. After copying anything from "default settings" we have logged out of the superadmin account and logged back in.
9. We then rebooted the phone. After rebooting the phone, the device shows "line unregistered".
10. We verified that the device can communicate with the pbx instance
11. The device is provisioning properly and can see it communicating with the pbx server
12. We have attempted to factory reset the polycom device several times
13. We have completely removed the device from the "devices" section under "accounts" several times
14. In a "banging my head against the desk" moment, we decided to copy the entire "polycom" subcategory under provisioning to the company.domain.com.
15. We've logged out of the system and logged back in.
16. We attempted to provision device again.
17. It can see and has retrieved the provisioning settings from the pbx but the line is still "unregistered"

Here is a log copy of the polycom device:
Code:
0924153305|app1 |*|00|Event: Multiple Key Combo 1
0924153305|app1 |*|00|Manual Reboot
0924153305|ldap |4|00|ldapXfer::start:xferStart failed cState=-1
0924153305|app1 |*|00|AppC::appReboot cDynamicData shutdown complete
0924153306|so   |*|00|SoNcasC::procMsg: Client service shutdown complete
0924153332|wdog |*|00|Watchdog Expired: tSupObjs, 8
0924153332|log  |*|00|---------- Final log entry ----------
000017.989|log  |*|00|---------- Initial log entry ----------
000009.815|wdog |*|00|Initial log entry. Current logging level 4
000017.598|rdisk|*|00|Initial log entry. Current logging level 4
000017.602|rdisk|*|00|RAM disk created and mounted at "/rfs0"
000017.602|rdisk|*|00|RAM disk created, size: 16,777,216 bytes
000017.967|so   |*|00|Platform: Model=VVX 300, Assembly=3111-46135-002 Rev=A Region=0
000017.968|so   |*|00|Platform: Interface    eth0 MAC=macaddress
000017.969|so   |*|00|Platform: BootBlock=3.0.4.0028 (46135-002) 30-Jan-13 18:00
000017.970|so   |*|00|Platform: BootL1=1.0.0.0009 (46135-002) 11-Jan-13 14:23
000017.978|so   |*|00|Platform: Updater=5.4.0.8782 25-Sep-14 18:53
000017.979|so   |*|00|Application, main: Label=SIP, Version=BlueNile 5.2.0.8330 25-Sep-14 18:16
000017.979|so   |*|00|Application, main: P/N=3150-11530-520
000017.979|rdisk|*|00|RAM disk created, size: 16,777,216 bytes
000017.983|ocsp |*|00|O.C.S.P. Enabled = 0
000018.052|tls  |*|00|Initial log entry. Current logging level 4
000018.102|pmt  |*|00|Initial log entry. Current logging level 4
000018.107|wdog |*|00|Initial log entry. Current logging level 4
000018.107|ethf |*|00|Initial log entry. Current logging level 4
000018.108|hw   |*|00|Initial log entry. Current logging level 4
000018.112|ares |*|00|Initial log entry. Current logging level 4
000018.116|dns  |*|00|Initial log entry. Current logging level 4
000018.116|cfg  |*|00|Initial log entry. Current logging level 4
000018.116|cfg  |*|00|Prm|cfgParamBaseProfileUpdate: Using Base Profile Generic
000018.130|dot1x|*|00|Initial log entry. Current logging level 4
000018.333|lic  |*|00|Initial log entry. Current logging level 4
000018.354|lic  |5|00|cfgCfsFlashKeyScratchLoad: could not load flash len 976
000018.355|lic  |*|00|License expiry check in 25315 seconds
000018.494|curl |*|00|Initial log entry. Current logging level 4
000018.494|utilm|*|00|Initial log entry. Current logging level 4
000018.496|copy |*|00|Initial log entry. Current logging level 4
000018.498|rtos |*|00|Initial log entry. Current logging level 4
000018.505|sec  |*|00|Initial log entry. Current logging level 4
000018.540|so   |*|00|Configuration files:
000018.540|cfg  |4|00|Prm|Local configuration statistics: 2 parameters were invalid and could not be interpreted.
000018.540|cfg  |4|00|Prm|Local configuration statistics: 0 parameter values were used but were out of range. 1 were invalid and ignored.
000018.554|log  |*|00|Log render level set to 1
000018.560|pcap |*|00|On-Phone Packet Capture disabled
000018.563|srtp |*|00|Initial log entry. Current logging level 4
000018.578|cfg  |5|00|Prm|Parameter 0 is out of range
000018.587|res  |*|00|Initial log entry. Current logging level 4
000018.591|httpa|*|00|Initial log entry. Current logging level 4
000018.591|lldp |*|00|Initial log entry. Current logging level 4
000018.591|cdp  |*|00|Initial log entry. Current logging level 4
000018.595|pcd  |*|00|Initial log entry. Current logging level 4
000018.613|tickt|*|00|Initial log entry. Current logging level 4
000018.641|utilm|4|00|uBLFUnCompressed: File /ffs0/Config/Local/WebTicket/0/sip.usr doesn't exist or is empty
000018.650|tickt|4|00|soWebTicketCertMove: Could not copy key to new slot
000018.654|tickt|4|00|soWebTicketCertMove: Could not copy cert to new slot
000018.654|tickt|4|00|soWebTicketCertMove: Could not copy SIP user to new slot
000018.655|tickt|4|00|soWebTicketCertMove: Could not copy SIP uuid to new slot
000018.655|tickt|4|00|soWebTicketCertMove: Could not copy server to new slot
000018.661|loc  |*|00|Initial log entry. Current logging level 4
000018.683|ssps |*|00|Initial log entry. Current logging level 4
000018.691|ssps |5|00|Allocated 36992 bytes for SspsHdiMemBase=0xdb1198
000018.695|ssps |4|00|sspsDspLoad: found Maroo 23.
000019.162|so   |*|00|System Info Reports:
000019.162|so   |*|00|  Cpu is ARMv6-compatible processor rev 7 (v6l), ID: 0x111700b0, running at 300MHz.
000019.162|so   |*|00|  Board is identified as PolycomVVX-VVX_300.
000019.162|so   |*|00|  DRAM_SIZE: 128 MB
000019.162|so   |*|00|  NAND flash detected is 20
000019.165|key  |*|00|Initial log entry. Current logging level 4
000019.218|httpd|*|00|Initial log entry. Current logging level 4
000019.226|poll |*|00|Initial log entry. Current logging level 4
000019.226|push |*|00|Initial log entry. Current logging level 4
000019.227|poll |*|00|Initial log entry. Current logging level 4
000019.229|poll |*|00|Initial log entry. Current logging level 4
000019.232|poll |*|00|Initial log entry. Current logging level 4
000019.233|poll |*|00|Initial log entry. Current logging level 4
000019.233|poll |*|00|Initial log entry. Current logging level 4
000019.236|ht   |*|00|Initial log entry. Current logging level 4
000019.241|dbuf |*|00|Initial log entry. Current logging level 4
000019.249|ssps |*|00|sspsMain: Set HDI mem to be 0, was 0
000019.269|ssps |*|00|Application, comp. 1: Label=PolyDSP Vino BCM11193 Mem1 FS1, Version=8.4.0.2005 17-Sep-2014 21:59
000019.269|ssps |*|00|Application, comp. 1: P/N=3150-11580-8X0.
000019.270|ice  |*|00|Initial log entry. Current logging level 4
000019.276|pps  |*|00|Initial log entry. Current logging level 4
000019.279|sip  |*|00|Initial log entry. Current logging level 4
000019.281|sipt |*|00|Initial log entry. Current logging level 4
000019.281|sipp |*|00|Initial log entry. Current logging level 4
000019.291|ptt  |*|00|Initial log entry. Current logging level 4
000019.298|xmpp |*|00|Initial log entry. Current logging level 4
000020.238|pwrsv|*|00|Initial log entry. Current logging level 4
000020.271|so   |*|00|Using TCP IP Media Port, configured initial RTP port 2222
000020.275|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.283|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.284|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.284|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.285|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.285|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.285|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.286|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.286|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.286|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.287|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.287|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.287|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.288|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.289|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.289|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.289|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.290|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.290|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.290|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.290|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.291|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.291|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.291|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.291|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.292|cfg  |5|00|Prm|Parameter tcpIpApp.ice.simul183ctr requested type 2 but is of type 0
000020.346|sshc |*|00|Initial log entry. Current logging level 4
000020.349|gbLoc|*|00|Initial log entry. Current logging level 4
000020.354|app1 |*|00|Initial log entry. Current logging level 4
000020.445|app1 |*|00|AppPhoneC::AppPhoneC() Number Of Line Keys = 48
000020.445|app1 |*|00|AppPhoneC::AppPhoneC() - new AppRegLineC, szUser = 1301@company.domain.com
000020.620|ldap |*|00|Initial log entry. Current logging level 4
000020.840|efk  |*|00|Initial log entry. Current logging level 4
000020.844|efk  |4|00|Prompt with id [1] is OK.
000020.948|app1 |4|00|getCfgParams
000020.963|cmp  |*|00|Initial log entry. Current logging level 4
000020.968|wmgr |*|00|Initial log entry. Current logging level 4
000020.983|slog |*|00|Initial log entry. Current logging level 3
000021.071|so   |4|00|SoKeyC::procMsg unexpected message 4099
000025.511|pgui |*|00|Initial log entry. Current logging level 4
000030.528|copy |4|00|DNS lookup failed for company.domain.com
000030.528|cfg  |4|00|Prov|Uploading phoneLocal.cfg failed
000033.590|dhcpc|*|00|Initial log entry. Current logging level 4
000037.912|cfg  |*|00|RT|Do not do DHCP VLAN Discovery.
000037.912|cfg  |*|00|RT|   Phone IP address is 10.xx.xx.228.
000037.912|cfg  |*|00|RT|   Subnet mask is 255.255.255.0.
000037.912|cfg  |*|00|RT|   Gateway address is 10.xx.xx.1.
000037.913|cfg  |*|00|RT|   DNS server is 10.xx.xx.1.
000037.913|cfg  |*|00|RT|   Time server is 10.xx.xx.1.
000037.913|cfg  |*|00|RT|   GMT offset is -18000 seconds.
000037.914|dns  |*|00|DNS resolver servers are '10.xx.xx.1' '8.8.8.8'
000037.914|dns  |*|00|DNS resolver search domain is ''
000037.916|cfg  |*|00|RT|Primary IP: 10.xx.xx.228 subnet mask 255.255.255.0
000038.079|so   |*|00|Network initialized. Starting network tasks.
000038.083|log  |*|00|Install file upload callback for 'so'

0924153444|pgui |*|00|Qt Mouse driver created.
0924153446|osd  |*|00|Initial log entry. Current logging level 4
0924153446|dbs  |*|00|Initial log entry. Current logging level 4
0924153447|clist|*|00|Initial log entry. Current logging level 4
0924153447|app1 |5|00|[connectToDatabase]: dbDirSet activated
0924153448|clist|4|00|dbSet::startLoadLocalSet:No local copy, requesting backup from server
0924153448|copy |*|00|Server 'company.domain.com' said 'provision/macaddress-calls.xml' is not present
0924153448|clist|4|00|dbIO::processResult:copy error
0924153448|clist|4|00|dbXML::createEntry:name=<bb> func=<0x17d3cc><0x17d37c>- invalid or already exist
0924153448|clist|4|00|dbXML::parse:parsing
0924153449|cfg  |5|00|Prm|Parameter dialplan.digitmap.count requested type 2 but is of type 0
0924153449|sip  |*|00|Sip Register Usr:1301@company.domain.com Dsp:1301 Auth:'1301' Inx:0
0924153449|cfg  |5|00|Prm|Parameter genband.E911.registration.line requested type 2 but is of type 0
0924153449|sip  |*|00|Fast Boot Measurement Point: Ready for Call, uptime: 61.422 sec.
0924153449|app1 |5|00|Corporate directory instance does not exists.
0924153449|sip  |4|00|CTcpSocket::ConnectThreadLocal failed with error -1 and erro no 115
0924153449|sip  |4|00|Registration failed User: 1301, Error Code:480 Temporarily not available
0924153449|so   |4|00|[SoMain]::procMsg: BToE Mgr is not running
0924153449|app1 |5|00|[connectToDatabase]: dbDirSet activated
0924153449|cfg  |*|00|Prov|Starting to update
0924153449|copy |*|00|Server 'downloads.polycom.com' said 'voice/software/3111-46135-002.sip.ld' is not present
0924153449|cfg  |*|00|Prov|Starting to update voice/software/sip.ld
0924153449|copy |*|00|Server 'downloads.polycom.com' said 'voice/software/sip.ld' is not present
0924153450|copy |*|00|Server 'company.domain.com' said 'provision/macaddress-web.cfg' is not present
0924153450|copy |*|00|Server 'company.domain.com' said 'provision/000000000000-license.cfg' is not present
0924153450|copy |*|00|Server 'company.domain.com' said 'provision/macaddress-license.cfg' is not present
0924153450|cfg  |4|00|Prm|Local configuration: Unknown parameter "reg..serverFeatureControl.cf" found, ignoring.
0924153450|cfg  |4|00|Prm|Local configuration: Unknown parameter "reg..serverFeatureControl.dnd" found, ignoring.
0924153450|cfg  |*|00|Prm|Local configuration: Value "" for configuration parameter tcpIpApp.sntp.gmtOffset is invalid, ignoring
0924153450|cfg  |*|00|Prov|Setting device parameters from configuration files.
0924153450|cfg  |4|00|RT|cfgFlashUpdateFromConfig: Failed to update SerialConfig
0924153450|cfg  |*|00|Prov|Finished updating configuration
0924153450|log  |4|00|UtilLogServerC::uploadFifoLog: upload error. protocol 0 result = -1
0924153450|log  |4|00|Failed to upload boot log on start up.
0924153450|copy |*|00|Server 'company.domain.com' said 'provision/macaddress-app.log' is not present
0924153450|copy |4|00|Could not set the custom header Content-Range for HTTP/s request. Head request failed to get content-length of server file.
0924153450|log  |4|00|UtilLogServerC::uploadFifoLog: upload error. protocol 0 result = -1
0924153451|em   |*|00|Initial log entry. Current logging level 4
0924153453|brow |*|00|Initial log entry. Current logging level 4

Part Three - Section One - Multi-Tenancy
I'll just start off and ask, is it REALLY worth it to pay for training classes through fusionpbx to get my system fully functional for multi-tenancy? I am not saying I do not see the worth of paying a monthly subscription to these guys, but I'd like to get the system in an operational state before spending money on it. Ultimately, we will reach a point where paying for their tiered program and support is 110% worth it. We're in a preliminary testing phase right now and the extra cost is not something we want to spend right at this moment.

1. Is there a guide that someone can point me towards to configure fusionpbx for multi-tenancy in a full and complete informational? EG: individual provisioning urls, firmware urls etc.


I believe this covers my issue as a whole at this very moment and if anyone has any tips I should try to resolve my issue, please let me know.

Thank you very much!
 
Last edited:

zanderb

New Member
Sep 18, 2020
2
0
1
35
Well.... It looks like we resolved the unregistered line issue by completely restarting the vmware server.

I did notice the following on the event log via fusionpbx:

Code:
2020-09-24 16:57:37.444040 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'internal' for [8046@serverip] from ip 62.4.14.123
2020-09-24 16:57:37.544022 [WARNING] sofia_reg.c:2930 Can't find user [8046@serverip] from 62.4.14.123
You must define a domain called 'serverip' in your directory and add a user with the id="8046" attribute
and you must configure your device to use the proper domain in its authentication credentials.
2020-09-24 16:57:37.544022 [WARNING] sofia_reg.c:1739 SIP auth failure (REGISTER) on sofia profile 'internal' for [8046@serverip] from ip 62.4.14.123

This is NOT from our device and we aren't sure what this could be. A bot maybe?

Part Three is still a relevant question

I also forgot to add this section as well:

Part Four - Section One - 10 digit calling
I'd like to set the system up where I only have to call (212)222-2222 vs 1(212)222-2222

I have the outbound route dialplan expression set to "11 digit long distance" because that's all that works.
When trying to set it to "10 digits long distance" calls fail going outbound.

Thank you
 
Status
Not open for further replies.