search

the-djmaze / snappymail

Simple, modern & fast web-based email client
https://snappymail.eu
GNU Affero General Public License v3.0
1.01k stars 121 forks source link

Not listing all emails in INBOX/SPAM #833

Closed mateors closed 1 year ago

mateors commented 1 year ago

Describe the bug Unable to read all inbox or spam box emails.

Screenshots If applicable, add screenshots to help explain your problem.

image

Spam box: image

Expected behavior It should display all of my messages from inbox or spam

Please complete the following information:

the-djmaze commented 1 year ago

There's a button wirh circle in the top bar. What happens when you click on it?

mateors commented 1 year ago

@the-djmaze Nothing just refresh the page

mateors commented 1 year ago

@the-djmaze Here is the log:

JSON[INFO]: Action: DoMessageList
POST[INFO]: {"offset":0,"limit":50,"search":"","uidNext":0,"sort":"","Hash":"cee2176a98f544ada42f30fa4e2da14163c8ab6f","Folder":"INBOX","threadUid":0,"Action":"MessageList","XToken":"a4e9d2180565dcd2a897a05b809373a3c3bdaca4"}
IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n

IMAP[INFO]: > TAG1 STARTTLS\r\n
IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n

IMAP[INFO]: > TAG2 CAPABILITY\r\n
IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS\r\n
IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n

IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n

IMAP[INFO]: > TAG4 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
IMAP[INFO]: < * STATUS INBOX (APPENDLIMIT 0 MESSAGES 4 UNSEEN 4 UIDNEXT 10 UIDVALIDITY 65119915)\r\n
IMAP[INFO]: < TAG4 OK STATUS completed\r\n

IMAP[INFO]: > TAG5 SELECT "INBOX"\r\n
IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
IMAP[INFO]: < * 4 EXISTS\r\n
IMAP[INFO]: < * 4 RECENT\r\n
IMAP[INFO]: < * OK [UIDNEXT 10] Predicted next UID\r\n
IMAP[INFO]: < * OK [UIDVALIDITY 65119915] UIDs valid\r\n
IMAP[INFO]: < TAG5 OK [READ-WRITE] SELECT completed\r\n

[INFO]: Get Serialized UIDS from cache ("INBOX" / UNDELETED) [count:0]

JSON[INFO]: {"Action":"MessageList","Result":{"@Object":"Collection\/MessageCollection","@Collection":[],"totalEmails":4,"totalThreads":null,"Folder":"INBOX","folderHash":"fa2a6692661ed137dae1d4ddde04ce36","folderInfo":{"id":null,"Name":"INBOX","Flags":["\\Seen","\\Answered","\\Flagged","\\Deleted","\\Draft"],"...
IMAP[INFO]: > TAG6 LOGOUT\r\n
IMAP[INFO]: < * BYE Closing connection\r\n
IMAP[INFO]: < TAG6 OK LOGOUT completed\r\n
the-djmaze commented 1 year ago

I've cleaned up your log. The important line in there is: [INFO]: Get Serialized UIDS from cache ("INBOX" / UNDELETED) [count:0]

Somehow your cache is corrupt and there are no messages. Clear your cache and try again.

mateors commented 1 year ago

@the-djmaze i did so, I removed the cache folder and restart the email server, Nginx again but remains the same. I think this is a bug.

the-djmaze commented 1 year ago

v2.24.5 has some improvements regarding caching. Please try with that

mateors commented 1 year ago

@the-djmaze

After upgrading to v2.24.5 email outgoing and inbox not working.

INBOX NOT WORKING like before image

image

image

outgoing not working image

mateors commented 1 year ago

@the-djmaze

email server mail.log

2023-01-14T03:06:26.509Z smtp: incoming message {"msg_id":"5331ba7b","sender":"pay@mateors.com","src_host":"webmail.lxctr.com","src_ip":"[::1]:37792"}
2023-01-14T03:06:26.537Z smtp: RCPT error       {"effective_rcpt":"bill.rassel@gmail.com","msg_id":"5331ba7b","rcpt":"bill.rassel@gmail.com","reason":"reject directive used","smtp_code":501,"smtp_enchcode":"5.1.8","smtp_msg":"Use Submission for outgoing SMTP"}
2023-01-14T03:06:26.538Z smtp: aborted  {"msg_id":"5331ba7b"}
mateors commented 1 year ago

snappymail log

[2023-01-14 02:58:30.396][de4b9553] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?admin/Json/&q[]=/0/]
[2023-01-14 02:58:30.397][de4b9553] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?admin/Json/&q[]=/0/]
[2023-01-14 02:58:30.397][de4b9553] JSON[INFO]: Action: DoAdminLogout
[2023-01-14 02:58:30.397][de4b9553] POST[INFO]: {"Action":"AdminLogout","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:30.398][de4b9553] JSON[INFO]: {"Action":"AdminLogout","Result":true,"epoch":1673665110}
[2023-01-14 02:58:30.399][de4b9553] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:30.399][de4b9553] [INFO]: Time delta: 0.0081849098205566
[2023-01-14 02:58:30.774][fbc50f25] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404632][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?admin]
[2023-01-14 02:58:30.774][fbc50f25] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404632][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?admin]
[2023-01-14 02:58:30.778][fbc50f25] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:30.778][fbc50f25] [INFO]: Time delta: 0.0082180500030518
[2023-01-14 02:58:31.313][b0b78ff6] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:419914][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?/AdminAppData/0/4102055617613327/]
[2023-01-14 02:58:31.315][b0b78ff6] APPDATA[INFO]: rl.initData({"Auth":false,"Title":"MATEORS Webmail","LoadingDescription":"MASTER MAIL","Plugins":[],"System":{"version":"2.24.5","token":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7","languages":["ar-SA","bg-BG","cs-CZ","da-DK","de-DE","el-GR","en","en-GB","eo","es-ES","et-EE","eu","fa-IR","fi-FI","fr-FR","hu-HU","id-ID","is-IS","it-IT","ja-JP","ko-KR","lt-LT","lv-LV","nb-NO","nl-NL","pl-PL","pt","pt-BR","pt-PT","ro-RO","ru-RU","sk-SK","sl-SI","sv-SE","tr-TR","uk-UA","vi-VN","zh-CN","zh-TW"],"webPath":"\/","webVersionPath":"\/snappymail\/v\/2.24.5\/","adminHostUse":false,"adminPath":"admin","adminAllowed":true},"AllowLanguagesOnLogin":false,"Theme":"Default","Language":"en","UserLanguage":"en","PluginsLink":"","StaticLibsJs":"\/snappymail\/v\/2.24.5\/static\/js\/min\/libs.min.js"});
[2023-01-14 02:58:31.316][b0b78ff6] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:31.316][b0b78ff6] [INFO]: Time delta: 0.0058588981628418
[2023-01-14 02:58:34.509][9f5b16e8] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/]
[2023-01-14 02:58:34.516][9f5b16e8] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:34.516][9f5b16e8] [INFO]: Time delta: 0.01082706451416
[2023-01-14 02:58:35.059][40ee0600] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404632][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?/AppData/0/8362651063366475/]
[2023-01-14 02:58:35.061][40ee0600] APPDATA[INFO]: rl.initData({"Auth":false,"Title":"MATEORS Webmail","LoadingDescription":"MASTER MAIL","Plugins":[],"System":{"version":"2.24.5","token":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7","languages":["ar-SA","bg-BG","cs-CZ","da-DK","de-DE","el-GR","en","en-GB","eo","es-ES","et-EE","eu","fa-IR","fi-FI","fr-FR","hu-HU","id-ID","is-IS","it-IT","ja-JP","ko-KR","lt-LT","lv-LV","nb-NO","nl-NL","pl-PL","pt","pt-BR","pt-PT","ro-RO","ru-RU","sk-SK","sl-SI","sv-SE","tr-TR","uk-UA","vi-VN","zh-CN","zh-TW"],"webPath":"\/","webVersionPath":"\/snappymail\/v\/2.24.5\/"},"AllowLanguagesOnLogin":false,"DevEmail":"","DevPassword":"","SignMe":"DefaultOff","AdditionalLoginError":"","Theme":"Default","Language":"en","UserLanguage":"en","PluginsLink":"","StaticLibsJs":"\/snappymail\/v\/2.24.5\/static\/js\/min\/libs.min.js"});
[2023-01-14 02:58:35.062][40ee0600] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:35.062][40ee0600] [INFO]: Time delta: 0.0065698623657227
[2023-01-14 02:58:40.126][f5cb0c65] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:419914][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 02:58:40.127][f5cb0c65] JSON[INFO]: Action: DoLogin
[2023-01-14 02:58:40.127][f5cb0c65] POST[INFO]: {"Email":"pay@mateors.com","Password":"*******","Language":"","SignMe":"1","Action":"Login","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:40.130][f5cb0c65] IMAP[INFO]: Start connection to "tcp://localhost:143"
[2023-01-14 02:58:40.132][f5cb0c65] IMAP[INFO]: Connect (success)
[2023-01-14 02:58:40.132][f5cb0c65] IMAP[DEBUG]: 0.0021510124206543 (raw connection)
[2023-01-14 02:58:40.133][f5cb0c65] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 02:58:40.133][f5cb0c65] IMAP[DEBUG]: 0.0029408931732178 (*)
[2023-01-14 02:58:40.134][f5cb0c65] IMAP[INFO]: > TAG1 STARTTLS\r\n
[2023-01-14 02:58:40.134][f5cb0c65] IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n
[2023-01-14 02:58:40.134][f5cb0c65] IMAP[DEBUG]: 0.0011279582977295 (TAG1)
[2023-01-14 02:58:40.142][f5cb0c65] IMAP[INFO]: > TAG2 CAPABILITY\r\n
[2023-01-14 02:58:40.143][f5cb0c65] IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS\r\n
[2023-01-14 02:58:40.143][f5cb0c65] IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n
[2023-01-14 02:58:40.143][f5cb0c65] IMAP[DEBUG]: 0.0010888576507568 (TAG2)
[2023-01-14 02:58:40.144][f5cb0c65] IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 02:58:40.246][f5cb0c65] IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 02:58:40.247][f5cb0c65] IMAP[DEBUG]: 0.10284686088562 (TAG3)
[2023-01-14 02:58:40.247][f5cb0c65] COOKIE[DEBUG]: set smremember
[2023-01-14 02:58:40.248][f5cb0c65] COOKIE[DEBUG]: set smaccount
[2023-01-14 02:58:40.253][f5cb0c65] SQL[INFO]: SELECT MAX(value_int) FROM rainloop_system WHERE sys_name = ?
[2023-01-14 02:58:40.256][f5cb0c65] SQL[INFO]: SELECT id_user FROM rainloop_users WHERE rl_email = :rl_email
[2023-01-14 02:58:40.258][f5cb0c65] JSON[INFO]: {"Action":"Login","Result":{"Auth":true,"Title":"MATEORS Webmail","LoadingDescription":"MASTER MAIL","Plugins":[],"System":{"version":"2.24.5","token":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7","languages":["ar-SA","bg-BG","cs-CZ","da-DK","de-DE","el-GR","en","en-GB","eo","es-ES","et-EE","eu","fa-IR...
[2023-01-14 02:58:40.259][f5cb0c65] IMAP[INFO]: > TAG4 LOGOUT\r\n
[2023-01-14 02:58:40.259][f5cb0c65] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 02:58:40.259][f5cb0c65] IMAP[INFO]: < TAG4 OK LOGOUT completed\r\n
[2023-01-14 02:58:40.259][f5cb0c65] IMAP[DEBUG]: 0.0010590553283691 (TAG4)
[2023-01-14 02:58:40.260][f5cb0c65] IMAP[INFO]: Disconnected from "tcp://localhost:143" (success)
[2023-01-14 02:58:40.260][f5cb0c65] IMAP[DEBUG]: 0.12972688674927 (net session)
[2023-01-14 02:58:40.260][f5cb0c65] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:40.260][f5cb0c65] [INFO]: Time delta: 0.13864278793335
[2023-01-14 02:58:40.527][e8ff3e6c] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404632][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 02:58:40.528][e8ff3e6c] JSON[INFO]: Action: DoFolders
[2023-01-14 02:58:40.528][e8ff3e6c] POST[INFO]: {"Action":"Folders","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:40.532][ffd1ace4] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?/Css/0/User/-/Default/-/47ec1a23a593df4839a1197b74aa7823/Hash/-/Json/]
[2023-01-14 02:58:40.533][ffd1ace4] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:40.533][ffd1ace4] [INFO]: Time delta: 0.0098609924316406
[2023-01-14 02:58:40.534][e8ff3e6c] IMAP[INFO]: Start connection to "tcp://localhost:143"
[2023-01-14 02:58:40.536][e8ff3e6c] IMAP[INFO]: Connect (success)
[2023-01-14 02:58:40.536][e8ff3e6c] IMAP[DEBUG]: 0.0016849040985107 (raw connection)
[2023-01-14 02:58:40.536][e8ff3e6c] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 02:58:40.537][e8ff3e6c] IMAP[DEBUG]: 0.002518892288208 (*)
[2023-01-14 02:58:40.538][e8ff3e6c] IMAP[INFO]: > TAG1 STARTTLS\r\n
[2023-01-14 02:58:40.538][e8ff3e6c] IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n
[2023-01-14 02:58:40.538][e8ff3e6c] IMAP[DEBUG]: 0.00071597099304199 (TAG1)
[2023-01-14 02:58:40.549][e8ff3e6c] IMAP[INFO]: > TAG2 CAPABILITY\r\n
[2023-01-14 02:58:40.550][e8ff3e6c] IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS\r\n
[2023-01-14 02:58:40.550][e8ff3e6c] IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n
[2023-01-14 02:58:40.550][e8ff3e6c] IMAP[DEBUG]: 0.0012149810791016 (TAG2)
[2023-01-14 02:58:40.551][e8ff3e6c] IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 02:58:40.653][e8ff3e6c] IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 02:58:40.653][e8ff3e6c] IMAP[DEBUG]: 0.10238289833069 (TAG3)
[2023-01-14 02:58:40.654][e8ff3e6c] IMAP[INFO]: > TAG4 LIST "" "*"\r\n
[2023-01-14 02:58:40.655][e8ff3e6c] IMAP[INFO]: < * LIST (\HasNoChildren) "." INBOX\r\n
[2023-01-14 02:58:40.656][e8ff3e6c] IMAP[INFO]: < * LIST (\Sent \HasNoChildren) "." "Sent"\r\n
[2023-01-14 02:58:40.656][e8ff3e6c] IMAP[INFO]: < * LIST (\Trash \HasNoChildren) "." "Trash"\r\n
[2023-01-14 02:58:40.656][e8ff3e6c] IMAP[INFO]: < * LIST (\Junk \HasNoChildren) "." "Junk"\r\n
[2023-01-14 02:58:40.657][e8ff3e6c] IMAP[INFO]: < * LIST (\Drafts \HasNoChildren) "." "Drafts"\r\n
[2023-01-14 02:58:40.657][e8ff3e6c] IMAP[INFO]: < * LIST (\Archive \HasNoChildren) "." "Archive"\r\n
[2023-01-14 02:58:40.657][e8ff3e6c] IMAP[INFO]: < TAG4 OK LIST completed\r\n
[2023-01-14 02:58:40.657][e8ff3e6c] IMAP[DEBUG]: 0.0032889842987061 (TAG4)
[2023-01-14 02:58:40.657][e8ff3e6c] IMAP[INFO]: > TAG5 NAMESPACE\r\n
[2023-01-14 02:58:40.658][e8ff3e6c] IMAP[INFO]: < * NAMESPACE (("" ".")) NIL NIL\r\n
[2023-01-14 02:58:40.658][e8ff3e6c] IMAP[INFO]: < TAG5 OK NAMESPACE completed\r\n
[2023-01-14 02:58:40.658][e8ff3e6c] IMAP[DEBUG]: 0.00062894821166992 (TAG5)
[2023-01-14 02:58:40.658][e8ff3e6c] JSON[INFO]: {"Action":"Folders","Result":{"@Object":"Collection\/FolderCollection","@Collection":[{"@Object":"Object\/Folder","name":"INBOX","FullName":"INBOX","Delimiter":".","isSubscribed":false,"Exists":true,"Selectable":true,"Flags":["\\hasnochildren"],"Metadata":[],"UidNext":null,"totalEmails":null,"unread...
[2023-01-14 02:58:40.658][e8ff3e6c] IMAP[INFO]: > TAG6 LOGOUT\r\n
[2023-01-14 02:58:40.659][e8ff3e6c] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 02:58:40.659][e8ff3e6c] IMAP[INFO]: < TAG6 OK LOGOUT completed\r\n
[2023-01-14 02:58:40.659][e8ff3e6c] IMAP[DEBUG]: 0.00050997734069824 (TAG6)
[2023-01-14 02:58:40.659][e8ff3e6c] IMAP[INFO]: Disconnected from "tcp://localhost:143" (success)
[2023-01-14 02:58:40.659][e8ff3e6c] IMAP[DEBUG]: 0.12485790252686 (net session)
[2023-01-14 02:58:40.659][e8ff3e6c] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:40.659][e8ff3e6c] [INFO]: Time delta: 0.14034509658813
[2023-01-14 02:58:40.938][ccf7db38] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:419914][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 02:58:40.938][ccf7db38] JSON[INFO]: Action: DoAccountsAndIdentities
[2023-01-14 02:58:40.938][ccf7db38] POST[INFO]: {"Action":"AccountsAndIdentities","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:40.942][fa17480c] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 02:58:40.942][fa17480c] JSON[INFO]: Action: DoMessageList
[2023-01-14 02:58:40.942][fa17480c] POST[INFO]: {"offset":0,"limit":20,"search":"","uidNext":0,"sort":"","folder":"INBOX","threadUid":0,"Action":"MessageList","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:40.945][ccf7db38] JSON[INFO]: {"Action":"AccountsAndIdentities","Result":{"Accounts":[],"Identities":[{"Id":"","Email":"pay@mateors.com","Name":"","ReplyTo":"","Bcc":"","Signature":"","SignatureInsertBefore":false}]},"epoch":1673665120}
[2023-01-14 02:58:40.946][ccf7db38] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:40.946][ccf7db38] [INFO]: Time delta: 0.011529922485352
[2023-01-14 02:58:40.948][fa17480c] IMAP[INFO]: Start connection to "tcp://localhost:143"
[2023-01-14 02:58:40.949][fa17480c] IMAP[INFO]: Connect (success)
[2023-01-14 02:58:40.949][fa17480c] IMAP[DEBUG]: 0.0014297962188721 (raw connection)
[2023-01-14 02:58:40.950][fa17480c] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 02:58:40.950][fa17480c] IMAP[DEBUG]: 0.0019779205322266 (*)
[2023-01-14 02:58:40.950][fa17480c] IMAP[INFO]: > TAG1 STARTTLS\r\n
[2023-01-14 02:58:40.950][fa17480c] IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n
[2023-01-14 02:58:40.951][fa17480c] IMAP[DEBUG]: 0.00056695938110352 (TAG1)
[2023-01-14 02:58:40.959][fa17480c] IMAP[INFO]: > TAG2 CAPABILITY\r\n
[2023-01-14 02:58:40.959][fa17480c] IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS\r\n
[2023-01-14 02:58:40.960][fa17480c] IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n
[2023-01-14 02:58:40.960][fa17480c] IMAP[DEBUG]: 0.0012249946594238 (TAG2)
[2023-01-14 02:58:40.960][fa17480c] IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 02:58:41.064][fa17480c] IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 02:58:41.064][fa17480c] IMAP[DEBUG]: 0.10388708114624 (TAG3)
[2023-01-14 02:58:41.065][fa17480c] IMAP[INFO]: > TAG4 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:41.066][fa17480c] IMAP[INFO]: < * STATUS INBOX (MESSAGES 7 UNSEEN 7 UIDNEXT 13 UIDVALIDITY 65119915 APPENDLIMIT 0)\r\n
[2023-01-14 02:58:41.066][fa17480c] IMAP[INFO]: < TAG4 OK STATUS completed\r\n
[2023-01-14 02:58:41.066][fa17480c] IMAP[DEBUG]: 0.001035213470459 (TAG4)
[2023-01-14 02:58:41.066][fa17480c] IMAP[INFO]: > TAG5 SELECT "INBOX"\r\n
[2023-01-14 02:58:41.067][fa17480c] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2023-01-14 02:58:41.067][fa17480c] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2023-01-14 02:58:41.068][fa17480c] IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
[2023-01-14 02:58:41.068][fa17480c] IMAP[INFO]: < * 7 EXISTS\r\n
[2023-01-14 02:58:41.068][fa17480c] IMAP[INFO]: < * 6 RECENT\r\n
[2023-01-14 02:58:41.068][fa17480c] IMAP[INFO]: < * OK [UIDNEXT 13] Predicted next UID\r\n
[2023-01-14 02:58:41.068][fa17480c] IMAP[INFO]: < * OK [UIDVALIDITY 65119915] UIDs valid\r\n
[2023-01-14 02:58:41.069][fa17480c] IMAP[INFO]: < TAG5 OK [READ-WRITE] SELECT completed\r\n
[2023-01-14 02:58:41.069][fa17480c] IMAP[DEBUG]: 0.002453088760376 (TAG5)
[2023-01-14 02:58:41.069][fa17480c] [INFO]: Get Serialized UIDS from cache ("INBOX" /  / UNDELETED) [count:0]
[2023-01-14 02:58:41.069][fa17480c] JSON[INFO]: {"Action":"MessageList","Result":{"@Object":"Collection\/MessageCollection","@Collection":[],"totalEmails":0,"totalThreads":null,"Folder":"INBOX","folderHash":"dabc5f78f174fb6117023e08bc31d487","folderInfo":{"id":null,"Name":"INBOX","Flags":["\\Seen","\\Answered","\\Flagged","\\Deleted","\\Draft"],"...
[2023-01-14 02:58:41.069][fa17480c] IMAP[INFO]: > TAG6 LOGOUT\r\n
[2023-01-14 02:58:41.070][fa17480c] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 02:58:41.070][fa17480c] IMAP[INFO]: < TAG6 OK LOGOUT completed\r\n
[2023-01-14 02:58:41.070][fa17480c] IMAP[DEBUG]: 0.00066018104553223 (TAG6)
[2023-01-14 02:58:41.070][fa17480c] IMAP[INFO]: Disconnected from "tcp://localhost:143" (success)
[2023-01-14 02:58:41.070][fa17480c] IMAP[DEBUG]: 0.12242293357849 (net session)
[2023-01-14 02:58:41.070][fa17480c] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:41.070][fa17480c] [INFO]: Time delta: 0.13145208358765
[2023-01-14 02:58:41.333][5abc0fcf] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404632][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 02:58:41.333][5abc0fcf] JSON[INFO]: Action: DoFolderInformation
[2023-01-14 02:58:41.333][5abc0fcf] POST[INFO]: {"Folder":"INBOX","FlagsUids":[],"UidNext":13,"Action":"FolderInformation","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:41.335][5abc0fcf] IMAP[INFO]: Start connection to "tcp://localhost:143"
[2023-01-14 02:58:41.336][5abc0fcf] IMAP[INFO]: Connect (success)
[2023-01-14 02:58:41.336][5abc0fcf] IMAP[DEBUG]: 0.00091695785522461 (raw connection)
[2023-01-14 02:58:41.337][5abc0fcf] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 02:58:41.337][5abc0fcf] IMAP[DEBUG]: 0.0013949871063232 (*)
[2023-01-14 02:58:41.337][5abc0fcf] IMAP[INFO]: > TAG1 STARTTLS\r\n
[2023-01-14 02:58:41.337][5abc0fcf] IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n
[2023-01-14 02:58:41.337][5abc0fcf] IMAP[DEBUG]: 0.00043416023254395 (TAG1)
[2023-01-14 02:58:41.345][5abc0fcf] IMAP[INFO]: > TAG2 CAPABILITY\r\n
[2023-01-14 02:58:41.346][5abc0fcf] IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS\r\n
[2023-01-14 02:58:41.346][5abc0fcf] IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n
[2023-01-14 02:58:41.346][5abc0fcf] IMAP[DEBUG]: 0.00083303451538086 (TAG2)
[2023-01-14 02:58:41.346][5abc0fcf] IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 02:58:41.450][5abc0fcf] IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 02:58:41.450][5abc0fcf] IMAP[DEBUG]: 0.10421109199524 (TAG3)
[2023-01-14 02:58:41.451][5abc0fcf] IMAP[INFO]: > TAG4 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:41.451][5abc0fcf] IMAP[INFO]: < * STATUS INBOX (MESSAGES 7 UNSEEN 7 UIDNEXT 13 UIDVALIDITY 65119915 APPENDLIMIT 0)\r\n
[2023-01-14 02:58:41.452][5abc0fcf] IMAP[INFO]: < TAG4 OK STATUS completed\r\n
[2023-01-14 02:58:41.452][5abc0fcf] IMAP[DEBUG]: 0.00083017349243164 (TAG4)
[2023-01-14 02:58:41.452][5abc0fcf] JSON[INFO]: {"Action":"FolderInformation","Result":{"Folder":"INBOX","totalEmails":7,"unreadEmails":7,"UidNext":13,"UidValidity":65119915,"HighestModSeq":null,"AppendLimit":null,"MailboxId":"","Hash":"b01a99e3973bf40165e62f778bbbb84a","MessagesFlags":[],"newMessages":[]},"epoch":1673665121}
[2023-01-14 02:58:41.452][5abc0fcf] IMAP[INFO]: > TAG5 LOGOUT\r\n
[2023-01-14 02:58:41.452][5abc0fcf] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 02:58:41.452][5abc0fcf] IMAP[INFO]: < TAG5 OK LOGOUT completed\r\n
[2023-01-14 02:58:41.452][5abc0fcf] IMAP[DEBUG]: 0.00054693222045898 (TAG5)
[2023-01-14 02:58:41.453][5abc0fcf] IMAP[INFO]: Disconnected from "tcp://localhost:143" (success)
[2023-01-14 02:58:41.453][5abc0fcf] IMAP[DEBUG]: 0.11731505393982 (net session)
[2023-01-14 02:58:41.453][5abc0fcf] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:41.453][5abc0fcf] [INFO]: Time delta: 0.12349414825439
[2023-01-14 02:58:41.713][4b6856b1] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:419914][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?/Json/&q[]=/0/MessageList/&q[]=/eyJvZmZzZXQiOjAsImxpbWl0IjoyMCwic2VhcmNoIjoiIiwidWlkTmV4dCI6MTMsInNvcnQiOiIiLCJmb2xkZXIiOiJJTkJPWCIsInRocmVhZFVpZCI6MCwiaGFzaCI6ImIwMWE5OWUzOTczYmY0MDE2NWU2MmY3NzhiYmJiODRhLTQwY2NjODc3NzM3MjllOTZkNTRjZWYwOTA3ZTQzM2NiZTlmMzE2ZGEifQ]
[2023-01-14 02:58:41.713][4b6856b1] JSON[INFO]: Action: DoMessageList
[2023-01-14 02:58:41.717][4b6856b1] IMAP[INFO]: Start connection to "tcp://localhost:143"
[2023-01-14 02:58:41.718][4b6856b1] IMAP[INFO]: Connect (success)
[2023-01-14 02:58:41.718][4b6856b1] IMAP[DEBUG]: 0.0012578964233398 (raw connection)
[2023-01-14 02:58:41.718][4b6856b1] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 02:58:41.718][4b6856b1] IMAP[DEBUG]: 0.0018479824066162 (*)
[2023-01-14 02:58:41.719][4b6856b1] IMAP[INFO]: > TAG1 STARTTLS\r\n
[2023-01-14 02:58:41.719][4b6856b1] IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n
[2023-01-14 02:58:41.719][4b6856b1] IMAP[DEBUG]: 0.00063490867614746 (TAG1)
[2023-01-14 02:58:41.727][4b6856b1] IMAP[INFO]: > TAG2 CAPABILITY\r\n
[2023-01-14 02:58:41.728][4b6856b1] IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=LOGIN AUTH=PLAIN COMPRESS\r\n
[2023-01-14 02:58:41.728][4b6856b1] IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n
[2023-01-14 02:58:41.728][4b6856b1] IMAP[DEBUG]: 0.00088596343994141 (TAG2)
[2023-01-14 02:58:41.728][4b6856b1] IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 02:58:41.833][4b6856b1] IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 02:58:41.833][4b6856b1] IMAP[DEBUG]: 0.10508680343628 (TAG3)
[2023-01-14 02:58:41.834][4b6856b1] IMAP[INFO]: > TAG4 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:41.835][4b6856b1] IMAP[INFO]: < * STATUS INBOX (MESSAGES 7 UNSEEN 7 UIDNEXT 13 UIDVALIDITY 65119915 APPENDLIMIT 0)\r\n
[2023-01-14 02:58:41.837][4b6856b1] IMAP[INFO]: < TAG4 OK STATUS completed\r\n
[2023-01-14 02:58:41.837][4b6856b1] IMAP[DEBUG]: 0.0032360553741455 (TAG4)
[2023-01-14 02:58:41.838][4b6856b1] IMAP[INFO]: > TAG5 SELECT "INBOX"\r\n
[2023-01-14 02:58:41.838][4b6856b1] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2023-01-14 02:58:41.838][4b6856b1] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2023-01-14 02:58:41.839][4b6856b1] IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
[2023-01-14 02:58:41.839][4b6856b1] IMAP[INFO]: < * 7 EXISTS\r\n
[2023-01-14 02:58:41.839][4b6856b1] IMAP[INFO]: < * 6 RECENT\r\n
[2023-01-14 02:58:41.839][4b6856b1] IMAP[INFO]: < * OK [UIDNEXT 13] Predicted next UID\r\n
[2023-01-14 02:58:41.839][4b6856b1] IMAP[INFO]: < * OK [UIDVALIDITY 65119915] UIDs valid\r\n
[2023-01-14 02:58:41.840][4b6856b1] IMAP[INFO]: < TAG5 OK [READ-WRITE] SELECT completed\r\n
[2023-01-14 02:58:41.840][4b6856b1] IMAP[DEBUG]: 0.0022120475769043 (TAG5)
[2023-01-14 02:58:41.841][4b6856b1] IMAP[INFO]: > TAG6 UNSELECT\r\n
[2023-01-14 02:58:41.841][4b6856b1] IMAP[INFO]: < TAG6 OK UNSELECT completed\r\n
[2023-01-14 02:58:41.841][4b6856b1] IMAP[DEBUG]: 0.0003659725189209 (TAG6)
[2023-01-14 02:58:41.841][4b6856b1] IMAP[INFO]: > TAG7 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:41.841][4b6856b1] IMAP[INFO]: < * STATUS INBOX (MESSAGES 7 UNSEEN 7 UIDNEXT 13 UIDVALIDITY 65119915 APPENDLIMIT 0)\r\n
[2023-01-14 02:58:41.842][4b6856b1] IMAP[INFO]: < TAG7 OK STATUS completed\r\n
[2023-01-14 02:58:41.842][4b6856b1] IMAP[DEBUG]: 0.00076103210449219 (TAG7)
[2023-01-14 02:58:41.842][4b6856b1] IMAP[INFO]: > TAG8 SELECT "INBOX"\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < * 7 EXISTS\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < * 6 RECENT\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < * OK [UIDNEXT 13] Predicted next UID\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < * OK [UIDVALIDITY 65119915] UIDs valid\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[INFO]: < TAG8 OK [READ-WRITE] SELECT completed\r\n
[2023-01-14 02:58:41.843][4b6856b1] IMAP[DEBUG]: 0.0015721321105957 (TAG8)
[2023-01-14 02:58:41.844][4b6856b1] [INFO]: Get Serialized UIDS from cache ("INBOX" /  / UNDELETED) [count:0]
[2023-01-14 02:58:41.844][4b6856b1] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:41.844][4b6856b1] [INFO]: Time delta: 0.13386297225952
[2023-01-14 02:58:41.844][4b6856b1] IMAP[INFO]: > TAG9 LOGOUT\r\n
[2023-01-14 02:58:41.844][4b6856b1] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 02:58:41.844][4b6856b1] IMAP[INFO]: < TAG9 OK LOGOUT completed\r\n
[2023-01-14 02:58:41.844][4b6856b1] IMAP[DEBUG]: 0.00047183036804199 (TAG9)
[2023-01-14 02:58:41.845][4b6856b1] IMAP[INFO]: Disconnected from "tcp://localhost:143" (success)
[2023-01-14 02:58:41.845][4b6856b1] IMAP[DEBUG]: 0.12825989723206 (net session)
[2023-01-14 02:58:41.943][a45f91c2] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 02:58:41.944][a45f91c2] JSON[INFO]: Action: DoFolderInformationMultiply
[2023-01-14 02:58:41.944][a45f91c2] POST[INFO]: {"Folders":["Archive","Drafts","Junk","Sent","Trash"],"Action":"FolderInformationMultiply","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:41.946][a45f91c2] IMAP[INFO]: Start connection to "tcp://localhost:143"
[2023-01-14 02:58:41.947][a45f91c2] IMAP[INFO]: Connect (success)
[2023-01-14 02:58:41.947][a45f91c2] IMAP[DEBUG]: 0.0011279582977295 (raw connection)
[2023-01-14 02:58:41.948][a45f91c2] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 02:58:41.948][a45f91c2] IMAP[DEBUG]: 0.0017192363739014 (*)
[2023-01-14 02:58:41.948][a45f91c2] IMAP[INFO]: > TAG1 STARTTLS\r\n
[2023-01-14 02:58:41.948][a45f91c2] IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n
[2023-01-14 02:58:41.948][a45f91c2] IMAP[DEBUG]: 0.00042390823364258 (TAG1)
[2023-01-14 02:58:41.957][a45f91c2] IMAP[INFO]: > TAG2 CAPABILITY\r\n
[2023-01-14 02:58:41.957][a45f91c2] IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS\r\n
[2023-01-14 02:58:41.957][a45f91c2] IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n
[2023-01-14 02:58:41.957][a45f91c2] IMAP[DEBUG]: 0.00075507164001465 (TAG2)
[2023-01-14 02:58:41.958][a45f91c2] IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 02:58:42.059][a45f91c2] IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 02:58:42.060][a45f91c2] IMAP[DEBUG]: 0.10209202766418 (TAG3)
[2023-01-14 02:58:42.060][a45f91c2] IMAP[INFO]: > TAG4 STATUS "Archive" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:42.061][a45f91c2] IMAP[INFO]: < * STATUS "Archive" (APPENDLIMIT 0 MESSAGES 0 UNSEEN 0 UIDNEXT 2 UIDVALIDITY 2753877850)\r\n
[2023-01-14 02:58:42.061][a45f91c2] IMAP[INFO]: < TAG4 OK STATUS completed\r\n
[2023-01-14 02:58:42.061][a45f91c2] IMAP[DEBUG]: 0.00093507766723633 (TAG4)
[2023-01-14 02:58:42.061][a45f91c2] IMAP[INFO]: > TAG5 STATUS "Drafts" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:42.062][a45f91c2] IMAP[INFO]: < * STATUS "Drafts" (UNSEEN 0 UIDNEXT 24 UIDVALIDITY 2693608042 APPENDLIMIT 0 MESSAGES 0)\r\n
[2023-01-14 02:58:42.062][a45f91c2] IMAP[INFO]: < TAG5 OK STATUS completed\r\n
[2023-01-14 02:58:42.062][a45f91c2] IMAP[DEBUG]: 0.00077199935913086 (TAG5)
[2023-01-14 02:58:42.062][a45f91c2] IMAP[INFO]: > TAG6 STATUS "Junk" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:42.063][a45f91c2] IMAP[INFO]: < * STATUS "Junk" (APPENDLIMIT 0 MESSAGES 0 UNSEEN 0 UIDNEXT 1 UIDVALIDITY 2998675105)\r\n
[2023-01-14 02:58:42.063][a45f91c2] IMAP[INFO]: < TAG6 OK STATUS completed\r\n
[2023-01-14 02:58:42.063][a45f91c2] IMAP[DEBUG]: 0.00073599815368652 (TAG6)
[2023-01-14 02:58:42.063][a45f91c2] IMAP[INFO]: > TAG7 STATUS "Sent" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:42.063][a45f91c2] IMAP[INFO]: < * STATUS "Sent" (UIDNEXT 11 UIDVALIDITY 3817017028 APPENDLIMIT 0 MESSAGES 0 UNSEEN 0)\r\n
[2023-01-14 02:58:42.064][a45f91c2] IMAP[INFO]: < TAG7 OK STATUS completed\r\n
[2023-01-14 02:58:42.064][a45f91c2] IMAP[DEBUG]: 0.00072288513183594 (TAG7)
[2023-01-14 02:58:42.064][a45f91c2] IMAP[INFO]: > TAG8 STATUS "Trash" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:42.064][a45f91c2] IMAP[INFO]: < * STATUS "Trash" (MESSAGES 0 UNSEEN 0 UIDNEXT 17 UIDVALIDITY 3346282728 APPENDLIMIT 0)\r\n
[2023-01-14 02:58:42.064][a45f91c2] IMAP[INFO]: < TAG8 OK STATUS completed\r\n
[2023-01-14 02:58:42.064][a45f91c2] IMAP[DEBUG]: 0.00073695182800293 (TAG8)
[2023-01-14 02:58:42.065][a45f91c2] JSON[INFO]: {"Action":"FolderInformationMultiply","Result":[{"Folder":"Archive","Hash":"9a724a8a41d15f20247ce9995b4bfb21","totalEmails":0,"unreadEmails":0},{"Folder":"Drafts","Hash":"5046bb8662b3b7035942e766c5287747","totalEmails":0,"unreadEmails":0},{"Folder":"Junk","Hash":"63fbb84e9c10751a57ced4b07e532c11","t...
[2023-01-14 02:58:42.065][a45f91c2] IMAP[INFO]: > TAG9 LOGOUT\r\n
[2023-01-14 02:58:42.065][a45f91c2] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 02:58:42.065][a45f91c2] IMAP[INFO]: < TAG9 OK LOGOUT completed\r\n
[2023-01-14 02:58:42.065][a45f91c2] IMAP[DEBUG]: 0.00046706199645996 (TAG9)
[2023-01-14 02:58:42.065][a45f91c2] IMAP[INFO]: Disconnected from "tcp://localhost:143" (success)
[2023-01-14 02:58:42.065][a45f91c2] IMAP[DEBUG]: 0.11921191215515 (net session)
[2023-01-14 02:58:42.065][a45f91c2] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:42.065][a45f91c2] [INFO]: Time delta: 0.12557888031006
[2023-01-14 02:58:42.104][a4aeb4a2] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404632][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 02:58:42.105][a4aeb4a2] JSON[INFO]: Action: DoFolderInformation
[2023-01-14 02:58:42.105][a4aeb4a2] POST[INFO]: {"Folder":"INBOX","FlagsUids":[],"UidNext":13,"Action":"FolderInformation","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 02:58:42.107][a4aeb4a2] IMAP[INFO]: Start connection to "tcp://localhost:143"
[2023-01-14 02:58:42.108][a4aeb4a2] IMAP[INFO]: Connect (success)
[2023-01-14 02:58:42.109][a4aeb4a2] IMAP[DEBUG]: 0.0013329982757568 (raw connection)
[2023-01-14 02:58:42.109][a4aeb4a2] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT STARTTLS LOGINDISABLED COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 02:58:42.109][a4aeb4a2] IMAP[DEBUG]: 0.0018379688262939 (*)
[2023-01-14 02:58:42.109][a4aeb4a2] IMAP[INFO]: > TAG1 STARTTLS\r\n
[2023-01-14 02:58:42.110][a4aeb4a2] IMAP[INFO]: < TAG1 OK Begin TLS negotiation now\r\n
[2023-01-14 02:58:42.110][a4aeb4a2] IMAP[DEBUG]: 0.00043320655822754 (TAG1)
[2023-01-14 02:58:42.117][a4aeb4a2] IMAP[INFO]: > TAG2 CAPABILITY\r\n
[2023-01-14 02:58:42.117][a4aeb4a2] IMAP[INFO]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS\r\n
[2023-01-14 02:58:42.117][a4aeb4a2] IMAP[INFO]: < TAG2 OK CAPABILITY completed\r\n
[2023-01-14 02:58:42.117][a4aeb4a2] IMAP[DEBUG]: 0.00072097778320312 (TAG2)
[2023-01-14 02:58:42.117][a4aeb4a2] IMAP[INFO]: > TAG3 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 02:58:42.218][a4aeb4a2] IMAP[INFO]: < TAG3 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 02:58:42.218][a4aeb4a2] IMAP[DEBUG]: 0.10084080696106 (TAG3)
[2023-01-14 02:58:42.219][a4aeb4a2] IMAP[INFO]: > TAG4 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 02:58:42.219][a4aeb4a2] IMAP[INFO]: < * STATUS INBOX (UIDNEXT 13 UIDVALIDITY 65119915 APPENDLIMIT 0 MESSAGES 7 UNSEEN 7)\r\n
[2023-01-14 02:58:42.220][a4aeb4a2] IMAP[INFO]: < TAG4 OK STATUS completed\r\n
[2023-01-14 02:58:42.220][a4aeb4a2] IMAP[DEBUG]: 0.00084519386291504 (TAG4)
[2023-01-14 02:58:42.220][a4aeb4a2] JSON[INFO]: {"Action":"FolderInformation","Result":{"Folder":"INBOX","totalEmails":7,"unreadEmails":7,"UidNext":13,"UidValidity":65119915,"HighestModSeq":null,"AppendLimit":null,"MailboxId":"","Hash":"b01a99e3973bf40165e62f778bbbb84a","MessagesFlags":[],"newMessages":[]},"epoch":1673665122}
[2023-01-14 02:58:42.220][a4aeb4a2] IMAP[INFO]: > TAG5 LOGOUT\r\n
[2023-01-14 02:58:42.220][a4aeb4a2] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 02:58:42.220][a4aeb4a2] IMAP[INFO]: < TAG5 OK LOGOUT completed\r\n
[2023-01-14 02:58:42.220][a4aeb4a2] IMAP[DEBUG]: 0.00047206878662109 (TAG5)
[2023-01-14 02:58:42.221][a4aeb4a2] IMAP[INFO]: Disconnected from "tcp://localhost:143" (success)
[2023-01-14 02:58:42.221][a4aeb4a2] IMAP[DEBUG]: 0.11350607872009 (net session)
[2023-01-14 02:58:42.221][a4aeb4a2] [INFO]: Memory peak usage: 2MB
[2023-01-14 02:58:42.221][a4aeb4a2] [INFO]: Time delta: 0.11979985237122
mateors commented 1 year ago

@the-djmaze 

[2023-01-14 11:56:34.076][e0393cc1] JSON[INFO]: Action: DoSendMessage
[2023-01-14 11:56:34.076][e0393cc1] POST[INFO]: {"IdentityID":"","MessageFolder":"","MessageUid":0,"SaveFolder":"Sent","From":"pay@mateors.com","To":"billahmdmostain@gmail.com","Cc":"","Bcc":"","ReplyTo":"","subject":"can i send now?","DraftInfo":null,"InReplyTo":"","References":"","MarkAsImportant":0,"Attachments":[],"Dsn":1,"ReadReceiptRequest":1,"Text":"can i send now?","Action":"SendMessage","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 11:56:34.080][e0393cc1] IMAP[INFO]: Start connection to "ssl://mx.lxctr.com:993"
[2023-01-14 11:56:34.098][e0393cc1] IMAP[INFO]: Connect (success)
[2023-01-14 11:56:34.098][e0393cc1] IMAP[DEBUG]: 0.018224000930786 (raw connection)
[2023-01-14 11:56:34.100][e0393cc1] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 11:56:34.101][e0393cc1] IMAP[DEBUG]: 0.020539999008179 (*)
[2023-01-14 11:56:34.101][e0393cc1] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 11:56:34.207][e0393cc1] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 11:56:34.207][e0393cc1] IMAP[DEBUG]: 0.10558104515076 (TAG1)
[2023-01-14 11:56:34.209][e0393cc1] SMTP[INFO]: Start connection to "ssl://mx.lxctr.com:465"
[2023-01-14 11:56:34.219][e0393cc1] SMTP[INFO]: Connect (success)
[2023-01-14 11:56:34.220][e0393cc1] SMTP[DEBUG]: 0.010030031204224 (raw connection)
[2023-01-14 11:56:34.220][e0393cc1] SMTP[INFO]: < 220 mx.lxctr.com ESMTP Service Ready\r\n
[2023-01-14 11:56:34.220][e0393cc1] SMTP[INFO]: > EHLO webmail.lxctr.com\r\n
[2023-01-14 11:56:34.220][e0393cc1] SMTP[INFO]: < 250-Hello webmail.lxctr.com\r\n
[2023-01-14 11:56:34.221][e0393cc1] SMTP[INFO]: < 250-PIPELINING\r\n
[2023-01-14 11:56:34.221][e0393cc1] SMTP[INFO]: < 250-8BITMIME\r\n
[2023-01-14 11:56:34.222][e0393cc1] SMTP[INFO]: < 250-ENHANCEDSTATUSCODES\r\n
[2023-01-14 11:56:34.222][e0393cc1] SMTP[INFO]: < 250-CHUNKING\r\n
[2023-01-14 11:56:34.222][e0393cc1] SMTP[INFO]: < 250-AUTH PLAIN LOGIN\r\n
[2023-01-14 11:56:34.222][e0393cc1] SMTP[INFO]: < 250-SMTPUTF8\r\n
[2023-01-14 11:56:34.222][e0393cc1] SMTP[INFO]: < 250-REQUIRETLS\r\n
[2023-01-14 11:56:34.222][e0393cc1] SMTP[INFO]: < 250 SIZE 33554432\r\n
[2023-01-14 11:56:34.223][e0393cc1] SMTP[INFO]: > MAIL FROM:<pay@mateors.com>\r\n
[2023-01-14 11:56:34.223][e0393cc1] SMTP[INFO]: < 502 5.7.0 Please authenticate first\r\n

[2023-01-14 11:56:34.223][e0393cc1] SMTP[ERROR]: MailSo\Smtp\Exceptions\NegativeResponseException: 502 5.7.0 Please authenticate first in /var/www/webmail/snappymail/v/2.24.5/app/libraries/MailSo/Smtp/SmtpClient.php:498

Stack trace:
#0 /var/www/webmail/snappymail/v/2.24.5/app/libraries/MailSo/Smtp/SmtpClient.php(403): MailSo\Smtp\SmtpClient->validateResponse()
#1 /var/www/webmail/snappymail/v/2.24.5/app/libraries/MailSo/Smtp/SmtpClient.php(208): MailSo\Smtp\SmtpClient->sendRequestWithCheck()
#2 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Actions/Messages.php(829): MailSo\Smtp\SmtpClient->MailFrom()
#3 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Actions/Messages.php(178): RainLoop\Actions->smtpSendMessage()
#4 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/ServiceActions.php(137): RainLoop\Actions->DoSendMessage()
#5 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(112): RainLoop\ServiceActions->ServiceJson()
#6 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(14): RainLoop\Service::RunResult()
#7 /var/www/webmail/snappymail/v/2.24.5/include.php(172): RainLoop\Service::Handle()
#8 /var/www/webmail/index.php(11): include('/var/www/webmai...')

#9 {main}
[2023-01-14 11:56:34.224][e0393cc1] SMTP[INFO]: > QUIT\r\n
[2023-01-14 11:56:34.224][e0393cc1] SMTP[INFO]: < 221 2.0.0 Bye\r\n
[2023-01-14 11:56:34.224][e0393cc1] SMTP[INFO]: Disconnected from "ssl://mx.lxctr.com:465" (success)
[2023-01-14 11:56:34.224][e0393cc1] SMTP[DEBUG]: 0.014497041702271 (net session)
[2023-01-14 11:56:34.224][e0393cc1] SERVICE[WARNING]: CantSendMessage[302]
#0 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/ServiceActions.php(137): RainLoop\Actions->DoSendMessage()
#1 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(112): RainLoop\ServiceActions->ServiceJson()
#2 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(14): RainLoop\Service::RunResult()
#3 /var/www/webmail/snappymail/v/2.24.5/include.php(172): RainLoop\Service::Handle()
#4 /var/www/webmail/index.php(11): include('/var/www/webmai...')
#5 {main}
[2023-01-14 11:56:34.224][e0393cc1] SERVICE[WARNING]: - 502 5.7.0 Please authenticate first @ /var/www/webmail/snappymail/v/2.24.5/app/libraries/MailSo/Smtp/SmtpClient.php#498
[2023-01-14 11:56:34.224][e0393cc1] [ERROR]: MailSo\Smtp\Exceptions\NegativeResponseException: 502 5.7.0 Please authenticate first in /var/www/webmail/snappymail/v/2.24.5/app/libraries/MailSo/Smtp/SmtpClient.php:498
Stack trace:
#0 /var/www/webmail/snappymail/v/2.24.5/app/libraries/MailSo/Smtp/SmtpClient.php(403): MailSo\Smtp\SmtpClient->validateResponse()
#1 /var/www/webmail/snappymail/v/2.24.5/app/libraries/MailSo/Smtp/SmtpClient.php(208): MailSo\Smtp\SmtpClient->sendRequestWithCheck()
#2 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Actions/Messages.php(829): MailSo\Smtp\SmtpClient->MailFrom()
#3 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Actions/Messages.php(178): RainLoop\Actions->smtpSendMessage()
#4 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/ServiceActions.php(137): RainLoop\Actions->DoSendMessage()
#5 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(112): RainLoop\ServiceActions->ServiceJson()
#6 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(14): RainLoop\Service::RunResult()
#7 /var/www/webmail/snappymail/v/2.24.5/include.php(172): RainLoop\Service::Handle()
#8 /var/www/webmail/index.php(11): include('/var/www/webmai...')
#9 {main}

Next RainLoop\Exceptions\ClientException: CantSendMessage[302] in /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Actions/Messages.php:271
Stack trace:
#0 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/ServiceActions.php(137): RainLoop\Actions->DoSendMessage()
#1 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(112): RainLoop\ServiceActions->ServiceJson()
#2 /var/www/webmail/snappymail/v/2.24.5/app/libraries/RainLoop/Service.php(14): RainLoop\Service::RunResult()
#3 /var/www/webmail/snappymail/v/2.24.5/include.php(172): RainLoop\Service::Handle()
#4 /var/www/webmail/index.php(11): include('/var/www/webmai...')
#5 {main}
[2023-01-14 11:56:34.225][e0393cc1] JSON[INFO]: {"Action":"SendMessage","Result":false,"ErrorCode":302,"ErrorMessage":"","ErrorMessageAdditional":"502 5.7.0 Please authenticate first","epoch":1673675794}
[2023-01-14 11:56:34.225][e0393cc1] IMAP[INFO]: > TAG2 LOGOUT\r\n
[2023-01-14 11:56:34.225][e0393cc1] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 11:56:34.225][e0393cc1] IMAP[INFO]: < TAG2 OK LOGOUT completed\r\n
[2023-01-14 11:56:34.225][e0393cc1] IMAP[DEBUG]: 0.00066113471984863 (TAG2)
[2023-01-14 11:56:34.226][e0393cc1] IMAP[INFO]: Disconnected from "ssl://mx.lxctr.com:993" (success)
[2023-01-14 11:56:34.226][e0393cc1] IMAP[DEBUG]: 0.14543485641479 (net session)
[2023-01-14 11:56:34.226][e0393cc1] [INFO]: Memory peak usage: 2MB
[2023-01-14 11:56:34.226][e0393cc1] [INFO]: Time delta: 0.15519690513611
[2023-01-14 11:56:34.481][d8239f2e] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST https://webmail.lxctr.com/?/Json/&q[]=/0/]
[2023-01-14 11:56:34.481][d8239f2e] JSON[INFO]: Action: DoFolderInformation
[2023-01-14 11:56:34.481][d8239f2e] POST[INFO]: {"Folder":"Drafts","FlagsUids":[],"UidNext":44,"Action":"FolderInformation","XToken":"bf2576b9db46f3723db5d6c5c6db6128ce6696e7"}
[2023-01-14 11:56:34.483][d8239f2e] IMAP[INFO]: Start connection to "ssl://mx.lxctr.com:993"
[2023-01-14 11:56:34.496][d8239f2e] IMAP[INFO]: Connect (success)
[2023-01-14 11:56:34.496][d8239f2e] IMAP[DEBUG]: 0.012292861938477 (raw connection)
[2023-01-14 11:56:34.496][d8239f2e] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-14 11:56:34.497][d8239f2e] IMAP[DEBUG]: 0.013160943984985 (*)
[2023-01-14 11:56:34.497][d8239f2e] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2023-01-14 11:56:34.610][d8239f2e] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-14 11:56:34.610][d8239f2e] IMAP[DEBUG]: 0.11320114135742 (TAG1)
[2023-01-14 11:56:34.611][d8239f2e] IMAP[INFO]: > TAG2 STATUS "Drafts" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-14 11:56:34.612][d8239f2e] IMAP[INFO]: < * STATUS "Drafts" (UIDVALIDITY 2693608042 APPENDLIMIT 0 MESSAGES 4 UNSEEN 0 UIDNEXT 44)\r\n
[2023-01-14 11:56:34.612][d8239f2e] IMAP[INFO]: < TAG2 OK STATUS completed\r\n
[2023-01-14 11:56:34.612][d8239f2e] IMAP[DEBUG]: 0.0011091232299805 (TAG2)
[2023-01-14 11:56:34.612][d8239f2e] JSON[INFO]: {"Action":"FolderInformation","Result":{"Folder":"Drafts","totalEmails":4,"unreadEmails":0,"UidNext":44,"UidValidity":2693608042,"HighestModSeq":null,"AppendLimit":null,"MailboxId":"","Hash":"d63414134ce7deb1aaeee2ff9cf69f36","MessagesFlags":[],"newMessages":[]},"epoch":1673675794}
[2023-01-14 11:56:34.613][d8239f2e] IMAP[INFO]: > TAG3 LOGOUT\r\n
[2023-01-14 11:56:34.613][d8239f2e] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-14 11:56:34.613][d8239f2e] IMAP[INFO]: < TAG3 OK LOGOUT completed\r\n
[2023-01-14 11:56:34.613][d8239f2e] IMAP[DEBUG]: 0.00047898292541504 (TAG3)
[2023-01-14 11:56:34.613][d8239f2e] IMAP[INFO]: Disconnected from "ssl://mx.lxctr.com:993" (success)
[2023-01-14 11:56:34.613][d8239f2e] IMAP[DEBUG]: 0.12999486923218 (net session)
[2023-01-14 11:56:34.613][d8239f2e] [INFO]: Memory peak usage: 2MB
[2023-01-14 11:56:34.614][d8239f2e] [INFO]: Time delta: 0.13562202453613
the-djmaze commented 1 year ago

Open application.ini or Admin -> Config There is a cache enable option and turn it off.

This should give you much better debug info why your messages are not loaded.

SMTP 502 5.7.0 Please authenticate first

Go to Admin -> Domains and click on the domain. Go to SMTP tab en check/enable use authentication because you have it turned off.

mateors commented 1 year ago

@the-djmaze Thank you for your response, as you said I did and now I can send email.

But for INBOX the problem still remains same, I cant read INBOX email. Please help me to fix this issue. image

the-djmaze commented 1 year ago

Open application.ini or Admin -> Config There is a cache enable option. Turn it off and save. afbeelding

Then reload the messagelist Then open the log in …/data/_data_/_default_/logs/ Place the log here.

mateors commented 1 year ago

@the-djmaze Thank you for your response. Please check the following.

I did save unchecking cache enable option image

there is no logs folder image

May be because I have setup my logs in a different place /var/www/webmail/log/

[logs]
; Enable logging
enable = On

; Path where log files will be stored
path = "/var/www/webmail/log/"

image

from my custom log file i got the following:

[2023-01-19 15:53:53.500][2e1c13df] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404631][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?/Json/&q[]=/0/MessageList/&q[]=/eyJvZmZzZXQiOjAsImxpbWl0IjoyMCwic2VhcmNoIjoiIiwidWlkTmV4dCI6MTIsInNvcnQiOiIiLCJmb2xkZXIiOiJTZW50IiwidGhyZWFkVWlkIjowLCJoYXNoIjoiODMzNGFlZDE0MjEwZDJmYTQ2YjkyM2UzMWQ1ODg2YWMtNDBjY2M4Nzc3MzcyOWU5NmQ1NGNlZjA5MDdlNDMzY2JlOWYzMTZkYSJ9]
[2023-01-19 15:53:53.500][2e1c13df] JSON[INFO]: Action: DoMessageList
[2023-01-19 15:53:53.503][2e1c13df] IMAP[INFO]: Start connection to "ssl://mx.lxctr.com:993"
[2023-01-19 15:53:53.556][2e1c13df] IMAP[INFO]: Connect (success)
[2023-01-19 15:53:53.556][2e1c13df] IMAP[DEBUG]: 0.052994012832642 (raw connection)
[2023-01-19 15:53:53.558][2e1c13df] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-19 15:53:53.558][2e1c13df] IMAP[DEBUG]: 0.054634094238281 (*)
[2023-01-19 15:53:53.558][2e1c13df] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2023-01-19 15:53:53.659][2e1c13df] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-19 15:53:53.660][2e1c13df] IMAP[DEBUG]: 0.10136485099792 (TAG1)
[2023-01-19 15:53:53.660][2e1c13df] IMAP[INFO]: > TAG2 STATUS "Sent" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-19 15:53:53.661][2e1c13df] IMAP[INFO]: < * STATUS "Sent" (MESSAGES 1 UNSEEN 0 UIDNEXT 12 UIDVALIDITY 3817017028 APPENDLIMIT 0)\r\n
[2023-01-19 15:53:53.661][2e1c13df] IMAP[INFO]: < TAG2 OK STATUS completed\r\n
[2023-01-19 15:53:53.661][2e1c13df] IMAP[DEBUG]: 0.0010149478912354 (TAG2)
[2023-01-19 15:53:53.662][2e1c13df] IMAP[INFO]: > TAG3 SELECT "Sent"\r\n
[2023-01-19 15:53:53.662][2e1c13df] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2023-01-19 15:53:53.662][2e1c13df] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2023-01-19 15:53:53.663][2e1c13df] IMAP[INFO]: < * 1 EXISTS\r\n
[2023-01-19 15:53:53.663][2e1c13df] IMAP[INFO]: < * 0 RECENT\r\n
[2023-01-19 15:53:53.663][2e1c13df] IMAP[INFO]: < * OK [UIDNEXT 12] Predicted next UID\r\n
[2023-01-19 15:53:53.663][2e1c13df] IMAP[INFO]: < * OK [UIDVALIDITY 3817017028] UIDs valid\r\n
[2023-01-19 15:53:53.663][2e1c13df] IMAP[INFO]: < TAG3 OK [READ-WRITE] SELECT completed\r\n
[2023-01-19 15:53:53.663][2e1c13df] IMAP[DEBUG]: 0.0018291473388672 (TAG3)
[2023-01-19 15:53:53.664][2e1c13df] IMAP[INFO]: > TAG4 UNSELECT\r\n
[2023-01-19 15:53:53.664][2e1c13df] IMAP[INFO]: < TAG4 OK UNSELECT completed\r\n
[2023-01-19 15:53:53.664][2e1c13df] IMAP[DEBUG]: 0.00042104721069336 (TAG4)
[2023-01-19 15:53:53.665][2e1c13df] IMAP[INFO]: > TAG5 STATUS "Sent" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-19 15:53:53.665][2e1c13df] IMAP[INFO]: < * STATUS "Sent" (MESSAGES 1 UNSEEN 0 UIDNEXT 12 UIDVALIDITY 3817017028 APPENDLIMIT 0)\r\n
[2023-01-19 15:53:53.665][2e1c13df] IMAP[INFO]: < TAG5 OK STATUS completed\r\n
[2023-01-19 15:53:53.665][2e1c13df] IMAP[DEBUG]: 0.00088810920715332 (TAG5)
[2023-01-19 15:53:53.665][2e1c13df] IMAP[INFO]: > TAG6 SELECT "Sent"\r\n
[2023-01-19 15:53:53.666][2e1c13df] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2023-01-19 15:53:53.667][2e1c13df] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2023-01-19 15:53:53.667][2e1c13df] IMAP[INFO]: < * 1 EXISTS\r\n
[2023-01-19 15:53:53.667][2e1c13df] IMAP[INFO]: < * 0 RECENT\r\n
[2023-01-19 15:53:53.667][2e1c13df] IMAP[INFO]: < * OK [UIDNEXT 12] Predicted next UID\r\n
[2023-01-19 15:53:53.667][2e1c13df] IMAP[INFO]: < * OK [UIDVALIDITY 3817017028] UIDs valid\r\n
[2023-01-19 15:53:53.667][2e1c13df] IMAP[INFO]: < TAG6 OK [READ-WRITE] SELECT completed\r\n
[2023-01-19 15:53:53.667][2e1c13df] IMAP[DEBUG]: 0.0020508766174316 (TAG6)
[2023-01-19 15:53:53.668][2e1c13df] IMAP[INFO]: > TAG7 UID SORT (REVERSE DATE) UTF-8 UNDELETED\r\n
[2023-01-19 15:53:53.669][2e1c13df] IMAP[INFO]: < * SORT 11\r\n
[2023-01-19 15:53:53.669][2e1c13df] IMAP[INFO]: < TAG7 OK UID SORT completed\r\n
[2023-01-19 15:53:53.669][2e1c13df] IMAP[DEBUG]: 0.0012679100036621 (TAG7)
[2023-01-19 15:53:53.670][2e1c13df] IMAP[INFO]: > TAG8 UID FETCH 11 (UID RFC822.SIZE INTERNALDATE FLAGS BODYSTRUCTURE BODY.PEEK[HEADER])\r\n
[2023-01-19 15:53:53.670][2e1c13df] IMAP[INFO]: < * 1 FETCH (UID 11 RFC822.SIZE 453 INTERNALDATE "19-Jan-2023 07:14:48 +0600" FLAGS (\Seen) BODYSTRUCTURE ("text" "plain" ("charset" "utf-8") NIL NIL "quoted-printable" 62 7 NIL NIL NIL NIL) BODY[HEADER] {391}\r\n
[2023-01-19 15:53:53.671][2e1c13df] IMAP[INFO]: < )\r\n
[2023-01-19 15:53:53.673][2e1c13df] IMAP[INFO]: < TAG8 OK UID FETCH completed\r\n
[2023-01-19 15:53:53.674][2e1c13df] IMAP[DEBUG]: 0.0040709972381592 (TAG8)
[2023-01-19 15:53:53.674][2e1c13df] JSON[INFO]: {"Action":"MessageList","Result":{"@Object":"Collection\/MessageCollection","@Collection":[{"@Object":"Object\/Message","Folder":"Sent","Uid":11,"subject":"can you send me the file now?","encrypted":false,"MessageId":"<bbfbfbca3840af4ad55ba2225a12fd393ab85383@mateors.com>","SpamScore":0,"SpamResult"...
[2023-01-19 15:53:53.674][2e1c13df] IMAP[INFO]: > TAG9 LOGOUT\r\n
[2023-01-19 15:53:53.675][2e1c13df] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-19 15:53:53.675][2e1c13df] IMAP[INFO]: < TAG9 OK LOGOUT completed\r\n
[2023-01-19 15:53:53.675][2e1c13df] IMAP[DEBUG]: 0.00076699256896973 (TAG9)
[2023-01-19 15:53:53.676][2e1c13df] IMAP[INFO]: Disconnected from "ssl://mx.lxctr.com:993" (success)
[2023-01-19 15:53:53.676][2e1c13df] IMAP[DEBUG]: 0.17250108718872 (net session)
[2023-01-19 15:53:53.676][2e1c13df] [INFO]: Memory peak usage: 2MB
[2023-01-19 15:53:53.676][2e1c13df] [INFO]: Time delta: 0.18052315711975
[2023-01-19 15:53:54.497][537a0d74] [INFO]: [SM:2.24.5][IP:103.124.226.98][PID:404632][nginx/1.18.0][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET https://webmail.lxctr.com/?/Json/&q[]=/0/MessageList/&q[]=/eyJvZmZzZXQiOjAsImxpbWl0IjoyMCwic2VhcmNoIjoiIiwidWlkTmV4dCI6MTYsInNvcnQiOiIiLCJmb2xkZXIiOiJJTkJPWCIsInRocmVhZFVpZCI6MCwiaGFzaCI6ImEyYjYxZGYzNjhmOTJmN2E0ZTQ0YTk1ZTdlMmEwNjQ4LTQwY2NjODc3NzM3MjllOTZkNTRjZWYwOTA3ZTQzM2NiZTlmMzE2ZGEifQ]
[2023-01-19 15:53:54.497][537a0d74] JSON[INFO]: Action: DoMessageList
[2023-01-19 15:53:54.500][537a0d74] IMAP[INFO]: Start connection to "ssl://mx.lxctr.com:993"
[2023-01-19 15:53:54.515][537a0d74] IMAP[INFO]: Connect (success)
[2023-01-19 15:53:54.516][537a0d74] IMAP[DEBUG]: 0.015858173370361 (raw connection)
[2023-01-19 15:53:54.518][537a0d74] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2023-01-19 15:53:54.520][537a0d74] IMAP[DEBUG]: 0.020212173461914 (*)
[2023-01-19 15:53:54.523][537a0d74] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2023-01-19 15:53:54.625][537a0d74] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2023-01-19 15:53:54.625][537a0d74] IMAP[DEBUG]: 0.10480904579163 (TAG1)
[2023-01-19 15:53:54.626][537a0d74] IMAP[INFO]: > TAG2 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-19 15:53:54.626][537a0d74] IMAP[INFO]: < * STATUS INBOX (MESSAGES 10 UNSEEN 10 UIDNEXT 16 UIDVALIDITY 65119915 APPENDLIMIT 0)\r\n
[2023-01-19 15:53:54.627][537a0d74] IMAP[INFO]: < TAG2 OK STATUS completed\r\n
[2023-01-19 15:53:54.627][537a0d74] IMAP[DEBUG]: 0.00088191032409668 (TAG2)
[2023-01-19 15:53:54.627][537a0d74] IMAP[INFO]: > TAG3 SELECT "INBOX"\r\n
[2023-01-19 15:53:54.627][537a0d74] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2023-01-19 15:53:54.628][537a0d74] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2023-01-19 15:53:54.628][537a0d74] IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
[2023-01-19 15:53:54.628][537a0d74] IMAP[INFO]: < * OK [UIDNEXT 16] Predicted next UID\r\n
[2023-01-19 15:53:54.628][537a0d74] IMAP[INFO]: < * OK [UIDVALIDITY 65119915] UIDs valid\r\n
[2023-01-19 15:53:54.628][537a0d74] IMAP[INFO]: < * 10 EXISTS\r\n
[2023-01-19 15:53:54.629][537a0d74] IMAP[INFO]: < * 6 RECENT\r\n
[2023-01-19 15:53:54.629][537a0d74] IMAP[INFO]: < TAG3 OK [READ-WRITE] SELECT completed\r\n
[2023-01-19 15:53:54.629][537a0d74] IMAP[DEBUG]: 0.0020689964294434 (TAG3)
[2023-01-19 15:53:54.629][537a0d74] IMAP[INFO]: > TAG4 UNSELECT\r\n
[2023-01-19 15:53:54.630][537a0d74] IMAP[INFO]: < TAG4 OK UNSELECT completed\r\n
[2023-01-19 15:53:54.630][537a0d74] IMAP[DEBUG]: 0.00057291984558105 (TAG4)
[2023-01-19 15:53:54.630][537a0d74] IMAP[INFO]: > TAG5 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2023-01-19 15:53:54.631][537a0d74] IMAP[INFO]: < * STATUS INBOX (MESSAGES 10 UNSEEN 10 UIDNEXT 16 UIDVALIDITY 65119915 APPENDLIMIT 0)\r\n
[2023-01-19 15:53:54.631][537a0d74] IMAP[INFO]: < TAG5 OK STATUS completed\r\n
[2023-01-19 15:53:54.631][537a0d74] IMAP[DEBUG]: 0.00096487998962402 (TAG5)
[2023-01-19 15:53:54.631][537a0d74] IMAP[INFO]: > TAG6 SELECT "INBOX"\r\n
[2023-01-19 15:53:54.632][537a0d74] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2023-01-19 15:53:54.632][537a0d74] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2023-01-19 15:53:54.632][537a0d74] IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
[2023-01-19 15:53:54.632][537a0d74] IMAP[INFO]: < * OK [UIDNEXT 16] Predicted next UID\r\n
[2023-01-19 15:53:54.632][537a0d74] IMAP[INFO]: < * OK [UIDVALIDITY 65119915] UIDs valid\r\n
[2023-01-19 15:53:54.632][537a0d74] IMAP[INFO]: < * 10 EXISTS\r\n
[2023-01-19 15:53:54.633][537a0d74] IMAP[INFO]: < * 6 RECENT\r\n
[2023-01-19 15:53:54.633][537a0d74] IMAP[INFO]: < TAG6 OK [READ-WRITE] SELECT completed\r\n
[2023-01-19 15:53:54.633][537a0d74] IMAP[DEBUG]: 0.0016398429870605 (TAG6)
[2023-01-19 15:53:54.633][537a0d74] IMAP[INFO]: > TAG7 UID SORT (REVERSE DATE) UTF-8 UNDELETED\r\n
[2023-01-19 15:53:54.634][537a0d74] IMAP[INFO]: < * SORT\r\n
[2023-01-19 15:53:54.634][537a0d74] IMAP[INFO]: < TAG7 OK UID SORT completed\r\n
[2023-01-19 15:53:54.634][537a0d74] IMAP[DEBUG]: 0.00072383880615234 (TAG7)
[2023-01-19 15:53:54.634][537a0d74] JSON[INFO]: {"Action":"MessageList","Result":{"@Object":"Collection\/MessageCollection","@Collection":[],"totalEmails":0,"totalThreads":null,"Folder":"INBOX","folderHash":"a2b61df368f92f7a4e44a95e7e2a0648","folderInfo":{"id":null,"Name":"INBOX","Flags":["\\Seen","\\Answered","\\Flagged","\\Deleted","\\Draft"],"...
[2023-01-19 15:53:54.634][537a0d74] IMAP[INFO]: > TAG8 LOGOUT\r\n
[2023-01-19 15:53:54.635][537a0d74] IMAP[INFO]: < * BYE Closing connection\r\n
[2023-01-19 15:53:54.635][537a0d74] IMAP[INFO]: < TAG8 OK LOGOUT completed\r\n
[2023-01-19 15:53:54.635][537a0d74] IMAP[DEBUG]: 0.00072908401489258 (TAG8)
[2023-01-19 15:53:54.635][537a0d74] IMAP[INFO]: Disconnected from "ssl://mx.lxctr.com:993" (success)
[2023-01-19 15:53:54.635][537a0d74] IMAP[DEBUG]: 0.13540101051331 (net session)
[2023-01-19 15:53:54.635][537a0d74] [INFO]: Memory peak usage: 2MB
[2023-01-19 15:53:54.635][537a0d74] [INFO]: Time delta: 0.14277982711792
the-djmaze commented 1 year ago 
IMAP[INFO]: > TAG6 SELECT "Sent"\r\n
IMAP[INFO]: < * 1 EXISTS\r\n
IMAP[INFO]: > TAG7 UID SORT (REVERSE DATE) UTF-8 UNDELETED\r\n
IMAP[INFO]: < * SORT 11\r\n

There is 1 message returned from server (UID 11)

IMAP[INFO]: > TAG6 SELECT "INBOX"\r\n
IMAP[INFO]: < * 10 EXISTS\r\n
IMAP[INFO]: > TAG7 UID SORT (REVERSE DATE) UTF-8 UNDELETED\r\n
IMAP[INFO]: < * SORT\r\n
IMAP[INFO]: < TAG7 OK UID SORT completed\r\n

There are 0 message returned from server (* SORT doesn't return UID) So all messages probably have the DELETED state.

Go to user Settings -> General and untick/disable "Hide messages marked for deletion". Then try again.

mateors commented 1 year ago

@the-djmaze

image

As you said I did and after that it seems working.

image

Thank you very much for your support, you are a great men.

the-djmaze commented 1 year ago

Thanks and you're welcome