OS X.4.8 Server imapd crashes every 4-10 minutes

Question

Level 1

0 points

OS X.4.8 Server imapd crashes every 4-10 minutes

Running only DNS, Web, and Mail servers on a PPC Dual G4, 1G RAM, os x.4.8 server we have serious crashing issues with imapd.

Thanks so much for your help...

mc

mailaccess.log
The master process exiting are the troubled ones
Sep 18 17:49:37 nameserver master[299]: process 1476 exited, signaled to death by 11
Sep 18 17:53:54 nameserver imap[1425]: Connection reset by peer, closing connection
Sep 18 17:53:54 nameserver imap[1434]: Connection reset by peer, closing connection
Sep 18 17:54:38 nameserver master[299]: process 1425 exited, signaled to death by 11
Sep 18 17:59:34 nameserver master[299]: process 1575 exited, signaled to death by 11
Sep 18 18:04:34 nameserver master[299]: process 1603 exited, signaled to death by 11
Sep 18 18:09:34 nameserver master[299]: process 1650 exited, signaled to death by 11
Sep 18 18:10:04 nameserver imap[1433]: Connection reset by peer, closing connection
Sep 18 18:10:04 nameserver imap[1555]: Connection reset by peer, closing connection
Sep 18 18:10:06 nameserver imap[1542]: Connection reset by peer, closing connection
Sep 18 18:14:34 nameserver master[299]: process 1695 exited, signaled to death by 11
Sep 18 18:23:53 nameserver deliver[144]: connect(/var/imap/socket/lmtp) failed: Connection refused
Sep 18 18:23:53 nameserver deliver[143]: connect(/var/imap/socket/lmtp) failed: Connection refused
Sep 18 18:24:41 nameserver master[42]: process 272 exited, signaled to death by 11

CONSOLE.LOG
Sep 18 17:49:37 nameserver master[299]: process 1476 exited, signaled to death by 11
Sep 18 17:53:54 nameserver imap[1425]: Connection reset by peer, closing connection
Sep 18 17:53:54 nameserver imap[1434]: Connection reset by peer, closing connection
Sep 18 17:54:38 nameserver master[299]: process 1425 exited, signaled to death by 11
Sep 18 17:59:34 nameserver master[299]: process 1575 exited, signaled to death by 11
Sep 18 18:04:34 nameserver master[299]: process 1603 exited, signaled to death by 11
Sep 18 18:09:34 nameserver master[299]: process 1650 exited, signaled to death by 11
Sep 18 18:10:04 nameserver imap[1433]: Connection reset by peer, closing connection
Sep 18 18:10:04 nameserver imap[1555]: Connection reset by peer, closing connection
Sep 18 18:10:06 nameserver imap[1542]: Connection reset by peer, closing connection
Sep 18 18:14:34 nameserver master[299]: process 1695 exited, signaled to death by 11
Sep 18 18:23:53 nameserver deliver[144]: connect(/var/imap/socket/lmtp) failed: Connection refused
Sep 18 18:23:53 nameserver deliver[143]: connect(/var/imap/socket/lmtp) failed: Connection refused
Sep 18 18:24:41 nameserver master[42]: process 272 exited, signaled to death by 11

CrashReporter Log:
Host Name: Mail-Server
Date/Time: 2007-09-18 18:09:33.386 -0500
OS Version: 10.4.8 (Build 8L127)
Report Version: 4

Command: imapd
Path: /usr/bin/cyrus/bin/imapd
Parent: master [299]

Version: ??? (???)

PID: 1650
Thread: 0

Exception: EXC BADACCESS (0x0001)
Codes: KERN INVALIDADDRESS (0x0001) at 0x727fdc40

Thread 0 Crashed:
0 imapd 0x00033c34 0x1000 + 207924
1 imapd 0x0000a468 0x1000 + 37992
2 imapd 0x000055f4 0x1000 + 17908
3 imapd 0x000049ec 0x1000 + 14828
4 imapd 0x00003130 0x1000 + 8496
5 imapd 0x00001c9c 0x1000 + 3228
6 imapd 0x00001b44 0x1000 + 2884

Thread 0 crashed with PPC Thread State 64:
srr0: 0x0000000000033c34 srr1: 0x000000000200f030 vrsave: 0x0000000000000000
cr: 0x44042242 xer: 0x0000000000000004 lr: 0x0000000000033c20 ctr: 0x0000000000000002
r0: 0x00000000031bc000 r1: 0x00000000bfffc120 r2: 0x00000000727fdc3c r3: 0x0000000004516880
r4: 0x00000000004268bc r5: 0x0000000000000038 r6: 0x0000000000000028 r7: 0x0000000000000002
r8: 0x0000000000000000 r9: 0x00000000727fdc40 r10: 0x0000000000000060 r11: 0x0000000000000004
r12: 0x0000000001819320 r13: 0x0000000000000000 r14: 0x0000000000747000 r15: 0x0000000000000001
r16: 0x0000000000000000 r17: 0x0000000000000000 r18: 0x0000000000000000 r19: 0x0000000000000000
r20: 0x0000000000000000 r21: 0x0000000000000000 r22: 0x0000000000000000 r23: 0x000000000135a880
r24: 0x00000000a000dbe4 r25: 0x00000000a000db8c r26: 0x0000000000747000 r27: 0x00000000000033fb
r28: 0x000000000017a0e4 r29: 0x000000000135a880 r30: 0x0000000000426880 r31: 0x00000000000338b8

Binary Images Description:
0x1000 - 0x172fff imapd /usr/bin/cyrus/bin/imapd
0x1ab000 - 0x1adfff apop.so /usr/lib/sasl2/apop.so
0x1b0000 - 0x1b8fff digestmd5WebDAV.so /usr/lib/sasl2/digestmd5WebDAV.so
0x1bb000 - 0x1bdfff libanonymous.2.so /usr/lib/sasl2/libanonymous.2.so
0x1c0000 - 0x1c2fff libcrammd5.2.so /usr/lib/sasl2/libcrammd5.2.so
0x1c5000 - 0x1cefff libdigestmd5.2.so /usr/lib/sasl2/libdigestmd5.2.so
0x1d1000 - 0x1d4fff libgssapiv2.2.so /usr/lib/sasl2/libgssapiv2.2.so
0x1d7000 - 0x1d9fff login.so /usr/lib/sasl2/login.so
0x1dc000 - 0x1e1fff libntlm.so /usr/lib/sasl2/libntlm.so
0x1e4000 - 0x1ecfff libotp.2.so /usr/lib/sasl2/libotp.2.so
0x1f5000 - 0x1f7fff libplain.2.so /usr/lib/sasl2/libplain.2.so
0x1fa000 - 0x1fdfff mschapv2.so /usr/lib/sasl2/mschapv2.so
0x3a7000 - 0x3b0fff pwauxprop.so /usr/lib/sasl2/pwauxprop.so
0x3cc000 - 0x3cefff shadow_auxprop.so /usr/lib/sasl2/shadow_auxprop.so
0x3d1000 - 0x3d4fff smb_lm.so /usr/lib/sasl2/smb_lm.so
0x3d7000 - 0x3dafff smb_nt.so /usr/lib/sasl2/smb_nt.so
0x3dd000 - 0x3e0fff smb_ntlmv2.so /usr/lib/sasl2/smb_ntlmv2.so
0x505000 - 0x585fff dhx.so /usr/lib/sasl2/dhx.so
0x8fe00000 - 0x8fe51fff dyld 45.3 /usr/lib/dyld
0x90000000 - 0x901bcfff libSystem.B.dylib /usr/lib/libSystem.B.dylib
0x90214000 - 0x90219fff libmathCommon.A.dylib /usr/lib/system/libmathCommon.A.dylib
0x907ba000 - 0x90893fff com.apple.CoreFoundation 6.4.6 (368.27) /System/Library/Frameworks/CoreFoundation.framework/Versions/A/CoreFoundation
0x908dc000 - 0x908dcfff com.apple.CoreServices 10.4 (???) /System/Library/Frameworks/CoreServices.framework/Versions/A/CoreServices
0x908de000 - 0x909e0fff libicucore.A.dylib /usr/lib/libicucore.A.dylib
0x90a3a000 - 0x90abefff libobjc.A.dylib /usr/lib/libobjc.A.dylib
0x90ae8000 - 0x90b58fff com.apple.framework.IOKit 1.4 (???) /System/Library/Frameworks/IOKit.framework/Versions/A/IOKit
0x90b6e000 - 0x90b80fff libauto.dylib /usr/lib/libauto.dylib
0x90b87000 - 0x90e5efff com.apple.CoreServices.CarbonCore 681.8 /System/Library/Frameworks/CoreServices.framework/Versions/A/Frameworks/CarbonC ore.framework/Versions/A/CarbonCore
0x90ec4000 - 0x90f44fff com.apple.CoreServices.OSServices 4.1 /System/Library/Frameworks/CoreServices.framework/Versions/A/Frameworks/OSServi ces.framework/Versions/A/OSServices
0x90f8e000 - 0x90fcffff com.apple.CFNetwork 4.0 (129.19) /System/Library/Frameworks/CoreServices.framework/Versions/A/Frameworks/CFNetwo rk.framework/Versions/A/CFNetwork
0x90fe4000 - 0x90ffcfff com.apple.WebServices 1.1.2 (1.1.0) /System/Library/Frameworks/CoreServices.framework/Versions/A/Frameworks/WebServ icesCore.framework/Versions/A/WebServicesCore
0x9100c000 - 0x9108dfff com.apple.SearchKit 1.0.5 /System/Library/Frameworks/CoreServices.framework/Versions/A/Frameworks/SearchK it.framework/Versions/A/SearchKit
0x910d3000 - 0x910fdfff com.apple.Metadata 10.4.4 (121.36) /System/Library/Frameworks/CoreServices.framework/Versions/A/Frameworks/Metadat a.framework/Versions/A/Metadata
0x9110e000 - 0x9111cfff libz.1.dylib /usr/lib/libz.1.dylib
0x9111f000 - 0x912dafff com.apple.security 4.6 (29770) /System/Library/Frameworks/Security.framework/Versions/A/Security
0x913d9000 - 0x913e2fff com.apple.DiskArbitration 2.1 /System/Library/Frameworks/DiskArbitration.framework/Versions/A/DiskArbitration
0x913e9000 - 0x91411fff com.apple.SystemConfiguration 1.8.3 /System/Library/Frameworks/SystemConfiguration.framework/Versions/A/SystemConfi guration
0x91424000 - 0x9142ffff libgcc_s.1.dylib /usr/lib/libgcc_s.1.dylib
0x91a10000 - 0x91ad7fff libcrypto.0.9.7.dylib /usr/lib/libcrypto.0.9.7.dylib
0x9293b000 - 0x92b66fff com.apple.Foundation 6.4.6 (567.27) /System/Library/Frameworks/Foundation.framework/Versions/C/Foundation
0x92c84000 - 0x92d62fff libxml2.2.dylib /usr/lib/libxml2.2.dylib
0x92d82000 - 0x92e70fff libiconv.2.dylib /usr/lib/libiconv.2.dylib
0x9366d000 - 0x9368dfff com.apple.DirectoryService.Framework 3.1 /System/Library/Frameworks/DirectoryService.framework/Versions/A/DirectoryServi ce
0x94e1c000 - 0x94e2cfff libsasl2.2.dylib /usr/lib/libsasl2.2.dylib
0x94e30000 - 0x94e5ffff libssl.0.9.7.dylib /usr/lib/libssl.0.9.7.dylib
0x94e6f000 - 0x94e8cfff libresolv.9.dylib /usr/lib/libresolv.9.dylib
0x9546c000 - 0x9559afff edu.mit.Kerberos 5.5.23 /System/Library/Frameworks/Kerberos.framework/Versions/A/Kerberos
0x95f26000 - 0x95f4afff com.apple.DirectoryService.PasswordServerFramework 2.1 /System/Library/PrivateFrameworks/PasswordServer.framework/Versions/A/PasswordS erver
0x9b752000 - 0x9b762fff libwrap.7.dylib /usr/lib/libwrap.7.dylib

Quicksilver Dual 1G G4, Mac OS X (10.4.8), 1G RAM

Posted on Sep 18, 2007 4:27 PM

Reply

Answer 1

smcnulty

Level 1

40 points

Sep 18, 2007 7:22 PM in response to sologroupmrc

Not to start a heavy conversation on updates BUT.... is there a reason you havent gone to 10.4.10 yet???? Make a backup first of course but i would hate to find out it was simply things already fixed by Apple. You are missing a bunch of KNOWN FIXED things btw .8 and .10
Others may disagree on this point but a few good hoousekeeping things also happen when doing updates.

Let us know what happens.

Sean

Reply

Answer 2

sologroupmrc Author

Level 1

0 points

Sep 18, 2007 8:24 PM in response to smcnulty

Thanks Sean. The biggest reason we have not yet updated is that it is a production server. This issue, in one form or another, has been going on for a long time. Call me pessemistic, but I'm not ready to count on a couple of updates to fix a major crashing issue.

However, I am willing to perform the updates in the event someone can concretely tell me that there may be something related.

Mike

Reply

Answer 3

pterobyte

Level 6

11,098 points

Sep 19, 2007 12:49 AM in response to sologroupmrc

Up the logging level for IMAP and SMTP so that you can see what happened before the crashes.

Reply

Answer 4

sologroupmrc Author

Level 1

0 points

Sep 19, 2007 8:39 AM in response to pterobyte

I increased the log level to debug for both servrvices. Now it takes down the entire system every 10-15 minutes. Not just aborting the imapd processes, but the entire system. It restarts every 10-15 minutes.

I noticed this morning that the imap connections quickly ramp up to 100 within a few minutes of people checking mail. POP is not on. Each person has several imap connections open from their machines, there are only 40 people in the office. mail:imap:max imapconnections was at 100, but I increased that to 200 to avoid the max connections error.

Is there a way to ensure that each user re-uses the impa connections that are open to them? It seems like each time they connect, more and more imap connections are being opened...

What setting is used to shorten the idle/inactive imap connection timeout? Perhaps I could shorten this and have the open connections timeout faster.

Could part of this be a memory issue? The system only has 1G of RAM, but it is only running DNS, WEB, and MAIL.

Reply

Answer 5

pterobyte

Level 6

11,098 points

Sep 19, 2007 8:41 AM in response to sologroupmrc

Since you increased the logging level, could you post the log entries "around" the crashes.

Reply

Answer 6

sologroupmrc Author

Level 1

0 points

Sep 19, 2007 9:25 AM in response to pterobyte

Thanks pterobyte.

This one happened at 11:13:09

/var/log/mailaccess.log (IMAP)
Sep 19 11:12:42 nameserver lmtpunix[441]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:42 nameserver lmtpunix[441]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:47 nameserver lmtpunix[543]: accepted connection
Sep 19 11:12:47 nameserver lmtpunix[543]: lmtp connection preauth'd as postman
Sep 19 11:12:47 nameserver lmtpunix[543]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:47 nameserver lmtpunix[543]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:47 nameserver lmtpunix[543]: AOD: user opts: looking up user record: mgreen
Sep 19 11:12:47 nameserver lmtpunix[543]: AOD: getting mail attribute for user: mgreen
Sep 19 11:12:47 nameserver lmtpunix[543]: AOD: mail enabled
Sep 19 11:12:47 nameserver lmtpunix[543]: AOD: mbr check_servicemembership with access allservices
Sep 19 11:12:47 nameserver lmtpunix[543]: AOD: checkServiceACL: Mail SACL not enabled
Sep 19 11:12:47 nameserver lmtpunix[543]: IOERROR: fstating sieve script /usr/sieve/m/mfleming/defaultbc: No such file or directory
Sep 19 11:12:47 nameserver lmtpunix[543]: duplicate_check: <7ABAE7C22ED4F44F8750245C7963BD800126B0DE@FS-EXCH.acpe-accredit.org> user.mfleming 0
Sep 19 11:12:48 nameserver lmtpunix[543]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:48 nameserver lmtpunix[543]: AOD: user opts: looking up user record: mfleming
Sep 19 11:12:48 nameserver lmtpunix[543]: AOD: getting mail attribute for user: mfleming
Sep 19 11:12:48 nameserver lmtpunix[543]: AOD: mail enabled
Sep 19 11:12:48 nameserver lmtpunix[543]: AOD: checkServiceACL: No SACL check, mail SACL is not enabled
Sep 19 11:12:48 nameserver lmtpunix[543]: mystore: starting txn 2147483703
Sep 19 11:12:48 nameserver lmtpunix[543]: mystore: committing txn 2147483703
Sep 19 11:12:48 nameserver lmtpunix[543]: duplicate_mark: <7ABAE7C22ED4F44F8750245C7963BD800126B0DE@FS-EXCH.acpe-accredit.org> user.mfleming 1190218367 19595
Sep 19 11:12:48 nameserver lmtpunix[543]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:48 nameserver lmtpunix[543]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:56 nameserver lmtpunix[434]: accepted connection
Sep 19 11:12:56 nameserver lmtpunix[434]: lmtp connection preauth'd as postman
Sep 19 11:12:56 nameserver lmtpunix[434]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:56 nameserver lmtpunix[434]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:56 nameserver lmtpunix[434]: AOD: user opts: looking up user record: bivers
Sep 19 11:12:56 nameserver lmtpunix[434]: AOD: getting mail attribute for user: bivers
Sep 19 11:12:56 nameserver lmtpunix[434]: AOD: mail enabled
Sep 19 11:12:56 nameserver lmtpunix[434]: AOD: mbr check_servicemembership with access allservices
Sep 19 11:12:56 nameserver lmtpunix[434]: AOD: checkServiceACL: Mail SACL not enabled
Sep 19 11:12:56 nameserver lmtpunix[434]: duplicate_check: <1190214495.6287@vernalsix.com> user.bivers 0
Sep 19 11:12:57 nameserver lmtpunix[434]: AOD: user opts: no-lookup for: bivers
Sep 19 11:12:57 nameserver lmtpunix[434]: mystore: starting txn 2147483704
Sep 19 11:12:57 nameserver lmtpunix[434]: mystore: committing txn 2147483704
Sep 19 11:12:57 nameserver lmtpunix[434]: duplicate_mark: <1190214495.6287@vernalsix.com> user.bivers 1190218376 41505
Sep 19 11:12:57 nameserver lmtpunix[434]: mystore: starting txn 2147483705
Sep 19 11:12:57 nameserver lmtpunix[434]: mystore: committing txn 2147483705
Sep 19 11:12:57 nameserver lmtpunix[434]: duplicate_mark: <1190214495.6287@vernalsix.com> .bivers+@.sieve. 1190218377 0
Sep 19 11:12:57 nameserver lmtpunix[434]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:57 nameserver lmtpunix[434]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:57 nameserver imap[291]: AOD: user opts: cleaning up user options structure
Sep 19 11:12:57 nameserver imap[291]: AOD: user opts: releasing search node reference
Sep 19 11:12:57 nameserver imap[291]: AOD: user opts: releasing directory reference
Sep 19 11:12:57 nameserver master[42]: process 291 exited, status 0
Sep 19 11:13:01 nameserver imap[338]: open: user kfinesilver opened Deleted Items
Sep 19 11:13:04 nameserver imap[295]: accepted connection
Sep 19 11:13:04 nameserver imap[295]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:04 nameserver imap[295]: AOD: user opts: looking up user record: jtaylor
Sep 19 11:13:04 nameserver imap[295]: AOD: getting mail attribute for user: jtaylor
Sep 19 11:13:04 nameserver imap[295]: AOD: mail enabled
Sep 19 11:13:04 nameserver imap[295]: AOD: mbr check_servicemembership with access allservices
Sep 19 11:13:04 nameserver imap[295]: AOD: checkServiceACL: Mail SACL not enabled
Sep 19 11:13:04 nameserver imap[295]: AOD: auth-authority crypt auth for: jtaylor
Sep 19 11:13:04 nameserver imap[295]: login: [192.168.111.123] jtaylor plaintext User logged in
Sep 19 11:13:04 nameserver imap[295]: quota set to "unlimited" for mailbox user.jtaylor
Sep 19 11:13:04 nameserver imap[295]: seen_db: user jtaylor opened /var/imap/user/j/jtaylor.seen
Sep 19 11:13:04 nameserver imap[295]: open: user jtaylor opened INBOX
Sep 19 11:13:08 nameserver imap[299]: open: user kdelaney opened INBOX
Sep 19 11:13:08 nameserver master[605]: about to exec /usr/bin/cyrus/bin/imapd
Sep 19 11:13:08 nameserver imap[605]: executed
Sep 19 11:13:08 nameserver imap[605]: accepted connection
Sep 19 11:13:08 nameserver imap[605]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:08 nameserver imap[605]: AOD: user opts: getting directory reference
Sep 19 11:13:08 nameserver imap[605]: AOD: user opts: getting search node reference
Sep 19 11:13:08 nameserver imap[605]: AOD: user opts: looking up user record: kdelaney
Sep 19 11:13:08 nameserver imap[605]: AOD: getting mail attribute for user: kdelaney
Sep 19 11:13:08 nameserver imap[605]: AOD: mail enabled
Sep 19 11:13:08 nameserver imap[605]: AOD: mbr check_servicemembership with access allservices
Sep 19 11:13:08 nameserver imap[605]: AOD: checkServiceACL: Mail SACL not enabled
Sep 19 11:13:08 nameserver imap[605]: AOD: auth-authority crypt auth for: kdelaney
Sep 19 11:13:08 nameserver imap[605]: login: [192.168.111.111] kdelaney plaintext User logged in
Sep 19 11:13:09 nameserver imap[605]: quota set to "unlimited" for mailbox user.kdelaney
Sep 19 11:13:09 nameserver imap[605]: seen_db: user kdelaney opened /var/imap/user/k/kdelaney.seen
Sep 19 11:13:09 nameserver master[42]: process 295 exited, signaled to death by 11
Sep 19 11:13:09 nameserver master[42]: service imap pid 295 in BUSY state: terminated abnormally
Sep 19 11:13:12 nameserver imap[407]: open: user academy opened INBOX
Sep 19 11:13:12 nameserver imap[605]: accepted connection
Sep 19 11:13:12 nameserver lmtpunix[441]: accepted connection
Sep 19 11:13:12 nameserver lmtpunix[441]: lmtp connection preauth'd as postman
Sep 19 11:13:12 nameserver imap[605]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:12 nameserver imap[605]: AOD: user opts: looking up user record: academy
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: user opts: looking up user record: svk
Sep 19 11:13:12 nameserver imap[605]: AOD: getting mail attribute for user: academy
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: getting mail attribute for user: svk
Sep 19 11:13:12 nameserver imap[605]: AOD: mail enabled
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: mail enabled
Sep 19 11:13:12 nameserver imap[605]: AOD: checkServiceACL: No SACL check, mail SACL is not enabled
Sep 19 11:13:12 nameserver imap[605]: AOD: auth-authority crypt auth for: academy
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: mbr check_servicemembership with access allservices
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: checkServiceACL: Mail SACL not enabled
Sep 19 11:13:12 nameserver imap[605]: login: [192.168.111.148] academy plaintext User logged in
Sep 19 11:13:12 nameserver imap[605]: quota set to "unlimited" for mailbox user.academy
Sep 19 11:13:12 nameserver lmtpunix[441]: duplicate_check: <97992AF6-9172-4734-B824-1EEF4E30C16C@hlcommission.org> user.svankollenburg 0
Sep 19 11:13:12 nameserver imap[605]: seen_db: user academy opened /var/imap/user/a/academy.seen
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: user opts: looking up user record: svankollenburg
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: getting mail attribute for user: svankollenburg
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: mail enabled
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: checkServiceACL: No SACL check, mail SACL is not enabled
Sep 19 11:13:12 nameserver lmtpunix[441]: mystore: starting txn 2147483706
Sep 19 11:13:12 nameserver lmtpunix[441]: mystore: committing txn 2147483706
Sep 19 11:13:12 nameserver lmtpunix[441]: duplicate_mark: <97992AF6-9172-4734-B824-1EEF4E30C16C@hlcommission.org> user.svankollenburg 1190218392 26102
Sep 19 11:13:12 nameserver lmtpunix[441]: mystore: starting txn 2147483707
Sep 19 11:13:12 nameserver lmtpunix[441]: mystore: committing txn 2147483707
Sep 19 11:13:12 nameserver lmtpunix[441]: duplicate_mark: <97992AF6-9172-4734-B824-1EEF4E30C16C@hlcommission.org> .svankollenburg+@.sieve. 1190218392 0
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:12 nameserver lmtpunix[441]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:18 nameserver imap[605]: accepted connection
Sep 19 11:13:18 nameserver imap[605]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:18 nameserver imap[605]: AOD: user opts: looking up user record: scrow
Sep 19 11:13:18 nameserver imap[605]: AOD: getting mail attribute for user: scrow
Sep 19 11:13:18 nameserver imap[605]: AOD: mail enabled
Sep 19 11:13:18 nameserver imap[605]: AOD: checkServiceACL: No SACL check, mail SACL is not enabled
Sep 19 11:13:18 nameserver imap[605]: AOD: auth-authority crypt auth for: scrow
Sep 19 11:13:18 nameserver imap[605]: login: bda109.bis.na.blackberry.com [216.9.249.109] scrow plaintext User logged in
Sep 19 11:13:18 nameserver imap[605]: quota set to "unlimited" for mailbox user.scrow
Sep 19 11:13:18 nameserver imap[605]: seen_db: user scrow opened /var/imap/user/s/scrow.seen
Sep 19 11:13:18 nameserver imap[605]: open: user scrow opened INBOX
Sep 19 11:13:18 nameserver imap[605]: open: user scrow opened INBOX
Sep 19 11:13:18 nameserver lmtpunix[543]: accepted connection
Sep 19 11:13:18 nameserver lmtpunix[543]: lmtp connection preauth'd as postman
Sep 19 11:13:18 nameserver lmtpunix[543]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:18 nameserver lmtpunix[543]: AOD: user opts: cleaning up user options structure
Sep 19 11:13:18 nameserver lmtpunix[543]: AOD: user opts: looking up user record: bivers
Sep 19 11:13:18 nameserver lmtpunix[543]: AOD: getting mail attribute for user: bivers
Sep 19 11:13:18 nameserver lmtpunix[543]: AOD: mail enabled
Sep 19 11:13:18 nameserver lmtpunix[543]: AOD: mbr check_servicemembership with access allservices
Sep 19 11:13:18 nameserver lmtpunix[543]: AOD: checkServiceACL: Mail SACL not enabled
Sep 19 11:13:18 nameserver lmtpunix[543]: duplicate_check: <000301c7fad0$5ebfd400$4201a8c0@Jack> user.bivers 0
Sep 19 11:13:19 nameserver lmtpunix[543]: AOD: user opts: no-lookup for: bivers
Sep 19 11:13:19 nameserver lmtpunix[543]: mystore: starting txn 2147483708
Sep 19 11:13:19 nameserver lmtpunix[543]: mystore: committing txn 2147483708

/var/log/mail.log (SMTP)
Sep 19 11:12:01 nameserver postfix/smtp[420]: B9B922AD9A4D: to=<info@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=3008, status=sent (250 2.6.0 Ok, id=00504-09, from MTA: 250 Ok: queued as 24EFF2ADAB85)
Sep 19 11:12:01 nameserver postfix/qmgr[75]: B9B922AD9A4D: removed
Sep 19 11:12:01 nameserver postfix/pipe[431]: 24EFF2ADAB85: to=<info@hlcommission.org>, relay=cyrus, delay=0, status=sent (mail.hlcommission.org)
Sep 19 11:12:01 nameserver postfix/qmgr[75]: 24EFF2ADAB85: removed
Sep 19 11:12:01 nameserver postfix/smtpd[442]: connect from adp73.neoplus.adsl.tpnet.pl[83.25.93.73]
Sep 19 11:12:04 nameserver postfix/smtpd[442]: NOQUEUE: reject: RCPT from adp73.neoplus.adsl.tpnet.pl[83.25.93.73]: 554 Service unavailable; Client host [83.25.93.73] blocked using bl.spamcop.net; Blocked - see http://www.spamcop.net/bl.shtml?83.25.93.73; from=<Grandjean@brzesowsky.de> to=<ksolomon@hlcommission.org> proto=ESMTP helo=<adp73.neoplus.adsl.tpnet.pl>
Sep 19 11:12:05 nameserver postfix/smtpd[442]: disconnect from adp73.neoplus.adsl.tpnet.pl[83.25.93.73]
Sep 19 11:12:12 nameserver postfix/smtpd[429]: connect from localhost[127.0.0.1]
Sep 19 11:12:12 nameserver postfix/smtpd[429]: 6ECB22ADABB5: client=localhost[127.0.0.1]
Sep 19 11:12:12 nameserver postfix/cleanup[226]: 6ECB22ADABB5: message-id=<OF1DB4E1AF.A65E81D6-ON8625735B.0055E245-8625735B.0056940A@email.zur ich.com>
Sep 19 11:12:12 nameserver postfix/qmgr[75]: 6ECB22ADABB5: from=<marguerite.hale@zurichna.com>, size=54937, nrcpt=1 (queue active)
Sep 19 11:12:12 nameserver postfix/smtpd[429]: disconnect from localhost[127.0.0.1]
Sep 19 11:12:12 nameserver postfix/smtp[421]: BD00A2AD9ED2: to=<jGardner@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=1584, status=sent (250 2.6.0 Ok, id=00500-10, from MTA: 250 Ok: queued as 6ECB22ADABB5)
Sep 19 11:12:12 nameserver postfix/qmgr[75]: BD00A2AD9ED2: removed
Sep 19 11:12:12 nameserver postfix/pipe[437]: 6ECB22ADABB5: to=<jGardner@hlcommission.org>, relay=cyrus, delay=0, status=sent (mail.hlcommission.org)
Sep 19 11:12:12 nameserver postfix/qmgr[75]: 6ECB22ADABB5: removed
Sep 19 11:12:16 nameserver postfix/smtpd[150]: connect from host-89-228-24-142.zamosc.mm.pl[89.228.24.142]
Sep 19 11:12:17 nameserver postfix/smtpd[333]: warning: 190.24.72.243: hostname adsl190-024072243.dyn.etb.net.co verification failed: Host not found
Sep 19 11:12:17 nameserver postfix/smtpd[333]: connect from unknown[190.24.72.243]
Sep 19 11:12:17 nameserver postfix/smtpd[150]: E24E82ADABE5: client=host-89-228-24-142.zamosc.mm.pl[89.228.24.142]
Sep 19 11:12:18 nameserver postfix/smtpd[537]: connect from localhost[127.0.0.1]
Sep 19 11:12:18 nameserver postfix/smtpd[537]: 320852ADABEA: client=localhost[127.0.0.1]
Sep 19 11:12:18 nameserver postfix/cleanup[580]: 320852ADABEA: message-id=<000201c7fad0$0b169780$c62aac59@gatesq3io3k4tz>
Sep 19 11:12:18 nameserver postfix/qmgr[75]: 320852ADABEA: from=<Kidactah@sharmenliao.com>, size=2581, nrcpt=1 (queue active)
Sep 19 11:12:18 nameserver postfix/smtpd[537]: disconnect from localhost[127.0.0.1]
Sep 19 11:12:18 nameserver postfix/smtp[420]: C862B2AD9963: to=<mgreen@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=3369, status=sent (250 2.6.0 Ok, id=00504-10, from MTA: 250 Ok: queued as 320852ADABEA)
Sep 19 11:12:18 nameserver postfix/qmgr[75]: C862B2AD9963: removed
Sep 19 11:12:18 nameserver postfix/pipe[541]: 320852ADABEA: to=<mgreen@hlcommission.org>, relay=cyrus, delay=0, status=sent (mail.hlcommission.org)
Sep 19 11:12:18 nameserver postfix/qmgr[75]: 320852ADABEA: removed
Sep 19 11:12:18 nameserver postfix/smtpd[333]: B43302ADAC00: client=unknown[190.24.72.243]
Sep 19 11:12:19 nameserver postfix/cleanup[580]: B43302ADAC00: message-id=<000d01c7fba1$24d40690$f34818be@ms12>
Sep 19 11:12:19 nameserver postfix/qmgr[75]: B43302ADAC00: from=<Carrick-kurtlak@popnetserver.de>, size=1948, nrcpt=1 (queue active)
Sep 19 11:12:19 nameserver postfix/smtpd[333]: disconnect from unknown[190.24.72.243]
Sep 19 11:12:19 nameserver postfix/cleanup[226]: E24E82ADABE5: message-id=<01c7fad7$d6d56390$8e18e459@linkommespaetermet>
Sep 19 11:12:20 nameserver postfix/qmgr[75]: E24E82ADABE5: from=<linkommespaetermet@kommespaeter.de>, size=9331, nrcpt=1 (queue active)
Sep 19 11:12:22 nameserver postfix/smtpd[150]: disconnect from host-89-228-24-142.zamosc.mm.pl[89.228.24.142]
Sep 19 11:12:27 nameserver postfix/smtpd[429]: connect from localhost[127.0.0.1]
Sep 19 11:12:27 nameserver postfix/smtpd[429]: 64B312ADAC1E: client=localhost[127.0.0.1]
Sep 19 11:12:27 nameserver postfix/cleanup[580]: 64B312ADAC1E: message-id=<1190215443434.62936045-ac97-450b-8ff2-d91178d89d3e@google.com>
Sep 19 11:12:27 nameserver postfix/qmgr[75]: 64B312ADAC1E: from=<3Ez_xRgYQBYos6Aow1vzq200w66w21.25uqr8zzvzq200w66w21.25u@blogger.bounces.g oogle.com>, size=2577, nrcpt=1 (queue active)
Sep 19 11:12:27 nameserver postfix/smtpd[429]: disconnect from localhost[127.0.0.1]
Sep 19 11:12:27 nameserver postfix/smtp[421]: CA9A12AD9AB7: to=<cdull@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=2904, status=sent (250 2.6.0 Ok, id=00577-01, from MTA: 250 Ok: queued as 64B312ADAC1E)
Sep 19 11:12:27 nameserver postfix/qmgr[75]: CA9A12AD9AB7: removed
Sep 19 11:12:27 nameserver postfix/pipe[431]: 64B312ADAC1E: to=<cdull@hlcommission.org>, relay=cyrus, delay=0, status=sent (mail.hlcommission.org)
Sep 19 11:12:27 nameserver postfix/qmgr[75]: 64B312ADAC1E: removed
Sep 19 11:12:32 nameserver postfix/smtpd[537]: connect from localhost[127.0.0.1]
Sep 19 11:12:32 nameserver postfix/smtpd[537]: DE5FB2ADAC44: client=localhost[127.0.0.1]
Sep 19 11:12:32 nameserver postfix/cleanup[226]: DE5FB2ADAC44: message-id=<390b01c7fad0$01ba4f32$a9317e52@ARennes-351-1-19-169.w82-126.abo.wan adoo.fr>
Sep 19 11:12:32 nameserver postfix/smtpd[537]: disconnect from localhost[127.0.0.1]
Sep 19 11:12:32 nameserver postfix/qmgr[75]: DE5FB2ADAC44: from=<sales@laboursolutionsinternational.com>, size=1738, nrcpt=1 (queue active)
Sep 19 11:12:32 nameserver postfix/smtp[420]: CB3A82AD9AC9: to=<jgardner@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=2855, status=sent (250 2.6.0 Ok, id=00582-01, from MTA: 250 Ok: queued as DE5FB2ADAC44)
Sep 19 11:12:32 nameserver postfix/qmgr[75]: CB3A82AD9AC9: removed
Sep 19 11:12:33 nameserver postfix/pipe[437]: DE5FB2ADAC44: to=<jgardner@hlcommission.org>, relay=cyrus, delay=1, status=sent (mail.hlcommission.org)
Sep 19 11:12:33 nameserver postfix/qmgr[75]: DE5FB2ADAC44: removed
Sep 19 11:12:42 nameserver postfix/smtpd[429]: connect from localhost[127.0.0.1]
Sep 19 11:12:42 nameserver postfix/smtpd[429]: 7D75A2ADAC70: client=localhost[127.0.0.1]
Sep 19 11:12:42 nameserver postfix/cleanup[580]: 7D75A2ADAC70: message-id=<7f4d01c7fad3$18f4dbe8$8f510553@strzoda-0pynael>
Sep 19 11:12:42 nameserver postfix/qmgr[75]: 7D75A2ADAC70: from=<sales@universalstudiosnewmedia.com>, size=1632, nrcpt=1 (queue active)
Sep 19 11:12:42 nameserver postfix/smtpd[429]: disconnect from localhost[127.0.0.1]
Sep 19 11:12:42 nameserver postfix/smtp[421]: CBF402AD9F10: to=<jtaylor@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=1596, status=sent (250 2.6.0 Ok, id=00577-02, from MTA: 250 Ok: queued as 7D75A2ADAC70)
Sep 19 11:12:42 nameserver postfix/qmgr[75]: CBF402AD9F10: removed
Sep 19 11:12:42 nameserver postfix/pipe[541]: 7D75A2ADAC70: to=<jtaylor@hlcommission.org>, relay=cyrus, delay=0, status=sent (mail.hlcommission.org)
Sep 19 11:12:42 nameserver postfix/qmgr[75]: 7D75A2ADAC70: removed
Sep 19 11:12:47 nameserver postfix/smtpd[537]: connect from localhost[127.0.0.1]
Sep 19 11:12:47 nameserver postfix/smtpd[537]: CD9AB2ADAC9D: client=localhost[127.0.0.1]
Sep 19 11:12:47 nameserver postfix/cleanup[226]: CD9AB2ADAC9D: message-id=<7ABAE7C22ED4F44F8750245C7963BD800126B0DE@FS-EXCH.acpe-accredit.org>
Sep 19 11:12:47 nameserver postfix/qmgr[75]: CD9AB2ADAC9D: from=<uchung@acpe-accredit.org>, size=9892, nrcpt=1 (queue active)
Sep 19 11:12:47 nameserver postfix/smtpd[537]: disconnect from localhost[127.0.0.1]
Sep 19 11:12:47 nameserver postfix/smtp[420]: CD4332AD9AB5: to=<mgreen@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=2928, status=sent (250 2.6.0 Ok, id=00582-02, from MTA: 250 Ok: queued as CD9AB2ADAC9D)
Sep 19 11:12:47 nameserver postfix/qmgr[75]: CD4332AD9AB5: removed
Sep 19 11:12:48 nameserver postfix/pipe[431]: CD9AB2ADAC9D: to=<mgreen@hlcommission.org>, relay=cyrus, delay=1, status=sent (mail.hlcommission.org)
Sep 19 11:12:49 nameserver postfix/qmgr[75]: CD9AB2ADAC9D: removed
Sep 19 11:12:56 nameserver postfix/smtpd[429]: connect from localhost[127.0.0.1]
Sep 19 11:12:56 nameserver postfix/smtpd[429]: BFB812ADACCA: client=localhost[127.0.0.1]
Sep 19 11:12:56 nameserver postfix/cleanup[580]: BFB812ADACCA: message-id=<1190214495.6287@vernalsix.com>
Sep 19 11:12:56 nameserver postfix/qmgr[75]: BFB812ADACCA: from=<ret@vernalsix.com>, size=5882, nrcpt=1 (queue active)
Sep 19 11:12:56 nameserver postfix/smtpd[429]: disconnect from localhost[127.0.0.1]
Sep 19 11:12:56 nameserver postfix/smtp[421]: D36C62AD9B05: to=<bivers@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=2761, status=sent (250 2.6.0 Ok, id=00577-03, from MTA: 250 Ok: queued as BFB812ADACCA)
Sep 19 11:12:56 nameserver postfix/qmgr[75]: D36C62AD9B05: removed
Sep 19 11:12:57 nameserver postfix/pipe[437]: BFB812ADACCA: to=<bivers@hlcommission.org>, relay=cyrus, delay=1, status=sent (mail.hlcommission.org)
Sep 19 11:12:57 nameserver postfix/qmgr[75]: BFB812ADACCA: removed
Sep 19 11:13:03 nameserver postfix/smtpd[136]: connect from 77-100-58-217.cable.ubr01.chwo.blueyonder.co.uk[77.100.58.217]
Sep 19 11:13:03 nameserver postfix/smtp[420]: D45622AD9AE4: to=<mfleming@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=2837, status=sent (250 2.7.1 Ok, discarded, UBE, id=00582-03)
Sep 19 11:13:03 nameserver postfix/qmgr[75]: D45622AD9AE4: removed
Sep 19 11:13:11 nameserver postfix/smtpd[537]: connect from localhost[127.0.0.1]
Sep 19 11:13:11 nameserver postfix/smtpd[537]: B4C572ADAD27: client=localhost[127.0.0.1]
Sep 19 11:13:11 nameserver postfix/cleanup[226]: B4C572ADAD27: message-id=<97992AF6-9172-4734-B824-1EEF4E30C16C@hlcommission.org>
Sep 19 11:13:12 nameserver postfix/smtpd[537]: disconnect from localhost[127.0.0.1]
Sep 19 11:13:12 nameserver postfix/qmgr[75]: B4C572ADAD27: from=<mgomez@hlcommission.org>, size=100909, nrcpt=1 (queue active)
Sep 19 11:13:12 nameserver postfix/smtp[421]: D87362AD9C5F: to=<svk@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=1931, status=sent (250 2.6.0 Ok, id=00577-04, from MTA: 250 Ok: queued as B4C572ADAD27)
Sep 19 11:13:12 nameserver postfix/qmgr[75]: D87362AD9C5F: removed
Sep 19 11:13:12 nameserver postfix/pipe[541]: B4C572ADAD27: to=<svk@hlcommission.org>, relay=cyrus, delay=1, status=sent (mail.hlcommission.org)
Sep 19 11:13:12 nameserver postfix/qmgr[75]: B4C572ADAD27: removed
Sep 19 11:13:16 nameserver postfix/smtpd[136]: lost connection after CONNECT from 77-100-58-217.cable.ubr01.chwo.blueyonder.co.uk[77.100.58.217]
Sep 19 11:13:16 nameserver postfix/smtpd[136]: disconnect from 77-100-58-217.cable.ubr01.chwo.blueyonder.co.uk[77.100.58.217]
Sep 19 11:13:18 nameserver postfix/smtpd[429]: connect from localhost[127.0.0.1]
Sep 19 11:13:18 nameserver postfix/smtpd[429]: BF84D2ADAD5B: client=localhost[127.0.0.1]
Sep 19 11:13:18 nameserver postfix/cleanup[580]: BF84D2ADAD5B: message-id=<000301c7fad0$5ebfd400$4201a8c0@Jack>
Sep 19 11:13:18 nameserver postfix/qmgr[75]: BF84D2ADAD5B: from=<jackc@cookandkocher.com>, size=6384, nrcpt=1 (queue active)
Sep 19 11:13:18 nameserver postfix/smtpd[429]: disconnect from localhost[127.0.0.1]
Sep 19 11:13:19 nameserver postfix/smtp[420]: DD6212AD9AA4: to=<bivers@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=2970, status=sent (250 2.6.0 Ok, id=00582-04, from MTA: 250 Ok: queued as BF84D2ADAD5B)
Sep 19 11:13:19 nameserver postfix/qmgr[75]: DD6212AD9AA4: removed
Sep 19 11:13:19 nameserver postfix/pipe[431]: BF84D2ADAD5B: to=<bivers@hlcommission.org>, relay=cyrus, delay=1, status=sent (mail.hlcommission.org)
Sep 19 11:13:19 nameserver postfix/qmgr[75]: BF84D2ADAD5B: removed
Sep 19 11:13:21 nameserver postfix/smtpd[442]: connect from 72.25.12.9.static.dejazzd.com[72.25.12.9]
Sep 19 11:13:22 nameserver postfix/smtpd[442]: NOQUEUE: reject: RCPT from 72.25.12.9.static.dejazzd.com[72.25.12.9]: 554 Service unavailable; Client host [72.25.12.9] blocked using bl.spamcop.net; Blocked - see http://www.spamcop.net/bl.shtml?72.25.12.9; from=<faw@bransontourismcenter.com> to=<pubs@hlcommission.org> proto=ESMTP helo=<72.25.12.9.static.dejazzd.com>
Sep 19 11:13:22 nameserver postfix/smtpd[442]: lost connection after DATA from 72.25.12.9.static.dejazzd.com[72.25.12.9]
Sep 19 11:13:22 nameserver postfix/smtpd[442]: disconnect from 72.25.12.9.static.dejazzd.com[72.25.12.9]
Sep 19 11:13:27 nameserver postfix/smtpd[537]: connect from localhost[127.0.0.1]
Sep 19 11:13:27 nameserver postfix/smtpd[537]: E71352ADAD9D: client=localhost[127.0.0.1]
Sep 19 11:13:27 nameserver postfix/cleanup[226]: E71352ADAD9D: message-id=<7D2FFFB0DE68CA4BA947AAB1CBDC7B73EDCC9B@ares.scciowa.edu>
Sep 19 11:13:27 nameserver postfix/smtpd[537]: disconnect from localhost[127.0.0.1]
Sep 19 11:13:27 nameserver postfix/qmgr[75]: E71352ADAD9D: from=<brump@scciowa.edu>, size=17197, nrcpt=1 (queue active)
Sep 19 11:13:28 nameserver postfix/smtp[421]: DE1632AD99C7: to=<mgreen@hlcommission.org>, relay=127.0.0.1[127.0.0.1], delay=3312, status=sent (250 2.6.0 Ok, id=00577-05, from MTA: 250 Ok: queued as E71352ADAD9D)
Sep 19 11:13:28 nameserver postfix/qmgr[75]: DE1632AD99C7: removed
Sep 19 11:13:28 nameserver postfix/pipe[437]: E71352ADAD9D: to=<mgreen@hlcommission.org>, relay=cyrus, delay=1, status=sent (mail.hlcommission.org)
Sep 19 11:13:28 nameserver postfix/qmgr[75]: E71352ADAD9D: removed
Sep 19 11:13:33 nameserver postfix/smtpd[429]: connect from localhost[127.0.0.1]
Sep 19 11:13:34 nameserver postfix/smtpd[429]: E9E1B2ADADCB: client=localhost[127.0.0.1]

/var/log/crashreporter.log
Wed Sep 19 11:08:11 2007 crashdump[463]: crashdump started
Wed Sep 19 11:08:12 2007 crashdump[463]: Couldn't find or create: /var/imap/Library/Logs/CrashReporter (euid: 77)
Wed Sep 19 11:08:12 2007 crashdump[463]: Started writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log
Wed Sep 19 11:08:13 2007 crashdump[463]: Finished writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log
Wed Sep 19 11:13:09 2007 crashdump[606]: crashdump started
Wed Sep 19 11:13:09 2007 crashdump[606]: Couldn't find or create: /var/imap/Library/Logs/CrashReporter (euid: 77)
Wed Sep 19 11:13:09 2007 crashdump[606]: Started writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log
Wed Sep 19 11:13:09 2007 crashdump[606]: Finished writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log
Wed Sep 19 11:18:09 2007 crashdump[729]: crashdump started
Wed Sep 19 11:18:10 2007 crashdump[729]: Couldn't find or create: /var/imap/Library/Logs/CrashReporter (euid: 77)
Wed Sep 19 11:18:10 2007 crashdump[729]: Started writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log
Wed Sep 19 11:18:10 2007 crashdump[729]: Finished writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log

system.log
Sep 19 11:11:56 nameserver imap[390]: login: [192.168.111.114] absent plaintext User logged in
Sep 19 11:12:04 nameserver imap[295]: login: [192.168.111.126] ms_lists plaintext User logged in
Sep 19 11:12:17 nameserver postfix/smtpd[333]: warning: 190.24.72.243: hostname adsl190-024072243.dyn.etb.net.co verification failed: Host not found
Sep 19 11:13:04 nameserver imap[295]: login: [192.168.111.123] jtaylor plaintext User logged in
Sep 19 11:13:08 nameserver imap[605]: login: [192.168.111.111] kdelaney plaintext User logged in
Sep 19 11:13:09 nameserver master[42]: process 295 exited, signaled to death by 11
Sep 19 11:13:09 nameserver crashdump[606]: imapd crashed
Sep 19 11:13:09 nameserver crashdump[606]: crash report written to: /Library/Logs/CrashReporter/imapd.crash.log
Sep 19 11:13:12 nameserver imap[605]: login: [192.168.111.148] academy plaintext User logged in
Sep 19 11:13:18 nameserver imap[605]: login: bda109.bis.na.blackberry.com [216.9.249.109] scrow plaintext User logged in
Sep 19 11:13:23 nameserver imap[605]: login: 24-136-16-139.stn-bsr1.chi-stn.il.cable.rcn.com [24.136.16.139] timeclock plaintext User logged in
Sep 19 11:13:24 nameserver imap[409]: Connection reset by peer, closing connection
Sep 19 11:13:25 nameserver imap[409]: login: [192.168.111.100] aqip plaintext User logged in
Sep 19 11:13:39 nameserver imap[605]: login: cpe-76-179-65-36.maine.res.rr.com [76.179.65.36] webmaster plaintext User logged in
Sep 19 11:13:39 nameserver imap[605]: ERROR: invaldi date/time: 0 (year = 69)
Sep 19 11:13:39 nameserver master[42]: process 605 exited, signaled to death by 6
Sep 19 11:13:45 nameserver imap[316]: login: [192.168.111.112] aqip plaintext User logged in
Sep 19 11:13:50 nameserver imap[337]: login: [192.168.111.115] jmoore plaintext User logged in
Sep 19 11:13:53 nameserver postfix/smtpd[136]: warning: 69.223.196.131: hostname mail2.adler.edu verification failed: Host not found
Sep 19 11:13:58 nameserver imap[397]: login: [192.168.111.116] affiliationmark plaintext User logged in
Sep 19 11:14:21 nameserver postfix/smtpd[635]: auxpropfunc error no mechanism available\n

console.log
Sep 19 11:08:40 nameserver imap[476]: ERROR: invaldi date/time: 0 (year = 69)
Sep 19 11:08:40 nameserver master[42]: process 476 exited, signaled to death by 6
Sep 19 11:09:19 nameserver postfix/smtpd[495]: auxpropfunc error no mechanism available\n
Sep 19 11:10:58 nameserver postfix/smtpd[537]: auxpropfunc error no mechanism available\n
Sep 19 11:13:09 nameserver master[42]: process 295 exited, signaled to death by 11
Sep 19 11:13:09 nameserver crashdump[606]: imapd crashed
Sep 19 11:13:09 nameserver crashdump[606]: crash report written to: /Library/Logs/CrashReporter/imapd.crash.log
Sep 19 11:13:39 nameserver imap[605]: ERROR: invaldi date/time: 0 (year = 69)
Sep 19 11:13:39 nameserver master[42]: process 605 exited, signaled to death by 6
Sep 19 11:14:21 nameserver postfix/smtpd[635]: auxpropfunc error no mechanism available\n
Sep 19 11:16:30 nameserver postfix/smtpd[682]: auxpropfunc error no mechanism available\n

Reply

Answer 7

pterobyte

Level 6

11,098 points

Sep 19, 2007 9:43 AM in response to sologroupmrc

Unfortunately, there isn't really anything in the logs that points to a cause.

My gut feeling is you may have some underlying issue and IMAP is just one of the symptoms. Hard to tell without sitting at the server.
Your hardware should be adequate for 40 users. The fact each of your users is using so many connections is probably just a side effect of continuous connection drops.

Can you think of anything that happened before the issues started? Changes on the server? Updates?

Reply

Answer 8

sologroupmrc Author

Level 1

0 points

Sep 19, 2007 9:45 AM in response to sologroupmrc

I just noticed that the time was not set properly. Opening System Preferences yielded the console.log report that the /etc/hostconfig file was not present. I fixed the time. Sure enought he hostconfig file was not there. Hmm. Probably killed in a crash? I sudo cp /etc/hostconfig~ /etc/hostconfig

This seem reasonable?

Looking more at the console logs, I see some more issue with cyrus db locking:

Sep 19 11:08:40 nameserver imap[476]: ERROR: invaldi date/time: 0 (year = 69)
Sep 19 11:08:40 nameserver master[42]: process 476 exited, signaled to death by 6
Sep 19 11:09:19 nameserver postfix/smtpd[495]: auxpropfunc error no mechanism available\n
Sep 19 11:10:58 nameserver postfix/smtpd[537]: auxpropfunc error no mechanism available\n
Sep 19 11:13:09 nameserver master[42]: process 295 exited, signaled to death by 11
Sep 19 11:13:09 nameserver crashdump[606]: imapd crashed
Sep 19 11:13:09 nameserver crashdump[606]: crash report written to: /Library/Logs/CrashReporter/imapd.crash.log
Sep 19 11:13:39 nameserver imap[605]: ERROR: invaldi date/time: 0 (year = 69)
Sep 19 11:13:39 nameserver master[42]: process 605 exited, signaled to death by 6
Sep 19 11:14:21 nameserver postfix/smtpd[635]: auxpropfunc error no mechanism available\n
Sep 19 11:16:30 nameserver postfix/smtpd[682]: auxpropfunc error no mechanism available\n

Thanks in advance, Mike

Reply

Answer 9

sologroupmrc Author

Level 1

0 points

Sep 19, 2007 9:57 AM in response to pterobyte

mail is the only thing that gives us trouble on the server. DNS and WEB are fine. But certainly they are not as disk or memory intensive as mail.

I am a consultant trying to help the client to repair their mail. The network admin has probably done quite a bit the the server - unfortunately, I do not know all the stuff he has done. I do know that a great deal of it had to do with mail. Moving databases to a separate store, then back again. Trying to repair different user databases. Maybe other things. I just don't know.

I have tried to repair permissions, check under the hood as much as I know how for common ailments, but I can't narrow it down in between the crash dumps....

At this point, I think the best course of action is to move the whole thing to a brand new server. Are there any docs on migrating an existing server (and all it's users) to another box (without copying all the bad stuff, of course! 🙂 I haven't really done that before in an attempt to 'rebuild' a broken server... They are running only a local domain on the standalone server.

Thanks again.

Reply

Answer 10

pterobyte

Level 6

11,098 points

Sep 19, 2007 10:04 AM in response to sologroupmrc

Probably a good idea to rebuild, especially if many different admins have been "playing" with this server.

Are you sure it is setup as standalone? I see Open Directory entries in the logs.

As far as rebuilding (short version):
-WGM can export/import users (but you will need to re-enter the passwords)
-Use mailbfr to backup/restore all mail messages and configuration data ( http://osx.topicdesk.com/tools/)
-Backup/restore the Web configuration in /etc/httpd
-Backup/restore the DNS configuration in /var/named and /private/etc/named.conf

Reply

Answer 11

sologroupmrc Author

Level 1

0 points

Sep 19, 2007 10:29 AM in response to pterobyte

I think this was a server that was 10.3 at one point. Other admins may have dabbled in open directory with it at either 10.3 or 10.4 - not sure. But right now, the only services running in serveradmin are DNS, WEB, and MAIL.

Noticing errors with auxpropfunc for postfix, and referrences to kerberos in the crashdumps, I thought maybe there was something misconfigured with OD. But since it is not on, I am reluctant to turn it on to check...

When editing users in the WGM, the local directory with the users is: /NetInfo/DefaultLocalNode I thought that makes it a standalone server. Is there any way to check for sure? Do you think there could be an issue with standalone vs od? I did notice that some users are using shadow passwords while others are using Crypt. Weird.

Reply

Answer 12

davidh

Level 4

1,890 points

Sep 19, 2007 11:47 AM in response to sologroupmrc

I fully agree with Pterobyte. You really should start fresh.

Those "killed to death by 6" & 11 mean, the program (cyrus) terminated with signal 6 (Abort), and 11 (Bus Error), and hint of a mailstore that came from 10.3.x and was not properly updated for 10.4 (ie not using skiplist) and meaning that a full reconstruct is needed.

Follow Pterobyte's advice.

As for OD vs. NetInfo users, simply check the upper left-hand globe in Workgroup Manager...
does it say "Authenticated as <yourdiradmin accountname> to /LDAPv3/127.0.0.1" (meaning OpenLDAP, "OD" user accounts) or does it say "(authenticated to)... local directory: /NetInfo/DefaultLocalNode

Reply

Answer 13

sologroupmrc Author

Level 1

0 points

Sep 19, 2007 12:04 PM in response to davidh

OK, thanks guys. I have another development. Is it possible that the imapd daemon is crashing because of a gargantuan mailbox? Traing all the imapd crashes this morning, I found that EVERY one of them exited after a certain user tries to access their mail box:

Wed Sep 19 13:59:58 2007 crashdump[728]: Started writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log
Wed Sep 19 13:59:59 2007 crashdump[728]: Finished writing crash report to: /Library/Logs/CrashReporter/imapd.crash.log

Sep 19 13:59:56 nameserver imap[709]: open: user jtaylor opened INBOX
Sep 19 13:59:58 nameserver master[42]: process 709 exited, signaled to death by 11
Sep 19 13:59:58 nameserver master[42]: service imap pid 709 in BUSY state: terminated abnormally

There is my user: jtaylor.

He has 1.8 G in his inbox (user/jtaylor) alone. In his subfolders, he has over about 15 folders totalling over 2G MORE. Though the process chokes right away (as far as I can tell) and doesn't report a database corruption error (that I can find).

But certainly, this morning alone, EVERY imapd crash was a process in which the last thing that happened was jtaylor accessing his INBOX.

Any advice?

Thanks, Mike

Reply