Unable to add the 27th product in quotes (customized)

2di7 · 29 November 2019 15:13

Hi everybody,
some time ago I customized quote module to add more product discount levels (line_items).

However, Only now we realized that, by entering more than 26 products, the twenty-seventh is not saved.

I don’t get console errors but I can attach what I find in the log file, where I actually see several fatal errors.
Here is an excerpt. I hope someone can help me understand.
Thank you.


#0 /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php(103): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->reIndex(Object(AOS_Products_Quotes))
#1 /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php(76): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->reIndexSafe(Object(AOS_Products_Quotes))
#2 /var/www/html/suitecrmtest/include/utils/LogicHook.php(272): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->beanSaved(Object(AOS_Products_Quotes), 'after_save', '')
#3 /var/www/html/suitecrmtest/include/utils/LogicHook.php(213): LogicHook->process_hooks(Array, 'after_save', '')
#4 /var/www/html/suitecrmtest/data/SugarBean.php(3129): LogicHook->call_custom_logic('AOS_Products_Qu...', 'after_save', '')
#5 /var/www/html/suitecrmtest/data/SugarBean.php(2442): SugarBean->call_custom_logic('after_save', '')
#6 /var/www/html/suitecrmtest/modules/AOS_Products_Quotes/AOS_Products_Quotes.php(129): SugarBean->save(false)
#7 /var/www/html/suitecrmtest/modules/AOS_Products_Quotes/AOS_Products_Quotes.php(118): AOS_Products_Quotes->save()
#8 /var/www/html/suitecrmtest/modules/AOS_Line_Item_Groups/AOS_Line_Item_Groups.php(112): AOS_Products_Quotes->save_lines(Array, Object(AOS_Quotes), Array, 'product_')
#9 /var/www/html/suitecrmtest/modules/AOS_Quotes/AOS_Quotes.php(83): AOS_Line_Item_Groups->save_groups(Array, Object(AOS_Quotes), 'group_')
#10 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(684): AOS_Quotes->save(false)
#11 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(525): SugarController->action_save()
#12 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(494): SugarController->do_action()
#13 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(468): SugarController->handle_action()
#14 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(373): SugarController->process()
#15 /var/www/html/suitecrmtest/include/MVC/SugarApplication.php(113): SugarController->execute()
#16 /var/www/html/suitecrmtest/index.php(52): SugarApplication->execute()
#17 {main}
Fri Nov 29 15:58:10 2019 [10673][1][FATAL] [ERROR] Failed to index bean to index
Fri Nov 29 15:58:10 2019 [10673][1][FATAL] [ERROR] SuiteCRM\Search\Exceptions\SearchException: Elasticsearch trying to re-indexing a bean but indexer is disabled in configuration. in /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php:121
Stack trace:
#0 /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php(103): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->reIndex(Object(AOS_Products_Quotes))
#1 /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php(76): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->reIndexSafe(Object(AOS_Products_Quotes))
#2 /var/www/html/suitecrmtest/include/utils/LogicHook.php(272): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->beanSaved(Object(AOS_Products_Quotes), 'after_save', '')
#3 /var/www/html/suitecrmtest/include/utils/LogicHook.php(213): LogicHook->process_hooks(Array, 'after_save', '')
#4 /var/www/html/suitecrmtest/data/SugarBean.php(3129): LogicHook->call_custom_logic('AOS_Products_Qu...', 'after_save', '')
#5 /var/www/html/suitecrmtest/data/SugarBean.php(2442): SugarBean->call_custom_logic('after_save', '')
#6 /var/www/html/suitecrmtest/modules/AOS_Products_Quotes/AOS_Products_Quotes.php(129): SugarBean->save(false)
#7 /var/www/html/suitecrmtest/modules/AOS_Products_Quotes/AOS_Products_Quotes.php(118): AOS_Products_Quotes->save()
#8 /var/www/html/suitecrmtest/modules/AOS_Line_Item_Groups/AOS_Line_Item_Groups.php(112): AOS_Products_Quotes->save_lines(Array, Object(AOS_Quotes), Array, 'product_')
#9 /var/www/html/suitecrmtest/modules/AOS_Quotes/AOS_Quotes.php(83): AOS_Line_Item_Groups->save_groups(Array, Object(AOS_Quotes), 'group_')
#10 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(684): AOS_Quotes->save(false)
#11 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(525): SugarController->action_save()
#12 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(494): SugarController->do_action()
#13 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(468): SugarController->handle_action()
#14 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(373): SugarController->process()
#15 /var/www/html/suitecrmtest/include/MVC/SugarApplication.php(113): SugarController->execute()
#16 /var/www/html/suitecrmtest/index.php(52): SugarApplication->execute()
#17 {main}
Fri Nov 29 15:58:10 2019 [10673][1][FATAL] [ERROR] Failed to index bean to index
Fri Nov 29 15:58:10 2019 [10673][1][FATAL] [ERROR] SuiteCRM\Search\Exceptions\SearchException: Elasticsearch trying to re-indexing a bean but indexer is disabled in configuration. in /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php:121
Stack trace:
#0 /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php(103): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->reIndex(Object(AOS_Products_Quotes))
#1 /var/www/html/suitecrmtest/lib/Search/ElasticSearch/ElasticSearchHooks.php(76): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->reIndexSafe(Object(AOS_Products_Quotes))
#2 /var/www/html/suitecrmtest/include/utils/LogicHook.php(272): SuiteCRM\Search\ElasticSearch\ElasticSearchHooks->beanSaved(Object(AOS_Products_Quotes), 'after_save', '')
#3 /var/www/html/suitecrmtest/include/utils/LogicHook.php(213): LogicHook->process_hooks(Array, 'after_save', '')
#4 /var/www/html/suitecrmtest/data/SugarBean.php(3129): LogicHook->call_custom_logic('AOS_Products_Qu...', 'after_save', '')
#5 /var/www/html/suitecrmtest/data/SugarBean.php(2442): SugarBean->call_custom_logic('after_save', '')
#6 /var/www/html/suitecrmtest/modules/AOS_Products_Quotes/AOS_Products_Quotes.php(129): SugarBean->save(false)
#7 /var/www/html/suitecrmtest/modules/AOS_Products_Quotes/AOS_Products_Quotes.php(118): AOS_Products_Quotes->save()
#8 /var/www/html/suitecrmtest/modules/AOS_Line_Item_Groups/AOS_Line_Item_Groups.php(112): AOS_Products_Quotes->save_lines(Array, Object(AOS_Quotes), Array, 'product_')
#9 /var/www/html/suitecrmtest/modules/AOS_Quotes/AOS_Quotes.php(83): AOS_Line_Item_Groups->save_groups(Array, Object(AOS_Quotes), 'group_')
#10 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(684): AOS_Quotes->save(false)
#11 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(525): SugarController->action_save()
#12 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(494): SugarController->do_action()
#13 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(468): SugarController->handle_action()
#14 /var/www/html/suitecrmtest/include/MVC/Controller/SugarController.php(373): SugarController->process()
#15 /var/www/html/suitecrmtest/include/MVC/SugarApplication.php(113): SugarController->execute()
#16 /var/www/html/suitecrmtest/index.php(52): SugarApplication->execute()

pgr · 29 November 2019 16:00

Please remember to always state your SuiteCRM version, it makes things so much easier for me…

These indexing errors have been fixed in the latest versions.

https://github.com/salesagility/SuiteCRM/pull/7020/files
and this
https://github.com/salesagility/SuiteCRM/pull/7921/files

I’m not sure if those are related to the Line Items problem, but at least they will clean up your logs and make it easier to look for the actual problem.

2di7 · 2 December 2019 11:04

Hi pgr,
I corrected this error.

Here is the cleaned log but I still need help to interpret it.
Thanks so much.


Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] current_language is: en_us
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheAPC
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheFile
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheMemcache
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheMemcached
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheMemory
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Using cache backend SugarCacheMemory, since 999 is less than 1000
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheRedis
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheWincache
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCacheZend
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Found cache backend SugarCachesMash
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Found language file: en_us.lang.php
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Found extended language file: en_us.lang.ext.php
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Found custom language file: en_us.lang.php
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT id, name, symbol, conversion_rate FROM currencies WHERE status = 'Active' and deleted = 0
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00030016899108887
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] rebuilding cache for Users
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Users' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00030994415283203
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] rebuilding cache for Employees
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Employees' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00028800964355469
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] rebuilding cache for EmailAddresses
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='EmailAddresses' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00029802322387695
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] rebuilding cache for UserPreferences
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='UserPreferences' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00029301643371582
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] rebuilding cache for Administration
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Administration' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00031185150146484
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT category, name, value FROM config
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00027585029602051
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT id FROM outbound_email WHERE type = 'system'
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00019502639770508
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT * FROM outbound_email WHERE id = 'b521632f-6d97-2aa6-e5e5-5cd58819b17c'
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00023007392883301
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Hook called: ::after_entry_point
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Including module specific hook file for custom/modules
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Including Ext hook file for custom/application
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Hook called: ::after_session_start
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Unable to find SugarController:: DetailView
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] We have an authenticated user id: 1
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Hook called: Users::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Including module specific hook file for custom/modules/Users
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Retrieve User : SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0
Mon Dec  2 11:59:18 2019 [16470][-none-][DEBUG] Limit Query:SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query:SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][-none-][INFO] Query Execution Time:0.00043487548828125
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] get_user_array query: SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00026297569274902
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT u1.first_name, u1.last_name from users  u1, users  u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023293495178223
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT 
                    ea.email_address,
                    ea.email_address_caps,
                    ea.invalid_email,
                    ea.opt_out,
                    ea.confirm_opt_in,
                    ea.date_created,
                    ea.date_modified,
                    ear.id,
                    ear.email_address_id,
                    ear.bean_id,
                    ear.bean_module,
                    ear.primary_address,
                    ear.reply_to_address,
                    ear.deleted
                FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
                WHERE 
                    ear.bean_module = 'Users'
                    AND ear.bean_id = '1'
                    AND ear.deleted = 0
                ORDER BY ear.reply_to_address, ear.primary_address DESC
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00041794776916504
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: Users::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] FACTOR AUTH: --------------------- CHECK FACTOR AUtH ---------------------
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] FACTOR AUTH: User needs factor auth, request is not Logout
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] FACTOR AUTH: User does`nt need factor auth
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Current user is: admin
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Current user is: admin
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] setting cookie ck_login_id_20 to 1
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] setting cookie ck_login_language_20 to en_us
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] current_language is: en_us
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Found language file: en_us.lang.php
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Found extended language file: en_us.lang.ext.php
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Found custom language file: en_us.lang.php
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00030016899108887
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Quotes : SELECT aos_quotes.* FROM aos_quotes  WHERE aos_quotes.id = '198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6' AND aos_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_quotes.* FROM aos_quotes  WHERE aos_quotes.id = '198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6' AND aos_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_quotes.* FROM aos_quotes  WHERE aos_quotes.id = '198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6' AND aos_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00038695335388184
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for Currencies
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Currencies' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00026416778564453
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Quotes].load_relationships, Loading relationship (billing_account_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (billing_account_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Quotes].load_relationships, Loading relationship (billing_contact_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (billing_contact_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Quotes].load_relationships, Loading relationship (opportunity_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (opportunity_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Using Action View Map:detail
Mon Dec  2 11:59:18 2019 [16470][1][DEPRECATED] Array
(
)

Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:INSERT INTO tracker (monitor_id,user_id,module_name,item_id,item_summary,date_modified,action,session_id,visible ) VALUES ( '8513be67-2da6-5551-ea98-5de4ee021140','1','AOS_Quotes','198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6','ddd','2019-12-02 10:59:18','detailview','c6tp8hbnhkqiavl82p8mhcltv3','1')
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0017108917236328
Mon Dec  2 11:59:18 2019 [16470][1][WARN] CSS File Dawn/yui.css not found
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for Favorites
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Favorites' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00043702125549316
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT parent_id, parent_type FROM favorites WHERE assigned_user_id = '1' AND deleted = 0 ORDER BY date_entered DESC
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00027012825012207
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for Trackers
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Trackers' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0002601146697998
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT item_id, item_summary, module_name, id FROM tracker WHERE id = (SELECT MAX(id) as id FROM tracker WHERE user_id = '1' AND deleted = 0 AND visible = 1) Start: 0 count: 50
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT item_id, item_summary, module_name, id FROM tracker WHERE id = (SELECT MAX(id) as id FROM tracker WHERE user_id = '1' AND deleted = 0 AND visible = 1) LIMIT 0,50
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00036501884460449
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:DELETE FROM tracker where id = '953'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0011928081512451
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Tracker: retrieving 10 items
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for Emails
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Emails' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00033688545227051
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] metadatafile=modules/AOS_Quotes/metadata/detailviewdefs.php
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id, name FROM aos_pdf_templates WHERE deleted=0 AND type='AOS_Quotes' AND active = 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00049304962158203
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT pg.id, pg.group_id FROM aos_products_quotes pg LEFT JOIN aos_line_item_groups lig ON pg.group_id = lig.id LEFT JOIN aos_products_quotes_cstm cstm ON pg.id = cstm.id_c WHERE pg.parent_type = 'AOS_Quotes' AND pg.parent_id = '198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6' AND pg.deleted = 0 ORDER BY lig.number ASC, pg.number ASC
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.000885009765625
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Products_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Products_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0004420280456543
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2d7a091b-be37-5e62-d978-5da9ce3de9a6' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2d7a091b-be37-5e62-d978-5da9ce3de9a6' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2d7a091b-be37-5e62-d978-5da9ce3de9a6' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0003809928894043
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0002751350402832
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00024008750915527
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '25e146a5-d6eb-c7ce-9e33-5dde2c5fe0b3' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '25e146a5-d6eb-c7ce-9e33-5dde2c5fe0b3' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '25e146a5-d6eb-c7ce-9e33-5dde2c5fe0b3' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00034093856811523
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00027298927307129
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023007392883301
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2e5b6c01-de75-217b-c75b-5dde2c2d535b' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2e5b6c01-de75-217b-c75b-5dde2c2d535b' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2e5b6c01-de75-217b-c75b-5dde2c2d535b' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00032901763916016
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00027608871459961
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023293495178223
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '37043c97-9346-6cde-abb7-5dde2c4edb11' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '37043c97-9346-6cde-abb7-5dde2c4edb11' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '37043c97-9346-6cde-abb7-5dde2c4edb11' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00033688545227051
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00027894973754883
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0002439022064209
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '45ebfc9a-be53-17d5-1e57-5dde2c6864a6' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '45ebfc9a-be53-17d5-1e57-5dde2c6864a6' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '45ebfc9a-be53-17d5-1e57-5dde2c6864a6' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00034713745117188
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0002739429473877
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00025701522827148
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '4ffe6a3b-069a-5740-80e4-5dde2c7498f8' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '4ffe6a3b-069a-5740-80e4-5dde2c7498f8' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '4ffe6a3b-069a-5740-80e4-5dde2c7498f8' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00031304359436035
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00022697448730469
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00020408630371094
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '5a082a94-7043-3e7c-2cb3-5dde2cf4b83b' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '5a082a94-7043-3e7c-2cb3-5dde2cf4b83b' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '5a082a94-7043-3e7c-2cb3-5dde2cf4b83b' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00028204917907715
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00021600723266602
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00019407272338867
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '63ebeeb3-321d-f555-f635-5dde2c38dfa6' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '63ebeeb3-321d-f555-f635-5dde2c38dfa6' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '63ebeeb3-321d-f555-f635-5dde2c38dfa6' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0002739429473877
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00021600723266602
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00019097328186035
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '24e63038-10a6-c143-e0c6-5dde2d99d4b9' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '24e63038-10a6-c143-e0c6-5dde2d99d4b9' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '24e63038-10a6-c143-e0c6-5dde2d99d4b9' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029802322387695
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00022697448730469
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00021696090698242
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2f430de0-38e0-e161-405d-5dde2d900e58' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2f430de0-38e0-e161-405d-5dde2d900e58' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '2f430de0-38e0-e161-405d-5dde2d900e58' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00033020973205566
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023603439331055
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00022196769714355
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '3a0a5751-47da-4693-9267-5dde2d4570fa' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '3a0a5751-47da-4693-9267-5dde2d4570fa' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '3a0a5751-47da-4693-9267-5dde2d4570fa' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00031018257141113
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023412704467773
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00025606155395508
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '4666df72-0632-1927-d58c-5dde2d7cfdf8' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '4666df72-0632-1927-d58c-5dde2d7cfdf8' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '4666df72-0632-1927-d58c-5dde2d7cfdf8' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029397010803223
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023198127746582
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0001981258392334
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '503e99da-d02d-922f-49e1-5dde2daab0a7' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '503e99da-d02d-922f-49e1-5dde2daab0a7' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '503e99da-d02d-922f-49e1-5dde2daab0a7' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029993057250977
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00025701522827148
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00022101402282715
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '5a335c89-d47c-ad93-85e3-5dde2de232ef' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '5a335c89-d47c-ad93-85e3-5dde2de232ef' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '5a335c89-d47c-ad93-85e3-5dde2de232ef' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00030708312988281
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023293495178223
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00020098686218262
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '63a67cdb-acb1-f593-d8a9-5dde2d397079' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '63a67cdb-acb1-f593-d8a9-5dde2d397079' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '63a67cdb-acb1-f593-d8a9-5dde2d397079' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00030088424682617
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023388862609863
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00019693374633789
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'b4e5aca0-76f8-ec89-2c1b-5dde2ef3e6b5' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'b4e5aca0-76f8-ec89-2c1b-5dde2ef3e6b5' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'b4e5aca0-76f8-ec89-2c1b-5dde2ef3e6b5' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029897689819336
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023794174194336
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00021100044250488
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'bc9fe78b-2c94-2776-7a68-5dde2e727bcc' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'bc9fe78b-2c94-2776-7a68-5dde2e727bcc' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'bc9fe78b-2c94-2776-7a68-5dde2e727bcc' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00030899047851562
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023698806762695
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0001990795135498
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'c47439b3-1919-6e0d-f136-5dde2e431f2d' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'c47439b3-1919-6e0d-f136-5dde2e431f2d' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'c47439b3-1919-6e0d-f136-5dde2e431f2d' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00030517578125
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023388862609863
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00019693374633789
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'cc44ce95-629e-a801-cf5c-5dde2e69903d' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'cc44ce95-629e-a801-cf5c-5dde2e69903d' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'cc44ce95-629e-a801-cf5c-5dde2e69903d' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029683113098145
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023293495178223
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00019598007202148
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'd4ec1a55-9a73-a39d-f9c5-5dde2e3cabd3' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'd4ec1a55-9a73-a39d-f9c5-5dde2e3cabd3' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'd4ec1a55-9a73-a39d-f9c5-5dde2e3cabd3' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029587745666504
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0002138614654541
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00018000602722168
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'dcfb50cf-1df5-144b-2037-5dde2e09c0b1' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'dcfb50cf-1df5-144b-2037-5dde2e09c0b1' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'dcfb50cf-1df5-144b-2037-5dde2e09c0b1' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029301643371582
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00022792816162109
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00019502639770508
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'e49c88f1-bbfe-d557-d00e-5dde2e9cbceb' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'e49c88f1-bbfe-d557-d00e-5dde2e9cbceb' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'e49c88f1-bbfe-d557-d00e-5dde2e9cbceb' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029802322387695
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023198127746582
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00019693374633789
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'ecb517a3-118d-9b94-97be-5dde2e74f55f' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'ecb517a3-118d-9b94-97be-5dde2e74f55f' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'ecb517a3-118d-9b94-97be-5dde2e74f55f' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029706954956055
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023102760314941
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0001978874206543
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '44d00985-037c-d0c0-0831-5dde2e03215e' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '44d00985-037c-d0c0-0831-5dde2e03215e' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = '44d00985-037c-d0c0-0831-5dde2e03215e' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029993057250977
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00028800964355469
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00020003318786621
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'dc2709b0-f7eb-62cc-8db4-5dde2e178b25' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'dc2709b0-f7eb-62cc-8db4-5dde2e178b25' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'dc2709b0-f7eb-62cc-8db4-5dde2e178b25' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00030016899108887
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0002291202545166
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00020003318786621
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'a76f3c2d-c266-e16b-648a-5dde2ed756c6' AND aos_products_quotes.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'a76f3c2d-c266-e16b-648a-5dde2ed756c6' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT aos_products_quotes.*,aos_products_quotes_cstm.* FROM aos_products_quotes  LEFT JOIN aos_products_quotes_cstm ON aos_products_quotes.id = aos_products_quotes_cstm.id_c  WHERE aos_products_quotes.id = 'a76f3c2d-c266-e16b-648a-5dde2ed756c6' AND aos_products_quotes.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029301643371582
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Quotes
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Quotes' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00023198127746582
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT id ,aos_quotes.name as parent_name , aos_quotes.assigned_user_id owner FROM aos_quotes WHERE deleted=0 AND id='198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6'
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.0001990795135498
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean[AOS_Products_Quotes].load_relationships, Loading relationship (group_id).
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (group_id)
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: AOS_Quotes::after_ui_frame
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Creating new instance of hook class hooks without parameters
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Contracts
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Contracts' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00046181678771973
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SubPanelDefinitions->get_available_tabs(): aos_quotes_aos_contracts= not exempt  ACL OK
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for AOS_Invoices
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='AOS_Invoices' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00034117698669434
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SubPanelDefinitions->get_available_tabs(): aos_quotes_aos_invoices= not exempt  ACL OK
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for Project
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Project' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00040006637573242
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] SubPanelDefinitions->get_available_tabs(): aos_quotes_project= not exempt  ACL OK
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] rebuilding cache for Relationships
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Relationships' AND  deleted = 0
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00025200843811035
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: Currencies::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve Currency : SELECT currencies.* FROM currencies  WHERE currencies.id = '' AND currencies.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT currencies.* FROM currencies  WHERE currencies.id = '' AND currencies.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT currencies.* FROM currencies  WHERE currencies.id = '' AND currencies.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00029087066650391
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Subpanel Where Clause: 
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: Currencies::before_retrieve
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Retrieve Currency : SELECT currencies.* FROM currencies  WHERE currencies.id = '' AND currencies.deleted=0
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Limit Query:SELECT currencies.* FROM currencies  WHERE currencies.id = '' AND currencies.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query:SELECT currencies.* FROM currencies  WHERE currencies.id = '' AND currencies.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Query Execution Time:0.00022006034851074
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Subpanel Where Clause: 
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][INFO] Subpanel Where Clause: 
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Undefined index: server_unique_key
Mon Dec  2 11:59:18 2019 [16470][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: ::after_ui_footer
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Hook called: ::server_round_trip
Mon Dec  2 11:59:18 2019 [16470][1][DEBUG] Calling MySQLi::disconnect()
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] current_language is: en_us
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheAPC
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheFile
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheMemcache
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheMemcached
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheMemory
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Using cache backend SugarCacheMemory, since 999 is less than 1000
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheRedis
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheWincache
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheZend
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Found cache backend SugarCachesMash
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Found language file: en_us.lang.php
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Found extended language file: en_us.lang.ext.php
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Found custom language file: en_us.lang.php
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT id, name, symbol, conversion_rate FROM currencies WHERE status = 'Active' and deleted = 0
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00030398368835449
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] rebuilding cache for Users
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Users' AND  deleted = 0
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00029492378234863
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] rebuilding cache for Employees
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Employees' AND  deleted = 0
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00029087066650391
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] rebuilding cache for EmailAddresses
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='EmailAddresses' AND  deleted = 0
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00031208992004395
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] rebuilding cache for UserPreferences
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='UserPreferences' AND  deleted = 0
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00026202201843262
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] rebuilding cache for Administration
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Administration' AND  deleted = 0
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00026702880859375
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT category, name, value FROM config
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00023293495178223
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT id FROM outbound_email WHERE type = 'system'
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00017905235290527
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT * FROM outbound_email WHERE id = 'b521632f-6d97-2aa6-e5e5-5cd58819b17c'
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00019001960754395
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Hook called: ::after_entry_point
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Including module specific hook file for custom/modules
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Including Ext hook file for custom/application
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Hook called: ::after_session_start
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Unable to find SugarController:: check_favorite
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] We have an authenticated user id: 1
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Hook called: Users::before_retrieve
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Including module specific hook file for custom/modules/Users
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Retrieve User : SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0
Mon Dec  2 11:59:19 2019 [16462][-none-][DEBUG] Limit Query:SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query:SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:19 2019 [16462][-none-][INFO] Query Execution Time:0.00039792060852051
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] get_user_array query: SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query:SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query Execution Time:0.00028109550476074
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query:SELECT u1.first_name, u1.last_name from users  u1, users  u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query Execution Time:0.00021100044250488
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query:SELECT 
                    ea.email_address,
                    ea.email_address_caps,
                    ea.invalid_email,
                    ea.opt_out,
                    ea.confirm_opt_in,
                    ea.date_created,
                    ea.date_modified,
                    ear.id,
                    ear.email_address_id,
                    ear.bean_id,
                    ear.bean_module,
                    ear.primary_address,
                    ear.reply_to_address,
                    ear.deleted
                FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
                WHERE 
                    ear.bean_module = 'Users'
                    AND ear.bean_id = '1'
                    AND ear.deleted = 0
                ORDER BY ear.reply_to_address, ear.primary_address DESC
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query Execution Time:0.00040292739868164
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Hook called: Users::after_retrieve
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] FACTOR AUTH: --------------------- CHECK FACTOR AUtH ---------------------
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] FACTOR AUTH: User needs factor auth, request is not Logout
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] FACTOR AUTH: User does`nt need factor auth
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Current user is: admin
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Current user is: admin
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] setting cookie ck_login_id_20 to 1
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] setting cookie ck_login_language_20 to en_us
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] current_language is: en_us
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Found language file: en_us.lang.php
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Found extended language file: en_us.lang.ext.php
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Found custom language file: en_us.lang.php
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] rebuilding cache for Favorites
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Favorites' AND  deleted = 0
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query Execution Time:0.00026798248291016
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Performing action: action_check_favorite MODULE: Favorites
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Get One: |SELECT id FROM favorites WHERE parent_id= '198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6' AND parent_type = 'AOS_Quotes' AND assigned_user_id = '1' AND deleted = 0 ORDER BY date_entered DESC|
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Limit Query:SELECT id FROM favorites WHERE parent_id= '198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6' AND parent_type = 'AOS_Quotes' AND assigned_user_id = '1' AND deleted = 0 ORDER BY date_entered DESC Start: 0 count: 1
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query:SELECT id FROM favorites WHERE parent_id= '198980c3-b3e7-2ff1-b9bd-5da9ce64a8e6' AND parent_type = 'AOS_Quotes' AND assigned_user_id = '1' AND deleted = 0 ORDER BY date_entered DESC LIMIT 0,1
Mon Dec  2 11:59:19 2019 [16462][1][INFO] Query Execution Time:0.00022602081298828
Mon Dec  2 11:59:19 2019 [16462][1][DEPRECATED] Array
(
)

Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Hook called: Favorites::after_ui_frame
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Creating new instance of hook class hooks without parameters
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Hook called: ::after_ui_footer
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Hook called: ::server_round_trip
Mon Dec  2 11:59:19 2019 [16462][1][DEBUG] Calling MySQLi::disconnect()
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] current_language is: en_us
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheAPC
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheFile
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheMemcache
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheMemcached
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheMemory
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Using cache backend SugarCacheMemory, since 999 is less than 1000
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheRedis
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheWincache
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCacheZend
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Found cache backend SugarCachesMash
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Found language file: en_us.lang.php
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Found extended language file: en_us.lang.ext.php
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Found custom language file: en_us.lang.php
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT id, name, symbol, conversion_rate FROM currencies WHERE status = 'Active' and deleted = 0
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00029182434082031
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] rebuilding cache for Users
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Users' AND  deleted = 0
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00028705596923828
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] rebuilding cache for Employees
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Employees' AND  deleted = 0
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00025200843811035
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] rebuilding cache for EmailAddresses
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='EmailAddresses' AND  deleted = 0
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00031304359436035
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] rebuilding cache for UserPreferences
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='UserPreferences' AND  deleted = 0
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00025010108947754
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] rebuilding cache for Administration
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Administration' AND  deleted = 0
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00024795532226562
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT category, name, value FROM config
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00024199485778809
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT id FROM outbound_email WHERE type = 'system'
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00017404556274414
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT * FROM outbound_email WHERE id = 'b521632f-6d97-2aa6-e5e5-5cd58819b17c'
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00025320053100586
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Hook called: ::after_entry_point
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Including module specific hook file for custom/modules
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Including Ext hook file for custom/application
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Hook called: ::after_session_start
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Unable to find SugarController:: get
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] We have an authenticated user id: 1
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Hook called: Users::before_retrieve
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Including module specific hook file for custom/modules/Users
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Retrieve User : SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0
Mon Dec  2 11:59:21 2019 [16462][-none-][DEBUG] Limit Query:SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query:SELECT users.* FROM users  WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Mon Dec  2 11:59:21 2019 [16462][-none-][INFO] Query Execution Time:0.00043416023254395
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] get_user_array query: SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query:SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query Execution Time:0.00022792816162109
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query:SELECT u1.first_name, u1.last_name from users  u1, users  u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query Execution Time:0.00021696090698242
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query:SELECT 
                    ea.email_address,
                    ea.email_address_caps,
                    ea.invalid_email,
                    ea.opt_out,
                    ea.confirm_opt_in,
                    ea.date_created,
                    ea.date_modified,
                    ear.id,
                    ear.email_address_id,
                    ear.bean_id,
                    ear.bean_module,
                    ear.primary_address,
                    ear.reply_to_address,
                    ear.deleted
                FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
                WHERE 
                    ear.bean_module = 'Users'
                    AND ear.bean_id = '1'
                    AND ear.deleted = 0
                ORDER BY ear.reply_to_address, ear.primary_address DESC
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query Execution Time:0.00041604042053223
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] Hook called: Users::after_retrieve
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] FACTOR AUTH: --------------------- CHECK FACTOR AUtH ---------------------
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] FACTOR AUTH: User needs factor auth, request is not Logout
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] FACTOR AUTH: User does`nt need factor auth
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] Current user is: admin
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] Current user is: admin
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] setting cookie ck_login_id_20 to 1
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] setting cookie ck_login_language_20 to en_us
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] current_language is: en_us
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Found language file: en_us.lang.php
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Found extended language file: en_us.lang.ext.php
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Found custom language file: en_us.lang.php
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] rebuilding cache for Alerts
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query:SELECT * FROM fields_meta_data WHERE  custom_module='Alerts' AND  deleted = 0
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query Execution Time:0.00028014183044434
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] Performing action: action_get MODULE: Alerts
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] get_full_list:  order_by = 'alerts.date_entered' and where = 'alerts.assigned_user_id = '1' AND is_read != '1''
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] SugarBean[Alert].load_relationships, Loading relationship (modified_user_link).
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] SugarBean[Alert].load_relationships, Loading relationship (created_by_link).
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] SugarBean[Alert].load_relationships, Loading relationship (assigned_user_link).
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] process_order_by: (alerts.date_entered) does not have a vardef entry.
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] process_full_list_query: query is  SELECT  alerts.*  , jt0.user_name modified_by_name , jt0.created_by modified_by_name_owner  , 'Users' modified_by_name_mod , jt1.user_name created_by_name , jt1.created_by created_by_name_owner  , 'Users' created_by_name_mod , jt2.user_name assigned_user_name , jt2.created_by assigned_user_name_owner  , 'Users' assigned_user_name_mod FROM alerts   LEFT JOIN  users jt0 ON alerts.modified_user_id=jt0.id AND jt0.deleted=0

 AND jt0.deleted=0  LEFT JOIN  users jt1 ON alerts.created_by=jt1.id AND jt1.deleted=0

 AND jt1.deleted=0  LEFT JOIN  users jt2 ON alerts.assigned_user_id=jt2.id AND jt2.deleted=0

 AND jt2.deleted=0 where (alerts.assigned_user_id = '1' AND is_read != '1') AND alerts.deleted=0
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query: SELECT  alerts.*  , jt0.user_name modified_by_name , jt0.created_by modified_by_name_owner  , 'Users' modified_by_name_mod , jt1.user_name created_by_name , jt1.created_by created_by_name_owner  , 'Users' created_by_name_mod , jt2.user_name assigned_user_name , jt2.created_by assigned_user_name_owner  , 'Users' assigned_user_name_mod FROM alerts   LEFT JOIN  users jt0 ON alerts.modified_user_id=jt0.id AND jt0.deleted=0

 AND jt0.deleted=0  LEFT JOIN  users jt1 ON alerts.created_by=jt1.id AND jt1.deleted=0

 AND jt1.deleted=0  LEFT JOIN  users jt2 ON alerts.assigned_user_id=jt2.id AND jt2.deleted=0

 AND jt2.deleted=0 where (alerts.assigned_user_id = '1' AND is_read != '1') AND alerts.deleted=0
Mon Dec  2 11:59:21 2019 [16462][1][INFO] Query Execution Time:0.00036311149597168
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] process_full_list_query: result is mysqli_result Object
(
    [current_field] => 0
    [field_count] => 23
    [lengths] => 
    [num_rows] => 0
    [type] => 0
)

Mon Dec  2 11:59:21 2019 [16462][1][DEPRECATED] Array
(
)

Mon Dec  2 11:59:21 2019 [16462][1][WARN] Saving error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:21 2019 [16462][1][ERROR] Pop error level. Try to remove the error_reporting() function from your code.
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] Hook called: ::server_round_trip
Mon Dec  2 11:59:21 2019 [16462][1][DEBUG] Calling MySQLi::disconnect()

blqt · 3 December 2019 17:24

Hi,

This limit in line items is usually due to max_input_vars being too small in php.ini

2di7 · 4 December 2019 10:19

Hi and thanks for reply.
How is it recommended to manage this value?
How much can I bring it up ?
(CRM runs on an internal server)

blqt · 4 December 2019 11:15

I believe the default value in php.ini is 1000.
Increasing it to 2000 might be good enough, but it really depends on the maximum number of line items you could have.

2di7 · 5 December 2019 13:30

Thank you! Resolved!