Imap error spamming logs

maverickws · 10 October 2023 17:06

Ok so the only issues I found using the keywords were these:

github.com/salesagility/SuiteCRM

Run Email Notification not working

opened 01:33PM - 24 Feb 19 UTC

closed 04:12PM - 11 Feb 20 UTC

wanesmith

Type:Bug Status:Fix Proposed Priority:Critical Area: Emails

Users are not getting notifications for email and for meetings Nothing I could …find is simular #### Issue Run Email Notification is running but no notification is being sent #### Expected Behavior Notifications to be sent #### Actual Behavior PHP Notice: Trying to get property of non-object in /power-choice/web/crm.company.com.au/public_html/crm/modules/Activities/EmailReminder.php on line 163 PHP Fatal error: Uncaught Error: Call to a member function getReplyToAddress() on null in /power-choice/web/crm.company.com.au/public_html/crm/modules/Activities/EmailReminder.php:163 Stack trace: #0 /power-choice/web/crm.company.com.au/public_html/crm/modules/Reminders/Reminder.php(212): EmailReminder->sendReminders(Object(Call), Object(Administration), Array) #1 /power-choice/web/crm.company.com.au/public_html/crm/modules/Activities/EmailReminder.php(97): Reminder::sendEmailReminders(Object(EmailReminder), Object(Administration)) #2 /power-choice/web/crm.company.com.au/public_html/crm/modules/Schedulers/_AddJobsHere.php(435): EmailReminder->process() #3 /power-choice/web/crm.company.com.au/public_html/crm/modules/SchedulersJobs/SchedulersJob.php(542): sendEmailReminders(Object(SchedulersJob)) #4 /power-choice/web/crm.compnay.com.au/public_html/crm/include/SugarQueue/SugarCronJobs.php(191): SchedulersJob->runJob() #5 /power-choice/web/crm.companycom.au/public_html/crm/include/SugarQueue/Su in /power-choice/web/crm.company.com.au/public_html/crm/modules/Activities/EmailReminder.php on line 163 #### Possible Fix #### Steps to Reproduce run the job get the error. #### Context can not run one of the core function of the system #### Your Environment * SuiteCRM Version used: Version 7.11.2 Sugar Version 6.5.25 (Build 344) * Browser name and version (e.g. Chrome Version 51.0.2704.63 (64-bit)): ![image](https://user-images.githubusercontent.com/2706403/53299880-4ade7c00-3873-11e9-9cfc-f3a0f7ccb524.png) * Environment name and version (e.g. MySQL, PHP 7): php 7.1 , mysql * Operating System and version (e.g Ubuntu 16.04): Selinux 7.6

github.com/salesagility/SuiteCRM

Emails synchronization does not work anymore

opened 02:26PM - 28 Apr 22 UTC

closed 11:59AM - 02 May 22 UTC

yassinefrikha

After upgrading from 7.11.x to 7.12.5, the pollMonitoredInboxes function in the… scheduler no longer works in the cron job. The rest of the tasks in the scheduler work fine. #### Expected Behavior If you have activated pollMonitoredInboxes in the scheduler, the emails must be synchronized #### Actual Behavior Cron job is executed, but emails are not synchronized. In Suitecrm.log there is only this error message: Job xxxx-xxxx-xxxx-xx (synchronize emails) failed in CRON run After debugging I got this error pollMonitoredInboxes Thu Apr 28 16:35:01 2022 [62121][1][DEBUG] Hook called: InboundEmail::after_retrieve Thu Apr 28 16:35:01 2022 [62121][1][WARN] Request ssl value not found. Thu Apr 28 16:35:01 2022 [62121][1][WARN] Requested folder is not defined Thu Apr 28 16:35:01 2022 [62121][1][ERROR] ImapHandler trying to use a non valid resource stream. Thu Apr 28 16:35:01 2022 [62121][1][ERROR] ImapHandler trying to use a non valid resource stream. Thu Apr 28 16:35:01 2022 [62121][1][INFO] Connected to mail server id: xxxxxxxxxxxxxxxx Thu Apr 28 16:35:01 2022 [62121][1][DEBUG] Trying to connect to mailserver for [ xxxxxxxxx ] Thu Apr 28 16:35:01 2022 [62121][1][DEBUG] Connected to mailserver Thu Apr 28 16:35:01 2022 [62121][1][WARN] An Imap error detected: "IMAP search error" Thu Apr 28 16:35:01 2022 [62121][1][DEBUG] Hook called: ::server_round_trip Thu Apr 28 16:35:01 2022 [62121][1][DEBUG] Calling MySQLi::disconnect() Thu Apr 28 16:35:01 2022 [62121][1][FATAL] Job 486187da-6f21-d204-4f4c-626aa53e8805 (Emails synchronisieren) failed in CRON run Thu Apr 28 16:35:01 2022 [62121][1][INFO] Resolving job 486187da-6f21-d204-4f4c-626aa53e8805 as failure: Unerwarteter Fehler, bitte überprüfen Sie die Logs von SuiteCRM und php. #### Context This has a very high priority, since a crm system without email function makes no sense #### Environment * SuiteCRM Version used: 7.12.5 * Browser name and version : Not relevant * PHP Version: 7.4 * PHP-cli Version: 7.4 * MySQL: 5.7.37 * Operating System and version: Debian GNU/Linux 10 (buster)

which are different from mine. I also noticed (had missed this before really) that I had php 7.4 in use, changed to 8.2 and the errors increased.
Reported it all here

github.com/salesagility/SuiteCRM

[FATAL] ImapHandler trying to use a non valid resource stream - log spam

opened 05:04PM - 10 Oct 23 UTC

maverick85

#### Issue Logs get the following lines every minute (PHP 7.4): ``` Tue Oct… 10 14:58:07 2023 [53772][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 14:58:07 2023 [53772][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 14:58:07 2023 [53772][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 14:59:07 2023 [53783][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 14:59:07 2023 [53783][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 14:59:07 2023 [53783][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:00:07 2023 [53792][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:00:07 2023 [53792][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:00:07 2023 [53792][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:01:07 2023 [53812][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:01:07 2023 [53812][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:01:07 2023 [53812][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:02:07 2023 [53821][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:02:07 2023 [53821][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:02:07 2023 [53821][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:03:07 2023 [53832][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:03:07 2023 [53832][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:03:07 2023 [53832][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:04:07 2023 [53841][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:04:07 2023 [53841][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:04:07 2023 [53841][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:05:07 2023 [53850][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:05:07 2023 [53850][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:05:07 2023 [53850][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:06:07 2023 [53861][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:06:07 2023 [53861][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:06:07 2023 [53861][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:07:07 2023 [53871][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:07:07 2023 [53871][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:07:07 2023 [53871][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:08:07 2023 [53881][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:08:07 2023 [53881][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:08:07 2023 [53881][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:09:07 2023 [53892][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:09:07 2023 [53892][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:09:07 2023 [53892][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:10:07 2023 [53901][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:10:07 2023 [53901][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:10:07 2023 [53901][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:11:07 2023 [53911][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:11:07 2023 [53911][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:11:07 2023 [53911][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:12:07 2023 [53921][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:12:07 2023 [53921][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:12:07 2023 [53921][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:13:07 2023 [53932][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:13:07 2023 [53932][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:13:07 2023 [53932][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:14:07 2023 [53943][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:14:07 2023 [53943][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:14:07 2023 [53943][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:15:07 2023 [53953][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:15:07 2023 [53953][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:15:07 2023 [53953][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:16:07 2023 [53962][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:16:07 2023 [53962][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:16:07 2023 [53962][1][FATAL] An Imap error detected: "IMAP search error" Tue Oct 10 15:17:07 2023 [53976][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:17:07 2023 [53976][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 15:17:07 2023 [53976][1][FATAL] An Imap error detected: "IMAP search error" ``` With PHP 8.2 you get a bit more as with each suitecrm entry you also get a PHP Deprecation Notice: ``` ==> /var/www/develop/public_html/sales/public/legacy/suitecrm.log <== Tue Oct 10 17:54:08 2023 [56153][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 17:54:08 2023 [56153][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 17:54:08 2023 [56153][1][FATAL] An Imap error detected: "IMAP search error" ==> /var/log/php-fpm/www-error.log <== [10-Oct-2023 17:54:19 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 134 [10-Oct-2023 17:54:19 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 144 [10-Oct-2023 17:54:19 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 131 [10-Oct-2023 17:54:19 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 141 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 134 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 144 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 131 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 141 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 134 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 144 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 131 [10-Oct-2023 17:54:34 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 141 ==> /var/www/develop/public_html/sales/public/legacy/suitecrm.log <== Tue Oct 10 17:55:08 2023 [56162][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 17:55:08 2023 [56162][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 17:55:08 2023 [56162][1][FATAL] An Imap error detected: "IMAP search error" ==> /var/log/php-fpm/www-error.log <== [10-Oct-2023 17:55:17 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 134 [10-Oct-2023 17:55:17 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 144 [10-Oct-2023 17:55:17 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 131 [10-Oct-2023 17:55:17 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 141 ==> /var/www/develop/public_html/sales/public/legacy/suitecrm.log <== Tue Oct 10 17:56:08 2023 [56171][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 17:56:08 2023 [56171][1][FATAL] ImapHandler trying to use a non valid resource stream. Tue Oct 10 17:56:08 2023 [56171][1][FATAL] An Imap error detected: "IMAP search error" ==> /var/log/php-fpm/www-error.log <== [10-Oct-2023 17:56:15 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 134 [10-Oct-2023 17:56:15 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Attribute\AttributeBag::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Attribute/AttributeBag.php on line 144 [10-Oct-2023 17:56:15 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::getIterator() should either be compatible with IteratorAggregate::getIterator(): Traversable, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 131 [10-Oct-2023 17:56:15 Europe/Lisbon] PHP Deprecated: Return type of Symfony\Component\HttpFoundation\Session\Session::count() should either be compatible with Countable::count(): int, or the #[\ReturnTypeWillChange] attribute should be used to temporarily suppress the notice in /var/www/develop/public_html/sales/vendor/symfony/http-foundation/Session/Session.php on line 141 ``` I've narrowed down the issue to the "Group" account with some help of the mail server, as every entry on suitecrm corresponds to an entry there: ``` 10/10/2023, 17:04:07 info imap(sales@domain.tld)<301935><Pbg+214HFZd0y66c>: Disconnected: Connection closed (CLOSE finished 0.117 secs ago) in=48 out=869 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0 10/10/2023, 17:04:06 info imap-login: Login: user=<sales@domain.tld>, method=PLAIN, rip=red.act.ed.ip, lip=172.22.1.250, mpid=301935, TLS, TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) ``` which looks like a clean exit. Related account configuration: - Server Configuration: basic auth IMAP at Port 993 Use SSL Connection String empty Monitored folders: INBOX Trash Sent - Outbound Config Allow users to send using from: yes from name: sales from address: sales email address reply to name: sales reply-to address: sales email address Auto Reply Config all empty as of yet - Group Configuration Import emails automatically: checked move messages to trash after import: checked - Case Configuration Create case from email: not checked a side note, each time I edit any inbound email account and hit "Test Account" I get two additional errors on the php error log: ``` [10-Oct-2023 16:51:47 Europe/Lisbon] PHP Notice: Undefined index: externalOauthConnectionName in /var/www/develop/public_html/sales/public/legacy/modules/InboundEmail/Popup.php on line 127 [10-Oct-2023 16:51:47 Europe/Lisbon] PHP Notice: Undefined index: externalOauthConnectionId in /var/www/develop/public_html/sales/public/legacy/modules/InboundEmail/Popup.php on line 130 ``` #### Expected Behavior No errors on logs #### Actual Behavior spamming logs every minute #### Steps to Reproduce 1. Add group email account (IMAP, secure, vanilla) 2. Look at the logs #### Context IT affects me as it keeps spamming logs when I'm looking for other stuff #### Your Environment - SuiteCRM 8.4.1 - Safari Version 17.0 (17616.1.27.111.22, 17616) - the issue is browser agnostic as it is not front-end related - Operative System: `AlmaLinux 9.2 (Turquoise Kodkod)` - Web Server: `Apache/2.4.53 (AlmaLinux)` - PHP Version: `PHP 8.2.11 (fpm-fcgi) (built: Sep 26 2023 11:11:58)`