Open kristofejro opened 11 months ago
ChristophWurst
commented
11 months ago does the account not have a single mailbox?
Horde lists all IMAP mailboxes, then take the first one using the reset function. The function returns false for empty arrays: https://www.php.net/manual/en/function.reset.php. The array access of delimiter on false causes the error.
kristofejro
commented
11 months ago I don't exactly know what you mean.
As I described at the begining I have 3 regular mailboxes (extenrnal commercial free accounts/mailboxes) configured in mail app in nextcloud. I can read all my emails in mail app.
Maybe these accounts are somehow wrongly configured in Nextcloud so the error appears?
ChristophWurst
commented
11 months ago Accounts != mailboxes
If the error appears during sync, I suggest to sync via the CLI with debug mode on and inspect the Horde IMAP log:
1) https://github.com/nextcloud/mail/blob/main/doc/admin.md#get-account-ids to find the account id 2) https://github.com/nextcloud/mail/blob/main/doc/admin.md#manual-account-synchronization-and-threading but prepend the command with NC_debug=true 3) Inspect data/horde_imap.log
kristofejro
commented
11 months ago I have followed your directions and below are results.
Accounts syncing from console:
[debug] account is up to date, skipping mailbox sync
[debug] Skipping mailbox sync for 16
[debug] Syncing 19
[debug] Locking mailbox 19 for new messages sync
[debug] Locking mailbox 19 for changed messages sync
[debug] Locking mailbox 19 for vanished messages sync
[debug] Running partial sync for 19
[debug] partial sync 2:INBOX - get all known UIDs took 0s. 9/10MB memory used
[debug] partial sync 2:INBOX - get new messages via Horde took 0s. 9/10MB memory used
[debug] partial sync 2:INBOX - persist new messages took 0s. 9/10MB memory used
[debug] partial sync 2:INBOX - get changed messages via Horde took 0s. 9/10MB memory used
[debug] partial sync 2:INBOX - persist changed messages took 0s. 9/10MB memory used
[debug] partial sync 2:INBOX - get vanished messages via Horde took 0s. 9/10MB memory used
[debug] partial sync 2:INBOX - delete vanished messages took 0s. 9/10MB memory used
[debug] partial sync 2:INBOX took 0s
[debug] Unlocking mailbox 19 from vanished messages sync
[debug] Unlocking mailbox 19 from changed messages sync
[debug] Unlocking mailbox 19 from new messages sync
[debug] Skipping mailbox sync for 17
[debug] Skipping mailbox sync for 18
[debug] Skipping mailbox sync for 15
[debug] Skipping threading as there were no significant changes
10MB of memory used
[debug] account is up to date, skipping mailbox sync
[debug] Skipping mailbox sync for 9
[debug] Syncing 12
[debug] Locking mailbox 12 for new messages sync
[debug] Locking mailbox 12 for changed messages sync
[debug] Locking mailbox 12 for vanished messages sync
[debug] Running partial sync for 12
[debug] partial sync 3:INBOX - get all known UIDs took 0s. 9/10MB memory used
[debug] partial sync 3:INBOX - get new messages via Horde took 0s. 9/10MB memory used
[debug] partial sync 3:INBOX - persist new messages took 0s. 9/10MB memory used
[debug] partial sync 3:INBOX - get changed messages via Horde took 1s. 10/10MB memory used
[debug] partial sync 3:INBOX - persist changed messages took 0s. 10/10MB memory used
[debug] partial sync 3:INBOX - get vanished messages via Horde took 0s. 10/10MB memory used
[debug] partial sync 3:INBOX - delete vanished messages took 0s. 10/10MB memory used
[debug] partial sync 3:INBOX took 1s
[debug] Unlocking mailbox 12 from vanished messages sync
[debug] Unlocking mailbox 12 from changed messages sync
[debug] Unlocking mailbox 12 from new messages sync
[debug] Skipping mailbox sync for 10
[debug] Skipping mailbox sync for 11
[debug] Skipping mailbox sync for 8
[debug] Skipping threading as there were no significant changes
10MB of memory used
[debug] account is up to date, skipping mailbox sync
[debug] Skipping mailbox sync for 22
[debug] Syncing 25
[debug] Locking mailbox 25 for new messages sync
[debug] Locking mailbox 25 for changed messages sync
[debug] Locking mailbox 25 for vanished messages sync
[debug] Running partial sync for 25
[debug] partial sync 4:INBOX - get all known UIDs took 0s. 9/10MB memory used
[debug] partial sync 4:INBOX - get new messages via Horde took 0s. 9/10MB memory used
[debug] partial sync 4:INBOX - persist new messages took 0s. 9/10MB memory used
[debug] partial sync 4:INBOX - get changed messages via Horde took 1s. 10/10MB memory used
[debug] partial sync 4:INBOX - persist changed messages took 0s. 10/10MB memory used
[debug] partial sync 4:INBOX - get vanished messages via Horde took 0s. 10/10MB memory used
[debug] partial sync 4:INBOX - delete vanished messages took 0s. 10/10MB memory used
[debug] partial sync 4:INBOX took 1s
[debug] Unlocking mailbox 25 from vanished messages sync
[debug] Unlocking mailbox 25 from changed messages sync
[debug] Unlocking mailbox 25 from new messages sync
[debug] Skipping mailbox sync for 23
[debug] Skipping mailbox sync for 24
[debug] Skipping mailbox sync for 21
[debug] Skipping threading as there were no significant changes
10MB of memory usedhorde_imap.log
------------------------------
>> Fri, 29 Dec 2023 09:26:54 +0000
>> Connection to: imap://imap.poczta.onet.pl:993/
>> Server connection took 0.0378 seconds.
S: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID LITERAL+ AUTH=PLAIN AUTH=LOGIN AUTH=XOAUTH2] Onet server ready.
C: 1 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: ACCOUNT_ID 2)]
S: 1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID MULTIAPPEND UNSELECT CHILDREN UIDPLUS LIST-STATUS LIST-EXTENDED I18NLEVEL=1 WITHIN SPECIAL-USE] Logged in
>> Command 1 took 0.0587 seconds.
>> CACHE: Using the OCA\Mail\Cache\Cache storage driver.
C: 2 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 0 UIDNEXT 1 UIDVALIDITY 1331140801)
S: 2 OK Status completed (0.139 + 0.000 + 0.138 secs).
>> Command 2 took 0.1486 seconds.
C: 3 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 0 UIDNEXT 1 UIDVALIDITY 1331140801)
S: 3 OK Status completed (0.018 + 0.000 + 0.017 secs).
>> Command 3 took 0.0275 seconds.
C: 4 SELECT INBOX
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
S: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted.
S: * 0 EXISTS
S: * 0 RECENT
S: * OK [UIDVALIDITY 1331140801] UIDs valid
S: * OK [UIDNEXT 1] Predicted next UID
S: * OK [NOMODSEQ] No permanent modsequences
S: 4 OK [READ-WRITE] Select completed (0.018 + 0.000 + 0.017 secs).
>> Command 4 took 0.0284 seconds.
C: 5 LOGOUT
S: * BYE Logging out
S: 5 OK Logout completed (0.001 + 0.000 secs).
>> Command 5 took 0.0096 seconds.
------------------------------
>> Fri, 29 Dec 2023 09:27:47 +0000
>> Connection to: imap://imap.poczta.onet.pl:993/
>> Server connection took 0.0383 seconds.
S: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID LITERAL+ AUTH=PLAIN AUTH=LOGIN AUTH=XOAUTH2] Onet server ready.
C: 1 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: ACCOUNT_ID 3)]
S: 1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID MULTIAPPEND UNSELECT CHILDREN UIDPLUS LIST-STATUS LIST-EXTENDED I18NLEVEL=1 WITHIN SPECIAL-USE] Logged in
>> Command 1 took 0.0434 seconds.
>> CACHE: Using the OCA\Mail\Cache\Cache storage driver.
C: 2 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 6 UIDNEXT 14138 UIDVALIDITY 1443381664)
S: 2 OK Status completed (0.103 + 0.000 + 0.102 secs).
>> Command 2 took 0.113 seconds.
C: 3 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 6 UIDNEXT 14138 UIDVALIDITY 1443381664)
S: 3 OK Status completed (0.021 + 0.000 + 0.020 secs).
>> Command 3 took 0.0302 seconds.
C: 4 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 6 UIDNEXT 14138 UIDVALIDITY 1443381664)
S: 4 OK Status completed (0.021 + 0.000 + 0.020 secs).
>> Command 4 took 0.0302 seconds.
C: 5 EXAMINE INBOX
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
S: * OK [PERMANENTFLAGS ()] Read-only mailbox.
S: * 6 EXISTS
S: * 6 RECENT
S: * OK [UIDVALIDITY 1443381664] UIDs valid
S: * OK [UIDNEXT 14138] Predicted next UID
S: * OK [NOMODSEQ] No permanent modsequences
S: 5 OK [READ-ONLY] Examine completed (0.018 + 0.000 + 0.017 secs).
>> Command 5 took 0.0306 seconds.
>> CACHE: Retrieved messages (HICenv,HICdate,HICdg [INBOX; 14118,14121,14127,14135:14137])
C: 6 UID FETCH 14118,14121,14127,14135:14137 (FLAGS BODY.PEEK[HEADER])
S: * 1 FETCH (UID 14118 FLAGS (\Deleted \Seen \Recent) BODY[HEADER] {2185}
S: [LITERAL DATA: 2185 bytes]
S: )
S: * 2 FETCH (UID 14121 FLAGS (\Deleted \Seen \Recent) BODY[HEADER] {2576}
S: [LITERAL DATA: 2576 bytes]
S: )
S: * 3 FETCH (UID 14127 FLAGS (\Seen \Recent) BODY[HEADER] {2526}
S: [LITERAL DATA: 2526 bytes]
S: )
S: * 4 FETCH (UID 14135 FLAGS (\Seen \Recent) BODY[HEADER] {3696}
S: [LITERAL DATA: 3696 bytes]
S: )
S: * 5 FETCH (UID 14136 FLAGS (\Seen \Recent) BODY[HEADER] {7906}
S: [LITERAL DATA: 7906 bytes]
S: )
S: * 6 FETCH (UID 14137 FLAGS (\Seen \Recent) BODY[HEADER] {3672}
S: [LITERAL DATA: 3672 bytes]
S: )
S: 6 OK Fetch completed (0.036 + 0.000 + 0.035 secs).
>> Command 6 took 0.0501 seconds.
C: 7 SELECT INBOX
S: * OK [CLOSED] Previous mailbox closed.
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
S: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted.
S: * 6 EXISTS
S: * 0 RECENT
S: * OK [UIDVALIDITY 1443381664] UIDs valid
S: * OK [UIDNEXT 14138] Predicted next UID
S: * OK [NOMODSEQ] No permanent modsequences
S: 7 OK [READ-WRITE] Select completed (0.023 + 0.000 + 0.022 secs).
>> Command 7 took 0.0319 seconds.
C: 8 UID SEARCH UID 14118,14121,14127,14135:14137
S: * SEARCH 14118 14121 14127 14135 14136 14137
S: 8 OK Search completed (0.001 + 0.000 secs).
>> Command 8 took 0.0097 seconds.
C: 9 LOGOUT
S: * BYE Logging out
S: 9 OK Logout completed (0.001 + 0.000 secs).
>> Command 9 took 0.0099 seconds.
------------------------------
>> Fri, 29 Dec 2023 09:27:54 +0000
>> Connection to: imap://imap.poczta.onet.pl:993/
>> Server connection took 0.0421 seconds.
S: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID LITERAL+ AUTH=PLAIN AUTH=LOGIN AUTH=XOAUTH2] Onet server ready.
C: 1 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: ACCOUNT_ID 4)]
S: 1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID MULTIAPPEND UNSELECT CHILDREN UIDPLUS LIST-STATUS LIST-EXTENDED I18NLEVEL=1 WITHIN SPECIAL-USE] Logged in
>> Command 1 took 0.0422 seconds.
>> CACHE: Using the OCA\Mail\Cache\Cache storage driver.
C: 2 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 6 UIDNEXT 2759 UIDVALIDITY 1534698799)
S: 2 OK Status completed (0.109 + 0.000 + 0.108 secs).
>> Command 2 took 0.1191 seconds.
C: 3 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 6 UIDNEXT 2759 UIDVALIDITY 1534698799)
S: 3 OK Status completed (0.016 + 0.000 + 0.016 secs).
>> Command 3 took 0.0258 seconds.
C: 4 STATUS INBOX (MESSAGES UIDNEXT UIDVALIDITY)
S: * STATUS INBOX (MESSAGES 6 UIDNEXT 2759 UIDVALIDITY 1534698799)
S: 4 OK Status completed (0.022 + 0.000 + 0.021 secs).
>> Command 4 took 0.0329 seconds.
C: 5 EXAMINE INBOX
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
S: * OK [PERMANENTFLAGS ()] Read-only mailbox.
S: * 6 EXISTS
S: * 6 RECENT
S: * OK [UNSEEN 2] First unseen.
S: * OK [UIDVALIDITY 1534698799] UIDs valid
S: * OK [UIDNEXT 2759] Predicted next UID
S: * OK [NOMODSEQ] No permanent modsequences
S: 5 OK [READ-ONLY] Examine completed (0.015 + 0.000 + 0.014 secs).
>> Command 5 took 0.0267 seconds.
>> CACHE: Retrieved messages (HICenv,HICdate,HICdg [INBOX; 2658,2754:2758])
C: 6 UID FETCH 2658,2754:2758 (FLAGS BODY.PEEK[HEADER])
S: * 1 FETCH (UID 2658 FLAGS (\Seen \Recent) BODY[HEADER] {3587}
S: [LITERAL DATA: 3587 bytes]
S: )
S: * 2 FETCH (UID 2754 FLAGS (\Recent) BODY[HEADER] {375}
S: [LITERAL DATA: 375 bytes]
S: )
S: * 3 FETCH (UID 2755 FLAGS (\Recent) BODY[HEADER] {349}
S: [LITERAL DATA: 349 bytes]
S: )
S: * 4 FETCH (UID 2756 FLAGS (\Recent) BODY[HEADER] {391}
S: [LITERAL DATA: 391 bytes]
S: )
S: * 5 FETCH (UID 2757 FLAGS (\Recent) BODY[HEADER] {354}
S: [LITERAL DATA: 354 bytes]
S: )
S: * 6 FETCH (UID 2758 FLAGS (\Recent) BODY[HEADER] {3379}
S: [LITERAL DATA: 3379 bytes]
S: )
S: 6 OK Fetch completed (0.481 + 0.000 + 0.480 secs).
>> Command 6 took 0.4918 seconds.
C: 7 SELECT INBOX
S: * OK [CLOSED] Previous mailbox closed.
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
S: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted.
S: * 6 EXISTS
S: * 0 RECENT
S: * OK [UNSEEN 2] First unseen.
S: * OK [UIDVALIDITY 1534698799] UIDs valid
S: * OK [UIDNEXT 2759] Predicted next UID
S: * OK [NOMODSEQ] No permanent modsequences
S: 7 OK [READ-WRITE] Select completed (0.017 + 0.000 + 0.016 secs).
>> Command 7 took 0.0273 seconds.
C: 8 UID SEARCH UID 2658,2754:2758
S: * SEARCH 2658 2754 2755 2756 2757 2758
S: 8 OK Search completed (0.001 + 0.000 secs).
>> Command 8 took 0.0097 seconds.
C: 9 LOGOUT
S: * BYE Logging out
S: 9 OK Logout completed (0.001 + 0.000 secs).
>> Command 9 took 0.0096 seconds.I cannot see any errors neither in console nor in horde_imap.log In nextcloud.log there are also no errors during performed manually syncs.
ChristophWurst
commented
11 months ago Thanks for testing. Logs look clean indeed.
Not sure how the originally reported error is triggered. There is no request URL or stack trace in the log entry
{
"reqId": "vP3M45gZ2mnAidQFYE7d",
"level": 3,
"time": "December 24, 2023 12:30:05",
"remoteAddr": "",
"user": "--",
"app": "PHP",
"method": "",
"url": "--",
"message": "Trying to access array offset on value of type bool at /var/www/nextcloud/apps/mail/vendor/bytestream/horde-imap-client/lib/Horde/Imap/Client/Base.php#776",
"userAgent": "--",
"version": "28.0.1.1",
"data": {
"app": "PHP"
}
}Enable https://docs.nextcloud.com/server/latest/admin_manual/configuration_server/config_sample_php_parameters.html#log-backtrace and look at your logs later. If the error happens again it will be logged with a backtrace.
kristofejro
commented
11 months ago This is what I managed to catch with log.backtrace enabled:
{"reqId":"hz6blD9sF5SDmeLG8A07","level":3,"time":"December 29, 2023 12:30:04","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"Trying to access array offset on value of type bool at /var/www/nextcloud/apps/mail/vendor/bytestream/horde-imap-client/lib/Horde/Imap/Client/Base.php#776","userAgent":"--","version":"28.0.1.1","data":{"app":"PHP","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":236,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/ErrorHandler.php","line":81,"function":"log","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/vendor/bytestream/horde-imap-client/lib/Horde/Imap/Client/Base.php","line":776,"function":"onError","class":"OC\\Log\\ErrorHandler","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":107,"function":"getNamespaces","class":"Horde_Imap_Client_Base","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":1,"time":"December 29, 2023 12:30:04","remoteAddr":"","user":"--","app":"mail","method":"","url":"--","message":"Account 2 does not have an archive mailbox","userAgent":"--","version":"28.0.1.1","data":{"app":"mail","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log.php","line":189,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":192,"function":"info","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/ScopedPsrLogger.php","line":124,"function":"info","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Listener/MailboxesSynchronizedSpecialMailboxesUpdater.php","line":110,"function":"info","class":"OC\\AppFramework\\ScopedPsrLogger","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/ServiceEventListener.php","line":86,"function":"handle","class":"OCA\\Mail\\Listener\\MailboxesSynchronizedSpecialMailboxesUpdater","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":230,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":59,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":94,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":106,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":148,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":1,"time":"December 29, 2023 12:30:04","remoteAddr":"","user":"--","app":"mail","method":"","url":"--","message":"Account 2 does not have an snooze mailbox","userAgent":"--","version":"28.0.1.1","data":{"app":"mail","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log.php","line":189,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":192,"function":"info","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/ScopedPsrLogger.php","line":124,"function":"info","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Listener/MailboxesSynchronizedSpecialMailboxesUpdater.php","line":129,"function":"info","class":"OC\\AppFramework\\ScopedPsrLogger","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/ServiceEventListener.php","line":86,"function":"handle","class":"OCA\\Mail\\Listener\\MailboxesSynchronizedSpecialMailboxesUpdater","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":230,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":59,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":94,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":106,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":148,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":3,"time":"December 29, 2023 12:30:05","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"Trying to access array offset on value of type bool at /var/www/nextcloud/apps/mail/vendor/bytestream/horde-imap-client/lib/Horde/Imap/Client/Base.php#776","userAgent":"--","version":"28.0.1.1","data":{"app":"PHP","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":236,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/ErrorHandler.php","line":81,"function":"log","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/vendor/bytestream/horde-imap-client/lib/Horde/Imap/Client/Base.php","line":776,"function":"onError","class":"OC\\Log\\ErrorHandler","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":107,"function":"getNamespaces","class":"Horde_Imap_Client_Base","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":1,"time":"December 29, 2023 12:30:05","remoteAddr":"","user":"--","app":"mail","method":"","url":"--","message":"Account 3 does not have an archive mailbox","userAgent":"--","version":"28.0.1.1","data":{"app":"mail","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log.php","line":189,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":192,"function":"info","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/ScopedPsrLogger.php","line":124,"function":"info","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Listener/MailboxesSynchronizedSpecialMailboxesUpdater.php","line":110,"function":"info","class":"OC\\AppFramework\\ScopedPsrLogger","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/ServiceEventListener.php","line":86,"function":"handle","class":"OCA\\Mail\\Listener\\MailboxesSynchronizedSpecialMailboxesUpdater","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":230,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":59,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":94,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":106,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":148,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":1,"time":"December 29, 2023 12:30:05","remoteAddr":"","user":"--","app":"mail","method":"","url":"--","message":"Account 3 does not have an snooze mailbox","userAgent":"--","version":"28.0.1.1","data":{"app":"mail","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log.php","line":189,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":192,"function":"info","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/ScopedPsrLogger.php","line":124,"function":"info","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Listener/MailboxesSynchronizedSpecialMailboxesUpdater.php","line":129,"function":"info","class":"OC\\AppFramework\\ScopedPsrLogger","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/ServiceEventListener.php","line":86,"function":"handle","class":"OCA\\Mail\\Listener\\MailboxesSynchronizedSpecialMailboxesUpdater","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":230,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":59,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":94,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":106,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":148,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":3,"time":"December 29, 2023 12:30:07","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"Trying to access array offset on value of type bool at /var/www/nextcloud/apps/mail/vendor/bytestream/horde-imap-client/lib/Horde/Imap/Client/Base.php#776","userAgent":"--","version":"28.0.1.1","data":{"app":"PHP","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":236,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/ErrorHandler.php","line":81,"function":"log","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/vendor/bytestream/horde-imap-client/lib/Horde/Imap/Client/Base.php","line":776,"function":"onError","class":"OC\\Log\\ErrorHandler","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":107,"function":"getNamespaces","class":"Horde_Imap_Client_Base","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":1,"time":"December 29, 2023 12:30:07","remoteAddr":"","user":"--","app":"mail","method":"","url":"--","message":"Account 4 does not have an archive mailbox","userAgent":"--","version":"28.0.1.1","data":{"app":"mail","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log.php","line":189,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":192,"function":"info","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/ScopedPsrLogger.php","line":124,"function":"info","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Listener/MailboxesSynchronizedSpecialMailboxesUpdater.php","line":110,"function":"info","class":"OC\\AppFramework\\ScopedPsrLogger","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/ServiceEventListener.php","line":86,"function":"handle","class":"OCA\\Mail\\Listener\\MailboxesSynchronizedSpecialMailboxesUpdater","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":230,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":59,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":94,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":106,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":148,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":1,"time":"December 29, 2023 12:30:07","remoteAddr":"","user":"--","app":"mail","method":"","url":"--","message":"Account 4 does not have an snooze mailbox","userAgent":"--","version":"28.0.1.1","data":{"app":"mail","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log.php","line":189,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":192,"function":"info","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/ScopedPsrLogger.php","line":124,"function":"info","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Listener/MailboxesSynchronizedSpecialMailboxesUpdater.php","line":129,"function":"info","class":"OC\\AppFramework\\ScopedPsrLogger","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/ServiceEventListener.php","line":86,"function":"handle","class":"OCA\\Mail\\Listener\\MailboxesSynchronizedSpecialMailboxesUpdater","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":230,"function":"__invoke","class":"OC\\EventDispatcher\\ServiceEventListener","type":"->"},{"file":"/var/www/nextcloud/3rdparty/symfony/event-dispatcher/EventDispatcher.php","line":59,"function":"callListeners","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":94,"function":"dispatch","class":"Symfony\\Component\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/EventDispatcher/EventDispatcher.php","line":106,"function":"dispatch","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/IMAP/MailboxSync.php","line":148,"function":"dispatchTyped","class":"OC\\EventDispatcher\\EventDispatcher","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/SyncJob.php","line":110,"function":"sync","class":"OCA\\Mail\\IMAP\\MailboxSync","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\SyncJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}
{"reqId":"hz6blD9sF5SDmeLG8A07","level":1,"time":"December 29, 2023 12:30:08","remoteAddr":"","user":"--","app":"mail","method":"","url":"--","message":"No iMIP messages to process.","userAgent":"--","version":"28.0.1.1","data":{"app":"mail","backtrace":[{"file":"/var/www/nextcloud/lib/private/Log.php","line":189,"function":"log","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/Log/PsrLoggerAdapter.php","line":192,"function":"info","class":"OC\\Log","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/ScopedPsrLogger.php","line":124,"function":"info","class":"OC\\Log\\PsrLoggerAdapter","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Service/IMipService.php","line":67,"function":"info","class":"OC\\AppFramework\\ScopedPsrLogger","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/BackgroundJob/IMipMessageJob.php","line":45,"function":"process","class":"OCA\\Mail\\Service\\IMipService","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/Job.php","line":81,"function":"run","class":"OCA\\Mail\\BackgroundJob\\IMipMessageJob","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":102,"function":"start","class":"OCP\\BackgroundJob\\Job","type":"->"},{"file":"/var/www/nextcloud/lib/public/BackgroundJob/TimedJob.php","line":92,"function":"start","class":"OCP\\BackgroundJob\\TimedJob","type":"->"},{"file":"/var/www/nextcloud/cron.php","line":152,"function":"execute","class":"OCP\\BackgroundJob\\TimedJob","type":"->"}]}}Just wanted to let you know. On Nextcloud 28.0.3 and Mail app 3.5.7 these errors are still present. (the only errors for my Nextclud instance)
Steps to reproduce
Expected behavior
There should be no errors for application.
Actual behavior
There are lot of errors from mail application.
Mail app version
3.5.0
Mailserver or service
few commercial free accounts from onet.pl
Operating system
Ubuntu 22.04.3 LTS
PHP engine version
PHP 8.1
Web server
Apache (supported)
Database
MySQL
Additional info
I have few email account configured mostly for reading and deleting messages. No fancy use case. I have observer that there are a lot of errors in nextcloud log.
What it is caused by and how to resolve that issue?