Cyrus won’t deliver email to users – 10.5.8

[ncudmore]

I have a G4 Mac mini running OS X 10.5.8 at home which looks after email for myself and next door. It’s sat there for several years behaving itself and been upgraded from 10.4.x right up to 10.5.7. However since 10.5.8……. life has changed…… About 10 days ago I got NDR’s with socket failure between postfix and cyrus. After a lot of coffee and hours I fixed this and all returned to normal, all for 4 days that is.

Sunday, we had 3 power outages in as many hours, and since then every inbound email get’s bounce, and an NDR is sent back. At present, I’m resorting to forwarding e-mail to google by sticking a forward in the persons mail settings in Workgroup manager. Which work’s fine.

If I remove the forwards and try to send email from google to home I get the following in the smtp log..

Nov 18 09:32:22 email postfix/smtp[16845]: E5682402DA0: to=<ncudmore@newashgreen.net>, relay=127.0.0.1[127.0.0.1]:10024, delay=127, delays=20/0.09/26/80, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=76313-06, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 7598F402DB5)
Nov 18 09:32:27 email postfix/lmtp[16996]: 7598F402DB5: to=<ncudmore@newashgreen.net>, relay=email.newashgreen.net[/var/imap/socket/lmtp], delay=9.1, delays=0.18/0.59/7.6/0.71, dsn=5.1.1, status=bounced (host email.newashgreen.net[/var/imap/socket/lmtp] said: 550-Mailbox unknown.  Either there is no mailbox associated with this 550-name or you do not have authorization to see it. 550 5.1.1 User unknown (in reply to RCPT TO command))
Nov 18 09:32:27 email postfix/cleanup[16992]: C2A7E402DB9: message-id=<20091118093227.C2A7E402DB9@email.newashgreen.net>
Nov 18 09:32:27 email postfix/qmgr[16942]: C2A7E402DB9: from=<>, size=4783, nrcpt=1 (queue active)
Nov 18 09:32:27 email postfix/bounce[17008]: 7598F402DB5: sender non-delivery notification: C2A7E402DB9
Nov 18 09:32:27 email postfix/qmgr[16942]: 7598F402DB5: removed
Nov 18 09:32:27 email postfix/qmgr[16942]: E5682402DA0: from=<ncudmore@googlemail.com>, size=2044, nrcpt=1 (queue active)
Nov 18 09:32:27 email postfix/qmgr[16942]: E5682402DA0: removed
Nov 18 09:32:30 email postfix/smtp[17010]: C2A7E402DB9: to=<ncudmore@googlemail.com>, relay=gmail-smtp-in.l.google.com[74.125.79.27]:25, delay=2.3, delays=0.18/0.14/0.96/1.1, dsn=2.0.0, status=sent (250 2.0.0 OK 1258536750 7si1251577eyg.17)
Nov 18 09:32:30 email postfix/qmgr[16942]: C2A7E402DB9: removed

I ran:-

email:bin root# ./chk_cyrus 
Examining partition: ALL PARTITIONS
-snip-
checking: user.ncudmore (/var/spool/imap/user/ncudmore)
 -> 7856 records
checking: user.ncudmore.Apple Mail To Do (/var/spool/imap/user/ncudmore/Apple Mail To Do)
 -> 1 records
checking: user.ncudmore.Deleted Items (/var/spool/imap/user/ncudmore/Deleted Items)
 -> 2 records
checking: user.ncudmore.Deleted Messages (/var/spool/imap/user/ncudmore/Deleted Messages)
 -> 14 records
checking: user.ncudmore.Drafts (/var/spool/imap/user/ncudmore/Drafts)
 -> 7 records
checking: user.ncudmore.Junk (/var/spool/imap/user/ncudmore/Junk)
 -> 0 records
checking: user.ncudmore.Junk E-mail (/var/spool/imap/user/ncudmore/Junk E-mail)
 -> 0 records
checking: user.ncudmore.Sent Items (/var/spool/imap/user/ncudmore/Sent Items)
 -> 1 records
checking: user.ncudmore.Sent Messages (/var/spool/imap/user/ncudmore/Sent Messages)
 -> 561 records
checking: user.ncudmore.Trash (/var/spool/imap/user/ncudmore/Trash)
 -> 0 records
-snip-

and this looked ok. But in the system log it showed the following:-

Nov 18 09:27:57 email ctl_cyrusdb[16623]: checkpointing cyrus databases
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: DB_ENV->log_flush: LSN of 1/26904 past current end-of-log of 1/3256
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: Database environment corrupt; the wrong log files may have been removed or incompatible database files imported from another environment
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: /var/imap/tls_sessions.db: unable to flush page: 0
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: txn_checkpoint: failed to flush the buffer cache Invalid argument
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR: couldn't checkpoint: Invalid argument
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR: sync /var/imap/db: cyrusdb error
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: DB_ENV->log_flush: LSN of 1/26904 past current end-of-log of 1/3256
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: Database environment corrupt; the wrong log files may have been removed or incompatible database files imported from another environment
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: /var/imap/tls_sessions.db: unable to flush page: 0
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: txn_checkpoint: failed to flush the buffer cache Invalid argument
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR: couldn't checkpoint: Invalid argument
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR: sync /var/imap/db: cyrusdb error
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: DB_ENV->log_flush: LSN of 1/26904 past current end-of-log of 1/3256
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: Database environment corrupt; the wrong log files may have been removed or incompatible database files imported from another environment
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: /var/imap/tls_sessions.db: unable to flush page: 0
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR db4: txn_checkpoint: failed to flush the buffer cache Invalid argument
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR: couldn't checkpoint: Invalid argument
Nov 18 09:27:57 email ctl_cyrusdb[16623]: DBERROR: sync /var/imap/db: cyrusdb error
Nov 18 09:27:57 email ctl_cyrusdb[16623]: done checkpointing cyrus databases

I can connect via IMAP and read, email, send external e-mail. I tried the following:-

email:bin root# sudo serveradmin stop mail
email:bin root# /usr/bin/cyrus/bin/reconstruct -i

which solved the problems shown by
./chk_cyrus

but to no avail….

One thing to note here is in my postfix/main.cf I have the following set:-

unknown_local_recipient_reject_code = 450

So, getting all email being bounced with 550 errors would imply it could be a permissions issue. So, I checked the /private/var/spool/imap/user directory:-

drwx------  10 _cyrus  mail   340 Nov 10 22:26 user

and then my directory below that:-

drwx------  7870 _cyrus  mail  267580 Nov 18 09:45 ncudmore

and that look’s OK.

In the GUI server manager, it shows all 8 accounts fine but all show with 0mb quota and Used 0mb, and the data store mail size and database location both show as 0mb.

After running a reconstruct on the accounts in the GUI they still show as 0mb, but at least in the Data Store, the mail store location now shows as 1.15GB and the database size as 313.39 mb.

running a manual

sudo /usr/bin/cyrus/bin/reconstruct -r user/ncudmore

didn’t help either…

At present, I’m back to forwarding e-mail to google by sticking a forward in the persons mail settings in Workgroup manager. Which work’s fine.

[Author]

Posts