7 Replies Latest reply: Jan 12, 2013 8:51 AM by pterobyte
kevinwalzer Level 1 Level 1 (0 points)

I am running Lion Server/Server.app on a 2.5 GHz iMac with four gigabytes of RAM. The server frequently locks up (as often as once as week), unable to spawn new process or respond to requests, and the culprit seems to be Postfix/mail service, usually. I have looked at various issues with Postfix and have tried to tune its performance (maximum number of connections, processes, etc.), but it doesn't seem to help. Is Lion Server prone to lock up and require a reboot? Is there a known issue with mail service? Can this be addressed by an upgrade to Mountain Lion server?


iMac (21.5-inch Mid 2011), OS X Server
  • 1. Re: Postfix freezes Lion Server, must reboot
    pterobyte Level 6 Level 6 (10,910 points)

    What do your logs say for the timeframe slightly before and until it freezes? This is certainly not a known issue with mail services. I have yet to see postfix causing a server to freeze. If something bad happens that looks related to mail services, it is often a user directory issue. To find you, you should check system.log, mail.log and mailaccess.log.

  • 2. Re: Postfix freezes Lion Server, must reboot
    kevinwalzer Level 1 Level 1 (0 points)

    The logs vary.

     

     

    In past weeks it was something like "cannot spawn new process." My guess was that Postfix had reached the maximum number of processes it could create (defaults to 100) and it overloaded the machine. I've seen reports of this with postfix on other systems.

     

     

    So, I reduced the number of Postfix processes from 100 to 50 via postconfig. Then, later, I got something like this:

     

     

    bad command startup -- throttling

     

     

    I had not reduced the maximum number of connections a client could make, so I reduced that from 50 to 25. (My theory was that a spammer bot was overloading the server.)

     

     

    Last night, there was nothing. The mail server simply froze in mid-connection (in the middle of a handshake with a connecting client) and no other errors were logged. That was also when the server started running an rsync backup to an external hard drive, but I wouldn't expect rsync to be a problem.

     

     

    Regardless, when these things happen, the effect is always the same: mail freezes, I can't SSH in, sometimes the web server locks up, and when I try to look at things in the GUI, I get a spinning beachball. Apparently things simply can't spawn any new processes and things lock up. A hard reboot is necessary.

     

     

    Needless to say, it's rather frustrating, and I'm not certain that upgrading to ML Server would solve the problems, as they tend to vary and are not easily reproducible.

     

     

    When you say "user directory," what do you mean?

  • 3. Re: Postfix freezes Lion Server, must reboot
    pterobyte Level 6 Level 6 (10,910 points)

    I would need to see exact log entries from the logs aforementioned. What you mention is only a result of the issues preceding it.

     

    By directory I mean Open Directory and/or the local directory.

  • 4. Re: Postfix freezes Lion Server, must reboot
    kevinwalzer Level 1 Level 1 (0 points)

    Here's a snippet from mail.log right before and then right after the crash:

     

    Jan  9 23:36:04 admins-iMac postfix/qmgr[5486]: E4E9FBE78D2: from=<tcllib-devel-bounces@xxx>, size=5107, nrcpt=1 (queue active)

    Jan  9 23:36:05 admins-iMac postfix/smtpd[84886]: disconnect from xxx[216.34.181.88]

    Jan  9 23:36:05 admins-iMac postfix/pipe[84890]: E4E9FBE78D2: to=<xxx@xxx.com>, orig_to=<xxx@xxx>, relay=dovecot, delay=0.11, delays=0.07/0.01/0/0.03, dsn=2.0.0, status=sent (delivered via dovecot service)

    Jan  9 23:36:05 admins-iMac postfix/qmgr[5486]: E4E9FBE78D2: removed

    Jan  9 23:37:48 admins-iMac postfix/anvil[84768]: statistics: max connection rate 1/60s for (smtpd:17.151.62.53) at Jan  9 23:27:48

    Jan  9 23:37:48 admins-iMac postfix/anvil[84768]: statistics: max connection count 1 for (smtpd:17.151.62.53) at Jan  9 23:27:48

    Jan  9 23:37:48 admins-iMac postfix/anvil[84768]: statistics: max cache size 2 at Jan  9 23:28:10

    Jan  9 23:47:56 admins-iMac postfix/qmgr[5486]: 8D5FBBD9A25: from=<_www@xxx.com>, size=35502, nrcpt=1 (queue active)

    Jan  9 23:47:56 admins-iMac postfix/smtp[84961]: warning: database /etc/postfix/sasl/passwd.db is older than source file /etc/postfix/sasl/passwd

    Jan  9 23:48:26 admins-iMac postfix/smtp[84961]: connect to bh.markmonitor.com[64.124.14.38]:25: Operation timed out

    Jan  9 23:48:26 admins-iMac postfix/smtp[84961]: 8D5FBBD9A25: to=<xxx@xxx.net>, relay=none, delay=189542, delays=189511/0.02/30/0, dsn=4.4.1, status=deferred (connect to bh.markmonitor.com[64.124.14.38]:25: Operation timed out)

    Jan 10 00:00:00 admins-iMac postfix/pickup[84012]: D724DBE79B2: uid=501 from=<admin>

    Jan 10 00:00:00 admins-iMac postfix/cleanup[85132]: D724DBE79B2: message-id=<20130110050000.D724DBE79B2@xxx.com>

    Jan 10 00:00:00 admins-iMac postfix/cleanup[85132]: sacl_check: mbr_user_name_to_uuid(xxx@xxx.com) failed: No such file or directory

    Jan 10 00:00:00 admins-iMac postfix/qmgr[5486]: D724DBE79B2: from=<xxx@xxx.com>, size=772, nrcpt=1 (queue active)

    Jan 10 00:00:01 admins-iMac postfix/pipe[85141]: D724DBE79B2: to=<xxx@xxx.com>, orig_to=<admin>, relay=dovecot, delay=0.26, delays=0.04/0.06/0/0.16, dsn=2.0.0, status=sent (delivered via dovecot service)

    Jan 10 00:00:01 admins-iMac postfix/qmgr[5486]: D724DBE79B2: removed

    Jan 10 00:00:39 admins-iMac postfix/postscreen[85162]: CONNECT from [117.213.50.157]:4009

    Jan 10 00:00:45 admins-iMac postfix/postscreen[85162]: PASS NEW [117.213.50.157]:4009

     

    [crash happens here, no output in system.log, next entries after after reboot six hours later]

     

    Jan 10 06:22:01 admins-iMac postfix/master[66]: daemon started -- version 2.8.4, configuration /etc/postfix

    Jan 10 06:22:01 admins-iMac postfix/qmgr[157]: 8D5FBBD9A25: from=<_www@xxx.com>, size=35502, nrcpt=1 (queue active)

    Jan 10 06:22:01 admins-iMac postfix/smtp[162]: warning: database /etc/postfix/sasl/passwd.db is older than source file /etc/postfix/sasl/passwd

    Jan 10 06:22:31 admins-iMac postfix/smtp[162]: connect to bh.markmonitor.com[64.124.14.38]:25: Operation timed out

    Jan 10 06:22:33 admins-iMac postfix/smtp[162]: 8D5FBBD9A25: to=<xxx@xxx.net>, relay=none, delay=213187, delays=213157/0.09/30/0, dsn=4.4.1, status=deferred (connect to bh.markmonitor.com[64.124.14.38]:25: Operation timed out)

    Jan 10 06:22:38 admins-iMac postfix/postscreen[504]: CONNECT from [69.20.90.49]:60414

  • 5. Re: Postfix freezes Lion Server, must reboot
    pterobyte Level 6 Level 6 (10,910 points)

    OK. So far so good. Nothing unusual here. What about the same timeframe in system.log and mailaccess.log?

  • 6. Re: Postfix freezes Lion Server, must reboot
    kevinwalzer Level 1 Level 1 (0 points)

    From system.log:

     

     

    an  9 23:58:38: --- last message repeated 2 times ---

    Jan  9 23:58:38 xxxs-iMac AppleFileServer[545]: _Assert: /SourceCache/afpserver/afpserver-585.7/afpserver/FPCarbonVol.cpp, 70 (-35)

    Jan  9 23:59:08: --- last message repeated 2 times ---

    Jan  9 23:59:08 xxxs-iMac AppleFileServer[545]: _Assert: /SourceCache/afpserver/afpserver-585.7/afpserver/FPCarbonVol.cpp, 70 (-35)

    Jan  9 23:59:38: --- last message repeated 2 times ---

    Jan  9 23:59:38 xxxs-iMac AppleFileServer[545]: _Assert: /SourceCache/afpserver/afpserver-585.7/afpserver/FPCarbonVol.cpp, 70 (-35)

    Jan 10 06:21:18 localhost bootlog[0]: BOOT_TIME 1357816878 0

    Jan 10 06:21:23 localhost com.apple.launchd[1]: *** launchd[1] has started up. ***

    Jan 10 06:21:33 localhost com.apple.launchd[1] (com.apple.instruments.server.mig): Unknown value for key POSIXSpawnType: Interactive

    Jan 10 06:21:33 localhost com.apple.launchd[1] (com.apple.powerd): Unknown value for key POSIXSpawnType: Interactive

    Jan 10 06:21:33 localhost com.apple.launchd[1] (com.apple.sandboxd): Unknown value for key POSIXSpawnType: Interactive

     

     

    From mailaccess.log:

     

     

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(xxx): Debug: Added userdb setting: plugin/quota=maildir:User quota:noenforcing

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(xxx): Debug: Added userdb setting: plugin/quota_rule=*:storage=0

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(xxx): Debug: Added userdb setting: mail=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77F171CCD9A 3

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(xxx): Debug: Added userdb setting: mail_location=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77 F171CCD9A3

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(xxx): Debug: Added userdb setting: plugin/sieve=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF65-77F171CCD9 A3/dovecot.sieve

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(xxx): Debug: Added userdb setting: plugin/sieve_dir=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF65-77F171 CCD9A3

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(xxx): Debug: Added userdb setting: plugin/sieve_storage=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF65-77 F171CCD9A3

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(pid 84992 user xxx): Debug: Effective uid=214, gid=6, home=

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(pid 84992 user xxx): Debug: Quota root: name=User quota backend=maildir args=noenforcing

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(pid 84992 user xxx): Debug: Quota rule: root=User quota mailbox=* bytes=0 messages=0

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(pid 84992 user xxx): Debug: Quota warning: bytes=0 (100%) messages=0 reverse=no command=quota-exceeded xxx

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(pid 84992 user xxx): Debug: maildir++: root=/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77F171CCD9A3, index=, control=, inbox=/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77F171CCD9A3, alt=

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(pid 84992 user kevin): ID sent: name=iPhone Mail, version=10A523, os=iOS, os-version=6.0.1 (10A523)

    Jan  9 23:49:04 xxxs-iMac dovecot[4421]: imap(pid 84992 user xxx): ID sent: name=iPhone Mail, version=10A523, os=iOS, os-version=6.0.1 (10A523)

    Jan  9 23:50:00 xxxs-iMac update-fts-index.pl[84999]: Updating search indexes for user xxx

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: master in: USER          1          xxx          service=imap

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): lookup user=xxx

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): cache lookup for user xxx

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): found user xxx in cache as xxx

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): record name=xxx, uid=1027, gid=20, but using _dovecot:mail

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): user=xxx, quota=*:storage=0

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): data store location=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77F171C CD9A3

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: master out: USER          1          xxx          uid=214          gid=6          quota=maildir:User quota:noenforcing          quota_rule=*:storage=0          mail=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77F171CCD9A 3          mail_location=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77 F171CCD9A3          sieve=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF65-77F171CCD9A3/dove cot.sieve          sieve_dir=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF65-77F171CCD9A3           sieve_storage=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF6

    Jan  9 23:50:00 xxxs-iMac dovecot[4421]: auth: Debug: 5-77F171CCD9A3

    Jan  9 23:50:00 xxxs-iMac update-fts-index.pl[84999]: Updating search index for user xxx mailbox 1 of 1 in IMAP process 85002

    Jan  9 23:50:01 xxxs-iMac update-fts-index.pl[84999]: Compacting search index for user xxx mailbox 1 of 1 in IMAP process 85002

    Jan  9 23:50:01 xxxs-iMac update-fts-index.pl[84999]: Done

    Jan  9 23:50:06 xxxs-iMac push_notify[86]: notification server connect failed, will retry in 300 seconds

    Jan  9 23:50:11 xxxs-iMac update-fts-index.pl[85006]: Updating search indexes for user xxx

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: master in: USER          1          xxx          service=imap

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): lookup user=xxx

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): cache lookup for user xxx

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): found user xxx in cache as xxx

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): record name=xxx, uid=1027, gid=20, but using _dovecot:mail

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): user=xxx, quota=*:storage=0

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): data store location=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77F171C CD9A3

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: master out: USER          1          xxx          uid=214          gid=6          quota=maildir:User quota:noenforcing          quota_rule=*:storage=0          mail=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77F171CCD9A 3          mail_location=maildir:/Library/Server/Mail/Data/mail/C9985B8F-4C33-4CDA-BF65-77 F171CCD9A3          sieve=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF65-77F171CCD9A3/dove cot.sieve          sieve_dir=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF65-77F171CCD9A3           sieve_storage=/Library/Server/Mail/Data/rules/C9985B8F-4C33-4CDA-BF6

    Jan  9 23:50:11 xxxs-iMac dovecot[4421]: auth: Debug: 5-77F171CCD9A3

    Jan  9 23:50:11 xxxs-iMac update-fts-index.pl[85006]: Updating search index for user xxx mailbox 1 of 1 in IMAP process 85009

    Jan  9 23:50:11 xxxs-iMac update-fts-index.pl[85006]: Compacting search index for user xxx mailbox 1 of 1 in IMAP process 85009

    Jan  9 23:50:12 xxxs-iMac update-fts-index.pl[85006]: Done

    Jan  9 23:55:06 xxxs-iMac push_notify[86]: notification server connect failed, will retry in 300 seconds

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Loading modules from directory: /usr/lib/dovecot

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Module loaded: /usr/lib/dovecot/lib10_quota_plugin.so

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Module loaded: /usr/lib/dovecot/lib20_fts_plugin.so

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Module loaded: /usr/lib/dovecot/lib20_push_notify_plugin.so

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Module loaded: /usr/lib/dovecot/lib20_zlib_plugin.so

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Module loaded: /usr/lib/dovecot/lib21_fts_sk_plugin.so

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Module loaded: /usr/lib/dovecot/lib90_sieve_plugin.so

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: master in: USER          1          xxx          service=lda

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): lookup user=xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): cache lookup for user xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): discarding cache entry for user xxx as xxx (age=3601)

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): directory lookup for user xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od[getpwnam_ext](xxx): no local settings found for guid: C84AEC24-1074-43BC-B9E3-C41CEC193BFC (xxx)

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od[getpwnam_ext](xxx): no attribute dsAttrTypeStandard:MailAttribute in user record

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od[getpwnam_ext](xxx): uid=501 gid=20 state=0xe quota=0 guid=C84AEC24-1074-43BC-B9E3-C41CEC193BFC name=xxx loc=(null) alt=(null)

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: auth input: xxx uid=214 gid=6 quota=maildir:User quota:noenforcing quota_rule=*:storage=0 mail=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BF C mail_location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C4 1CEC193BFC sieve=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC/dove cot.sieve sieve_dir=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC sieve_storage=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193 BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): caching user xxx as xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): record name=xxx, uid=501, gid=20, but using _dovecot:mail

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): user=xxx, quota=*:storage=0

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): data store location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC1 93BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Added userdb setting: plugin/quota=maildir:User quota:noenforcing

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: master out: USER          1          xxx          uid=214          gid=6          quota=maildir:User quota:noenforcing          quota_rule=*:storage=0          mail=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BF C          mail_location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C4 1CEC193BFC          sieve=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC/dove cot.sieve          sieve_dir=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC           sieve_storage=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Added userdb setting: plugin/quota_rule=*:storage=0

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: B9E3-C41CEC193BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Added userdb setting: mail=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BF C

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Added userdb setting: mail_location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C4 1CEC193BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Added userdb setting: plugin/sieve=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193B FC/dovecot.sieve

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Added userdb setting: plugin/sieve_dir=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC 193BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda: Debug: Added userdb setting: plugin/sieve_storage=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C4 1CEC193BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: Effective uid=214, gid=6, home=

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: Quota root: name=User quota backend=maildir args=noenforcing

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: Quota rule: root=User quota mailbox=* bytes=0 messages=0

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: Quota warning: bytes=0 (100%) messages=0 reverse=no command=quota-exceeded xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: maildir++: root=/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BFC, index=, control=, inbox=/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BFC, alt=

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: Quota root: name=User quota backend=maildir args=

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: Quota warning: bytes=0 (100%) messages=0 reverse=no command=quota-exceeded xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: none: root=, index=, control=, inbox=, alt=

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: fts_sk: Disabled with in-memory indexes

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: fts: No backends enabled by the fts setting

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: Destination address:xxx@xxx.com (source: user@hostname)

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: master in: USER          2          xxx          service=lib-storage

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): lookup user=xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): cache lookup for user xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): found user xxx in cache as xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): record name=xxx, uid=501, gid=20, but using _dovecot:mail

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): user=xxx, quota=*:storage=0

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): data store location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC1 93BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: master out: USER          2          xxx          uid=214          gid=6          quota=maildir:User quota:noenforcing          quota_rule=*:storage=0          mail=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BF C          mail_location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C4 1CEC193BFC          sieve=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC/dove cot.sieve          sieve_dir=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC           sieve_storage=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: B9E3-C41CEC193BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: auth input: xxx uid=214 gid=6 quota=maildir:User quota:noenforcing quota_rule=*:storage=0 mail=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BF C mail_location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C4 1CEC193BFC sieve=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC/dove cot.sieve sieve_dir=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC sieve_storage=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193 BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: sieve: include: sieve_global_dir is not set; it is currently not possible to include `:global' scripts.

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: sieve: user's script path /Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC/dovecot.si eve doesn't exist (using global script path in stead)

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: sieve: user has no valid personal script

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): Debug: sieve: no scripts to execute: reverting to default delivery.

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): msgid=<20130110050000.D724DBE79B2@wordtechcommunications.com>: saved mail to INBOX

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): push-notify: push notification enabled

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): push-notify: notify: xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[85142]: lda(pid 85142 user xxx): push-notify: data sent: 1680

    Jan 10 00:00:01 xxxs-iMac push_notify[86]: discarding message; not connected to notification server

    Jan 10 00:00:01 xxxs-iMac update-fts-index.pl[85143]: Updating search indexes for user xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: master in: USER          1          xxx          service=imap

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): lookup user=xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): cache lookup for user xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): found user xxx in cache as xxx

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): record name=xxx, uid=501, gid=20, but using _dovecot:mail

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): user=xxx, quota=*:storage=0

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: od(xxx): data store location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC1 93BFC

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: master out: USER          1          xxx          uid=214          gid=6          quota=maildir:User quota:noenforcing          quota_rule=*:storage=0          mail=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C41CEC193BF C          mail_location=maildir:/Library/Server/Mail/Data/mail/C84AEC24-1074-43BC-B9E3-C4 1CEC193BFC          sieve=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC/dove cot.sieve          sieve_dir=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-B9E3-C41CEC193BFC           sieve_storage=/Library/Server/Mail/Data/rules/C84AEC24-1074-43BC-

    Jan 10 00:00:01 xxxs-iMac dovecot[4421]: auth: Debug: B9E3-C41CEC193BFC

    Jan 10 00:00:01 xxxs-iMac update-fts-index.pl[85143]: Updating search index for user xxx mailbox 1 of 1 in IMAP process 85146

    Jan 10 00:00:02 xxxs-iMac update-fts-index.pl[85143]: Compacting search index for user xxx mailbox 1 of 1 in IMAP process 85146

    Jan 10 00:00:02 xxxs-iMac update-fts-index.pl[85143]: Done

    Jan 10 00:00:06 xxxs-iMac push_notify[86]: notification server connect failed, will retry in 300 seconds

    Jan 10 06:21:52 localhost push_notify[87]: initializing mail notification services

    Jan 10 06:21:52 localhost push_notify[87]: socket receive buffer size: 8192

    Jan 10 06:21:52 localhost push_notify[87]: notification service address: xxxs-iMac.local

    Jan 10 06:21:52 localhost push_notify[87]: notification service user: com.apple.notificationuser

    Jan 10 06:21:52 localhost push_notify[87]: notification service port: 5220

    Jan 10 06:21:52 localhost push_notify[87]: starting mail notification services

    Jan 10 06:21:57 xxxs-iMac push_notify[87]: notification server connect failed, will retry in 15 seconds

  • 7. Re: Postfix freezes Lion Server, must reboot
    pterobyte Level 6 Level 6 (10,910 points)

    I don't see anything that would indicate that mail services are the culprit.

     

    It could be a hardware issue (run hardware diagnostics), or some other service. TimeMachine and AFP quite often showed unexpected behaviour like this. For the sake of troubleshooting, try and disable on or the other.