time of day discrepancy in new install

7 posts / 0 new
Last post
#1 Thu, 03/08/2012 - 17:20
midol

time of day discrepancy in new install

I have a Ubuntu 10.04 install with VM Pro. The host is in the Eastern time zone (NA) and I am in Pacific. I've set up virtualmin to sync hardware and system time to pool.ntp. Date on the system shows the correct date and time for my time zone, I've run dpkg configure tzdata and things look ok. But the tail of the maillog shows times in Eastern time zone. I wouldn't be concerned about this except that Dovecot (or postfix, it isn't clear to me) isn't delivering to my inbox. I can successfully auto fwd to my usual pop account but nothing seems to land in my inbox on either port 20000 or roundcube.

Ideas?

Dave

Thu, 03/08/2012 - 23:21
andreychek

Howdy,

Hmm, I think normally syslog is able to pick up on the time changes.

But, just to make super-sure about that -- you may want to try restarting your Syslog daemon.

Also, if you run the command "date" -- is that showing the same date, time, and timezone as the other tools you've looked at?

I'm not sure all that is related to your email delivery issue, but I suppose that's possible; but either way, it's simpler to solve one problem at a time :-)

-Eric

Mon, 03/12/2012 - 15:01 (Reply to #2)
midol

A recent update to tzdata and a reboot have made that problem go away, but I still have a mail delivery issue.

Going into Virtualmin's system status pane shows postfix running. If I click though to postfix config and then to user mailboxes, the summary data beside the mailbox name show how many mails there are. That number seems to be correct(i.e., it's what I expect) but when I click on the mailbox I get to a screen showing zero mails in the inbox. So does the postfix screen show all mails in all mailboxes? Or is the inbox incorrrectly configured somehow? I don't know where to look. I get the same empty inbox showing up in webmin and roundcube.

Dave

Mon, 03/12/2012 - 15:12
andreychek

Howdy,

Well, here's where I'd start --

First, send yourself a new email... and then take a peek in the mail logs, in either /var/log/maillog, or /var/log/mail.log. Does that email appear to delivery correctly, or do you see any errors?

And then, if you look in /var/log/procmail.log, you should see details of where specifically that email is delivered to (the exact path on the filesystem). Or, if there's any errors, you should see them listed there.

Finally, go ahead and log into RoundCube, and try to view those emails again. And once you do that, take one more peek in the mail logs, and see if any errors show up there.

Feel free to paste any errors you see in here, we can try and help sort it out :-)

-Eric

Mon, 03/12/2012 - 19:22
midol

I logged into my usual IMAP account, not on this server, and sent myself a test mail timestamped 16:54.

The mail does not get delivered to the mailbox. It does, however get sent to my usual pop account. I suppose this is because I am the admin for the domain and I've given my address as primary admin contact.

I can not decipher the /var/log/mail.log output, some of which I append here:

Mar 12 16:53:25 quiethost dovecot: imap-login: Login: user=<dave.quiethost>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, secured
Mar 12 16:53:25 quiethost dovecot: last message repeated 8 times
Mar 12 16:53:25 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=73/705
Mar 12 16:53:25 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=74/705
Mar 12 16:53:25 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=73/705
Mar 12 16:53:25 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=75/705
Mar 12 16:53:25 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=188/1611
Mar 12 16:53:26 quiethost dovecot: imap-login: Login: user=<dave.quiethost>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, secured
Mar 12 16:53:26 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=72/703
Mar 12 16:53:26 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=74/705
Mar 12 16:53:26 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=75/705
Mar 12 16:53:26 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=116/1269
Mar 12 16:53:26 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=73/705
Mar 12 16:54:11 quiethost postfix/smtpd[10415]: connect from smtp-relay2e.uniserve.ca[216.113.195.59]
Mar 12 16:54:11 quiethost postfix/trivial-rewrite[10418]: warning: do not list domain quiethost.ca in BOTH mydestination and virtual_alias_domains
Mar 12 16:54:11 quiethost postfix/smtpd[10415]: BCC7E280A3E: client=smtp-relay2e.uniserve.ca[216.113.195.59]
Mar 12 16:54:11 quiethost postfix/cleanup[10419]: BCC7E280A3E: message-id=<20120312165429.55183ulgskxfwss5@webmail.uniserve.com>
Mar 12 16:54:11 quiethost postfix/qmgr[31266]: BCC7E280A3E: from=<geek@uniserve.com>, size=2046, nrcpt=1 (queue active)
Mar 12 16:54:12 quiethost postfix/smtpd[10415]: disconnect from smtp-relay2e.uniserve.ca[216.113.195.59]
Mar 12 16:54:14 quiethost postfix/smtp[10420]: BCC7E280A3E: to=<geek@uniserve.com>, orig_to=<dave@quiethost.ca>, relay=mx0.uniserve.ca[204.239.42.2]:25, delay=2.6, delays=0.21/0/0.42/2, dsn=2.0.0, status=sent (250 OK id=1S7F4R-0005wq-DI)
Mar 12 16:54:14 quiethost postfix/qmgr[31266]: BCC7E280A3E: removed
Mar 12 16:54:19 quiethost dovecot: imap-login: Login: user=<dave.quiethost>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, secured
Mar 12 16:54:19 quiethost dovecot: last message repeated 5 times
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=73/705
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=75/705
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=140/1625
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=74/705
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=73/704
Mar 12 16:54:19 quiethost dovecot: imap-login: Login: user=<dave.quiethost>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, secured
Mar 12 16:54:19 quiethost dovecot: last message repeated 3 times
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=75/705
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=74/705
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=116/1269
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=73/705
Mar 12 16:54:19 quiethost dovecot: IMAP(dave.quiethost): Connection closed bytes=72/703
Mar 12 16:57:32 quiethost postfix/anvil[10417]: statistics: max connection rate 1/60s for (smtp:216.113.195.59) at Mar 12 16:54:11
Mar 12 16:57:32 quiethost postfix/anvil[10417]: statistics: max connection count 1 for (smtp:216.113.195.59) at Mar 12 16:54:11
Mar 12 16:57:32 quiethost postfix/anvil[10417]: statistics: max cache size 1 at Mar 12 16:54:11

and

/var/log$ ls -l proc*
-rw-r--r-- 1 root root     0 2012-03-12 06:38 procmail.log

If the file is automatically created then 6:38 am would be plausible but no entries.

Dave

Mon, 03/12/2012 - 21:36
andreychek

Well, it looks like that email is being forwarded to a remote location, at uniserve.com.

Is that forwarding something you're trying to do?

With forwarding enabled, you'd also need to make sure that it's setup to deliver locally as well.

However, what output do you receive when running this command:

postconf -n

Tue, 03/13/2012 - 19:17
midol
$ postconf -n
alias_database = hash:/etc/aliases
alias_maps = hash:/etc/aliases
allow_percent_hack = no
append_dot_mydomain = no
biff = no
broken_sasl_auth_clients = yes
config_directory = /etc/postfix
home_mailbox = Maildir/
mailbox_command = /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME
mailbox_size_limit = 0
mydestination = telkwamail.ca, quiethost.ca, localhost.ca, bcenclave.ca, localhost, $myhostname
myhostname = quiethost.ca
mynetworks = 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128
myorigin = /etc/mailname
readme_directory = no
recipient_delimiter = +
sender_bcc_maps = hash:/etc/postfix/bcc
smtp_tls_session_cache_database = btree:${data_directory}/smtp_scache
smtpd_banner = $myhostname ESMTP $mail_name (Ubuntu)
smtpd_recipient_restrictions = permit_mynetworks permit_sasl_authenticated reject_unauth_destination
smtpd_sasl_auth_enable = yes
smtpd_sasl_security_options = noanonymous
smtpd_tls_cert_file = /etc/ssl/certs/ssl-cert-snakeoil.pem
smtpd_tls_key_file = /etc/ssl/private/ssl-cert-snakeoil.key
smtpd_tls_session_cache_database = btree:${data_directory}/smtpd_scache
smtpd_use_tls = yes
virtual_alias_maps = hash:/etc/postfix/virtual

so I've just gone into VMPro and looked at the users settings and forwarding is not set and deliver to mailbox IS set. Using system status | postfix | user mailboxes and clicking on dave.quiethost (with the parenthetical 5 mails) shows no mail in the maildir, sent, drafts, trash.

Dave

Topic locked