Services stopped working

[lilwong]

IMAP access stopped working all of a sudden. Any attempts at access yielded a refusal. A check on the logs yielded only this which I can't decipher. Can anybody help with finding out what this means?

-------------------

Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: antispam: Running
Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: antivirus: Running
Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: ldap: Running
Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: logger: Running
Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: mailbox: Running
Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: mta: Running
Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: snmp: Running
Aug 15 22:10:57 lespaul zimbramon[30317]: 30317:info: 2006-08-15 22:10:02, STATUS: lespaul.imocha.com.my: spell: Running
Aug 15 22:10:57 lespaul crond(pam_unix)[30311]: session closed for user zimbra
Aug 15 22:12:01 lespaul crond(pam_unix)[32261]: session opened for user zimbra by (uid=0)
Aug 15 22:14:01 lespaul crond(pam_unix)[32306]: session opened for user zimbra by (uid=0)
Aug 15 22:15:31 lespaul crond(pam_unix)[30309]: session closed for user zimbra
Aug 15 22:16:00 lespaul zimbramon[32262]: 32262:info: 2006-08-15 22:12:01, STATUS: : Cannot: determine
Aug 15 22:16:01 lespaul crond(pam_unix)[32391]: session opened for user zimbra by (uid=0)
Aug 15 22:17:57 lespaul zimbramon[32307]: 32307:info: 2006-08-15 22:14:01, STATUS: : Cannot: determine
Aug 15 22:18:01 lespaul crond(pam_unix)[32474]: session opened for user zimbra by (uid=0)
Aug 15 22:19:56 lespaul zimbramon[32392]: 32392:info: 2006-08-15 22:16:02, STATUS: : Cannot: determine
Aug 15 22:20:02 lespaul crond(pam_unix)[32515]: session opened for user zimbra by (uid=0)
Aug 15 22:20:02 lespaul crond(pam_unix)[32512]: session opened for user zimbra by (uid=0)
Aug 15 22:20:02 lespaul crond(pam_unix)[32514]: session opened for user zimbra by (uid=0)
Aug 15 22:20:02 lespaul crond(pam_unix)[32513]: session opened for user zimbra by (uid=0)
Aug 15 22:20:03 lespaul zimbramon[32517]: 32517:info: 2006-08-15 22:20:02, QUEUE: 0 0
Aug 15 22:20:03 lespaul crond(pam_unix)[32512]: session closed for user zimbra
Aug 15 22:21:23 lespaul zimbramon[32518]: 32518:info: 2006-08-15 22:20:02, DISK: lespaul.imocha.com.my: dev: /dev/sda3, mp: /, tot: 108536, avail: 97214
Aug 15 22:21:23 lespaul zimbramon[32518]: 32518:info: 2006-08-15 22:20:02, DISK: lespaul.imocha.com.my: dev: /dev/sdb1, mp: /home, tot: 112666, avail: 106844
Aug 15 22:21:40 lespaul crond(pam_unix)[32261]: session closed for user zimbra
Aug 15 22:21:53 lespaul zimbramon[32475]: 32475:info: 2006-08-15 22:18:02, STATUS: : Cannot: determine
Aug 15 22:22:01 lespaul crond(pam_unix)[32668]: session opened for user zimbra by (uid=0)
Aug 15 22:22:34 lespaul saslauthd[23727]: auth_zimbra: royngan@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'
Aug 15 22:22:34 lespaul saslauthd[23727]: do_auth : auth failure: [user=royngan@imocha.com.my] [service=smtp] [realm=imocha.com.my] [mech=zimbra] [reason=Unknown]
Aug 15 22:23:45 lespaul crond(pam_unix)[32306]: session closed for user zimbra
Aug 15 22:23:59 lespaul zimbramon[32516]: 32516:info: 2006-08-15 22:20:02, STATUS: : Cannot: determine
Aug 15 22:24:01 lespaul crond(pam_unix)[32763]: session opened for user zimbra by (uid=0)
Aug 15 22:25:22 lespaul saslauthd[23728]: auth_zimbra: royngan@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'
Aug 15 22:25:22 lespaul saslauthd[23728]: do_auth : auth failure: [user=royngan@imocha.com.my] [service=smtp] [realm=imocha.com.my] [mech=zimbra] [reason=Unknown]
Aug 15 22:25:35 lespaul crond(pam_unix)[32391]: session closed for user zimbra
Aug 15 22:26:02 lespaul crond(pam_unix)[395]: session opened for user zimbra by (uid=0)
Aug 15 22:26:03 lespaul zimbramon[32669]: 32669:info: 2006-08-15 22:22:02, STATUS: : Cannot: determine
Aug 15 22:27:15 lespaul crond(pam_unix)[32474]: session closed for user zimbra
Aug 15 22:27:54 lespaul zimbramon[32764]: 32764:info: 2006-08-15 22:24:02, STATUS: : Cannot: determine
Aug 15 22:28:01 lespaul crond(pam_unix)[443]: session opened for user zimbra by (uid=0)
Aug 15 22:29:03 lespaul crond(pam_unix)[32515]: session closed for user zimbra
Aug 15 22:29:31 lespaul crond(pam_unix)[32514]: session closed for user zimbra
Aug 15 22:29:31 lespaul crond(pam_unix)[32513]: session closed for user zimbra
Aug 15 22:29:58 lespaul zimbramon[396]: 396:info: 2006-08-15 22:26:02, STATUS: : Cannot: determine
Aug 15 22:30:01 lespaul crond(pam_unix)[556]: session opened for user zimbra by (uid=0)
Aug 15 22:30:01 lespaul crond(pam_unix)[557]: session opened for user zimbra by (uid=0)
Aug 15 22:30:01 lespaul crond(pam_unix)[555]: session opened for user zimbra by (uid=0)
Aug 15 22:30:01 lespaul crond(pam_unix)[558]: session opened for user zimbra by (uid=0)
Aug 15 22:30:02 lespaul zimbramon[562]: 562:info: 2006-08-15 22:30:02, QUEUE: 0 0
Aug 15 22:30:02 lespaul crond(pam_unix)[555]: session closed for user zimbra
Aug 15 22:30:55 lespaul crond(pam_unix)[32668]: session closed for user zimbra
Aug 15 22:31:21 lespaul zimbramon[561]: 561:info: 2006-08-15 22:30:02, DISK: lespaul.imocha.com.my: dev: /dev/sda3, mp: /, tot: 108536, avail: 97213
Aug 15 22:31:21 lespaul zimbramon[561]: 561:info: 2006-08-15 22:30:02, DISK: lespaul.imocha.com.my: dev: /dev/sdb1, mp: /home, tot: 112666, avail: 106844
Aug 15 22:32:01 lespaul crond(pam_unix)[663]: session opened for user zimbra by (uid=0)
Aug 15 22:32:02 lespaul zimbramon[444]: 444:info: 2006-08-15 22:28:01, STATUS: : Cannot: determine
Aug 15 22:33:19 lespaul crond(pam_unix)[32763]: session closed for user zimbra
Aug 15 22:33:58 lespaul zimbramon[563]: 563:info: 2006-08-15 22:30:02, STATUS: : Cannot: determine
Aug 15 22:34:01 lespaul crond(pam_unix)[755]: session opened for user zimbra by (uid=0)
Aug 15 22:35:11 lespaul crond(pam_unix)[395]: session closed for user zimbra
Aug 15 22:35:50 lespaul zimbramon[664]: 664:info: 2006-08-15 22:32:02, STATUS: : Cannot: determine
Aug 15 22:36:01 lespaul crond(pam_unix)[858]: session opened for user zimbra by (uid=0)
Aug 15 22:37:01 lespaul crond(pam_unix)[443]: session closed for user zimbra
Aug 15 22:38:01 lespaul crond(pam_unix)[958]: session opened for user zimbra by (uid=0)
Aug 15 22:38:04 lespaul zimbramon[756]: 756:info: 2006-08-15 22:34:01, STATUS: : Cannot: determine
Aug 15 22:38:07 lespaul saslauthd[23724]: auth_zimbra: kelly@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'
Aug 15 22:38:07 lespaul saslauthd[23724]: do_auth : auth failure: [user=kelly@imocha.com.my] [service=smtp] [realm=imocha.com.my] [mech=zimbra] [reason=Unknown]
Aug 15 22:39:04 lespaul saslauthd[23725]: auth_zimbra: kelly@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'
Aug 15 22:39:04 lespaul saslauthd[23725]: do_auth : auth failure: [user=kelly@imocha.com.my] [service=smtp] [realm=imocha.com.my] [mech=zimbra] [reason=Unknown]
Aug 15 22:39:14 lespaul crond(pam_unix)[558]: session closed for user zimbra
Aug 15 22:39:14 lespaul crond(pam_unix)[556]: session closed for user zimbra
Aug 15 22:39:57 lespaul crond(pam_unix)[557]: session closed for user zimbra
Aug 15 22:39:58 lespaul zimbramon[859]: 859:info: 2006-08-15 22:36:01, STATUS: : Cannot: determine
Aug 15 22:40:01 lespaul crond(pam_unix)[998]: session opened for user zimbra by (uid=0)
Aug 15 22:40:01 lespaul crond(pam_unix)[1000]: session opened for user zimbra by (uid=0)
Aug 15 22:40:01 lespaul crond(pam_unix)[999]: session opened for user zimbra by (uid=0)
Aug 15 22:40:01 lespaul crond(pam_unix)[997]: session opened for user zimbra by (uid=0)
Aug 15 22:40:02 lespaul zimbramon[1004]: 1004:info: 2006-08-15 22:40:01, QUEUE: 0 0
Aug 15 22:40:02 lespaul crond(pam_unix)[997]: session closed for user zimbra
Aug 15 22:40:28 lespaul saslauthd[23726]: auth_zimbra: kelly@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'
Aug 15 22:40:28 lespaul saslauthd[23726]: do_auth : auth failure: [user=kelly@imocha.com.my] [service=smtp] [realm=imocha.com.my] [mech=zimbra] [reason=Unknown]
Aug 15 22:41:10 lespaul saslauthd[23727]: auth_zimbra: kelly@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'
Aug 15 22:41:10 lespaul saslauthd[23727]: do_auth : auth failure: [user=kelly@imocha.com.my] [service=smtp] [realm=imocha.com.my] [mech=zimbra] [reason=Unknown]
Aug 15 22:41:22 lespaul zimbramon[1003]: 1003:info: 2006-08-15 22:40:01, DISK: lespaul.imocha.com.my: dev: /dev/sda3, mp: /, tot: 108536, avail: 97215
Aug 15 22:41:22 lespaul zimbramon[1003]: 1003:info: 2006-08-15 22:40:01, DISK: lespaul.imocha.com.my: dev: /dev/sdb1, mp: /home, tot: 112666, avail: 106844
Aug 15 22:41:41 lespaul crond(pam_unix)[663]: session closed for user zimbra
Aug 15 22:41:49 lespaul zimbramon[959]: 959:info: 2006-08-15 22:38:01, STATUS: : Cannot: determine
Aug 15 22:42:01 lespaul crond(pam_unix)[1153]: session opened for user zimbra by (uid=0)
Aug 15 22:43:03 lespaul crond(pam_unix)[755]: session closed for user zimbra
Aug 15 22:44:01 lespaul crond(pam_unix)[1245]: session opened for user zimbra by (uid=0)
Aug 15 22:44:02 lespaul zimbramon[1002]: 1002:info: 2006-08-15 22:40:01, STATUS: : Cannot: determine
Aug 15 22:45:25 lespaul amavis[21296]: Net::Server: 2006/08/15-22:45:25 Server closing!
Aug 15 22:45:38 lespaul crond(pam_unix)[858]: session closed for user zimbra
Aug 15 22:45:43 lespaul saslauthd[23724]: server_exit : master exited: 23724
Aug 15 22:45:49 lespaul clamd[22035]: Pid file removed.
Aug 15 22:45:49 lespaul clamd[22035]: Exiting (clean)
Aug 15 22:45:49 lespaul clamd[22035]: --- Stopped at Tue Aug 15 22:45:49 2006
Aug 15 22:46:03 lespaul zimbramon[1154]: 1154:info: 2006-08-15 22:42:01, STATUS: : Cannot: determine
Aug 15 22:46:04 lespaul crond(pam_unix)[1528]: session opened for user zimbra by (uid=0)
Aug 15 22:47:07 lespaul crond(pam_unix)[958]: session closed for user zimbra
Aug 15 22:47:55 lespaul sshd(pam_unix)[1693]: session opened for user kokwei by (uid=0)
Aug 15 22:48:01 lespaul zimbramon[1246]: 1246:info: 2006-08-15 22:44:01, STATUS: : Cannot: determine
Aug 15 22:48:01 lespaul crond(pam_unix)[1722]: session opened for user zimbra by (uid=0)
Aug 15 22:48:21 lespaul su(pam_unix)[1720]: session opened for user root by kokwei(uid=502)
Aug 15 22:48:34 lespaul su(pam_unix)[1779]: session opened for user zimbra by kokwei(uid=0)

-----------------

[phoenix]

I would make a guess that this is (was) the problem.

Aug 15 22:22:34 lespaul saslauthd[23727]: auth_zimbra: royngan@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'

For some reason your server became 'invisible' ie it's address couldn't be resolved. Do you run a DNS server or lose your connection to a DNS server at that time? Did other things start to fail? How did you recover, a reboot?

[andreychek]

Howdy,

Well, this particular error may be what's going on:

"auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'"

(you have some guitar fans on staff, eh? ;-)

The problem it's complaining about is that it can't tell what the IP for lespaul.imocha.com.my is. Maybe something changed?

For that to work, lespaul.imocha.com.my needs to both have an entry in /etc/hosts, as well as an 'a' record available in DNS.

If you were to type:

host lespaul.imocha.com.my

Does it give you an IP for that address, and is that IP what you'd expect?
-Eric

[lilwong]

Originally Posted by andreychek

Howdy,

Well, this particular error may be what's going on:

"auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'"

(you have some guitar fans on staff, eh? ;-)

The problem it's complaining about is that it can't tell what the IP for lespaul.imocha.com.my is. Maybe something changed?

For that to work, lespaul.imocha.com.my needs to both have an entry in /etc/hosts, as well as an 'a' record available in DNS.

If you were to type:

host lespaul.imocha.com.my

Does it give you an IP for that address, and is that IP what you'd expect?

The guitar fan would be me *grin* I've got an ibanez and a prsdragon somewhere else in the internal network jamming hard at their own gigs as well.

Turns out that 'tis right, I didn't have lespaul's IP address in /etc/hosts. Our DNS is hosted externally as well and there might have been a connection error during that time.

Thanks a lot!

[lilwong]

Originally Posted by phoenix

I would make a guess that this is (was) the problem.

Aug 15 22:22:34 lespaul saslauthd[23727]: auth_zimbra: royngan@imocha.com.my auth failed: curl_easy_perform: error(6): Couldn't resolve host 'lespaul.imocha.com.my'

For some reason your server became 'invisible' ie it's address couldn't be resolved. Do you run a DNS server or lose your connection to a DNS server at that time? Did other things start to fail? How did you recover, a reboot?

thanks, turned out the error was at /etc/hosts and a DNS service connection failure. I tried a "zmcontrol stop" to stop the server but nothing happened. Had to kill -9 the processes before proceeding to start the services up again.