Skip to content
This repository has been archived by the owner on Nov 4, 2024. It is now read-only.

Can't Send Message - Issue #1025

Closed
rbwilkinson opened this issue May 4, 2016 · 3 comments
Closed

Can't Send Message - Issue #1025

rbwilkinson opened this issue May 4, 2016 · 3 comments

Comments

@rbwilkinson
Copy link

rbwilkinson commented May 4, 2016 •
edited
Loading

I get an error message when I attempt to send to email addresses external to my server.
If I send to email accounts that are on the server, they are successful. I have several domains on the server and there is no issue sending to them.
When I try to send to gmail or other account I get this in my log:

[06:06:29.205][e5337303] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:26164][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:06:29.205][e5337303] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:06:29.205][e5337303] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:06:29.208][e5337303] AJAX[NOTE]: Action: DoSendMessage
[06:06:29.208][e5337303] POST[DATA]: {"IdentityID":"","MessageFolder":"Drafts","MessageUid":"85","SentFolder":"Sent","To":"[email protected]","Cc":"","Bcc":"","ReplyTo":"","Subject":"Bing","TextIsHtml":"1","Text":"<div data-html-editor-font-wrapper="true" style="font-family: arial, sans-serif; font-size: 13px;"><div data-x-div-type="html"><div data-x-div-type="body"><div style="font-family: arial, sans-serif;font-size: 13px">bing</div></div></div></div>","DraftInfo":"","InReplyTo":"","References":"","Dsn":"0","ReadReceiptRequest":"0","MarkAsImportant":"0","Action":"SendMessage","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:06:29.222][e5337303] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:06:29.236][e5337303] IMAP[NOTE]: Connected (success)
[06:06:29.276][e5337303] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:06:29.277][e5337303] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "********"\r\n
[06:06:29.289][e5337303] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:06:29.304][e5337303] SMTP[NOTE]: Start connection to "tcp://otgb.net:25"
[06:06:29.306][e5337303] SMTP[NOTE]: Connected (success)
[06:06:29.307][e5337303] SMTP[DATA]: < 220 mail.otgb.net ESMTP Postfix\r\n
[06:06:29.307][e5337303] SMTP[DATA]: > EHLO otgb.net\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-mail.otgb.net\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-PIPELINING\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-SIZE 10240000\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-VRFY\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-ETRN\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250-ENHANCEDSTATUSCODES\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250-8BITMIME\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250 DSN\r\n
[06:06:29.308][e5337303] SMTP[DATA]: > MAIL FROM:[email protected]\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250 2.1.0 Ok\r\n
[06:06:29.308][e5337303] SMTP[DATA]: > RCPT TO:[email protected]\r\n
[06:06:29.310][e5337303] SMTP[DATA]: < 554 5.7.1 [email protected]: Relay access denied\r\n
[06:06:29.310][e5337303] SMTP[ERROR]: exception 'MailSo\Smtp\Exceptions\NegativeResponseException' with message '554 5.7.1 [email protected]: Relay access denied' in /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php:744
Stack trace:
#0 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(625): MailSo\Smtp\SmtpClient->validateResponse(Array)
#1 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(371): MailSo\Smtp\SmtpClient->sendRequestWithCheck('RCPT', Array, 'TO:<rbwilkinson...')
#2 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6222): MailSo\Smtp\SmtpClient->Rcpt('rbwilkinson2@gm...', false)
#3 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6318): RainLoop\Actions->smtpSendMessage(Object(RainLoop\Model\Account), Object(MailSo\Mime\Message), Resource id #83, 964, false, true)
#4 [internal function]: RainLoop\Actions->DoSendMessage()
#5 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/ServiceActions.php(164): call_user_func(Array)
#6 [internal function]: RainLoop\ServiceActions->ServiceAjax()
#7 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(146): call_user_func(Array)
#8 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(56): RainLoop\Service->localHandle()
#9 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(79): RainLoop\Service->__construct()
#10 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/handle.php(88): RainLoop\Service::Handle()
#11 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/include.php(224): include('/var/www/vhosts...')
#12 /var/www/vhosts/rainloop/index.php(12): include('/var/www/vhosts...')
#13 {main}

[06:06:29.310][e5337303] SMTP[DATA]: > QUIT\r\n
[06:06:29.311][e5337303] SMTP[DATA]: < 221 2.0.0 Bye\r\n
[06:06:29.311][e5337303] SMTP[NOTE]: Disconnected from "tcp://otgb.net:25" (success)
[06:06:29.312][e5337303] INFO[ERROR]: exception 'MailSo\Smtp\Exceptions\NegativeResponseException' with message '554 5.7.1 [email protected]: Relay access denied' in /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php:744
Stack trace:
#0 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(625): MailSo\Smtp\SmtpClient->validateResponse(Array)
#1 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(371): MailSo\Smtp\SmtpClient->sendRequestWithCheck('RCPT', Array, 'TO:<rbwilkinson...')
#2 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6222): MailSo\Smtp\SmtpClient->Rcpt('rbwilkinson2@gm...', false)
#3 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6318): RainLoop\Actions->smtpSendMessage(Object(RainLoop\Model\Account), Object(MailSo\Mime\Message), Resource id #83, 964, false, true)
#4 [internal function]: RainLoop\Actions->DoSendMessage()
#5 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/ServiceActions.php(164): call_user_func(Array)
#6 [internal function]: RainLoop\ServiceActions->ServiceAjax()
#7 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(146): call_user_func(Array)
#8 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(56): RainLoop\Service->localHandle()
#9 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(79): RainLoop\Service->__construct()
#10 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/handle.php(88): RainLoop\Service::Handle()
#11 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/include.php(224): include('/var/www/vhosts...')
#12 /var/www/vhosts/rainloop/index.php(12): include('/var/www/vhosts...')
#13 {main}

Next exception 'RainLoop\Exceptions\ClientException' with message 'CantSendMessage[302]' in /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php:6427
Stack trace:
#0 [internal function]: RainLoop\Actions->DoSendMessage()
#1 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/ServiceActions.php(164): call_user_func(Array)
#2 [internal function]: RainLoop\ServiceActions->ServiceAjax()
#3 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(146): call_user_func(Array)
#4 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(56): RainLoop\Service->localHandle()
#5 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(79): RainLoop\Service->__construct()
#6 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/handle.php(88): RainLoop\Service::Handle()
#7 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/include.php(224): include('/var/www/vhosts...')
#8 /var/www/vhosts/rainloop/index.php(12): include('/var/www/vhosts...')
#9 {main}

[06:06:29.312][e5337303] AJAX[DATA]: {"Action":"SendMessage","Result":false,"ErrorCode":302,"ErrorMessage":"","ErrorMessageAdditional":"","Time":134}
[06:06:29.312][e5337303] IMAP[DATA]: > TAG2 LOGOUT\r\n
[06:06:29.313][e5337303] IMAP[DATA]: < * BYE Logging out\r\n
[06:06:29.313][e5337303] IMAP[DATA]: < TAG2 OK Logout completed.\r\n
[06:06:29.314][e5337303] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:06:29.314][e5337303] INFO[MEMORY]: Memory peak usage: 8.25MB
[06:06:29.314][e5337303] INFO[TIME]: Time delta: 0.13583993911743

[06:06:29.501][4350890b] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:26164][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:06:29.501][4350890b] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:06:29.502][4350890b] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:06:29.505][4350890b] AJAX[NOTE]: Action: DoMessageList
[06:06:29.505][4350890b] POST[DATA]: {"Folder":"Drafts","Offset":"0","Limit":"20","Search":"","UidNext":"","UseThreads":"0","ThreadUid":"","Action":"MessageList","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:06:29.518][4350890b] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:06:29.533][4350890b] IMAP[NOTE]: Connected (success)
[06:06:29.572][4350890b] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:06:29.573][4350890b] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "*******_"\r\n
[06:06:29.583][4350890b] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:06:29.584][4350890b] IMAP[DATA]: > TAG2 SELECT "Drafts"\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft _)] Flags permitted.\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * 3 EXISTS\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * 0 RECENT\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * OK [UIDVALIDITY 1349226538] UIDs valid\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * OK [UIDNEXT 86] Predicted next UID\r\n
[06:06:29.587][4350890b] IMAP[DATA]: < * OK [HIGHESTMODSEQ 249] Highest\r\n
[06:06:29.587][4350890b] IMAP[DATA]: < TAG2 OK [READ-WRITE] Select completed (0.000 secs).\r\n
[06:06:29.587][4350890b] IMAP[DATA]: > TAG3 CAPABILITY\r\n
[06:06:29.588][4350890b] IMAP[DATA]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE\r\n
[06:06:29.588][4350890b] IMAP[DATA]: < TAG3 OK Capability completed.\r\n
[06:06:29.588][4350890b] IMAP[DATA]: > TAG4 STATUS "Drafts" (MESSAGES UNSEEN UIDNEXT HIGHESTMODSEQ)\r\n
[06:06:29.589][4350890b] IMAP[DATA]: < * OK [HIGHESTMODSEQ 249] Highest\r\n
[06:06:29.589][4350890b] IMAP[DATA]: < * STATUS Drafts (MESSAGES 3 UIDNEXT 86 UNSEEN 0 HIGHESTMODSEQ 249)\r\n
[06:06:29.589][4350890b] IMAP[DATA]: < TAG4 OK [CLIENTBUG] Status on selected mailbox completed.\r\n
[06:06:29.589][4350890b] INFO[DATA]: Get Serialized UIDS from cache ("Drafts" / UNDELETED) [count:3]
[06:06:29.590][4350890b] IMAP[DATA]: > TAG5 UID FETCH 85,2,1 (UID RFC822.SIZE INTERNALDATE FLAGS BODYSTRUCTURE BODY.PEEK[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)])\r\n
[06:06:29.590][4350890b] IMAP[DATA]: < * 1 FETCH (UID 1 RFC822.SIZE 637 INTERNALDATE "28-Dec-2013 12:03:39 +0000" FLAGS (\Seen) BODYSTRUCTURE ("text" "plain" ("charset" "iso-8859-1") NIL NIL "8bit" 90 2 NIL NIL NIL NIL) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {475}\r\n
[06:06:29.591][4350890b] IMAP[DATA]: < )\r\n
[06:06:29.591][4350890b] IMAP[DATA]: < * 2 FETCH (UID 2 RFC822.SIZE 23988 INTERNALDATE "27-Nov-2014 18:03:38 +0000" FLAGS (\Seen) BODYSTRUCTURE (("text" "plain" ("charset" "utf-8") NIL NIL "base64" 9758 125 NIL NIL NIL NIL)("text" "html" ("charset" "utf-8") NIL NIL "base64" 13382 171 NIL NIL NIL NIL) "alternative" ("boundary" "--ALT--f26bdf6b1417111413") NIL NIL NIL) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {414}\r\n
[06:06:29.592][4350890b] IMAP[DATA]: < )\r\n
[06:06:29.629][4350890b] IMAP[DATA]: < * 3 FETCH (UID 85 RFC822.SIZE 964 INTERNALDATE "04-May-2016 06:06:18 +0000" FLAGS (\Seen) BODYSTRUCTURE (("text" "plain" ("charset" "utf-8") NIL NIL "quoted-printable" 6 1 NIL NIL NIL NIL)("text" "html" ("charset" "utf-8") NIL NIL "quoted-printable" 346 5 NIL NIL NIL NIL) "alternative" ("boundary" "--=_RainLoop_666_449582180.1462341978") NIL NIL NIL) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {272}\r\n
[06:06:29.630][4350890b] IMAP[DATA]: < )\r\n
[06:06:29.630][4350890b] IMAP[DATA]: < TAG5 OK Fetch completed.\r\n
[06:06:29.648][4350890b] AJAX[DATA]: {"Action":"MessageList","Result":{"@object":"Collection/MessageCollection","@count":3,"@Collection":[{"@object":"Object/Message","Folder":"Drafts","Uid":"85","Subject":"Bing","MessageId":"[email protected]","Size":964,"DateTimeStampInUTC":1462341978,"ReplyTo":null,"From":...
[06:06:29.648][4350890b] IMAP[DATA]: > TAG6 LOGOUT\r\n
[06:06:29.649][4350890b] IMAP[DATA]: < * BYE Logging out\r\n
[06:06:29.649][4350890b] IMAP[DATA]: < TAG6 OK Logout completed.\r\n
[06:06:29.650][4350890b] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:06:29.650][4350890b] INFO[MEMORY]: Memory peak usage: 8MB
[06:06:29.650][4350890b] INFO[TIME]: Time delta: 0.17708778381348

[06:06:44.302][d45644b1] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:20224][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:06:44.303][d45644b1] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:06:44.303][d45644b1] REQUEST[NOTE]: [GET] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../Message/&q[]=/RHJhZnRzADg1AGZiYzJiYWJmNjgyYWY5ZGJkYTg5MjI4ZjA0YmVlYWY4ADA.
[06:06:44.306][d45644b1] AJAX[NOTE]: Action: DoMessage
[06:06:44.320][d45644b1] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:06:44.334][d45644b1] IMAP[NOTE]: Connected (success)
[06:06:44.372][d45644b1] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:06:44.373][d45644b1] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "*******_"\r\n
[06:06:44.383][d45644b1] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:06:44.385][d45644b1] IMAP[DATA]: > TAG2 SELECT "Drafts"\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft _)] Flags permitted.\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * 3 EXISTS\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * 0 RECENT\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < * OK [UIDVALIDITY 1349226538] UIDs valid\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < * OK [UIDNEXT 86] Predicted next UID\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < * OK [HIGHESTMODSEQ 249] Highest\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < TAG2 OK [READ-WRITE] Select completed (0.000 secs).\r\n
[06:06:44.388][d45644b1] IMAP[DATA]: > TAG3 UID FETCH 85 (UID RFC822.SIZE BODYSTRUCTURE)\r\n
[06:06:44.388][d45644b1] IMAP[DATA]: < * 3 FETCH (UID 85 RFC822.SIZE 964 BODYSTRUCTURE (("text" "plain" ("charset" "utf-8") NIL NIL "quoted-printable" 6 1 NIL NIL NIL NIL)("text" "html" ("charset" "utf-8") NIL NIL "quoted-printable" 346 5 NIL NIL NIL NIL) "alternative" ("boundary" "--=_RainLoop_666_449582180.1462341978") NIL NIL NIL))\r\n
[06:06:44.389][d45644b1] IMAP[DATA]: < TAG3 OK Fetch completed.\r\n
[06:06:44.391][d45644b1] IMAP[DATA]: > TAG4 UID FETCH 85 (UID RFC822.SIZE INTERNALDATE FLAGS BODY.PEEK[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] BODY.PEEK[2])\r\n
[06:06:44.392][d45644b1] IMAP[DATA]: < * 3 FETCH (UID 85 RFC822.SIZE 964 INTERNALDATE "04-May-2016 06:06:18 +0000" FLAGS (\Seen) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {272}\r\n
[06:06:44.393][d45644b1] IMAP[DATA]: < BODY[2] {346}\r\n
[06:06:44.393][d45644b1] IMAP[DATA]: < )\r\n
[06:06:44.393][d45644b1] IMAP[DATA]: < TAG4 OK Fetch completed.\r\n
[06:06:44.406][d45644b1] AJAX[DATA]: {"Action":"Message","Result":{"@object":"Object/Message","Folder":"Drafts","Uid":"85","Subject":"Bing","MessageId":"[email protected]","Size":964,"DateTimeStampInUTC":1462341978,"ReplyTo":null,"From":[{"@object":"Object/Email","Name":"","Email":"[email protected]","DkimStat...
[06:06:44.406][d45644b1] IMAP[DATA]: > TAG5 LOGOUT\r\n
[06:06:44.407][d45644b1] IMAP[DATA]: < * BYE Logging out\r\n
[06:06:44.407][d45644b1] IMAP[DATA]: < TAG5 OK Logout completed.\r\n
[06:06:44.408][d45644b1] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:06:44.408][d45644b1] INFO[MEMORY]: Memory peak usage: 8MB
[06:06:44.408][d45644b1] INFO[TIME]: Time delta: 0.13422918319702

[06:07:18.721][f96d9254] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:21858][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:07:18.721][f96d9254] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:07:18.722][f96d9254] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:07:18.725][f96d9254] AJAX[NOTE]: Action: DoFolderInformation
[06:07:18.725][f96d9254] POST[DATA]: {"Folder":"INBOX","FlagsUids":"","UidNext":"5328","Action":"FolderInformation","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:07:18.739][f96d9254] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"

[06:07:18.747][cc8686ae] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:10665][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:07:18.747][cc8686ae] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:07:18.748][cc8686ae] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:07:18.751][cc8686ae] AJAX[NOTE]: Action: DoFolderInformation
[06:07:18.751][cc8686ae] POST[DATA]: {"Folder":"Drafts","FlagsUids":"","UidNext":"","Action":"FolderInformation","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:07:18.758][f96d9254] IMAP[NOTE]: Connected (success)
[06:07:18.764][cc8686ae] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:07:18.781][cc8686ae] IMAP[NOTE]: Connected (success)
[06:07:18.796][f96d9254] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:07:18.797][f96d9254] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "*******"\r\n
[06:07:18.807][f96d9254] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:07:18.807][f96d9254] IMAP[DATA]: > TAG2 CAPABILITY\r\n
[06:07:18.808][f96d9254] IMAP[DATA]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE\r\n
[06:07:18.808][f96d9254] IMAP[DATA]: < TAG2 OK Capability completed.\r\n
[06:07:18.808][f96d9254] IMAP[DATA]: > TAG3 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT HIGHESTMODSEQ)\r\n
[06:07:18.809][f96d9254] IMAP[DATA]: < * STATUS INBOX (MESSAGES 36 UIDNEXT 5328 UNSEEN 9 HIGHESTMODSEQ 3735)\r\n
[06:07:18.809][f96d9254] IMAP[DATA]: < TAG3 OK Status completed.\r\n
[06:07:18.810][f96d9254] AJAX[DATA]: {"Action":"FolderInformation","Result":{"Folder":"INBOX","Hash":"849def66f93b54d77b1b97d20f676770","MessageCount":36,"MessageUnseenCount":9,"UidNext":"5328","Flags":[],"HighestModSeq":"3735","NewMessages":[],"Version":"1.9.4.415"},"Time":118}
[06:07:18.810][f96d9254] IMAP[DATA]: > TAG4 LOGOUT\r\n
[06:07:18.810][f96d9254] IMAP[DATA]: < * BYE Logging out\r\n
[06:07:18.811][f96d9254] IMAP[DATA]: < TAG4 OK Logout completed.\r\n
[06:07:18.811][f96d9254] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:07:18.811][f96d9254] INFO[MEMORY]: Memory peak usage: 7MB
[06:07:18.811][f96d9254] INFO[TIME]: Time delta: 0.11853122711182
[06:07:18.819][cc8686ae] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:07:18.820][cc8686ae] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "*******"\r\n
[06:07:18.828][cc8686ae] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:07:18.829][cc8686ae] IMAP[DATA]: > TAG2 CAPABILITY\r\n
[06:07:18.830][cc8686ae] IMAP[DATA]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE\r\n
[06:07:18.830][cc8686ae] IMAP[DATA]: < TAG2 OK Capability completed.\r\n
[06:07:18.830][cc8686ae] IMAP[DATA]: > TAG3 STATUS "Drafts" (MESSAGES UNSEEN UIDNEXT HIGHESTMODSEQ)\r\n
[06:07:18.831][cc8686ae] IMAP[DATA]: < * STATUS Drafts (MESSAGES 3 UIDNEXT 86 UNSEEN 0 HIGHESTMODSEQ 249)\r\n
[06:07:18.831][cc8686ae] IMAP[DATA]: < TAG3 OK Status completed.\r\n
[06:07:18.832][cc8686ae] AJAX[DATA]: {"Action":"FolderInformation","Result":{"Folder":"Drafts","Hash":"eae5ea87369b120dfa79a31ec1937c89","MessageCount":3,"MessageUnseenCount":0,"UidNext":"86","Flags":[],"HighestModSeq":"249","NewMessages":[],"Version":"1.9.4.415"},"Time":114}
[06:07:18.832][cc8686ae] IMAP[DATA]: > TAG4 LOGOUT\r\n
[06:07:18.833][cc8686ae] IMAP[DATA]: < * BYE Logging out\r\n
[06:07:18.833][cc8686ae] IMAP[DATA]: < TAG4 OK Logout completed.\r\n
[06:07:18.833][cc8686ae] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:07:18.833][cc8686ae] INFO[MEMORY]: Memory peak usage: 7MB
[06:07:18.834][cc8686ae] INFO[TIME]: Time delta: 0.11484885215759
@ervee
Copy link
Contributor

ervee commented May 4, 2016

[06:06:29.304][e5337303] SMTP[NOTE]: Start connection to "tcp://otgb.net:25"
[06:06:29.306][e5337303] SMTP[NOTE]: Connected (success)
[06:06:29.307][e5337303] SMTP[DATA]: < 220 mail.otgb.net ESMTP Postfix\r\n
[06:06:29.307][e5337303] SMTP[DATA]: > EHLO otgb.net\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-mail.otgb.net\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-PIPELINING\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-SIZE 10240000\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-VRFY\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-ETRN\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250-ENHANCEDSTATUSCODES\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250-8BITMIME\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250 DSN\r\n
[06:06:29.308][e5337303] SMTP[DATA]: > MAIL FROM:[email protected]\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250 2.1.0 Ok\r\n
[06:06:29.308][e5337303] SMTP[DATA]: > RCPT TO:[email protected]\r\n
[06:06:29.310][e5337303] SMTP[DATA]: < 554 5.7.1 [email protected]: Relay access denied\r\n
[06:06:29.310][e5337303] SMTP[ERROR]: exception 'MailSo\Smtp\Exceptions\NegativeResponseException' with message '554 5.7.1 [email protected]: Relay access denied' in /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php:744

You use the SMTP Server otgb.net which clearly does not allow you to relay email outbound. If this is your local server, configure it to accept relaying from your RainLoop Server IP, or from the subnet where your Servers live. Or probably localhost if it is one box.

Or properly configure authentication to the SMTP Server.

I don't think this is a RainLoop issue.

@extremeshok
Copy link

It's your smtp server. Try using port 587 ssl.

Sent from my iPhone

On 04 May 2016, at 8:12 AM, Robert Wilkinson [email protected] wrote:

I get and error message when I attempt to send an email external to my server.
If I send to existing email accounts it is successful. I have several domains on the server and there is no issue sending to them.
When I try to send to gmail or other account I get this in my log:

[06:06:29.205][e5337303] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:26164][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:06:29.205][e5337303] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:06:29.205][e5337303] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:06:29.208][e5337303] AJAX[NOTE]: Action: DoSendMessage
[06:06:29.208][e5337303] POST[DATA]: {"IdentityID":"","MessageFolder":"Drafts","MessageUid":"85","SentFolder":"Sent","To":"[email protected]","Cc":"","Bcc":"","ReplyTo":"","Subject":"Bing","TextIsHtml":"1","Text":"

bing</div></div></div></div>","DraftInfo":"","InReplyTo":"","References":"","Dsn":"0","ReadReceiptRequest":"0","MarkAsImportant":"0","Action":"SendMessage","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:06:29.222][e5337303] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:06:29.236][e5337303] IMAP[NOTE]: Connected (success)
[06:06:29.276][e5337303] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:06:29.277][e5337303] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "********"\r\n
[06:06:29.289][e5337303] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:06:29.304][e5337303] SMTP[NOTE]: Start connection to "tcp://otgb.net:25"
[06:06:29.306][e5337303] SMTP[NOTE]: Connected (success)
[06:06:29.307][e5337303] SMTP[DATA]: < 220 mail.otgb.net ESMTP Postfix\r\n
[06:06:29.307][e5337303] SMTP[DATA]: > EHLO otgb.net\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-mail.otgb.net\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-PIPELINING\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-SIZE 10240000\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-VRFY\r\n
[06:06:29.307][e5337303] SMTP[DATA]: < 250-ETRN\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250-ENHANCEDSTATUSCODES\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250-8BITMIME\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250 DSN\r\n
[06:06:29.308][e5337303] SMTP[DATA]: > MAIL FROM:[email protected]\r\n
[06:06:29.308][e5337303] SMTP[DATA]: < 250 2.1.0 Ok\r\n
[06:06:29.308][e5337303] SMTP[DATA]: > RCPT TO:[email protected]\r\n
[06:06:29.310][e5337303] SMTP[DATA]: < 554 5.7.1 [email protected]: Relay access denied\r\n
[06:06:29.310][e5337303] SMTP[ERROR]: exception 'MailSo\Smtp\Exceptions\NegativeResponseException' with message '554 5.7.1 [email protected]: Relay access denied' in /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php:744
Stack trace:
#0 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(625): MailSo\Smtp\SmtpClient->validateResponse(Array)
#1 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(371): MailSo\Smtp\SmtpClient->sendRequestWithCheck('RCPT', Array, 'TO: #2 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6222): MailSo\Smtp\SmtpClient->Rcpt('rbwilkinson2@gm...', false)
#3 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6318): RainLoop\Actions->smtpSendMessage(Object(RainLoop\Model\Account), Object(MailSo\Mime\Message), Resource id #83, 964, false, true)
#4 [internal function]: RainLoop\Actions->DoSendMessage()
#5 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/ServiceActions.php(164): call_user_func(Array)
#6 [internal function]: RainLoop\ServiceActions->ServiceAjax()
#7 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(146): call_user_func(Array)
#8 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(56): RainLoop\Service->localHandle()
#9 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(79): RainLoop\Service->__construct()
#10 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/handle.php(88): RainLoop\Service::Handle()
#11 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/include.php(224): include('/var/www/vhosts...')
#12 /var/www/vhosts/rainloop/index.php(12): include('/var/www/vhosts...')
#13 {main}
[06:06:29.310][e5337303] SMTP[DATA]: > QUIT\r\n
[06:06:29.311][e5337303] SMTP[DATA]: < 221 2.0.0 Bye\r\n
[06:06:29.311][e5337303] SMTP[NOTE]: Disconnected from "tcp://otgb.net:25" (success)
[06:06:29.312][e5337303] INFO[ERROR]: exception 'MailSo\Smtp\Exceptions\NegativeResponseException' with message '554 5.7.1 [email protected]: Relay access denied' in /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php:744
Stack trace:
#0 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(625): MailSo\Smtp\SmtpClient->validateResponse(Array)
#1 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/MailSo/Smtp/SmtpClient.php(371): MailSo\Smtp\SmtpClient->sendRequestWithCheck('RCPT', Array, 'TO: #2 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6222): MailSo\Smtp\SmtpClient->Rcpt('rbwilkinson2@gm...', false)
#3 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php(6318): RainLoop\Actions->smtpSendMessage(Object(RainLoop\Model\Account), Object(MailSo\Mime\Message), Resource id #83, 964, false, true)
#4 [internal function]: RainLoop\Actions->DoSendMessage()
#5 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/ServiceActions.php(164): call_user_func(Array)
#6 [internal function]: RainLoop\ServiceActions->ServiceAjax()
#7 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(146): call_user_func(Array)
#8 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(56): RainLoop\Service->localHandle()
#9 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(79): RainLoop\Service->__construct()
#10 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/handle.php(88): RainLoop\Service::Handle()
#11 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/include.php(224): include('/var/www/vhosts...')
#12 /var/www/vhosts/rainloop/index.php(12): include('/var/www/vhosts...')
#13 {main}
Next exception 'RainLoop\Exceptions\ClientException' with message 'CantSendMessage[302]' in /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Actions.php:6427
Stack trace:
#0 [internal function]: RainLoop\Actions->DoSendMessage()
#1 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/ServiceActions.php(164): call_user_func(Array)
#2 [internal function]: RainLoop\ServiceActions->ServiceAjax()
#3 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(146): call_user_func(Array)
#4 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(56): RainLoop\Service->localHandle()
#5 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/libraries/RainLoop/Service.php(79): RainLoop\Service->__construct()
#6 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/app/handle.php(88): RainLoop\Service::Handle()
#7 /var/www/vhosts/rainloop/rainloop/v/1.9.4.415/include.php(224): include('/var/www/vhosts...')
#8 /var/www/vhosts/rainloop/index.php(12): include('/var/www/vhosts...')
#9 {main}
[06:06:29.312][e5337303] AJAX[DATA]: {"Action":"SendMessage","Result":false,"ErrorCode":302,"ErrorMessage":"","ErrorMessageAdditional":"","Time":134}
[06:06:29.312][e5337303] IMAP[DATA]: > TAG2 LOGOUT\r\n
[06:06:29.313][e5337303] IMAP[DATA]: < * BYE Logging out\r\n
[06:06:29.313][e5337303] IMAP[DATA]: < TAG2 OK Logout completed.\r\n
[06:06:29.314][e5337303] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:06:29.314][e5337303] INFO[MEMORY]: Memory peak usage: 8.25MB
[06:06:29.314][e5337303] INFO[TIME]: Time delta: 0.13583993911743

[06:06:29.501][4350890b] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:26164][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:06:29.501][4350890b] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:06:29.502][4350890b] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:06:29.505][4350890b] AJAX[NOTE]: Action: DoMessageList
[06:06:29.505][4350890b] POST[DATA]: {"Folder":"Drafts","Offset":"0","Limit":"20","Search":"","UidNext":"","UseThreads":"0","ThreadUid":"","Action":"MessageList","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:06:29.518][4350890b] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:06:29.533][4350890b] IMAP[NOTE]: Connected (success)
[06:06:29.572][4350890b] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:06:29.573][4350890b] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "*******"\r\n
[06:06:29.583][4350890b] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:06:29.584][4350890b] IMAP[DATA]: > TAG2 SELECT "Drafts"\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft )] Flags permitted.\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * 3 EXISTS\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * 0 RECENT\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * OK [UIDVALIDITY 1349226538] UIDs valid\r\n
[06:06:29.586][4350890b] IMAP[DATA]: < * OK [UIDNEXT 86] Predicted next UID\r\n
[06:06:29.587][4350890b] IMAP[DATA]: < * OK [HIGHESTMODSEQ 249] Highest\r\n
[06:06:29.587][4350890b] IMAP[DATA]: < TAG2 OK [READ-WRITE] Select completed (0.000 secs).\r\n
[06:06:29.587][4350890b] IMAP[DATA]: > TAG3 CAPABILITY\r\n
[06:06:29.588][4350890b] IMAP[DATA]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE\r\n
[06:06:29.588][4350890b] IMAP[DATA]: < TAG3 OK Capability completed.\r\n
[06:06:29.588][4350890b] IMAP[DATA]: > TAG4 STATUS "Drafts" (MESSAGES UNSEEN UIDNEXT HIGHESTMODSEQ)\r\n
[06:06:29.589][4350890b] IMAP[DATA]: < * OK [HIGHESTMODSEQ 249] Highest\r\n
[06:06:29.589][4350890b] IMAP[DATA]: < * STATUS Drafts (MESSAGES 3 UIDNEXT 86 UNSEEN 0 HIGHESTMODSEQ 249)\r\n
[06:06:29.589][4350890b] IMAP[DATA]: < TAG4 OK [CLIENTBUG] Status on selected mailbox completed.\r\n
[06:06:29.589][4350890b] INFO[DATA]: Get Serialized UIDS from cache ("Drafts" / UNDELETED) [count:3]
[06:06:29.590][4350890b] IMAP[DATA]: > TAG5 UID FETCH 85,2,1 (UID RFC822.SIZE INTERNALDATE FLAGS BODYSTRUCTURE BODY.PEEK[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)])\r\n
[06:06:29.590][4350890b] IMAP[DATA]: < * 1 FETCH (UID 1 RFC822.SIZE 637 INTERNALDATE "28-Dec-2013 12:03:39 +0000" FLAGS (\Seen) BODYSTRUCTURE ("text" "plain" ("charset" "iso-8859-1") NIL NIL "8bit" 90 2 NIL NIL NIL NIL) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {475}\r\n
[06:06:29.591][4350890b] IMAP[DATA]: < )\r\n
[06:06:29.591][4350890b] IMAP[DATA]: < * 2 FETCH (UID 2 RFC822.SIZE 23988 INTERNALDATE "27-Nov-2014 18:03:38 +0000" FLAGS (\Seen) BODYSTRUCTURE (("text" "plain" ("charset" "utf-8") NIL NIL "base64" 9758 125 NIL NIL NIL NIL)("text" "html" ("charset" "utf-8") NIL NIL "base64" 13382 171 NIL NIL NIL NIL) "alternative" ("boundary" "--ALT--f26bdf6b1417111413") NIL NIL NIL) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {414}\r\n
[06:06:29.592][4350890b] IMAP[DATA]: < )\r\n
[06:06:29.629][4350890b] IMAP[DATA]: < * 3 FETCH (UID 85 RFC822.SIZE 964 INTERNALDATE "04-May-2016 06:06:18 +0000" FLAGS (\Seen) BODYSTRUCTURE (("text" "plain" ("charset" "utf-8") NIL NIL "quoted-printable" 6 1 NIL NIL NIL NIL)("text" "html" ("charset" "utf-8") NIL NIL "quoted-printable" 346 5 NIL NIL NIL NIL) "alternative" ("boundary" "--=_RainLoop_666_449582180.1462341978") NIL NIL NIL) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {272}\r\n
[06:06:29.630][4350890b] IMAP[DATA]: < )\r\n
[06:06:29.630][4350890b] IMAP[DATA]: < TAG5 OK Fetch completed.\r\n
[06:06:29.648][4350890b] AJAX[DATA]: {"Action":"MessageList","Result":{"@object":"Collection/MessageCollection","@count":3,"@Collection":[{"@object":"Object/Message","Folder":"Drafts","Uid":"85","Subject":"Bing","MessageId":"[email protected]","Size":964,"DateTimeStampInUTC":1462341978,"ReplyTo":null,"From":...
[06:06:29.648][4350890b] IMAP[DATA]: > TAG6 LOGOUT\r\n
[06:06:29.649][4350890b] IMAP[DATA]: < * BYE Logging out\r\n
[06:06:29.649][4350890b] IMAP[DATA]: < TAG6 OK Logout completed.\r\n
[06:06:29.650][4350890b] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:06:29.650][4350890b] INFO[MEMORY]: Memory peak usage: 8MB
[06:06:29.650][4350890b] INFO[TIME]: Time delta: 0.17708778381348

[06:06:44.302][d45644b1] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:20224][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:06:44.303][d45644b1] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:06:44.303][d45644b1] REQUEST[NOTE]: [GET] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../Message/&q[]=/RHJhZnRzADg1AGZiYzJiYWJmNjgyYWY5ZGJkYTg5MjI4ZjA0YmVlYWY4ADA.
[06:06:44.306][d45644b1] AJAX[NOTE]: Action: DoMessage
[06:06:44.320][d45644b1] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:06:44.334][d45644b1] IMAP[NOTE]: Connected (success)
[06:06:44.372][d45644b1] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:06:44.373][d45644b1] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "*******"\r\n
[06:06:44.383][d45644b1] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:06:44.385][d45644b1] IMAP[DATA]: > TAG2 SELECT "Drafts"\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft )] Flags permitted.\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * 3 EXISTS\r\n
[06:06:44.386][d45644b1] IMAP[DATA]: < * 0 RECENT\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < * OK [UIDVALIDITY 1349226538] UIDs valid\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < * OK [UIDNEXT 86] Predicted next UID\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < * OK [HIGHESTMODSEQ 249] Highest\r\n
[06:06:44.387][d45644b1] IMAP[DATA]: < TAG2 OK [READ-WRITE] Select completed (0.000 secs).\r\n
[06:06:44.388][d45644b1] IMAP[DATA]: > TAG3 UID FETCH 85 (UID RFC822.SIZE BODYSTRUCTURE)\r\n
[06:06:44.388][d45644b1] IMAP[DATA]: < * 3 FETCH (UID 85 RFC822.SIZE 964 BODYSTRUCTURE (("text" "plain" ("charset" "utf-8") NIL NIL "quoted-printable" 6 1 NIL NIL NIL NIL)("text" "html" ("charset" "utf-8") NIL NIL "quoted-printable" 346 5 NIL NIL NIL NIL) "alternative" ("boundary" "--=_RainLoop_666_449582180.1462341978") NIL NIL NIL))\r\n
[06:06:44.389][d45644b1] IMAP[DATA]: < TAG3 OK Fetch completed.\r\n
[06:06:44.391][d45644b1] IMAP[DATA]: > TAG4 UID FETCH 85 (UID RFC822.SIZE INTERNALDATE FLAGS BODY.PEEK[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] BODY.PEEK[2])\r\n
[06:06:44.392][d45644b1] IMAP[DATA]: < * 3 FETCH (UID 85 RFC822.SIZE 964 INTERNALDATE "04-May-2016 06:06:18 +0000" FLAGS (\Seen) BODY[HEADER.FIELDS (RETURN-PATH RECEIVED MIME-VERSION MESSAGE-ID CONTENT-TYPE FROM TO CC BCC SENDER REPLY-TO DELIVERED-TO IN-REPLY-TO REFERENCES DATE SUBJECT SENSITIVITY X-MSMAIL-PRIORITY IMPORTANCE X-PRIORITY X-DRAFT-INFO RETURN-RECEIPT-TO DISPOSITION-NOTIFICATION-TO X-CONFIRM-READING-TO AUTHENTICATION-RESULTS X-DKIM-AUTHENTICATION-RESULTS)] {272}\r\n
[06:06:44.393][d45644b1] IMAP[DATA]: < BODY[2] {346}\r\n
[06:06:44.393][d45644b1] IMAP[DATA]: < )\r\n
[06:06:44.393][d45644b1] IMAP[DATA]: < TAG4 OK Fetch completed.\r\n
[06:06:44.406][d45644b1] AJAX[DATA]: {"Action":"Message","Result":{"@object":"Object/Message","Folder":"Drafts","Uid":"85","Subject":"Bing","MessageId":"[email protected]","Size":964,"DateTimeStampInUTC":1462341978,"ReplyTo":null,"From":[{"@object":"Object/Email","Name":"","Email":"[email protected]","DkimStat...
[06:06:44.406][d45644b1] IMAP[DATA]: > TAG5 LOGOUT\r\n
[06:06:44.407][d45644b1] IMAP[DATA]: < * BYE Logging out\r\n
[06:06:44.407][d45644b1] IMAP[DATA]: < TAG5 OK Logout completed.\r\n
[06:06:44.408][d45644b1] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:06:44.408][d45644b1] INFO[MEMORY]: Memory peak usage: 8MB
[06:06:44.408][d45644b1] INFO[TIME]: Time delta: 0.13422918319702

[06:07:18.721][f96d9254] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:21858][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:07:18.721][f96d9254] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:07:18.722][f96d9254] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:07:18.725][f96d9254] AJAX[NOTE]: Action: DoFolderInformation
[06:07:18.725][f96d9254] POST[DATA]: {"Folder":"INBOX","FlagsUids":"","UidNext":"5328","Action":"FolderInformation","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:07:18.739][f96d9254] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"

[06:07:18.747][cc8686ae] INFO[DATA]: [DATE:04.05.16][RL:1.9.4.415][PHP:5.6.20-1+deb.sury.org~trusty+1][IP:37.152.221.115][PID:10665][Apache/2.4.7 (Ubuntu)][apache2handler]
[06:07:18.747][cc8686ae] INFO[DATA]: [Suhosin:off][APC:off][MB:on][PDO:mysql,sqlite][Streams:tcp,udp,unix,udg,ssl,sslv3,tls,tlsv1.0,tlsv1.1,tlsv1.2]
[06:07:18.748][cc8686ae] REQUEST[NOTE]: [POST] http://otgb.net/mail/?/Ajax/&q[]=/_VIK9zDDlj0xnJvMrEE6wXn8BegrxNoqDItNXWaFaNfZMXiLL5RTkdOid-9OizU2m_qZaq2FtfmpXZTbWmCtkOT8l_OkdBUC75I_mu53GRoaLH0PA5JZjxzK39N7qj2dNXvDWCgog_Xb_aS_HzP9eFdGr6NKWrbFjB5U2EVyW5zV0AvRR02IcNmymV_OoyYMbDg9usaeE4TeR6O-BLDS2sOoP1dvpk_9R-VwOpnCKP6jHJccmuAsMmdchSp8LDX1Lf4Cs0m6OR0CQ2j2a9OCDn2Qhbsof-n4zSJuYdVIMbBcEIKhCU1lbZm3HjV1TgbGeIz7NBA../
[06:07:18.751][cc8686ae] AJAX[NOTE]: Action: DoFolderInformation
[06:07:18.751][cc8686ae] POST[DATA]: {"Folder":"Drafts","FlagsUids":"","UidNext":"","Action":"FolderInformation","XToken":"0e228ee46557570ad49ee4b71d77395c"}
[06:07:18.758][f96d9254] IMAP[NOTE]: Connected (success)
[06:07:18.764][cc8686ae] IMAP[NOTE]: Start connection to "ssl://otgb.net:993"
[06:07:18.781][cc8686ae] IMAP[NOTE]: Connected (success)
[06:07:18.796][f96d9254] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:07:18.797][f96d9254] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "****"\r\n
[06:07:18.807][f96d9254] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:07:18.807][f96d9254] IMAP[DATA]: > TAG2 CAPABILITY\r\n
[06:07:18.808][f96d9254] IMAP[DATA]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE\r\n
[06:07:18.808][f96d9254] IMAP[DATA]: < TAG2 OK Capability completed.\r\n
[06:07:18.808][f96d9254] IMAP[DATA]: > TAG3 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT HIGHESTMODSEQ)\r\n
[06:07:18.809][f96d9254] IMAP[DATA]: < * STATUS INBOX (MESSAGES 36 UIDNEXT 5328 UNSEEN 9 HIGHESTMODSEQ 3735)\r\n
[06:07:18.809][f96d9254] IMAP[DATA]: < TAG3 OK Status completed.\r\n
[06:07:18.810][f96d9254] AJAX[DATA]: {"Action":"FolderInformation","Result":{"Folder":"INBOX","Hash":"849def66f93b54d77b1b97d20f676770","MessageCount":36,"MessageUnseenCount":9,"UidNext":"5328","Flags":[],"HighestModSeq":"3735","NewMessages":[],"Version":"1.9.4.415"},"Time":118}
[06:07:18.810][f96d9254] IMAP[DATA]: > TAG4 LOGOUT\r\n
[06:07:18.810][f96d9254] IMAP[DATA]: < * BYE Logging out\r\n
[06:07:18.811][f96d9254] IMAP[DATA]: < TAG4 OK Logout completed.\r\n
[06:07:18.811][f96d9254] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:07:18.811][f96d9254] INFO[MEMORY]: Memory peak usage: 7MB
[06:07:18.811][f96d9254] INFO[TIME]: Time delta: 0.11853122711182
[06:07:18.819][cc8686ae] IMAP[DATA]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot (Ubuntu) ready.\r\n
[06:07:18.820][cc8686ae] IMAP[SECURE]: > TAG1 LOGIN "[email protected]" "****"\r\n
[06:07:18.828][cc8686ae] IMAP[DATA]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE] Logged in\r\n
[06:07:18.829][cc8686ae] IMAP[DATA]: > TAG2 CAPABILITY\r\n
[06:07:18.830][cc8686ae] IMAP[DATA]: < * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE\r\n
[06:07:18.830][cc8686ae] IMAP[DATA]: < TAG2 OK Capability completed.\r\n
[06:07:18.830][cc8686ae] IMAP[DATA]: > TAG3 STATUS "Drafts" (MESSAGES UNSEEN UIDNEXT HIGHESTMODSEQ)\r\n
[06:07:18.831][cc8686ae] IMAP[DATA]: < * STATUS Drafts (MESSAGES 3 UIDNEXT 86 UNSEEN 0 HIGHESTMODSEQ 249)\r\n
[06:07:18.831][cc8686ae] IMAP[DATA]: < TAG3 OK Status completed.\r\n
[06:07:18.832][cc8686ae] AJAX[DATA]: {"Action":"FolderInformation","Result":{"Folder":"Drafts","Hash":"eae5ea87369b120dfa79a31ec1937c89","MessageCount":3,"MessageUnseenCount":0,"UidNext":"86","Flags":[],"HighestModSeq":"249","NewMessages":[],"Version":"1.9.4.415"},"Time":114}
[06:07:18.832][cc8686ae] IMAP[DATA]: > TAG4 LOGOUT\r\n
[06:07:18.833][cc8686ae] IMAP[DATA]: < * BYE Logging out\r\n
[06:07:18.833][cc8686ae] IMAP[DATA]: < TAG4 OK Logout completed.\r\n
[06:07:18.833][cc8686ae] IMAP[NOTE]: Disconnected from "ssl://otgb.net:993" (success)
[06:07:18.833][cc8686ae] INFO[MEMORY]: Memory peak usage: 7MB
[06:07:18.834][cc8686ae] INFO[TIME]: Time delta: 0.11484885215759

—
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub

@RainLoop
Copy link
Owner

The problem is on IMAP server side.

[06:06:29.308][e5337303] SMTP[DATA]: > RCPT TO:[email protected]\r\n
[06:06:29.310][e5337303] SMTP[DATA]: < 554 5.7.1 [email protected]: Relay access denied\r\n

Some links:
https://www.google.com/search?q=relay+access+denied+dovecot
https://www.howtoforge.com/community/threads/postfix-dovecot-relay-access-denied.42817/

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@rbwilkinson @ervee @RainLoop @extremeshok