sendmail and cyrus-imap - delivery problem
Mario Pavlov
freebsd at abv.bg
Thu Mar 26 15:37:20 EDT 2009
Hi,
I've got some more verbose logging
I've created new mailbox (just for fun)
localhost> cm user/jane at yo.com
localhost> lm
user/boo at yo.com (\HasNoChildren) user/jane at yo.com (\HasNoChildren)
I've increased the loglevel of sendmail to 16 and I've sent an e-mail from gmail to jane at yo.com
and I got this in sendmail's output:
Mar 26 19:15:56 yo sendmail[23789]: NOQUEUE: connect from fg-out-1718.google.com [72.14.220.154]
Mar 26 19:15:56 yo sendmail[23789]: AUTH: available mech=NTLM LOGIN ANONYMOUS PLAIN GSSAPI OTP DIGEST-MD5 CRAM-MD5, allowed mech=GSSAPI DIGEST-MD5 CRAM-MD5 LOGIN
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: Milter: no active filter
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 220 yo.com ESMTP Sendmail 8.14.3/8.14.3; Thu, 26 Mar 2009 19:15:56 GMT
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: <-- EHLO fg-out-1718.google.com
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-yo.com Hello fg-out-1718.google.com [72.14.220.154], pleased to meet you
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-ENHANCEDSTATUSCODES
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-PIPELINING
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-8BITMIME
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-SIZE
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-DSN
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-ETRN
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-AUTH GSSAPI DIGEST-MD5 CRAM-MD5 LOGIN
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250-DELIVERBY
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250 HELP
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: <-- MAIL FROM:<whoever at gmail.com>
Mar 26 19:15:56 yo sendmail[23789]: n2QJFuHM023789: --- 250 2.1.0 <whoever at gmail.com>... Sender ok
Mar 26 19:15:57 yo sendmail[23789]: n2QJFuHM023789: <-- RCPT TO:<jane at yo.com>
Mar 26 19:15:57 yo sendmail[23789]: n2QJFuHM023789: --- 250 2.1.5 <jane at yo.com>... Recipient ok
Mar 26 19:15:57 yo sendmail[23789]: n2QJFuHM023789: <-- DATA
Mar 26 19:15:57 yo sendmail[23789]: n2QJFuHM023789: --- 354 Enter mail, end with "." on a line by itself
Mar 26 19:15:57 yo sendmail[23789]: n2QJFuHM023789: from=<whoever at gmail.com>, size=1592, class=0, nrcpts=1, msgid=<9cddded00903261215i77936b6fs8dbc476326cf47f5 at mail.gmail.com>, proto=ESMTP, daemon=IPv4, relay=fg-out-1718.google.com [72.14.220.154]
Mar 26 19:15:57 yo sendmail[23792]: n2QJFuHM023789: --- 050 <jane at yo.com>... Connecting to /var/imap/socket/lmtp via cyrusv3d...
Mar 26 19:15:57 yo sendmail[23789]: n2QJFuHM023789: --- 250 2.0.0 n2QJFuHM023789 Message accepted for delivery
Mar 26 19:15:57 yo sendmail[23792]: AUTH=client, relay=localhost, mech=, bits=0
Mar 26 19:15:57 yo sendmail[23792]: n2QJFuHM023789: --- 550 5.1.1 <jane at yo.com>... User unknown (hold)
Mar 26 19:15:57 yo sendmail[23792]: n2QJFuHM023789: to=<jane at yo.com>, delay=00:00:00, xdelay=00:00:00, mailer=cyrusv3d, pri=31592, relay=localhost, dsn=5.1.1, stat=User unknown
Mar 26 19:15:57 yo sendmail[23792]: n2QJFuHM023789: n2QJFvHM023792: DSN: User unknown
Mar 26 19:15:57 yo sendmail[23792]: n2QJFvHM023792: --- 050 <whoever at gmail.com>... Connecting to gmail-smtp-in.l.google.com. via esmtp...
Mar 26 19:15:57 yo sendmail[23792]: n2QJFvHM023792: SMTP outgoing connect on reverse.yo.com
Mar 26 19:15:58 yo sendmail[23792]: n2QJFvHM023792: --- 050 <whoever at gmail.com>... Sent (OK 1238094956 3si369136fxm.108)
Mar 26 19:15:58 yo sendmail[23792]: n2QJFvHM023792: to=<whoever at gmail.com>, delay=00:00:01, xdelay=00:00:01, mailer=esmtp, pri=32616, relay=gmail-smtp-in.l.google.com. [209.85.220.27], dsn=2.0.0, stat=Sent (OK 1238094956 3si369136fxm.108)
Mar 26 19:15:58 yo sendmail[23792]: n2QJFvHM023792: done; delay=00:00:01, ntries=1
Mar 26 19:15:58 yo sendmail[23792]: n2QJFuHM023789: done; delay=00:00:01, ntries=1
Mar 26 19:15:58 yo sendmail[23792]: NOQUEUE: --- 050 Closing connection to gmail-smtp-in.l.google.com.
Mar 26 19:15:58 yo sendmail[23792]: NOQUEUE: --- 050 Closing connection to localhost
and I also got this in cyrus' output:
Mar 26 19:15:57 yo smmapd[23780]: accepted connection
Mar 26 19:15:57 yo master[23790]: about to exec /usr/local/cyrus/bin/smmapd
Mar 26 19:15:57 yo smmapd[23790]: executed
Mar 26 19:15:57 yo master[23793]: about to exec /usr/local/cyrus/bin/lmtpd
Mar 26 19:15:57 yo lmtpunix[23793]: executed
Mar 26 19:15:57 yo lmtpunix[23793]: accepted connection
Mar 26 19:15:57 yo lmtpunix[23793]: lmtp connection preauth'd as postman
Mar 26 19:15:57 yo lmtpunix[23793]: verify_user(user.jane) failed: Mailbox does not exist
you can see that the domain is stripped "verify_user(user.jane)" but sendmail seems to pass the user with the domain...is this normal ?
here's what happens if I send an e-mail to non-existing mailbox
sendmail:
Mar 26 19:30:58 yo sendmail[23827]: NOQUEUE: connect from mail-fx0-f163.google.com [209.85.220.163]
Mar 26 19:30:58 yo sendmail[23827]: AUTH: available mech=NTLM LOGIN ANONYMOUS PLAIN GSSAPI OTP DIGEST-MD5 CRAM-MD5, allowed mech=GSSAPI DIGEST-MD5 CRAM-MD5 LOGIN
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: Milter: no active filter
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 220 yo.com ESMTP Sendmail 8.14.3/8.14.3; Thu, 26 Mar 2009 19:30:58 GMT
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: <-- EHLO mail-fx0-f163.google.com
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-yo.com Hello mail-fx0-f163.google.com [209.85.220.163], pleased to meet you
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-ENHANCEDSTATUSCODES
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-PIPELINING
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-8BITMIME
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-SIZE
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-DSN
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-ETRN
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-AUTH GSSAPI DIGEST-MD5 CRAM-MD5 LOGIN
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250-DELIVERBY
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250 HELP
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: <-- MAIL FROM:<whoever at gmail.com>
Mar 26 19:30:58 yo sendmail[23827]: n2QJUwmk023827: --- 250 2.1.0 <whoever at gmail.com>... Sender ok
Mar 26 19:30:59 yo sendmail[23827]: n2QJUwmk023827: <-- RCPT TO:<josh at yo.com>
Mar 26 19:30:59 yo sendmail[23827]: n2QJUwmk023827: --- 550 5.1.1 <josh at yo.com>... Mailbox is not available.
Mar 26 19:30:59 yo sendmail[23827]: n2QJUwmk023827: <josh at yo.com>... Mailbox is not available.
Mar 26 19:30:59 yo sendmail[23827]: n2QJUwmk023827: <-- QUIT
Mar 26 19:30:59 yo sendmail[23827]: n2QJUwmk023827: --- 221 2.0.0 yo.com closing connection
Mar 26 19:30:59 yo sendmail[23827]: n2QJUwmk023827: from=<whoever at gmail.com>, size=0, class=0, nrcpts=0, proto=ESMTP, daemon=IPv4, relay=mail-fx0-f163.google.com [209.85.220.163]
cyrus:
Mar 26 19:30:59 yo smmapd[23817]: accepted connection
Mar 26 19:30:59 yo smmapd[23817]: verify_user(user.josh at yo^com) failed: Mailbox does not exist
I can see sendmail says the mailbox is not available so does cyrus only this time the domain is present "verify_user(user.josh at yo^com)" and it's with that sign "^" instead of dot...is this normal ?
thank you.
>Hi,
>I've tried the new version of cyrus-imapd (2.3.14) ... it works like the previous version for me
>but in addition I got a strange problem with cyradm
>when I do:
>cyradm --user cyrus localhost
>
>I have to wait about a minute before I'm prompted for password
>
>could you tell me how can I set up detailed (debug) logging so I can try to see what's wrong with my mailbox matching...
>
>I tried to add these:
>local6.debug /var/log/imapd.log
>auth.debug /var/log/auth.log
>
>to syslog.conf but I get no logging messages
>
>thank you.
>
>
> >Hi,
> >I've discovered something odd (at least to me)
> >when I first configured cyrus-imap I was unable to create mailboxes
> >I kept getting this:
> >
> >localhost> cm user.boo at yo.com
> >createmailbox: Permission denied
> >
> >then I read somewhere that I should set "unixhierarchysep" to "yes" and it will work
> >so I did and it worked...I was able to create mailboxes
> >
> >localhost> cm user/boo at yo.com
> >localhost> lm
> >user/boo at yo.com (\HasNoChildren)
> >
> >and while I was struggling with my delivery problem these days I decided to set "unixhierarchysep" back to "no" just to see what happens
> >so it was the same...I was unable to create mailboxes because of that "Permission denied" error
> >but when I wrote "lm" I saw that:
> >
> >localhost> lm
> >user.boo at yo^com (\HasNoChildren)
> >
> >you see the difference ? There is this sign "^" in the domain name instead of dot "."
> >I checked:
> >
> >localhost> info user.boo at yo.com // info user.boo at yo^com // Mario Pavlov wrote:
> > >> thanks again to all of you for your time I've tried all that you
> > >> suggested I've tried ckuser_cyrus as Matthew Seaman suggested...it
> > >> made no difference then I've tried rtcyrus3 as Andrzej Adam Filip
> > >> suggested...it didn't work either...and the logging output in maillog
> > >> stopped.. [...]
> > >
> > >First: Use cyradm to recheck that cyrus reports mailbox named as in
> > >"RCPT TO:" of LMTP session.
> > >
> > >As I understand you have achieved that sendmail uses correct
> > >"mailbox name with domain" in LMTP session but Cyrus rejects
> > >the recipient.
> > >
> > >--
> > >[pl>en: Andrew] Andrzej Adam Filip : anfi at onet.eu
> > >Life without caffeine is stimulating enough.
> > > -- Sanka Ad
> > >----
> > >Cyrus Home Page: http://cyrusimap.web.cmu.edu/
> > >Cyrus Wiki/FAQ: http://cyrusimap.web.cmu.edu/twiki
> > >List Archives/Info: http://asg.web.cmu.edu/cyrus/mailing-list.html
> > >
> >
>
More information about the Info-cyrus
mailing list