Check Inbound Mailboxes job crashing

Hi,

I hope that someone can help.

My SuiteCRM system is configured to collect mail from a mailbox and create/update cases from the mailbox. The issue I have is that every other day the procedure seems to get a segfault when adding the entry into the database.

I know that it is processing the mails correctly as it collects and updates the Activity feed, but it does create/update the case. I can’t seem to find out what is causing the segfault. I’ve looked at the RAM settings in PHP, but that doesn’t seem to have any effect. I’m not sure where else to look. If anyone has any idea on to go about tracing the issue, it would be much appreciated.

I turned on debugging for SuiteCRM at the following is in the logs. The line at the end with [ERROR] is where the segfault occurs:


Mon Jul 25 11:30:03 2016 [28291[1[DEBUG] InboundEmail processing 1 email 29-----------------------------------------------------------------------------------------
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] *********** InboundEmail doing dupe check.
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT count(emails.id) AS c FROM emails WHERE emails.message_id = '7ab3b413afaf5ce1e3df7ca9151cddd8' and emails.deleted = 0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00025701522827148
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] *********** NO duplicate found, continuing with processing.
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Loading Preferences DB admin
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT contents FROM user_preferences WHERE assigned_user_id='1' AND category = 'global' AND deleted = 0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00027799606323242
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Localization: translating [<html xmlns:v="urn:schemas-microsoft-com:vml" xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns="http://www.w3.org/TR/REC-html40">

<head>

<meta http-equiv="Content-Type" content="text/html; charset=us-ascii">

<meta name="Generator" content="Microsoft Word 15 (filtered medium)">

<style><!--

/* Font Definitions */

@font-face

	{font-family:"Cambria Math";

	panose-1:2 4 5 3 5 4 6 3 2 4;}

@font-face

	{font-family:Calibri;

	panose-1:2 15 5 2 2 2 4 3 2 4;}

/* Style Definitions */

p.MsoNormal, li.MsoNormal, div.MsoNormal

	{margin:0cm;

	margin-bottom:.0001pt;

	font-size:11.0pt;

	font-family:"Calibri",sans-serif;}

a:link, span.MsoHyperlink

	{mso-style-priority:99;

	color:#0563C1;

	text-decoration:underline;}

a:visited, span.MsoHyperlinkFollowed

	{mso-style-priority:99;

	color:#954F72;

	text-decoration:underline;}

span.EmailStyle17

	{mso-style-type:personal-compose;

	font-family:"Calibri",sans-serif;

	color:windowtext;}

.MsoChpDefault

	{mso-style-type:export-only;

	font-family:"Calibri",sans-serif;}

@page WordSection1

	{size:612.0pt 792.0pt;

	margin:72.0pt 72.0pt 72.0pt 72.0pt;}

div.WordSection1

	{page:WordSection1;}

--></style><!--[if gte mso 9]><xml>

<o:shapedefaults v:ext="edit" spidmax="1026" />

</xml><![endif]--><!--[if gte mso 9]><xml>

<o:shapelayout v:ext="edit">

<o:idmap v:ext="edit" data="1" />

</o:shapelayout></xml><![endif]-->

</head>

<body lang="EN-US" link="#0563C1" vlink="#954F72">

<div class="WordSection1">

<p class="MsoNormal">Remove [Removed] Auto data from vaults.<o:p></o:p></p>

<p class="MsoNormal"><o:p>&nbsp;</o:p></p>

<p class="MsoNormal"><span lang="EN-ZA">Agent = C288B<o:p></o:p></span></p>

<p class="MsoNormal"><span lang="EN-ZA">Policy = F764A<o:p></o:p></span></p>

<p class="MsoNormal"><o:p>&nbsp;</o:p></p>

<p class="MsoNormal"><o:p></o:p></p>

</div>

</body>

</html>

] from us-ascii into UTF-8
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] InboundEmail Message (id:7ab3b413afaf5ce1e3df7ca9151cddd8) has no body
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:INSERT INTO emails_text (email_id,from_addr,reply_to_addr,to_addrs,cc_addrs,bcc_addrs,description,description_html,deleted)
					VALUES ('205afe64-84fd-1de9-97b6-5795dc7e3a4d','[Removed]  <[Removed]>','','helpdesk <[Removed]>','','','','\n<div class=\"WordSection1\">\n<p class=\"MsoNormal\">Remove [Removed] Auto data from vaults.</p><p></p>\n<p class=\"MsoNormal\"></p><p>Â </p>\n<p class=\"MsoNormal\"><span lang=\"en-za\">Agent = C288B</span></p><p></p>\n<p class=\"MsoNormal\"><span lang=\"en-za\">Policy = F764A</span></p><p></p>\n<p class=\"MsoNormal\"></p><p>Â </p>\n<p class=\"MsoNormal\">[Removed]</p><p></p>\n</div>\n',0)
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.0003049373626709
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT id FROM email_addresses WHERE email_address_caps = '[REMOVED]'
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00017619132995605
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT * FROM emails_email_addr_rel WHERE email_id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND email_address_id = 'bd27d34b-e62c-b91a-dbdf-56ec142dac76' AND address_type = 'from' AND deleted = 0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00021481513977051
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:INSERT INTO emails_email_addr_rel VALUES('252ea6f4-83d6-05ac-96fc-5795dce25e9d', '205afe64-84fd-1de9-97b6-5795dc7e3a4d', 'from', 'bd27d34b-e62c-b91a-dbdf-56ec142dac76', 0)
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.0001070499420166
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT id FROM email_addresses WHERE email_address_caps = '[REMOVED]'
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00011396408081055
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT * FROM emails_email_addr_rel WHERE email_id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND email_address_id = '5fdce173-329d-138c-042a-56ec126fdc18' AND address_type = 'to' AND deleted = 0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00014901161193848
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:INSERT INTO emails_email_addr_rel VALUES('2551243b-912b-058e-25e6-5795dc4e31a9', '205afe64-84fd-1de9-97b6-5795dc7e3a4d', 'to', '5fdce173-329d-138c-042a-56ec126fdc18', 0)
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00015592575073242
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] -------------------------------> Email called save()
Mon Jul 25 11:30:03 2016 [28291][1][DEPRECATED] Formatting correction: Emails->date_sent had formatting automatically corrected. This will be removed in the future, please upgrade your external code
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: Emails::before_save
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Including module specific hook file for custom/modules/Emails
Mon Jul 25 11:30:03 2016 [28291][1][DEPRECATED] SugarBean.php: preprocess_fields_on_save() is deprecated
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:INSERT INTO emails (id,date_entered,date_modified,modified_user_id,created_by,deleted,date_sent,message_id,name,type,status,intent,mailbox_id)
					VALUES ('205afe64-84fd-1de9-97b6-5795dc7e3a4d','2016-07-25 09:30:01','2016-07-25 09:30:01','1','1',0,'2016-07-25 09:12:00','7ab3b413afaf5ce1e3df7ca9151cddd8','Remove [Removed] Auto data from 02 vaults','inbound','unread','createcase','3dc2f156-6df2-155a-67dc-5714b3a48700')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.0001671314239502
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00030016899108887
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Retrieve Default Groups: select securitygroups_default.id, securitygroups.name, securitygroups_default.module, securitygroups_default.securitygroup_id from securitygroups_default inner join securitygroups on securitygroups_default.securitygroup_id = securitygroups.id where securitygroups_default.deleted = 0 and securitygroups.deleted = 0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:select securitygroups_default.id, securitygroups.name, securitygroups_default.module, securitygroups_default.securitygroup_id from securitygroups_default inner join securitygroups on securitygroups_default.securitygroup_id = securitygroups.id where securitygroups_default.deleted = 0 and securitygroups.deleted = 0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00014305114746094
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00027704238891602
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00026202201843262
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Inherit from Creator: insert into securitygroups_records(id,securitygroup_id,record_id,module,date_modified,deleted) select distinct  uuid() ,u.securitygroup_id,'205afe64-84fd-1de9-97b6-5795dc7e3a4d','Emails',CURDATE(),0 from securitygroups_users u inner join securitygroups g on u.securitygroup_id = g.id and g.deleted = 0 and (g.noninheritable is null or g.noninheritable <> 1) left join securitygroups_records d on d.securitygroup_id = u.securitygroup_id and d.record_id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' and d.module = 'Emails' and d.deleted = 0 where d.id is null and u.user_id = '1' and u.deleted = 0 and (u.noninheritable is null or u.noninheritable <> 1)
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:insert into securitygroups_records(id,securitygroup_id,record_id,module,date_modified,deleted) select distinct  uuid() ,u.securitygroup_id,'205afe64-84fd-1de9-97b6-5795dc7e3a4d','Emails',CURDATE(),0 from securitygroups_users u inner join securitygroups g on u.securitygroup_id = g.id and g.deleted = 0 and (g.noninheritable is null or g.noninheritable <> 1) left join securitygroups_records d on d.securitygroup_id = u.securitygroup_id and d.record_id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' and d.module = 'Emails' and d.deleted = 0 where d.id is null and u.user_id = '1' and u.deleted = 0 and (u.noninheritable is null or u.noninheritable <> 1)
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00027799606323242
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: Emails::after_save
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class CaseUpdatesHook without parameters
Mon Jul 25 11:30:03 2016 [28291][1][WARN] CaseUpdatesHook: saveEmailUpdate: Not a create case or wrong parent type
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class AOD_LogicHooks without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class AOW_WorkFlow without parameters
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT id FROM aow_workflow WHERE aow_workflow.flow_module = 'Emails' AND aow_workflow.status = 'Active' AND (aow_workflow.run_when = 'Always' OR aow_workflow.run_when = 'On_Save' OR aow_workflow.run_when = 'Create') AND aow_workflow.deleted = 0 
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.0001220703125
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] -------------------------------> Email save() done
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] ********************************* InboundEmail finished import of 1 email: Remove [Removed] Auto data from 02 vaults
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:INSERT INTO folders_rel (id, folder_id, polymorphic_module, polymorphic_id, deleted)
				VALUES('2e835650-1673-17d5-db9c-5795dc79ec3a', '39299a70-e5e8-1858-ffea-5714b356bbe2', 'Emails', '205afe64-84fd-1de9-97b6-5795dc7e3a4d', 0)
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00026488304138184
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] userId [ 208a564c-f1a1-1a29-628b-578361861229 ]
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] In handleCreateCase in AOPInboundEmail
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] retrieveing email
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: Emails::before_retrieve
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Retrieve Email : SELECT emails.* FROM emails  WHERE emails.id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND emails.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Limit Query:SELECT emails.* FROM emails  WHERE emails.id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND emails.deleted=0 Start: 0 count: 1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT emails.* FROM emails  WHERE emails.id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND emails.deleted=0 LIMIT 0,1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00016212463378906
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT contacts.first_name, contacts.last_name, contacts.phone_work, contacts.id, contacts.assigned_user_id contact_name_owner, 'Contacts' contact_name_mod FROM contacts, emails_beans WHERE emails_beans.email_id='205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND emails_beans.bean_id=contacts.id AND emails_beans.bean_module = 'Contacts' AND emails_beans.deleted=0 AND contacts.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00029087066650391
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Call(205afe64-84fd-1de9-97b6-5795dc7e3a4d): contact_name = 
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Call(205afe64-84fd-1de9-97b6-5795dc7e3a4d): contact_phone = 
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Call(205afe64-84fd-1de9-97b6-5795dc7e3a4d): contact_id = 
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Call(205afe64-84fd-1de9-97b6-5795dc7e3a4d): contact_email1 = 
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: Emails::after_retrieve
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT from_addr, reply_to_addr, to_addrs, cc_addrs, bcc_addrs, description, description_html, raw_source FROM emails_text WHERE email_id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d'
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:9.9897384643555E-5
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT email_address, address_type
				FROM emails_email_addr_rel eam
				JOIN email_addresses ea ON ea.id = eam.email_address_id
				WHERE eam.email_id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND eam.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.0001380443572998
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean[Email].load_relationships, Loading relationship (notes).
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT id FROM notes WHERE notes.parent_id = '205afe64-84fd-1de9-97b6-5795dc7e3a4d' AND notes.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00020313262939453
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] finding related accounts with address [Removed]
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT bean_id FROM email_addr_bean_rel eabr
				JOIN email_addresses ea ON (eabr.email_address_id = ea.id)
				WHERE bean_module = 'Accounts' AND ea.email_address_caps in ( '[Removed]' ) AND eabr.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00018000602722168
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT bean_id FROM email_addr_bean_rel eabr
				JOIN email_addresses ea ON (eabr.email_address_id = ea.id)
				WHERE bean_module = 'Contacts' AND ea.email_address_caps in ( '[Removed]' ) AND eabr.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00015401840209961
Mon Jul 25 11:30:03 2016 [28291][1][DEPRECATED] Formatting correction: Cases->jjwg_maps_lat_c had formatting automatically corrected. This will be removed in the future, please upgrade your external code
Mon Jul 25 11:30:03 2016 [28291][1][DEPRECATED] Formatting correction: Cases->jjwg_maps_lng_c had formatting automatically corrected. This will be removed in the future, please upgrade your external code
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: Cases::before_save
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Including module specific hook file for custom/modules/Cases
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class CaseFeed without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: SugarFeed::before_save
Mon Jul 25 11:30:03 2016 [28291][1][DEPRECATED] SugarBean.php: preprocess_fields_on_save() is deprecated
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:INSERT INTO sugarfeed (id,name,date_entered,date_modified,modified_user_id,created_by,deleted,assigned_user_id,related_module,related_id)
					VALUES ('315b0f43-f193-5a34-b99a-5795dc4e11da','<b>{this.CREATED_BY}</b> {SugarFeed.CREATED_CASE} [Cases:30d94ba9-4303-b24b-c4eb-5795dc764e81:Remove [Removed] Auto data from 02 vaults] {SugarFeed.FOR} [Accounts::]: \n\nRemove Brits Auto data from vaults.\n \nAgent = C288B\nPolicy = F764A\n \n[Removed]\n\n','2016-07-25 09:30:01','2016-07-25 09:30:01','1','1',0,'208a564c-f1a1-1a29-628b-578361861229','Cases','30d94ba9-4303-b24b-c4eb-5795dc764e81'
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00020503997802734
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00028896331787109
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00026106834411621
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00025701522827148
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00026607513427734
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: SugarFeed::after_save
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class AOD_LogicHooks without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class AOW_WorkFlow without parameters
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT id FROM aow_workflow WHERE aow_workflow.flow_module = 'SugarFeed' AND aow_workflow.status = 'Active' AND (aow_workflow.run_when = 'Always' OR aow_workflow.run_when = 'On_Save' OR aow_workflow.run_when = 'Create') AND aow_workflow.deleted = 0 
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00011897087097168
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class CaseUpdatesHook without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class CaseEventsHook without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: Cases::before_retrieve
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Retrieve Case : SELECT cases.*,cases_cstm.* FROM cases  LEFT JOIN cases_cstm ON cases.id = cases_cstm.id_c  WHERE cases.id = '30d94ba9-4303-b24b-c4eb-5795dc764e81' AND cases.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Limit Query:SELECT cases.*,cases_cstm.* FROM cases  LEFT JOIN cases_cstm ON cases.id = cases_cstm.id_c  WHERE cases.id = '30d94ba9-4303-b24b-c4eb-5795dc764e81' AND cases.deleted=0 Start: 0 count: 1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT cases.*,cases_cstm.* FROM cases  LEFT JOIN cases_cstm ON cases.id = cases_cstm.id_c  WHERE cases.id = '30d94ba9-4303-b24b-c4eb-5795dc764e81' AND cases.deleted=0 LIMIT 0,1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00012302398681641
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: AOP_Case_Events::before_save
Mon Jul 25 11:30:03 2016 [28291][1][DEPRECATED] SugarBean.php: preprocess_fields_on_save() is deprecated
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:INSERT INTO aop_case_events (id,name,date_entered,date_modified,modified_user_id,created_by,description,deleted,case_id)
					VALUES ('32a293a8-d685-d459-1099-5795dce2188b','Priority changed from  to P1.','2016-07-25 09:30:01','2016-07-25 09:30:01','1','1','Priority changed from  to P1.',0,'30d94ba9-4303-b24b-c4eb-5795dc764e81')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00015401840209961
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00027799606323242
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00025796890258789
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SecuritySuite: Get SecuritySuite Enabled Modules: SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = 'SecurityGroups' OR rhs_module='SecurityGroups')
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00026702880859375
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: AOP_Case_Events::after_save
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Creating new instance of hook class AOD_LogicHooks without parameters
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: AOP_Case_Events::before_retrieve
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Retrieve AOP_Case_Events : SELECT aop_case_events.* FROM aop_case_events  WHERE aop_case_events.id = '32a293a8-d685-d459-1099-5795dce2188b' AND aop_case_events.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Limit Query:SELECT aop_case_events.* FROM aop_case_events  WHERE aop_case_events.id = '32a293a8-d685-d459-1099-5795dce2188b' AND aop_case_events.deleted=0 Start: 0 count: 1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT aop_case_events.* FROM aop_case_events  WHERE aop_case_events.id = '32a293a8-d685-d459-1099-5795dce2188b' AND aop_case_events.deleted=0 LIMIT 0,1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.0001070499420166
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean[AOP_Case_Events].load_relationships, Loading relationship (assigned_user_id).
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (assigned_user_id)
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean[AOP_Case_Events].load_relationships, Loading relationship (assigned_user_id).
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (assigned_user_id)
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: Cases::before_retrieve
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Retrieve Case : SELECT cases.*,cases_cstm.* FROM cases  LEFT JOIN cases_cstm ON cases.id = cases_cstm.id_c  WHERE cases.id = '30d94ba9-4303-b24b-c4eb-5795dc764e81' AND cases.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Limit Query:SELECT cases.*,cases_cstm.* FROM cases  LEFT JOIN cases_cstm ON cases.id = cases_cstm.id_c  WHERE cases.id = '30d94ba9-4303-b24b-c4eb-5795dc764e81' AND cases.deleted=0 Start: 0 count: 1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query:SELECT cases.*,cases_cstm.* FROM cases  LEFT JOIN cases_cstm ON cases.id = cases_cstm.id_c  WHERE cases.id = '30d94ba9-4303-b24b-c4eb-5795dc764e81' AND cases.deleted=0 LIMIT 0,1
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00011420249938965
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] Hook called: AOP_Case_Events::after_retrieve
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] get_full_list:  order_by = '' and where = 'aod_indexevent.record_id = '32a293a8-d685-d459-1099-5795dce2188b' AND aod_indexevent.record_module = 'AOP_Case_Events''
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean[AOD_IndexEvent].load_relationships, Loading relationship (modified_user_link).
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean[AOD_IndexEvent].load_relationships, Loading relationship (created_by_link).
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] SugarBean[AOD_IndexEvent].load_relationships, Loading relationship (assigned_user_link).
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] process_full_list_query: query is  SELECT  aod_indexevent.*  , 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 aod_indexevent   LEFT JOIN  users jt0 ON aod_indexevent.modified_user_id=jt0.id AND jt0.deleted=0

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

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

 AND jt2.deleted=0 where (aod_indexevent.record_id = '32a293a8-d685-d459-1099-5795dce2188b' AND aod_indexevent.record_module = 'AOP_Case_Events') AND aod_indexevent.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query: SELECT  aod_indexevent.*  , 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 aod_indexevent   LEFT JOIN  users jt0 ON aod_indexevent.modified_user_id=jt0.id AND jt0.deleted=0

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

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

 AND jt2.deleted=0 where (aod_indexevent.record_id = '32a293a8-d685-d459-1099-5795dce2188b' AND aod_indexevent.record_module = 'AOP_Case_Events') AND aod_indexevent.deleted=0
Mon Jul 25 11:30:03 2016 [28291][1][INFO] Query Execution Time:0.00043511390686035
Mon Jul 25 11:30:03 2016 [28291][1][DEBUG] process_full_list_query: result is mysqli_result Object
(
    [current_field] => 0
    [field_count] => 22
    [lengths] => 
    [num_rows] => 0
    [type] => 0
)

Mon Jul 25 11:30:03 2016 [28291][1][ERROR]