Upgrade 7.10.19 LTS to 7.10.20 LTS failed

MaNec · 9 September 2019 12:13

Hi!

I did an upgrade from 7.10.19 LTS to 7.10.20 LTS via Upgrade Wizzard which failed.

In the upgradeWizard.log I found:

Mon, 09 Sep 2019 10:20:25 +0200 [UpgradeWizard] - Rebuilding language cache
Mon, 09 Sep 2019 10:30:45 +0200 [UpgradeWizard] - setting session variables...

10:20 chrashed the wizzard - maybe during rebuilding language cache. I restarted the wizzard 10:30 and it seemed to continue the upgrade but after the upgrade I only get an empty page for login.

In the suitecrm.log I found:

Mon Sep  9 10:20:25 2019 [1740][e11cece6-aecf-9c54-1319-5a7496f6515b][FATAL] Exception handling in /var/www/html/crm/include/MVC/Controller/SugarController.php:400
Mon Sep  9 10:20:25 2019 [1740][e11cece6-aecf-9c54-1319-5a7496f6515b][FATAL] Exception in Controller: Some garbage state left in stack (did you pop everything?)
Needs to restore:
        [error_reporting.sugar_smarty_errors] => 'sugar_smarty_errors'

Mon Sep  9 10:20:25 2019 [1740][e11cece6-aecf-9c54-1319-5a7496f6515b][FATAL] backtrace:
#0 /var/www/html/crm/include/Sugar_Smarty.php(167): SuiteCRM\StateSaver->__destruct()
#1 /var/www/html/crm/include/Smarty/Smarty.class.php(1115): Sugar_Smarty->fetch('modules/Upgrade...', NULL, NULL, true)
#2 /var/www/html/crm/modules/UpgradeWizard/index.php(539): Smarty->display('modules/Upgrade...')
#3 /var/www/html/crm/include/MVC/View/SugarView.php(818): include_once('/var/www/html/c...')
#4 /var/www/html/crm/include/MVC/View/views/view.classic.php(72): SugarView->includeClassicFile('/var/www/html/c...')
#5 /var/www/html/crm/include/MVC/View/SugarView.php(211): ViewClassic->display()
#6 /var/www/html/crm/include/MVC/Controller/SugarController.php(435): SugarView->process()
#7 /var/www/html/crm/include/MVC/Controller/SugarController.php(375): SugarController->processView()
#8 /var/www/html/crm/include/MVC/SugarApplication.php(113): SugarController->execute()
#9 /var/www/html/crm/index.php(52): SugarApplication->execute()
#10 {main}
Mon Sep  9 10:20:25 2019 [1740][e11cece6-aecf-9c54-1319-5a7496f6515b][FATAL] Previous:

If I try to login “/crm/index.php?action=Login&module=Users”
and get in the apache error log:

[Mon Sep 09 14:03:39.925940 2019] [:error] [pid 815] [client 192.168.1.5:63948] PHP Warning:  include(/var/www/html/crm/vendor/composer/../../lib/SuiteCRM/Utility/SuiteLogger.php): failed to open stream: No such file or directory in /var/www/html/crm/vendor/composer/ClassLoader.php on line 444
[Mon Sep 09 14:03:39.926010 2019] [:error] [pid 815] [client 192.168.1.5:63948] PHP Warning:  include(): Failed opening '/var/www/html/crm/vendor/composer/../../lib/SuiteCRM/Utility/SuiteLogger.php' for inclusion (include_path='/var/www/html/crm:/var/www/html/crm/include/..:.:/usr/share/php') in /var/www/html/crm/vendor/composer/ClassLoader.php on line 444
[Mon Sep 09 14:03:39.926142 2019] [:error] [pid 815] [client 192.168.1.5:63948] PHP Fatal error:  Uncaught Error: Class 'SuiteCRM\\Utility\\SuiteLogger' not found in /var/www/html/crm/include/utils/recaptcha_utils.php:206\nStack trace:\n#0 /var/www/html/crm/modules/Users/Login.php(163): displayRecaptcha()\n#1 /var/www/html/crm/include/MVC/View/SugarView.php(818): include_once('/var/www/html/c...')\n#2 /var/www/html/crm/include/MVC/View/views/view.classic.php(72): SugarView->includeClassicFile('modules/Users/L...')\n#3 /var/www/html/crm/include/MVC/View/SugarView.php(211): ViewClassic->display()\n#4 /var/www/html/crm/include/MVC/Controller/SugarController.php(435): SugarView->process()\n#5 /var/www/html/crm/include/MVC/Controller/SugarController.php(375): SugarController->processView()\n#6 /var/www/html/crm/include/MVC/SugarApplication.php(113): SugarController->execute()\n#7 /var/www/html/crm/index.php(52): SugarApplication->execute()\n#8 {main}\n  thrown in /var/www/html/crm/include/utils/recaptcha_utils.php on line 206

How can I fix this the easy way?

Thanks for your help.
Mandre

pgr · 9 September 2019 15:56

Maybe the same as this

https://github.com/salesagility/SuiteCRM/issues/7820

When upgrading, did you check the warning in the release notes about Smarty customizations (especially if you have a custom theme)?

https://docs.suitecrm.com/admin/releases/7.10.x/

MaNec · 10 September 2019 08:24

Hi, thanks for your answer.

Yes I read the warning and ignored it because I’m not aware of any customization in our system. So I don’t think it applies here.

Mandre

pgr · 10 September 2019 08:32

Ok.

Please check your resources allocation in php.ini, maybe increase some of these values:

memory_limit
max_execution_time
max_post_size
max_upload_size

maybe your upgrade is exceeding one of these. Remember to restart web server.

Dillon-Brown · 10 September 2019 10:36

Hi MacNec,

this looks like an issue with composer, I would suggest running the following commands in the root SuiteCRM directory:

composer clearcache
composer dump-autoload
composer install

Let me know if you run into any issues.

abuelodelanada · 11 September 2019 20:03

Hi Dillon,

I runned those command, and nothing changed

This is happening to me with 7.10.20. It’s strange, because this happens only in develop environment… in my laptop and in testing environment does not happens.

The only big difference between the 3 environments (my laptop, develop, and testing) is:

My laptop: Webserver: php -S (php 7.3.9)
develop: Webserver: Apache 2.4.38 - mod_php - php 7.3.4
develop: Webserver: Apache 2.4.38 - PHP-FPM - php 7.3.4

I will install PHP-FPM in develop environment and test again

MaNec · 12 September 2019 13:28

memory_limit = 256M
max_execution_time = 60

The other two

max_post_size
max_upload_size

are not set in php.ini

But I found instead - maybe you mean these?

post_max_size = 30M
upload_max_filesize = 30M

I increase now

max_execution_time to 120
post_max_size to 120M
upload_max_filesize to 120M

and hope the next upgrade will run successfuly.
Mandre

MaNec · 12 September 2019 13:44

Hi!

I did not try the composer update but fixed my problem the following way.

As the apache error log showed was /var/www/html/crm/vendor/composer/…/…/lib/SuiteCRM/Utility/SuiteLogger.php missing.
I found the missing file in the upload/upgrades/patch/SuiteCRM-Upgrade-7.10.x-to-7.10.20.zip.
I found more missing files so I copied the whole SuiteCRM-Upgrade-7.10.x-to-7.10.20.zip into my crm directory.
I changed the owner and permissions the same way as during installation.
Now I was able again to login.
I did run a Admin -> Quick repair which fixed a small issue with the database.

Now the CRM seems up and running again. The next days will show if I missed something. Otherwise I hope the next upgrade to .21 will fix everything under the hood I did not see.

Mandre

abuelodelanada · 13 September 2019 20:16

Hi,

None of the alternatives mentioned worked to solve the problem.
In my laptop my instance was working OK, until a DB error appears. After that, seems that this error was ‘cached’ and no matter what you do, the following appears in the log.


Fri Sep 13 17:09:24 2019 [26554][5631d153-b056-1e59-180d-5d5c458a91c4][FATAL] Error filling in additional detail fields: Query Failed: SELECT name , assigned_user_id parent_name_owner from contacts where id = '': MySQL error 1054: Unknown column 'name' in 'field list'                                                 
Fri Sep 13 17:09:24 2019 [26554][5631d153-b056-1e59-180d-5d5c458a91c4][FATAL] Exception handling in /home/jose/trabajos/gca-suitecrm7.10x/include/MVC/Controller/SugarController.php:400
Fri Sep 13 17:09:24 2019 [26554][5631d153-b056-1e59-180d-5d5c458a91c4][FATAL] Exception in Controller: Some garbage state left in stack (did you pop everything?) Needs to restore: [error_reporting.sugar_smarty_errors] => 'sugar_smarty_errors'                                                                           
Fri Sep 13 17:09:24 2019 [26554][5631d153-b056-1e59-180d-5d5c458a91c4][FATAL] backtrace: #0 /home/jose/trabajos/gca-suitecrm7.10x/include/Sugar_Smarty.php(164): SuiteCRM\StateSaver->__destruct() #1 /home/jose/trabajos/gca-suitecrm7.10x/vendor/smarty/smarty/libs/Smarty.class.php(1113): Sugar_Smarty->fetch('themes/BCCL/tpl...', NULL, NULL, true) #2 /home/jose/trabajos/gca-suitecrm7.10x/custom/include/MVC/View/SugarView.php(683): Smarty->display('themes/BCCL/tpl...') #3 /home/jose/trabajos/gca-suitecrm7.10x/custom/include/MVC/View/SugarView.php(100): CustomSugarView->displayHeader() #4 /home/jose/trabajos/gca-suitecrm7.10x/include/MVC/Controller/SugarController.php(435): CustomSugarView->process() #5 /home/jose/trabajos/gca-suitecrm7.10x/include/MVC/Controller/SugarController.php(375): SugarController->processView() #6 /home/jose/trabajos/gca-suitecrm7.10x/include/MVC/SugarApplication.php(113): SugarController->execute() #7 /home/jose/trabajos/gca-suitecrm7.10x/index.php(80): SugarApplication->execute() #8 {main}
Fri Sep 13 17:09:24 2019 [26554][5631d153-b056-1e59-180d-5d5c458a91c4][FATAL] Previous:                                                                       
Fri Sep 13 17:09:24 2019 [26554][5631d153-b056-1e59-180d-5d5c458a91c4][FATAL] Exception in Controller: Error de base de datos. Por favor, consulte SuiteCRM error .log para más detalles.
Fri Sep 13 17:09:24 2019 [26554][5631d153-b056-1e59-180d-5d5c458a91c4][FATAL] backtrace: #0 /home/jose/trabajos/gca-suitecrm7.10x/include/database/DBManager.php(353): sugar_die('Error de base d...') #1 /home/jose/trabajos/gca-suitecrm7.10x/include/database/DBManager.php(328): DBManager->registerError(' Error filling
...', ' Error filling ...', true) #2 /home/jose/trabajos/gca-suitecrm7.10x/custom/include/database/MysqliManagerGcoop.php(41): DBManager->checkError(' Error filling ...', true, false) #3 /home/jose/trabajos/gca-suitecrm7.10x/modules/Tasks/Task.php(218): MysqliManagerGcoop->query('SELECT name , ...', true, ' Error filling ...') #4 /home/jose/trabajos/gca-suitecrm7.10x/data/SugarBean.php(4838): Task->fill_in_additional_parent_fields() #5 /home/jose/trabajos/gca-suitecrm7.10x/modules/Tasks/Task.php(167): SugarBean->fill_in_additional_detail_fields() #6 /home/jose/trabajos/gca-suitecrm7.10x/data/SugarBean.php(4565): Task->fill_in_additional_detail_fields() #7 /home/jose/trabajos/gca-suitecrm7.10x/data/BeanFactory.php(104): SugarBean->retrieve('50625416-4808-8...', true, true) #8 /home/jose/trabajos/gca-suitecrm7.10x/include/Smarty/plugins/function.suite_check_access.php(35): BeanFactory::getBean('Tasks', '50625416-4808-8...') #9 /home/jose/trabajos/gca-suitecrm7.10x/cache/smarty/templates_c/%%30^30B^30B88B75%%_headerModuleList.tpl.php(131): smarty_function_suite_check_access(Array, Object(Sugar_Smarty)) #10 /home/jose/trabajos/gca-suitecrm7.10x/vendor/smarty/smarty/libs/Smarty.class.php(1876): include('/home/jose/trab...') #11 /home/jose/trabajos/gca-suitecrm7.10x/cache/smarty/templates_c/%%B7^B79^B79695C8%%header.tpl.php(13): Smarty->_smarty_include(Array) #12 /home/jose/trabajos/gca-suitecrm7.10x/vendor/smarty/smarty/libs/Smarty.class.php(1263): include('/home/jose/trab...') #13 /home/jose/trabajos/gca-suitecrm7.10x/include/Sugar_Smarty.php(164): Smarty->fetch('themes/BCCL/tpl...', NULL, NULL, true) #14 /home/jose/trabajos/gca-suitecrm7.10x/vendor/smarty/smarty/libs/Smarty.class.php(1113): Sugar_Smarty->fetch('themes/BCCL/tpl...', NULL, NULL, true) #15 /home/jose/trabajos/gca-suitecrm7.10x/custom/include/MVC/View/SugarView.php(683): Smarty->display('themes/BCCL/tpl...') #16 /home/jose/trabajos/gca-suitecrm7.10x/custom/include/MVC/View/SugarView.php(100): CustomSugarView->displayHeader() #17 /home/jose/trabajos/gca-suitecrm7.10x/include/MVC/Controller/SugarController.php(435): CustomSugarView->process() #18 /home/jose/trabajos/gca-suitecrm7.10x/include/MVC/Controller/SugarController.php(375): SugarController->processView() #19 /home/jose/trabajos/gca-suitecrm7.10x/include/MVC/SugarApplication.php(113): SugarController->execute() #20 /home/jose/trabajos/gca-suitecrm7.10x/index.php(80): SugarApplication->execute() #21 {main}

I could not discover the error yet.