IMAP mail store corrupted on Leopard Server 10.5.2 when stopping

[kamil]

I’d like to see if anyone else has this problem, or could give me some insight in to why it is happening. It seems that our mail store is being corrupted when we stop the mail service.

Some logs. The server was stopped and restarted pretty much right after, at around 11:17

mailaccess.log:
[code]
Mar 4 11:17:03 myserver master[65809]: process started
Mar 4 11:17:04 myserver ctl_cyrusdb[65810]: verifying cyrus databases
Mar 4 11:17:04 myserver ctl_cyrusdb[65810]: skiplist: recovered /Volumes/SAN/Mail/DB/mailboxes.db (203 records, 33076 bytes) in 0 seconds
Mar 4 11:17:04 myserver ctl_cyrusdb[65810]: skiplist: recovered /Volumes/SAN/Mail/DB/annotations.db (0 records, 144 bytes) in 0 seconds
Mar 4 11:17:14 myserver master[65820]: process started
Mar 4 11:17:25 myserver master[65825]: process started
Mar 4 11:17:35 myserver master[65833]: process started
Mar 4 11:17:51 myserver cyrus-quota[65897]: DBERROR db4: PANIC: fatal region error detected; run recovery
Mar 4 11:17:51 myserver cyrus-quota[65897]: DBERROR: critical database situation
Mar 4 11:17:52 myserver cyrus-quota[65898]: DBERROR db4: PANIC: fatal region error detected; run recovery
Mar 4 11:17:52 myserver cyrus-quota[65898]: DBERROR: critical database situation
[/code]

system.log:
[code]
Mar 4 11:17:03 myserver com.apple.launchd[1] (org.amavis.amavisd[67613]): Stray process with PGID equal to this dead job: PID 65765 PPID 1 perl
Mar 4 11:17:03 myserver com.apple.launchd[1] (org.amavis.amavisd[67613]): Stray process with PGID equal to this dead job: PID 65629 PPID 1 perl
Mar 4 11:17:03 myserver master[65809]: process started
Mar 4 11:17:04 myserver ctl_cyrusdb[65810]: verifying cyrus databases
Mar 4 11:17:04 myserver ctl_cyrusdb[65810]: skiplist: recovered /Volumes/SAN/Mail/DB/mailboxes.db (203 records, 33076 bytes) in 0 seconds
Mar 4 11:17:04 myserver ctl_cyrusdb[65810]: skiplist: recovered /Volumes/SAN/Mail/DB/annotations.db (0 records, 144 bytes) in 0 seconds
Mar 4 11:17:04 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65809]): Stray process with PGID equal to this dead job: PID 65810 PPID 1 ctl_cyrusdb
Mar 4 11:17:04 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65809]): Exited abnormally: User defined signal 1
Mar 4 11:17:04 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master): Throttling respawn: Will start in 10 seconds
Mar 4 11:17:14 myserver master[65820]: process started
Mar 4 11:17:17 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65820]): Stray process with PGID equal to this dead job: PID 65821 PPID 1 ctl_cyrusdb
Mar 4 11:17:17 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65820]): Exited abnormally: User defined signal 1
Mar 4 11:17:17 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master): Throttling respawn: Will start in 8 seconds
Mar 4 11:17:25 myserver master[65825]: process started
Mar 4 11:17:28 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65825]): Stray process with PGID equal to this dead job: PID 65826 PPID 1 ctl_cyrusdb
Mar 4 11:17:28 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65825]): Exited abnormally: User defined signal 1
Mar 4 11:17:28 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master): Throttling respawn: Will start in 7 seconds
Mar 4 11:17:35 myserver master[65833]: process started
Mar 4 11:17:39 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65833]): Stray process with PGID equal to this dead job: PID 65834 PPID 1 ctl_cyrusdb
Mar 4 11:17:39 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master[65833]): Exited abnormally: User defined signal 1
Mar 4 11:17:39 myserver com.apple.launchd[1] (edu.cmu.andrew.cyrus.master): Throttling respawn: Will start in 7 seconds
Mar 4 11:17:41 myserver com.apple.launchd[1] (org.postfix.master[24654]): Exited: Terminated
Mar 4 11:17:41 myserver postfix/postfix-script[65840]: fatal: the Postfix mail system is not running
Mar 4 11:17:44 myserver org.clamav.clamd[67611]: Socket file removed.
Mar 4 11:17:44 myserver org.clamav.clamd[67611]: Pid file removed.
Mar 4 11:17:44 myserver org.clamav.clamd[67611]: — Stopped at Tue Mar 4 11:17:44 2008
Mar 4 11:17:44 myserver com.apple.launchd[1] (org.amavis.amavisd[65806]): Stray process with PGID equal to this dead job: PID 65817 PPID 1 perl
Mar 4 11:17:44 myserver com.apple.launchd[1] (org.amavis.amavisd[65806]): Stray process with PGID equal to this dead job: PID 65816 PPID 1 perl
Mar 4 11:17:51 myserver sudo[65897]: root : TTY=unknown ; PWD=/ ; USER=_cyrus ; COMMAND=/usr/bin/cyrus/bin/cyrus-quota -r
Mar 4 11:17:51 myserver cyrus-quota[65897]: DBERROR db4: PANIC: fatal region error detected; run recovery
Mar 4 11:17:51 myserver cyrus-quota[65897]: DBERROR: critical database situation
Mar 4 11:17:52 myserver sudo[65898]: root : TTY=unknown ; PWD=/ ; USER=_cyrus ; COMMAND=/usr/bin/cyrus/bin/cyrus-quota
Mar 4 11:17:52 myserver cyrus-quota[65898]: DBERROR db4: PANIC: fatal region error detected; run recovery
Mar 4 11:17:52 myserver cyrus-quota[65898]: DBERROR: critical database situation
Mar 4 11:17:52 myserver postfix/postqueue[65899]: warning: Mail system is down — accessing queue directly
[/code]

We had to rebuild the mail database to get things to work again, which reset all of the read flags. Any ideas why this may be happening?

[likkie]

Hi Kamil,

I am seeing exactly the same thing.

It doesn’t matter if I stop and then immediately restart mail services, the database becomes corrupted.

Have you found any clues?

Cheers,

Erik.

[Author]

Posts