Activate after restore takes no action

I recently had to restore a backup and now the ACTIVATE does not appear to work.

The activate button appears to cause some action to take place, but simply repaints the same screen along with the FREE OFFER. One thing I did notice is that the amount of time before the refresh is about 5 sec. and I know there are some timeouts imposed here. I was able to ping externally with low latency.

The server is hosted for me and was completely wiped / rebuilt and I then applied a restore from a previous backup. Since the activation was not working, I did perform an unlock in the SANGOMA Portal deployment, which completed fine.

This means you have a DNS or network issue and it can not reach our servers.

I am able to resolve names including katanafpbx.schmoozecom.com which i found in a previous post on this topic.

The DNS appears to be working and is configured properly:

I ran “amportal a dbug” and it returned the following:

[[email protected] ~]# amportal a dbug

Fetching FreePBX settings with gen_amp_conf.php…

trying to run as user asterisk:

[FPBXDBUGDISABLE] changed from previous value: [1] to new value: [0]

==> /var/log/asterisk/freepbx_dbug <==

2017-Jan-30 20:17:02 /var/www/html/admin/modules/weakpasswords/functions.inc.php:0
[NOTICE]: Undefined variable: text

2017-Jan-30 20:20:02 /var/www/html/admin/modules/qxact_reports/functions.inc/import_queue_data.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:02 /var/www/html/admin/modules/qxact_reports/functions.inc/import_queue_data.php:0
[WARNING]: License check failed!

==> /var/log/httpd/error_log <==
[Mon Jan 30 16:58:55 2017] [notice] Digest: done
[Mon Jan 30 16:58:56 2017] [notice] Apache/2.2.15 (Unix) DAV/2 PHP/5.3.28 configured – resuming normal operations
[Mon Jan 30 18:16:36 2017] [error] [client x.x.x.x] File does not exist: /var/www/html/favicon.ico
Unable to open /dev/dahdi/ctl: No such file or directory
Unable to open /dev/dahdi/ctl: No such file or directory
Unable to open /dev/dahdi/ctl: No such file or directory
Unable to open /dev/dahdi/ctl: No such file or directory
[Mon Jan 30 20:06:48 2017] [error] [client x.x.x.x] File does not exist: /var/www/html/admin/modules/sysadmin/assets/js/timezone.js, referer: http://x.x.x.x/admin/config.php?display=sysadmin&view=timezone
[Mon Jan 30 20:06:49 2017] [error] [client x.x.x.x] File does not exist: /var/www/html/admin/modules/sysadmin/assets/js/timezone.js, referer: http://x.x.x.x/admin/config.php?display=sysadmin&view=timezone
/bin/cat: /proc/mdstat: No such file or directory

==> /var/log/asterisk/freepbx.log <==
[2017-Jan-30 20:10:31] [ERROR] (BMO/Notifications.class.php:493) - [NOTIFICATION]-[freepbx]-[modules_disabled] - You have a disabled module (The following modules are disabled because they need to be upgraded:
digiumaddoninstaller

You should go to the module admin page to fix these.)
[2017-Jan-30 20:17:02] [WARNING] (libraries/modulefunctions.legacy.php:7) - Depreciated Function module_getinfo detected in /var/www/html/admin/modules/cxpanel/functions.inc.php on line 52
[2017-Jan-30 20:17:02] [WARNING] (libraries/modulefunctions.legacy.php:7) - Depreciated Function module_getinfo detected in /var/www/html/admin/modules/cxpanel/functions.inc.php on line 61
[2017-Jan-30 20:20:27] [INFO] (bin/freepbx_setting:42) - trying to run as user asterisk:
[2017-Jan-30 20:20:27] [INFO] (bin/freepbx_setting:43) -
[2017-Jan-30 20:20:27] [INFO] (bin/freepbx_setting:79) - [FPBXDBUGDISABLE] changed from previous value: [1] to new value: [0]
[2017-Jan-30 20:20:27] [INFO] (bin/freepbx_setting:80) -

==> /var/log/asterisk/freepbx_dbug <==
2017-Jan-30 20:20:35 /var/www/html/admin/modules/conferencespro/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:35 /var/www/html/admin/modules/conferencespro/functions.inc.php:0
[WARNING]: License check failed!

==> /var/log/asterisk/freepbx.log <==
[2017-Jan-30 20:20:35] [WARNING] (libraries/modulefunctions.legacy.php:7) - Depreciated Function module_getinfo detected in /var/www/html/admin/modules/cxpanel/functions.inc.php on line 52
[2017-Jan-30 20:20:35] [WARNING] (libraries/modulefunctions.legacy.php:7) - Depreciated Function module_getinfo detected in /var/www/html/admin/modules/cxpanel/functions.inc.php on line 61

==> /var/log/asterisk/freepbx_dbug <==
2017-Jan-30 20:20:35 /var/www/html/admin/modules/faxpro/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:35 /var/www/html/admin/modules/faxpro/functions.inc.php:0
[WARNING]: License check failed!

2017-Jan-30 20:20:35 /var/www/html/admin/modules/pinsetspro/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:35 /var/www/html/admin/modules/pinsetspro/functions.inc.php:0
[WARNING]: License check failed!

2017-Jan-30 20:20:35 /var/www/html/admin/modules/recording_report/functions.inc/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:35 /var/www/html/admin/modules/recording_report/functions.inc/functions.inc.php:0
[WARNING]: License check failed!

==> /var/log/asterisk/freepbx.log <==
[2017-Jan-30 20:20:35] [ERROR] (BMO/Notifications.class.php:493) - [NOTIFICATION]-[freepbx]-[modules_disabled] - You have a disabled module (The following modules are disabled because they need to be upgraded:
digiumaddoninstaller

You should go to the module admin page to fix these.)

==> /var/log/asterisk/freepbx_dbug <==
2017-Jan-30 20:20:35 /var/www/html/admin/libraries/BMO/PKCS.class.php:0
[WARNING]: in_array() expects parameter 2 to be array, boolean given

2017-Jan-30 20:20:36 /var/www/html/admin/libraries/BMO/Installer.class.php:0
[WARNING]: Invalid argument supplied for foreach()

2017-Jan-30 20:20:36 /var/www/html/admin/modules/sysadmin/page.sysadmin.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:36 /var/www/html/admin/modules/sysadmin/page.sysadmin.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:36 /var/www/html/admin/modules/sysadmin/views/rnav.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:36 /var/www/html/admin/modules/sysadmin/views/rnav.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:36 /var/www/html/admin/modules/sysadmin/views/view.activation.unlicenced.php:0
[WARNING]: Invalid argument supplied for foreach()

2017-Jan-30 20:20:36 /var/www/html/admin/views/footer_content.php:0
[NOTICE]: Undefined index: deploymentid

2017-Jan-30 20:20:36 /var/www/html/admin/views/footer.php:0
[NOTICE]: Undefined offset: 0

2017-Jan-30 20:20:36 /var/www/html/admin/views/footer.php:0
[NOTICE]: Undefined variable: mod_version_tag

2017-Jan-30 20:20:44 /var/www/html/admin/modules/conferencespro/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:44 /var/www/html/admin/modules/conferencespro/functions.inc.php:0
[WARNING]: License check failed!

==> /var/log/asterisk/freepbx.log <==
[2017-Jan-30 20:20:44] [WARNING] (libraries/modulefunctions.legacy.php:7) - Depreciated Function module_getinfo detected in /var/www/html/admin/modules/cxpanel/functions.inc.php on line 52
[2017-Jan-30 20:20:44] [WARNING] (libraries/modulefunctions.legacy.php:7) - Depreciated Function module_getinfo detected in /var/www/html/admin/modules/cxpanel/functions.inc.php on line 61

==> /var/log/asterisk/freepbx_dbug <==
2017-Jan-30 20:20:44 /var/www/html/admin/modules/faxpro/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:44 /var/www/html/admin/modules/faxpro/functions.inc.php:0
[WARNING]: License check failed!

2017-Jan-30 20:20:44 /var/www/html/admin/modules/pinsetspro/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:44 /var/www/html/admin/modules/pinsetspro/functions.inc.php:0
[WARNING]: License check failed!

2017-Jan-30 20:20:44 /var/www/html/admin/modules/recording_report/functions.inc/functions.inc.php:0
[WARNING]: No license for this product (PBXact) - make sure zend_loader.license_path is properly configured in your ini file!

2017-Jan-30 20:20:44 /var/www/html/admin/modules/recording_report/functions.inc/functions.inc.php:0
[WARNING]: License check failed!

==> /var/log/asterisk/freepbx.log <==
[2017-Jan-30 20:20:44] [ERROR] (BMO/Notifications.class.php:493) - [NOTIFICATION]-[freepbx]-[modules_disabled] - You have a disabled module (The following modules are disabled because they need to be upgraded:
digiumaddoninstaller

You should go to the module admin page to fix these.)

==> /var/log/asterisk/freepbx_dbug <==
2017-Jan-30 20:20:44 /var/www/html/admin/libraries/BMO/PKCS.class.php:0
[WARNING]: in_array() expects parameter 2 to be array, boolean given

2017-Jan-30 20:20:59 /var/www/html/admin/libraries/BMO/Installer.class.php:0
[WARNING]: Invalid argument supplied for foreach()

2017-Jan-30 20:20:59 /var/www/html/admin/modules/sysadmin/page.sysadmin.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:59 /var/www/html/admin/modules/sysadmin/page.sysadmin.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:59 /var/www/html/admin/modules/sysadmin/views/rnav.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:59 /var/www/html/admin/modules/sysadmin/views/rnav.php:0
[NOTICE]: Undefined index: deploy_type

2017-Jan-30 20:20:59 /var/www/html/admin/modules/sysadmin/views/view.activation.unlicenced.php:0
[WARNING]: Invalid argument supplied for foreach()

2017-Jan-30 20:20:59 /var/www/html/admin/views/footer_content.php:0
[NOTICE]: Undefined index: deploymentid

2017-Jan-30 20:20:59 /var/www/html/admin/views/footer.php:0
[NOTICE]: Undefined offset: 0

2017-Jan-30 20:20:59 /var/www/html/admin/views/footer.php:0
[NOTICE]: Undefined variable: mod_version_tag

^Z
[1]+ Stopped amportal a dbug
[[email protected] ~]#

It doesn’t just need to resolve, it needs to have a complete connection to it.

The most common failure is a firewall in the way trying to do SSL packet inspection. Make sure the device has unfiltered access to the internet.

The server is in the Vitelity data center and nothing from a networking perspective has changed. After attempting to upgrade FreePBX something cased the process throw many errors and forced me to restore the server from a backup. I will ask the question, but I have had hosted several other FreePBX installs there and not had this issue in the past and dont expect this is the cause.

thanks.

Hello,

I would try to reinstall the sysadmin module:

yum -y reinstall sysadmin

and try activate the machine again.

Thank you,

I confirmed the with Vitelity they are not performing any “filtering” and have also reinstalled the SYSADMIN module which did not resolve the issue.

I noticed a post that mentioned adding the host to the system host file, can you confirm the entry and do you feel this is worth the effort?

Hello,

what about the zend optimizer?

yum -y install php-5.3-zend-guard-loader

Thank you,

Daniel Friedman
Trixton LTD.

Thanks for the suggestion, but I had tried that as well.

Is there a definitive way to debug the transaction.

Hello,

Try the apache logs in a separate window:

tail -f /var/log/httpd/error.log

Thank you,

Daniel Friedman
Trixton LTD

^^^ This is most likely your problem. You may be half at 13 half at 12. Can I suggest you upgrade to FreePBX 13, and then re-try the activation? (Also, there’s much better debugging about activation issues in 13, that weren’t possible in 12)

No, as i mentioned earlier this server was wiped and rebuilt and then I restored a backup from the same release/build (6.12.65-20). All the module updates have been applied without error, so this is essentially a new install. My mistake was to not activate prior to the restore, but that should not cause this issue since the system was active when the backup was taken.

Daniel - this is what I get from the ACCESS_LOG, ERROR_LOG returned nothing

Firefox
x.x.x.x - - [31/Jan/2017:14:34:34 -0500] “GET /admin/ajax.php?command=restartoobe&module=sysadmin HTTP/1.1” 200 51 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0) Gecko/20100101 Firefox/51.0"
x.x.x.x - - [31/Jan/2017:14:34:34 -0500] “POST /admin/config.php?display=sysadmin&view=activation HTTP/1.1” 200 30320 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0) Gecko/20100101 Firefox/51.0"
x.x.x.x - - [31/Jan/2017:14:34:51 -0500] “GET /admin/config.php?handler=file&module=sysadmin&file=sysadmin.js HTTP/1.1” 200 213 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0) Gecko/20100101 Firefox/51.0"
x.x.x.x - - [31/Jan/2017:14:34:51 -0500] “GET /admin/config.php?handler=file&module=sysadmin&file=sysadmin.css&load_version=12.0.45.6 HTTP/1.1” 200 216 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0) Gecko/20100101 Firefox/51.0"
x.x.x.x - - [31/Jan/2017:14:34:51 -0500] “GET /admin/assets/less/cache/lessphp_c24ae63926c839d4e1377491f1b15c86b04eed7c.css HTTP/1.1” 200 143547 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0) Gecko/20100101 Firefox/51.0"
x.x.x.x - - [31/Jan/2017:14:34:52 -0500] “GET /admin/assets/less/buttons/cache/lessphp_1629a9513bbd2940cffefcfd76c0d15bb2da9b2a.css HTTP/1.1” 200 7229 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0) Gecko/20100101 Firefox/51.0"
x.x.x.x - - [31/Jan/2017:14:34:52 -0500] “GET /admin/ajax.php?command=getoffers&module=sysadmin&depid=false HTTP/1.1” 200 106 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” “Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0) Gecko/20100101 Firefox/51.0”

Chrome
x.x.x.x - - [31/Jan/2017:14:38:11 -0500] “GET /admin/ajax.php?command=restartoobe&module=sysadmin HTTP/1.1” 200 51 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36"
x.x.x.x - - [31/Jan/2017:14:38:12 -0500] “POST /admin/config.php?display=sysadmin&view=activation HTTP/1.1” 200 30323 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36"
x.x.x.x - - [31/Jan/2017:14:38:28 -0500] “GET /admin/config.php?handler=file&module=sysadmin&file=sysadmin.css&load_version=12.0.45.6 HTTP/1.1” 200 216 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36"
x.x.x.x - - [31/Jan/2017:14:38:28 -0500] “GET /admin/config.php?handler=file&module=sysadmin&file=sysadmin.js HTTP/1.1” 200 213 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36"
x.x.x.x - - [31/Jan/2017:14:38:28 -0500] “GET /admin/assets/less/cache/lessphp_c24ae63926c839d4e1377491f1b15c86b04eed7c.css HTTP/1.1” 200 143547 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36"
x.x.x.x - - [31/Jan/2017:14:38:29 -0500] “GET /admin/assets/less/buttons/cache/lessphp_1629a9513bbd2940cffefcfd76c0d15bb2da9b2a.css HTTP/1.1” 200 7229 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36"
x.x.x.x - - [31/Jan/2017:14:38:29 -0500] “GET /admin/ajax.php?command=getoffers&module=sysadmin&depid=false HTTP/1.1” 200 106 “http://y.y.y.y/admin/config.php?display=sysadmin&view=activation” “Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36”

I think you missed the second part:

Can I suggest you upgrade to FreePBX 13, and then re-try the activation? (Also, there’s much better debugging about activation issues in 13, that weren’t possible in 12)

Yes - Agree. I am planning to do this, but its just an issue of timing. I spent two days getting this system back up and supporting calls after the failure. I need to coordinate with customers - I have done the update/upgrade several times and just dont have time if things go south during the upgrade.

Really just trying to get this activated so i can re-apply admin tools, email and commercial modules. Just really frustrating when things don’t work when there isn’t an apparent reason.

If you have any other thoughts, please let me know - I appreciate all the input and experience.

Hello,

Where is the error log?

Thank you,

Daniel Friedman
Trixton LTD.

The file is actually “error_log” not “error.log” and when tested it generated no results.

Can someone confirm that “katanafpbx.schmoozecom.com” is the site activation is attempting to reachout to?

Well i have been able to now activate the deployment.

After several conversation updates with Vitelity, it appears they “updated some modules”. Now not only can i activate the deployment, but also see outstanding updates. They said since i didnt pay for the professional services, they wont tell me what they did.

I find it curious they were able to update anything after I completed several direct “yum” command-line updates as well as updates through module admin. Hmmmm.

At this point I don’t really care as long as calls are being routed, vmail notifications are going out and the system appears to be stable.

Need to get the upgrade completed in a few weeks.

Thanks all for your input.