Inbound Job Seems to work but not even try to connect to mail server

Hi, in my log I have this lines
Thu Sep 14 18:36:01 2017 [30758][1][DEBUG] process_full_list: Scheduler(ea46f40d-6b90-5a70-0892-592be2586255): name = Check Inbound Mailboxes
Thu Sep 14 18:36:01 2017 [30758][1][DEBUG] ----->Scheduler found valid job (Check Inbound Mailboxes) for time GMT(2017-09-14 16:36:00)
VALUES (‘1’,‘d61e98a1-9f26-32a9-b646-59baaf41a2a7’,‘Check Inbound Mailboxes’,0,‘2017-09-14 16:36:01’,‘2017-09-14 16:36:01’,‘ea46f40d-6b90-5a70-0892-592be2586255’,‘2017-09-14 16:36:01’,‘queued’,‘queued’,‘function::pollMonitoredInboxesAOP’,0,0,’’)
SET assigned_user_id=‘1’,name=‘Check Inbound Mailboxes’,date_modified=‘2017-09-14 16:36:01’,scheduler_id=‘ea46f40d-6b90-5a70-0892-592be2586255’,execute_time=‘2017-09-14 16:36:00’,status=‘running’,resolution=‘queued’,message=NULL,target=‘function::pollMonitoredInboxesAOP’,data=NULL,requeue=0,retry_count=NULL,failure_count=NULL,job_delay=0,client=‘CRON674ccce78f8db20882832d93dfc07a26:30758’,percent_complete=NULL
SET assigned_user_id=‘1’,name=‘Check Inbound Mailboxes’,date_modified=‘2017-09-14 16:36:01’,scheduler_id=‘ea46f40d-6b90-5a70-0892-592be2586255’,execute_time=‘2017-09-14 16:36:00’,status=‘done’,resolution=‘success’,message=NULL,target=‘function::pollMonitoredInboxesAOP’,data=NULL,requeue=0,retry_count=NULL,failure_count=NULL,job_delay=0,client=‘CRON674ccce78f8db20882832d93dfc07a26:30758’,percent_complete=NULL

but when I check the mail server logs i don’t see any connection attempt. The inbound configuration test works perfectly, but the job doesn’t

That’s just the Scheduler deciding it will run the Job, it doesn’t show any messages from the Job itself.

Either you can find them a little bit below in the log, or maybe it’s getting some FATAL error and disappearing without any message. You probably have something in php_errors.log in that case, can you check?

Sorry, you’re right

Thu Sep 14 18:17:01 2017 [30582][1][DEBUG] ----->Scheduler found valid job (Check Inbound Mailboxes) for time GMT(2017-09-14 16:17:00)
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Get One: |SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’|
Thu Sep 14 18:17:01 2017 [30582][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
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query:SELECT id FROM users WHERE id = ‘1’ AND is_admin = 1 AND deleted = 0 AND status = ‘Active’ LIMIT 0,1
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query Execution Time:0.00038290023803711
Thu Sep 14 18:17:01 2017 [30582][1][DEBUG] Hook called: Users::before_retrieve
Thu Sep 14 18:17:01 2017 [30582][1][DEBUG] Retrieve User : SELECT users.,users_cstm. FROM users LEFT JOIN users_cstm ON users.id = users_cstm.id_c WHERE users.id = ‘1’ AND users.deleted=0
Thu Sep 14 18:17:01 2017 [30582][1][DEBUG] Limit Query:SELECT users.,users_cstm. FROM users LEFT JOIN users_cstm ON users.id = users_cstm.id_c WHERE users.id = ‘1’ AND users.deleted=0 Start: 0 count: 1
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query:SELECT users.,users_cstm. FROM users LEFT JOIN users_cstm ON users.id = users_cstm.id_c WHERE users.id = ‘1’ AND users.deleted=0 LIMIT 0,1
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query Execution Time:0.0004429817199707
Thu Sep 14 18:17:01 2017 [30582][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
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query Execution Time:0.00039792060852051
Thu Sep 14 18:17:01 2017 [30582][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, 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
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query Execution Time:0.00047612190246582
Thu Sep 14 18:17:01 2017 [30582][1][DEBUG] Hook called: Users::after_retrieve
Thu Sep 14 18:17:01 2017 [30582][1][DEBUG] Hook called: SchedulersJobs::before_save
Thu Sep 14 18:17:01 2017 [30582][1][DEPRECATED] SugarBean.php: preprocess_fields_on_save() is deprecated
Thu Sep 14 18:17:01 2017 [30582][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’,‘9b5d8627-5839-7958-4505-59baabfbbf62’,‘Check Inbound Mailboxes’,0,‘2017-09-14 16:17:01’,‘2017-09-14 16:17:01’,‘ea46f40d-6b90-5a70-0892-592be2586255’,‘2017-09-14 16:17:01’,‘queued’,‘queued’,‘function::pollMonitoredInboxesAOP’,0,0,’’)
Thu Sep 14 18:17:01 2017 [30582][1][INFO] Query Execution Time:0.0062839984893799
Thu Sep 14 18:17:01 2017 [30582][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’)

No fatal entries except:
Thu Sep 14 19:09:45 2017 [1301][-none-][FATAL] Configuration variable date.timezone is not set, guessed timezone Europe/Berlin. Please set date.timezone=“Europe/Berlin” in php.ini!

You need to correct the timezone error, it’s FATAL, it blocks execution.

Since this is a Scheduler job, you need to tweak the php.ini for the CLI, not the one for the web server.

Read the section called “What php.ini settings apply to the cron jobs?” here.

I fixed the time zone error.

Maybe the problem is a misconfiguration.

I want to use an account to manage the cases emails. When I update a case an email is sent to the customer. what I want is that if the customer answers, automatically attach the mail to the case.

I configured an account as Bounce Handling account, maybe this is the problem. I don’t know if I missed something.

Ok, I figured out. I just created a group email ans suitecrm import the mails. My mistake.

So, the FATAL error is now gone, and your log shows that the Inbound Email job is running, is that correct?

1 Like

Yes, the problem was that I don’t configured any group account, just the bounce mail account.

But now the emails of the case updates are not sent…i’m going crazy :slight_smile:

I will open another thread for that.

NO! sorry. All seems to work correctly.

Finally the only problem (besides the time zone) was that I didn’t configure any inbound account.

I’m glad you sorted things out!

Thank you for your time!!!