Hi,
i got error in scheduled report
Notice: Undefined index: aor_email_to_list in C:\xampp\htdocs\zamp\modules\AOR_Scheduled_Reports\emailRecipients.php on line 49
Notice: Undefined index: email_to_type in C:\xampp\htdocs\zamp\modules\AOR_Scheduled_Reports\emailRecipients.php on line 61
my scheduled report not working, last run time in showing correct in scheduler but last run time is showing blank in scheduled report.
plz help me thanks for advance
hi anybody please help me for this error, my scheduled report not working, scheduler is ok but scheduled report not running.
Thanks in Advance
What SuiteCRM version are you using?
When you said: “i got error in scheduled report”, where do you see these error? A screenshot will help a lot.
Regards
Dear dir,
here is debug log of suitecrm,
i had disable all scheduled job from schedular, only enabled --Run Report Generation Scheduled Tasks
schdular staus is here
Job Name:
Run Report Generation Scheduled Tasks
Status:
Active
Date & Time Start:
01/01/2015 23:00
Active From:
Always
Date & Time End:
Active To:
Always
Last Successful Run:
08/28/2019 13:43
Interval:
As often as possible.
Execute If Missed:
Job:
function::aorRunScheduledReports
Date Created:
08/27/2019 10:30 by admin
Date Modified:
08/28/2019 13:15 by admin
and scheduled report status is here
Name:
test
Status:
Active
Reports:
report
Schedule:
Daily at 13:43
Last run:
Email Recipients:
Users: all
Description:
suite crmdebug log:–
Wed Aug 28 11:13:03 2019 [3468][1][DEBUG] Hook called: Configurator::after_ui_frame
Wed Aug 28 11:13:03 2019 [3468][1][DEBUG] Creating new instance of hook class hooks without parameters
Wed Aug 28 11:13:03 2019 [3468][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Wed Aug 28 11:13:03 2019 [3468][1][WARN] Undefined index: server_unique_key
Wed Aug 28 11:13:03 2019 [3468][1][DEBUG] Hook called: ::after_ui_footer
Wed Aug 28 11:13:03 2019 [3468][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Wed Aug 28 11:13:03 2019 [3468][1][DEBUG] Hook called: ::server_round_trip
Wed Aug 28 11:13:03 2019 [3468][1][DEBUG] Calling MySQLi::disconnect()
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] current_language is: en_us
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheAPC
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheFile
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheMemcache
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheMemcached
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheMemory
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Using cache backend SugarCacheMemory, since 999 is less than 1000
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheRedis
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCachesMash
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheWincache
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheZend
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Found language file: en_us.lang.php
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Found extended language file: en_us.lang.ext.php
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query:SELECT id, name, symbol, conversion_rate FROM currencies WHERE status = ‘Active’ and deleted = 0
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query:SELECT category, name, value FROM config
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query:SELECT id FROM outbound_email WHERE type = ‘system’
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query:SELECT * FROM outbound_email WHERE id = ‘3f9414ef-cfde-f7b0-df42-5d64c72339c3’
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Hook called: ::after_entry_point
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Including module specific hook file for custom/modules
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Including Ext hook file for custom/application
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Hook called: ::after_session_start
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Unable to find SugarController:: get
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] We have an authenticated user id: 1
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Hook called: Users::before_retrieve
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Including module specific hook file for custom/modules/Users
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0
Wed Aug 28 11:13:06 2019 [3468][-none-][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:06 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][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
Wed Aug 28 11:13:06 2019 [3468][1][INFO] Query:SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Wed Aug 28 11:13:06 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][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
Wed Aug 28 11:13:06 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Wed Aug 28 11:13:06 2019 [3468][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
Wed Aug 28 11:13:06 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] Hook called: Users::after_retrieve
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] FACTOR AUTH: --------------------- CHECK FACTOR AUtH ---------------------
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] FACTOR AUTH: User needs factor auth, request is not Logout
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] FACTOR AUTH: User does`nt need factor auth
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] Current user is: admin
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] Current user is: admin
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] setting cookie ck_login_id_20 to 1
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] setting cookie ck_login_language_20 to en_us
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] current_language is: en_us
Wed Aug 28 11:13:06 2019 [3468][1][INFO] Found language file: en_us.lang.php
Wed Aug 28 11:13:06 2019 [3468][1][INFO] Found extended language file: en_us.lang.ext.php
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] Performing action: action_get MODULE: Alerts
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] get_full_list: order_by = ‘alerts.date_entered’ and where = ‘alerts.assigned_user_id = ‘1’ AND is_read != ‘1’’
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] SugarBean[Alert].load_relationships, Loading relationship (modified_user_link).
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] SugarBean[Alert].load_relationships, Loading relationship (created_by_link).
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] SugarBean[Alert].load_relationships, Loading relationship (assigned_user_link).
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] process_order_by: (alerts.date_entered) does not have a vardef entry.
Wed Aug 28 11:13:06 2019 [3468][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
Wed Aug 28 11:13:06 2019 [3468][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
Wed Aug 28 11:13:06 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] process_full_list_query: result is mysqli_result Object
(
[current_field] => 0
[field_count] => 23
[lengths] =>
[num_rows] => 0
[type] => 0
)
Wed Aug 28 11:13:06 2019 [3468][1][DEPRECATED] Array
(
)
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] Hook called: ::server_round_trip
Wed Aug 28 11:13:06 2019 [3468][1][DEBUG] Calling MySQLi::disconnect()
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] current_language is: en_us
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheAPC
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheFile
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheMemcache
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheMemcached
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheMemory
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Using cache backend SugarCacheMemory, since 999 is less than 1000
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheRedis
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCachesMash
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheWincache
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Found cache backend SugarCacheZend
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Found language file: en_us.lang.php
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Found extended language file: en_us.lang.ext.php
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query:SELECT id, name, symbol, conversion_rate FROM currencies WHERE status = ‘Active’ and deleted = 0
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query:SELECT category, name, value FROM config
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query:SELECT id FROM outbound_email WHERE type = ‘system’
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query:SELECT * FROM outbound_email WHERE id = ‘3f9414ef-cfde-f7b0-df42-5d64c72339c3’
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Hook called: ::after_entry_point
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Including module specific hook file for custom/modules
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Including Ext hook file for custom/application
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Found language file: en_us.lang.php
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Found extended language file: en_us.lang.ext.php
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Hook called: Users::before_retrieve
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Including module specific hook file for custom/modules/Users
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0
Wed Aug 28 11:13:14 2019 [3716][-none-][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Loading Preferences DB admin
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT contents FROM user_preferences WHERE assigned_user_id=‘1’ AND category = ‘global’ AND deleted = 0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Users::after_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] --------------------------------------------> at cron.php <--------------------------------------------
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Using SugarCronJobs as CRON driver
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM job_queue WHERE status=‘running’ AND date_modified <= ‘2019-08-27 09:13:14’
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] get_full_list: order_by = ‘’ and where = ‘schedulers.status=‘Active’ AND NOT EXISTS(SELECT id FROM job_queue WHERE scheduler_id=schedulers.id AND status!=‘done’)’
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[Scheduler].load_relationships, Loading relationship (created_by_link).
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[Scheduler].load_relationships, Loading relationship (modified_user_link).
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list_query: query is SELECT schedulers.* , jt0.user_name created_by_name , jt0.created_by created_by_name_owner , ‘Users’ created_by_name_mod , jt1.user_name modified_by_name , jt1.created_by modified_by_name_owner , ‘Users’ modified_by_name_mod FROM schedulers LEFT JOIN users jt0 ON jt0.id=schedulers.created_by AND jt0.deleted=0
AND jt0.deleted=0 LEFT JOIN users jt1 ON schedulers.modified_user_id=jt1.id AND jt1.deleted=0
AND jt1.deleted=0 where (schedulers.status=‘Active’ AND NOT EXISTS(SELECT id FROM job_queue WHERE scheduler_id=schedulers.id AND status!=‘done’)) AND schedulers.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query: SELECT schedulers.* , jt0.user_name created_by_name , jt0.created_by created_by_name_owner , ‘Users’ created_by_name_mod , jt1.user_name modified_by_name , jt1.created_by modified_by_name_owner , ‘Users’ modified_by_name_mod FROM schedulers LEFT JOIN users jt0 ON jt0.id=schedulers.created_by AND jt0.deleted=0
AND jt0.deleted=0 LEFT JOIN users jt1 ON schedulers.modified_user_id=jt1.id AND jt1.deleted=0
AND jt1.deleted=0 where (schedulers.status=‘Active’ AND NOT EXISTS(SELECT id FROM job_queue WHERE scheduler_id=schedulers.id AND status!=‘done’)) AND schedulers.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list_query: result is mysqli_result Object
(
[current_field] => 0
[field_count] => 22
[lengths] =>
[num_rows] => 1
[type] => 0
)
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): id = 9ecb2e5a-dae0-8043-94de-5d64c77e491b
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): deleted = 0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): date_entered = 2019-08-27 06:00:51
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): date_modified = 2019-08-28 08:45:27
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): created_by = 1
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): created_by_name = admin
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): modified_user_id = 1
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): modified_by_name = admin
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): name = Run Report Generation Scheduled Tasks
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): job = function::aorRunScheduledReports
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): date_time_start = 2015-01-01 18:30:00
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): job_interval = /1::::::::*
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): last_run = 2019-08-28 09:10:01
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): status = Active
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: Scheduler(9ecb2e5a-dae0-8043-94de-5d64c77e491b): catch_up = 1
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Schedulers::process_record
Wed Aug 28 11:13:14 2019 [3716][1][INFO] -----> Scheduler found [ 1 ] ACTIVE jobs
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] ----->Schedulers->deriveDBDateTimes() got an object of type: Scheduler
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] ----->got * day
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] ----->got * months
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] ----->got * dates
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] ----->got * hours
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Constraints: start: 2015-01-01 18:30:00 from: 1970-01-01 00:00:00 end: 2019-08-29 09:13:15 to: 2019-08-29 09:13:15 now: 2019-08-28 09:13:14
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] ----->Scheduler found valid job (Run Report Generation Scheduled Tasks) for time GMT(2019-08-28 09:13:00)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Get One: |SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’|
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’ Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’ LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Users::before_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Users::after_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Get One: |SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’|
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’ Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’ LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Users::before_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Users::after_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::before_save
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:INSERT INTO job_queue (assigned_user_id,id,name,deleted,date_entered,date_modified,scheduler_id,execute_time,status,resolution,target,requeue,job_delay,client)
VALUES (‘1’,‘86d25dd6-b878-08dd-722e-5d66457ae907’,‘Run Report Generation Scheduled Tasks’,0,‘2019-08-28 09:13:14’,‘2019-08-28 09:13:14’,‘9ecb2e5a-dae0-8043-94de-5d64c77e491b’,‘2019-08-28 09:13:14’,‘queued’,‘queued’,‘function::aorRunScheduledReports’,0,0,’’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0.046799898147583
Wed Aug 28 11:13:14 2019 [3716][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’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = ‘SecurityGroups’ OR rhs_module=‘SecurityGroups’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = ‘SecurityGroups’ OR rhs_module=‘SecurityGroups’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = ‘SecurityGroups’ OR rhs_module=‘SecurityGroups’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = ‘SecurityGroups’ OR rhs_module=‘SecurityGroups’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::after_save
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AOD_LogicHooks without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: AOD_Index::before_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Retrieve AOD_Index : SELECT aod_index.* FROM aod_index WHERE aod_index.id = ‘1’ AND aod_index.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT aod_index.* FROM aod_index WHERE aod_index.id = ‘1’ AND aod_index.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT aod_index.* FROM aod_index WHERE aod_index.id = ‘1’ AND aod_index.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[AOD_Index].load_relationships, Loading relationship (assigned_user_id).
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (assigned_user_id)
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[AOD_Index].load_relationships, Loading relationship (assigned_user_id).
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (assigned_user_id)
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: AOD_Index::after_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class SuiteCRM\Search\ElasticSearch\ElasticSearchHooks without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AOW_WorkFlow without parameters
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM aow_workflow WHERE aow_workflow.flow_module = ‘SchedulersJobs’ 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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Get One: |SELECT id FROM job_queue WHERE execute_time <= ‘2019-08-28 09:13:14’ AND status = ‘queued’ ORDER BY date_entered ASC|
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT id FROM job_queue WHERE execute_time <= ‘2019-08-28 09:13:14’ AND status = ‘queued’ ORDER BY date_entered ASC Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM job_queue WHERE execute_time <= ‘2019-08-28 09:13:14’ AND status = ‘queued’ ORDER BY date_entered ASC LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::before_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Retrieve SchedulersJob : SELECT job_queue.* FROM job_queue WHERE job_queue.id = ‘86d25dd6-b878-08dd-722e-5d66457ae907’ AND job_queue.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT job_queue.* FROM job_queue WHERE job_queue.id = ‘86d25dd6-b878-08dd-722e-5d66457ae907’ AND job_queue.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT job_queue.* FROM job_queue WHERE job_queue.id = ‘86d25dd6-b878-08dd-722e-5d66457ae907’ AND job_queue.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::after_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:UPDATE job_queue SET status=‘running’, date_modified=‘2019-08-28 09:13:14’, client=‘CRON713c6a726c9b731ca75193dcd1df4d61:3716’ WHERE id=‘86d25dd6-b878-08dd-722e-5d66457ae907’ AND status=‘queued’
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0.031200170516968
Wed Aug 28 11:13:14 2019 [3716][1][DEPRECATED] Formatting correction: SchedulersJobs->date_entered had formatting automatically corrected. This will be removed in the future, please upgrade your external code
Wed Aug 28 11:13:14 2019 [3716][1][DEPRECATED] Formatting correction: SchedulersJobs->date_modified had formatting automatically corrected. This will be removed in the future, please upgrade your external code
Wed Aug 28 11:13:14 2019 [3716][1][DEPRECATED] Formatting correction: SchedulersJobs->execute_time had formatting automatically corrected. This will be removed in the future, please upgrade your external code
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::before_save
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:UPDATE job_queue
SET assigned_user_id=‘1’,name=‘Run Report Generation Scheduled Tasks’,date_modified=‘2019-08-28 09:13:14’,scheduler_id=‘9ecb2e5a-dae0-8043-94de-5d64c77e491b’,execute_time=‘2019-08-28 09:13:00’,status=‘running’,resolution=‘queued’,message=NULL,target=‘function::aorRunScheduledReports’,data=NULL,requeue=0,retry_count=NULL,failure_count=NULL,job_delay=0,client=‘CRON713c6a726c9b731ca75193dcd1df4d61:3716’,percent_complete=NULL
WHERE job_queue.id = ‘86d25dd6-b878-08dd-722e-5d66457ae907’ AND deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0.031199932098389
Wed Aug 28 11:13:14 2019 [3716][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’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = ‘SecurityGroups’ OR rhs_module=‘SecurityGroups’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::after_save
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AOD_LogicHooks without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class SuiteCRM\Search\ElasticSearch\ElasticSearchHooks without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AOW_WorkFlow without parameters
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM aow_workflow WHERE aow_workflow.flow_module = ‘SchedulersJobs’ 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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Users::before_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Wed Aug 28 11:13:14 2019 [3716][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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: Users::after_retrieve
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] ----->SchedulersJob calling function: aorRunScheduledReports
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] get_full_list: order_by = ‘’ and where = ‘’
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[AOR_Scheduled_Reports].load_relationships, Loading relationship (modified_user_link).
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[AOR_Scheduled_Reports].load_relationships, Loading relationship (created_by_link).
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean[AOR_Scheduled_Reports].load_relationships, Loading relationship (aor_scheduled_reports_aor_reports).
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] SugarBean.load_relationships, failed Loading relationship (aor_scheduled_reports_aor_reports)
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list_query: query is SELECT aor_scheduled_reports.* , 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 FROM aor_scheduled_reports LEFT JOIN users jt0 ON aor_scheduled_reports.modified_user_id=jt0.id AND jt0.deleted=0
AND jt0.deleted=0 LEFT JOIN users jt1 ON aor_scheduled_reports.created_by=jt1.id AND jt1.deleted=0
AND jt1.deleted=0 where aor_scheduled_reports.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query: SELECT aor_scheduled_reports.* , 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 FROM aor_scheduled_reports LEFT JOIN users jt0 ON aor_scheduled_reports.modified_user_id=jt0.id AND jt0.deleted=0
AND jt0.deleted=0 LEFT JOIN users jt1 ON aor_scheduled_reports.created_by=jt1.id AND jt1.deleted=0
AND jt1.deleted=0 where aor_scheduled_reports.deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list_query: result is mysqli_result Object
(
[current_field] => 0
[field_count] => 19
[lengths] =>
[num_rows] => 1
[type] => 0
)
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): id = 317701ef-f300-24c2-22de-5d64e353182c
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): name = test
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): date_entered = 2019-08-27 08:00:18
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): date_modified = 2019-08-28 09:12:53
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): modified_user_id = 1
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): modified_by_name = admin
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): created_by = 1
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): created_by_name = admin
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): deleted = 0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): schedule = 43 13 * * *
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): status = active
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): email_recipients = YToyOntzOjE3OiJlbWFpbF90YXJnZXRfdHlwZSI7YToxOntpOjA7czo1OiJVc2VycyI7fXM6NToiZW1haWwiO2E6MTp7aTowO2E6Mzp7aTowO3M6MzoiYWxsIjtpOjE7czowOiIiO2k6MjtzOjA6IiI7fX19
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] process_full_list: AOR_Scheduled_Reports(317701ef-f300-24c2-22de-5d64e353182c): aor_report_id = d700f974-3361-b937-d843-5d64de085eb9
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: AOR_Scheduled_Reports::process_record
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Resolving job 86d25dd6-b878-08dd-722e-5d66457ae907 as success:
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::before_save
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:UPDATE job_queue
SET assigned_user_id=‘1’,name=‘Run Report Generation Scheduled Tasks’,date_modified=‘2019-08-28 09:13:14’,scheduler_id=‘9ecb2e5a-dae0-8043-94de-5d64c77e491b’,execute_time=‘2019-08-28 09:13:00’,status=‘done’,resolution=‘success’,message=NULL,target=‘function::aorRunScheduledReports’,data=NULL,requeue=0,retry_count=NULL,failure_count=NULL,job_delay=0,client=‘CRON713c6a726c9b731ca75193dcd1df4d61:3716’,percent_complete=NULL
WHERE job_queue.id = ‘86d25dd6-b878-08dd-722e-5d66457ae907’ AND deleted=0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0.062400102615356
Wed Aug 28 11:13:14 2019 [3716][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’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT lhs_module, rhs_module FROM relationships WHERE deleted=0 AND (lhs_module = ‘SecurityGroups’ OR rhs_module=‘SecurityGroups’)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: SchedulersJobs::after_save
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AOD_LogicHooks without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class SuiteCRM\Search\ElasticSearch\ElasticSearchHooks without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AssignGroups without parameters
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Creating new instance of hook class AOW_WorkFlow without parameters
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM aow_workflow WHERE aow_workflow.flow_module = ‘SchedulersJobs’ 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
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:UPDATE schedulers SET last_run=‘2019-08-28 09:13:14’ WHERE id=‘9ecb2e5a-dae0-8043-94de-5d64c77e491b’
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0.031199932098389
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Get One: |SELECT id FROM job_queue WHERE execute_time <= ‘2019-08-28 09:13:14’ AND status = ‘queued’ ORDER BY date_entered ASC|
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Limit Query:SELECT id FROM job_queue WHERE execute_time <= ‘2019-08-28 09:13:14’ AND status = ‘queued’ ORDER BY date_entered ASC Start: 0 count: 1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:SELECT id FROM job_queue WHERE execute_time <= ‘2019-08-28 09:13:14’ AND status = ‘queued’ ORDER BY date_entered ASC LIMIT 0,1
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Hook called: ::server_round_trip
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Calling MySQLi::disconnect()
Wed Aug 28 11:13:14 2019 [3716][1][DEBUG] Calling MySQLi::disconnect()
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] current_language is: en_us
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheAPC
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheFile
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheMemcache
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheMemcached
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheMemory
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Using cache backend SugarCacheMemory, since 999 is less than 1000
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheRedis
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCachesMash
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheWincache
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Found cache backend SugarCacheZend
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Found language file: en_us.lang.php
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Found extended language file: en_us.lang.ext.php
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query:SELECT id, name, symbol, conversion_rate FROM currencies WHERE status = ‘Active’ and deleted = 0
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query:SELECT category, name, value FROM config
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query Execution Time:0.015599966049194
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query:SELECT id FROM outbound_email WHERE type = ‘system’
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query Execution Time:0.015599966049194
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query:SELECT * FROM outbound_email WHERE id = ‘3f9414ef-cfde-f7b0-df42-5d64c72339c3’
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Hook called: ::after_entry_point
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Including module specific hook file for custom/modules
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Including Ext hook file for custom/application
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Hook called: ::after_session_start
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Unable to find SugarController:: LogView
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] We have an authenticated user id: 1
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Hook called: Users::before_retrieve
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Including module specific hook file for custom/modules/Users
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0
Wed Aug 28 11:13:19 2019 [3468][-none-][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 Start: 0 count: 1
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query:SELECT users.* FROM users WHERE users.id = ‘1’ AND users.deleted=0 LIMIT 0,1
Wed Aug 28 11:13:19 2019 [3468][-none-][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][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
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Query:SELECT id, first_name, last_name, user_name FROM users WHERE 1=1 ORDER BY first_name, last_name ASC
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][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
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Wed Aug 28 11:13:19 2019 [3468][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
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] Hook called: Users::after_retrieve
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] FACTOR AUTH: --------------------- CHECK FACTOR AUtH ---------------------
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] FACTOR AUTH: -------------------------------------------------------------
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] FACTOR AUTH: User needs factor auth, request is not Logout
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] FACTOR AUTH: User does`nt need factor auth
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] Current user is: admin
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] Current user is: admin
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] setting cookie ck_login_id_20 to 1
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] setting cookie ck_login_language_20 to en_us
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] current_language is: en_us
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Found language file: en_us.lang.php
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Found extended language file: en_us.lang.ext.php
Wed Aug 28 11:13:19 2019 [3468][1][DEBUG] Module:Configurator using file: LogView
Wed Aug 28 11:13:19 2019 [3468][1][DEPRECATED] Array
(
)
Wed Aug 28 11:13:19 2019 [3468][1][WARN] CSS File Dawn/yui.css not found
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Query:SELECT parent_id, parent_type FROM favorites WHERE assigned_user_id = ‘1’ AND deleted = 0 ORDER BY date_entered DESC
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][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
Wed Aug 28 11:13:19 2019 [3468][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
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Query Execution Time:0
Wed Aug 28 11:13:19 2019 [3468][1][INFO] Tracker: retrieving 10 items
Please help me thanks in advance
I didn’t see any errors in log.
What SuiteCRM version are you using?
Dear sir,
I am using suitecrm 7.11.8
I think here is possibility of error,
2.scheduled report last run time showing blank (Last run:…),(Set time -->Schedule: Daily at 13:43)
but according to suitcrm log it is execute on (execute_time='2019-08-28 09:13:00',status`='done)
Wed Aug 28 11:13:14 2019 [3716][1][INFO] Query:UPDATE job_queue
SET assigned_user_id=‘1’,name=‘Run Report Generation Scheduled Tasks’,date_modified=‘2019-08-28 09:13:14’,scheduler_id=‘9ecb2e5a-dae0-8043-94de-5d64c77e491b’,execute_time=‘2019-08-28 09:13:00’,status=‘done’,resolution=‘success’,message=NULL,target=‘function::aorRunScheduledReports’,data=NULL,requeue=0,retry_count=NULL,failure_count=NULL,job_delay=0,client=‘CRON713c6a726c9b731ca75193dcd1df4d61:3716’,percent_complete=NULL
WHERE job_queue.id = ‘86d25dd6-b878-08dd-722e-5d66457ae907’ AND deleted=0
3.showing two notice in setting of scheduled report
Notice: Undefined index: aor_email_to_list in C:\xampp\htdocs\zamp\modules\AOR_Scheduled_Reports\emailRecipients.php on line 49
Notice: Undefined index: email_to_type in C:\xampp\htdocs\zamp\modules\AOR_Scheduled_Reports\emailRecipients.php on line 61
so i think some thing wrong please check whats a problem.
thanks in Advance
Dear sir,
Please give a idea regarding solution