<html>
<head>
<meta http-equiv="Content-Type" content="text/html; charset=UTF-8">
</head>
<body bgcolor="#FFFFFF" text="#000000">
<p>Patrick, Javier, at last progress. Brilliant. :-) See below.<br>
</p>
<div class="moz-cite-prefix">On 30/11/2018 14:16, Patrick Boutilier
wrote:<br>
</div>
<blockquote type="cite"
cite="mid:dfc6baa0-0ff3-bba1-d964-23b38c0a1427@ednet.ns.ca">On
11/30/18 10:00 AM, Charles Bradshaw via Info-cyrus wrote:
<br>
<blockquote type="cite">Javier
<br>
<br>
On 30/11/2018 11:49, Javier Angulo wrote:
<br>
<blockquote type="cite">On 11/29/18 8:00 PM, Charles Bradshaw
via Info-cyrus wrote:
<br>
<blockquote type="cite">Now you tell me is cyrus syslog being
sent to /var/log/maillog? Or
<br>
should it be going to /var/imapd.log as the configuration
files, man
<br>
pages and cyrus installation guides ( found here:
<br>
<a class="moz-txt-link-freetext" href="https://www.cyrusimap.org/imap/installing.html">https://www.cyrusimap.org/imap/installing.html</a> ) say it
should?
<br>
</blockquote>
I believe there is no "syslog_facility:" option in cyrus 2.4
(at least I
<br>
was unable to find it). You can configure it in cyrus3 and
maybe in
<br>
cyrus 2.5.
<br>
</blockquote>
I removed syslog_facility from imapd.conf
<br>
<blockquote type="cite">So in /etc/imapd.conf I would remove the
syslog_facility line and set:
<br>
syslog_prefix: cyrus
<br>
</blockquote>
Has no effect: present or not, or changed to test.
<br>
<blockquote type="cite">And in /etc/rsyslog.conf:
<br>
mail.* -/var/log/maillog
<br>
</blockquote>
Has always been in my rsyslog.conf
<br>
<blockquote type="cite">
<br>
Restart rsyslog and check logs for cyrus/something ...
<br>
</blockquote>
<br>
# /etc/init.d/rsyslog restart
<br>
<br>
# service sendmail restart
<br>
<br>
Now when I connect (from another host) using Thunderbird Mail I
see in
<br>
/etc/maillog:
<br>
<br>
Nov 30 13:01:02 dell2600-1 sendmail[9865]: NOQUEUE: stopping
daemon,
<br>
reason=signal
<br>
Nov 30 13:01:02 dell2600-1 sendmail[9950]: starting daemon
(8.14.4):
<br>
SMTP+queueing@01:00:00
<br>
Nov 30 13:01:02 dell2600-1 sendmail[9950]: STARTTLS: CRLFile
missing
<br>
Nov 30 13:01:03 dell2600-1 sendmail[9950]: STARTTLS=server,
<br>
Diffie-Hellman init, key=1024 bit (1)
<br>
Nov 30 13:01:03 dell2600-1 sendmail[9950]: STARTTLS=server,
init=1
<br>
Nov 30 13:01:03 dell2600-1 sendmail[9950]: started as:
<br>
/usr/sbin/sendmail -bd -q1h
<br>
Nov 30 13:01:03 dell2600-1 sm-msp-queue[9960]: starting daemon
(8.14.4):
<br>
queueing@01:00:00
<br>
Nov 30 13:01:26 dell2600-1 cyrus/imaps[8645]: USAGE
<br>
<a class="moz-txt-link-abbreviated" href="mailto:brad@bradcan.homelinux.com">brad@bradcan.homelinux.com</a> user: 0.141978 sys: 0.087986
<br>
Nov 30 13:05:59 dell2600-1 cyrus/imaps[8743]: starttls: TLSv1.2
with
<br>
cipher AES128-SHA (128/128 bits new) no authentication
<br>
Nov 30 13:05:59 dell2600-1 cyrus/imaps[8743]: login:
[192.168.0.6]
<br>
<a class="moz-txt-link-abbreviated" href="mailto:brad@bradcan.homelinux.com">brad@bradcan.homelinux.com</a> CRAM-MD5+TLS User logged in
<br>
SESSIONID=<cyrus-8743-1543583158-1>
<br>
Nov 30 13:05:59 dell2600-1 cyrus/imaps[8743]: client id: "name"
<br>
"Thunderbird" "version" "60.2.1"
<br>
<br>
Hum.. cyrus/imaps sends logging to /etc/maillog
<br>
<br>
I think it is absolutely clear:
<br>
<br>
1 - where cyrus syslog goes to is a red herring. It goes to, and
has
<br>
always gone to /var/maillog. It is simply that the prefix
'cyrus' only
<br>
appears for cyrus imap transactions and other sendmail is
labeled 'sendmail'
<br>
<br>
2 - imapd is working fine: allows brad.bradcan.homelinux.com to
connect
<br>
an email client. Also to move email from one mailbox to another.
The
<br>
proof is that since enabling telemetry logging
<br>
/var/lib/imap/log/brad@bradcan.homelinux.com/ reflects imap
transactions.
<br>
<br>
3 - A problem remains with LMTP. as is clearly evident from
'User
<br>
unknown' appearing in maillog.
<br>
<br>
My original question remains: How do I diagnose this when a test
email
<br>
is sent to <a class="moz-txt-link-abbreviated" href="mailto:brad@bradcan.homelinux.com">brad@bradcan.homelinux.com</a> :
<br>
<br>
Nov 30 12:59:48 dell2600-1 sendmail[9882]: wAUCxmBS009882:
<br>
<a class="moz-txt-link-abbreviated" href="mailto:to=brad@bradcan.homelinux.com">to=brad@bradcan.homelinux.com</a>, delay=00:00:00, xdelay=00:00:00,
<br>
mailer=cyrusv2, pri=32701, relay=localhost [[UNIX:
<br>
/var/lib/imap/socket/lmtp]], dsn=5.1.1, stat=User unknown
<br>
</blockquote>
<br>
<br>
I think why people are concentrating on the logging is that there
should be lmtp entries in your logs to indicate what the issue is.
Are there any lmtp entries in either /etc/maillog or
/var/log/maillog ?
<br>
</blockquote>
<p>The only lmtp entries are the one shown above.</p>
<p>I do # cat /var/log/maillog | grep lmtp</p>
<p>Nov 30 12:59:48 dell2600-1 sendmail[9882]: wAUCxmBR009882:
to=<a class="moz-txt-link-rfc2396E" href="mailto:brad@bradcan.homelinux.com"><brad@bradcan.homelinux.com></a>, delay=00:00:00,
xdelay=00:00:00, mailer=cyrusv2, pri=31677, relay=localhost
[[UNIX: /var/lib/imap/socket/lmtp]], dsn=5.1.1, stat=User unknown</p>
<blockquote type="cite"
cite="mid:dfc6baa0-0ff3-bba1-d964-23b38c0a1427@ednet.ns.ca">
<br>
<br>
Another option is to limit lmtpd to one process and strace it.
<br>
</blockquote>
<p>If I do:</p>
<p>[root@dell2600-1 brad]# ps -A | grep lmtp<br>
10146 ? 00:00:00 lmtpd<br>
<br>
</p>
[root@dell2600-1 brad]# strace -p 10146<br>
Process 10146 attached<br>
accept(4, 0, NULL) = 11<br>
fcntl64(10, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0,
len=0}) = 0<br>
alarm(0) = 0<br>
write(3, "\2\0\0\0\242'\0\0", 8) = 8<br>
dup2(11, 0) = 0<br>
dup2(11, 1) = 1<br>
dup2(11, 2) = 2<br>
close(11) = 0<br>
write(3, "\3\0\0\0\242'\0\0", 8) = 8<br>
time(NULL) = 1543588732<br>
getpeername(0, {sa_family=AF_LOCAL, NULL}, [2]) = 0<br>
open("/var/lib/imap/log/postman/lmtpunix-10146",
O_WRONLY|O_CREAT|O_APPEND, 0644) = -1 ENOENT (No such file or
directory)<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "220 dell2600-1.bradcan.homelinux"..., 94) = 94<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999704})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "LHLO bradcan.homelinux.com\r\n", 4096) = 28<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250-dell2600-1.bradcan.homelinux"..., 139) = 139<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
997839})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "MAIL From:<<a class="moz-txt-link-abbreviated" href="mailto:brad@bradcan.homelinu">brad@bradcan.homelinu</a>"..., 4096) = 49<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.1.0 ok\r\n", 14) = 14<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
998503})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RCPT To:<brad>\r\nDATA\r\n", 4096) = 22<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
fcntl64(6, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0,
len=0}) = 0<br>
fstat64(6, {st_mode=S_IFREG|0600, st_size=22204, ...}) = 0<br>
stat64("/var/lib/imap/mailboxes.db", {st_mode=S_IFREG|0600,
st_size=22204, ...}) = 0<br>
fcntl64(6, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0,
len=0}) = 0<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "550-Mailbox unknown. Either the"..., 174) = 174<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999990})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RSET\r\n", 4096) = 6<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.0.0 ok SESSIONID=<cyrus-10"..., 51) = 51<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
932516})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RSET\r\n", 4096) = 6<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.0.0 ok SESSIONID=<cyrus-10"..., 51) = 51<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999995})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "MAIL From:<> SIZE=1677\r\n", 4096) = 24<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.1.0 ok\r\n", 14) = 14<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999524})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RCPT To:<brad>\r\nDATA\r\n", 4096) = 22<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
fcntl64(6, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0,
len=0}) = 0<br>
fstat64(6, {st_mode=S_IFREG|0600, st_size=22204, ...}) = 0<br>
stat64("/var/lib/imap/mailboxes.db", {st_mode=S_IFREG|0600,
st_size=22204, ...}) = 0<br>
fcntl64(6, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0,
len=0}) = 0<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "550-Mailbox unknown. Either the"..., 174) = 174<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999612})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RSET\r\n", 4096) = 6<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.0.0 ok SESSIONID=<cyrus-10"..., 51) = 51<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
946078})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RSET\r\n", 4096) = 6<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.0.0 ok SESSIONID=<cyrus-10"..., 51) = 51<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999994})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "MAIL From:<> SIZE=2701\r\n", 4096) = 24<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.1.0 ok\r\n", 14) = 14<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999927})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RCPT To:<brad>\r\nDATA\r\n", 4096) = 22<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
fcntl64(6, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0,
len=0}) = 0<br>
fstat64(6, {st_mode=S_IFREG|0600, st_size=22204, ...}) = 0<br>
stat64("/var/lib/imap/mailboxes.db", {st_mode=S_IFREG|0600,
st_size=22204, ...}) = 0<br>
fcntl64(6, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0,
len=0}) = 0<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "550-Mailbox unknown. Either the"..., 174) = 174<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
999987})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "RSET\r\n", 4096) = 6<br>
open("/var/lib/imap/msg/shutdown", O_RDONLY) = -1 ENOENT (No such
file or directory)<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {0, 0}) = 0 (Timeout)<br>
write(1, "250 2.0.0 ok SESSIONID=<cyrus-10"..., 51) = 51<br>
time(NULL) = 1543588732<br>
select(1, [0], NULL, NULL, {360, 0}) = 1 (in [0], left {359,
871969})<br>
time(NULL) = 1543588732<br>
time(NULL) = 1543588732<br>
read(0, "QUIT\r\n", 4096) = 6<br>
write(1, "221 2.0.0 bye\r\n", 15) = 15<br>
open("/dev/null", O_RDWR) = 11<br>
shutdown(0, SHUT_RD) = 0<br>
dup2(11, 0) = 0<br>
shutdown(1, SHUT_RD) = 0<br>
dup2(11, 1) = 1<br>
shutdown(2, SHUT_RD) = 0<br>
dup2(11, 2) = 2<br>
close(11) = 0<br>
write(3, "\1\0\0\0\242'\0\0", 8) = 8<br>
rt_sigaction(SIGALRM, {0x80113f00, [], SA_RESETHAND}, NULL, 8) = 0<br>
rt_sigaction(SIGQUIT, {0x80113f00, [], SA_RESETHAND}, NULL, 8) = 0<br>
rt_sigaction(SIGHUP, {0x80113f00, [], SA_RESTART|SA_RESETHAND},
NULL, 8) = 0<br>
rt_sigaction(SIGINT, {0x80113f00, [], SA_RESTART|SA_RESETHAND},
NULL, 8) = 0<br>
alarm(74) = 0<br>
fcntl64(10, F_SETLKW, {type=F_WRLCK, whence=SEEK_SET, start=0,
len=0}) = ? ERESTARTSYS (To be restarted if SA_RESTART is set)<br>
--- SIGALRM {si_signo=SIGALRM, si_code=SI_KERNEL,
si_value={int=1698768, ptr=0x19ebd0}} ---<br>
sigreturn() (mask []) = -1 EINTR (Interrupted
system call)<br>
munmap(0xb7700000, 16384) = 0<br>
close(5) = 0<br>
munmap(0xb76f8000, 32768) = 0<br>
close(6) = 0<br>
munmap(0xb76f4000, 16384) = 0<br>
close(7) = 0<br>
munmap(0xb76f0000, 16384) = 0<br>
close(8) = 0<br>
exit_group(0) = ?<br>
+++ exited with 0 +++<br>
<p>I don't understand the above! Except there are a number of lines
like:</p>
<p>open("/var/lib/imap/log/postman/lmtpunix-10146",
O_WRONLY|O_CREAT|O_APPEND, 0644) = -1 ENOENT (No such file or
directory)<br>
</p>
<p>Does any of the above explain 'User unknown', apart from fact
that lmtp quit with SIGALRM</p>
<p> I created /var/lib/imap/log/postman then when the test email is
sent /var/lib/imap/log/postman contains this message:</p>
---------- postman Fri Nov 30 15:04:47 2018<br>
<br>
>1543590287>220 dell2600-1.bradcan.homelinux.com Cyrus LMTP
v2.4.17-Invoca-RPM-2.4.17-7.el6 server ready<br>
<1543590287<LHLO bradcan.homelinux.com<br>
>1543590287>250-dell2600-1.bradcan.homelinux.com<br>
250-8BITMIME<br>
250-ENHANCEDSTATUSCODES<br>
250-PIPELINING<br>
250-SIZE<br>
250-AUTH EXTERNAL<br>
250 IGNOREQUOTA<br>
<1543590287<MAIL From:<a class="moz-txt-link-rfc2396E" href="mailto:brad@bradcan.homelinux.com"><brad@bradcan.homelinux.com></a>
SIZE=653<br>
>1543590287>250 2.1.0 ok<br>
<1543590287<RCPT To:<brad><br>
DATA<br>
>1543590287>550-Mailbox unknown. Either there is no mailbox
associated with this<br>
550-name or you do not have authorization to see it.<br>
550 5.1.1 User unknown<br>
...<br>
<p>Now I'm guessing, since user <a class="moz-txt-link-abbreviated" href="mailto:brad@bradcan.homelinux.com">brad@bradcan.homelinux.com</a> does
exist and is working then it must be authorization.</p>
<p>So why is authorization failing after years of working? How do I
test authorization?</p>
<blockquote type="cite"
cite="mid:dfc6baa0-0ff3-bba1-d964-23b38c0a1427@ednet.ns.ca">
<br>
<br>
<br>
<br>
<br>
<br>
<blockquote type="cite">
<br>
Thanks for your patience.
<br>
<br>
<blockquote type="cite">
<br>
Cheers
<br>
----
<br>
Cyrus Home Page: <a class="moz-txt-link-freetext" href="http://www.cyrusimap.org/">http://www.cyrusimap.org/</a>
<br>
List Archives/Info:
<a class="moz-txt-link-freetext" href="http://lists.andrew.cmu.edu/pipermail/info-cyrus/">http://lists.andrew.cmu.edu/pipermail/info-cyrus/</a>
<br>
To Unsubscribe:
<br>
<a class="moz-txt-link-freetext" href="https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus">https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus</a>
<br>
</blockquote>
----
<br>
Cyrus Home Page: <a class="moz-txt-link-freetext" href="http://www.cyrusimap.org/">http://www.cyrusimap.org/</a>
<br>
List Archives/Info:
<a class="moz-txt-link-freetext" href="http://lists.andrew.cmu.edu/pipermail/info-cyrus/">http://lists.andrew.cmu.edu/pipermail/info-cyrus/</a>
<br>
To Unsubscribe:
<br>
<a class="moz-txt-link-freetext" href="https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus">https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus</a>
<br>
<br>
</blockquote>
<br>
<br>
<fieldset class="mimeAttachmentHeader"></fieldset>
<pre class="moz-quote-pre" wrap="">----
Cyrus Home Page: <a class="moz-txt-link-freetext" href="http://www.cyrusimap.org/">http://www.cyrusimap.org/</a>
List Archives/Info: <a class="moz-txt-link-freetext" href="http://lists.andrew.cmu.edu/pipermail/info-cyrus/">http://lists.andrew.cmu.edu/pipermail/info-cyrus/</a>
To Unsubscribe:
<a class="moz-txt-link-freetext" href="https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus">https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus</a></pre>
</blockquote>
</body>
</html>