[PLUG] Mail log reporting: cron issue?

[Joe Pruett]

>   Yesterday (Saturday), both the 00:02 (root's crontab) and 04:40
> (cron.daily) reports were run with the results mailed to me and seen in my
> inbox.
>
>   Today, the 00:02 invocation ran but did not appear in my inbox; the 04:40
> invocation did not run.
>
>   According to the trace in /etc/cron.daily/1pflogsumm:
>
>>>> MAIL From:<root at salmo.appl-ecosys.com> SIZE=43164
> 250 2.1.0 Ok
>>>> RCPT To:<rshepard at appl-ecosys.com>
>>>> DATA
> 250 2.1.5 Ok
> 354 End data with <CR><LF>.<CR><LF>
>>>> .
> 250 2.0.0 Ok: queued as B03CEFEA
> rshepard at appl-ecosys.com... Sent (Ok: queued as B03CEFEA)
>
>   This shows up in /var/log/maillog.1 as:
>
> May 24 00:02:02 salmo postfix/smtpd[20889]: B03CEFEA:
> client=localhost.localdomain[127.0.0.1]
> May 24 00:02:02 salmo postfix/cleanup[20926]: B03CEFEA:
> message-id=<200905240702.n4O722Mk020922 at salmo.appl-ecosys.com>
> May 24 00:02:03 salmo postfix/qmgr[11521]: B03CEFEA:
> from=<root at salmo.appl-ecosys.com>, size=44636, nrcpt=1 (queue active)
> May 24 00:02:03 salmo sendmail[20922]: n4O722Mk020922:
> to=rshepard at appl-ecosys.com, ctladdr=root (0/0), delay=00:00:01,
> xdelay=00:00:01, mailer=relay, pri=73164, relay=[127.0.0.1] [127.0.0.1],
> dsn=2.0.0, stat=Sent (Ok: queued as B03CEFEA)
> May 24 00:02:10 salmo postfix/local[20927]: B03CEFEA:
> to=<rshepard at appl-ecosys.com>, relay=local, delay=7.8, delays=0.47/0/0/7.4,
> dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail)
> May 24 00:02:10 salmo postfix/qmgr[11521]: B03CEFEA: removed
>
>   Then I find a reference to that timestamp in ~/procmail/log:
>
> procmail: [20934] Sun May 24 00:02:17 2009
> procmail: Assigning "LOGABSTRACT=all"
> procmail: Assigning "MAILDIR=/home/rshepard/mail"
> procmail: Assigning "PMDIR=/home/rshepard/procmail"
> procmail: Assigning "DEFAULT=/var/spool/mail/rshepard"
> procmail: Assigning "ORGMAIL=/var/spool/mail/rshepard"
> procmail: Assigning "COMSAT=no"
> procmail: Assigning "COMSAT=no"
> procmail: Assigning "INCLUDERC=/home/rshepard/procmail/recipes.rc"
>   ...
> procmail: Locking "/var/spool/mail/rshepard.lock"
> procmail: Assigning "LASTFOLDER=/var/spool/mail/rshepard"
> procmail: Opening "/var/spool/mail/rshepard"
> procmail: Acquiring kernel-lock
> procmail: Unlocking "/var/spool/mail/rshepard.lock"
>> From root at salmo.appl-ecosys.com  Sun May 24 00:02:07 2009
>  Subject: cron: /etc/cron.daily/1pflogsumm
>   Folder: /var/spool/mail/rshepard
>
> But, this appears to be the results of 'strace' on the script, not the
> summary of the mail log.
>
>   I've absolutely no idea where the source of this problem might be.
> Regardless, I sure would like to have it resolved ... soon.

so are you saying that even though you have procmail logs showing it was 
delivered, you don't see the email?  if you look at your procmail log, do 
you see delivery that isn't preceded by the locking of rshepard.lock?

also, what email client do you run?  any chance it is filtering things for 
you?  maybe a quick rule at the top of procmailrc to keep a copy of all 
incoming email while you're debugging?

:0c:
$HOME/incoming.archive

and then only look at the with simple unix commands (cat, grep, less, 
etc) just to make sure you aren't having some weird mail client issue.