[vchkpw] vpopmail 5.3.27 - repost

[X-Istence]

Hey all,

I know this is a repost, but this time ill add all the info i can collect.

Since the switch to vpopmail 5.3.27 we have been getting these in our logs:

@400000003f68dc7d0e0da60c info msg 217: bytes 845 from <[EMAIL PROTECTED]> qp 67340 uid 0
@400000003f68dc7d0e83ef4c starting delivery 1: msg 217 to local ************
@400000003f68dc7d0e8e5374 status: local 1/10 remote 0/20
@400000003f68dc7d0f68540c delivery 1: deferral: /usr/local/vpopmail/bin/vdelivermail:_Permission_denied/
@400000003f68dc7d0f70b87c status: local 0/10 remote 0/20

So i followed instructions as per the vpopmail mailing list, and chown -r'ed all dirs in /usr/local/vpopmail/ to vpopmail:vchkpw:

allhope# ls -al
total 16
drwxr-xr-x   8 root      wheel   512 Sep 17 22:37 .
drwxr-xr-x  16 root      wheel   512 Sep 17 22:37 ..
drwxr-x---   2 vpopmail  vchkpw  512 Sep 17 22:37 bin
drwxr-xr-x   4 vpopmail  vchkpw  512 Sep 17 22:37 doc
drwx------   3 vpopmail  vchkpw  512 Sep 17 23:09 domains
drwxr-xr-x   2 vpopmail  vchkpw  512 Sep 17 22:37 etc
drwxr-xr-x   2 vpopmail  vchkpw  512 Sep 17 22:37 include
drwxr-x---   2 vpopmail  vchkpw  512 Sep 17 22:37 lib
allhope# ls -al ./domains/*
total 18
drwx------  4 vpopmail  vchkpw   512 Sep 18 11:04 .
drwx------  3 vpopmail  vchkpw   512 Sep 17 23:09 ..
-rw-------  1 vpopmail  vchkpw    34 Sep 17 23:12 .dir-control
-rw-------  1 vpopmail  vchkpw    91 Sep 17 23:12 .qmail-default
-rw-------  1 vpopmail  vchkpw     0 Sep 18 11:04 .vpasswd.lock
drwx------  3 vpopmail  vchkpw   512 Sep 17 23:09 postmaster
-rw-------  1 vpopmail  vchkpw   239 Sep 18 11:04 vpasswd
-rw-------  1 vpopmail  vchkpw  2331 Sep 18 11:04 vpasswd.cdb
drwx------  3 vpopmail  vchkpw   512 Sep 17 23:12 xistence
allhope# ls -al ./bin/*
-rwx--x--x  1 vpopmail  vchkpw  48856 Sep 17 22:37 ./bin/clearopensmtp
-rwx--x--x  1 vpopmail  vchkpw  49368 Sep 17 22:37 ./bin/vaddaliasdomain
-rwx--x--x  1 vpopmail  vchkpw  52088 Sep 17 22:37 ./bin/vadddomain
-rwx--x--x  1 vpopmail  vchkpw  52056 Sep 17 22:37 ./bin/vadduser
-rwx--x--x  1 vpopmail  vchkpw  52808 Sep 17 22:37 ./bin/valias
-rwx--x--x  1 vpopmail  vchkpw  60408 Sep 17 22:37 ./bin/vchkpw
-rwx--x--x  1 vpopmail  vchkpw  52120 Sep 17 22:37 ./bin/vconvert
-rwx--x--x  1 vpopmail  vchkpw  49144 Sep 17 22:37 ./bin/vdeldomain
-rwx--x--x  1 vpopmail  vchkpw  68676 Sep 17 22:37 ./bin/vdelivermail
-rwx--x--x  1 vpopmail  vchkpw  52056 Sep 17 22:37 ./bin/vdeloldusers
-rwx--x--x  1 vpopmail  vchkpw  49272 Sep 17 22:37 ./bin/vdeluser
-rwx--x--x  1 vpopmail  vchkpw  52088 Sep 17 22:37 ./bin/vdominfo
-rwx--x--x  1 vpopmail  vchkpw  49656 Sep 17 22:37 ./bin/vipmap
-rwx--x--x  1 vpopmail  vchkpw  49048 Sep 17 22:37 ./bin/vkill
-rwx--x--x  1 vpopmail  vchkpw  48664 Sep 17 22:37 ./bin/vmkpasswd
-rwx--x--x  1 vpopmail  vchkpw  60312 Sep 17 22:37 ./bin/vmoddomlimits
-rwx--x--x  1 vpopmail  vchkpw  52216 Sep 17 22:37 ./bin/vmoduser
-rwx--x--x  1 vpopmail  vchkpw  49400 Sep 17 22:37 ./bin/vpasswd
-rwx--x--x  1 vpopmail  vchkpw  56184 Sep 17 22:37 ./bin/vpopbull
-rwx--x--x  1 vpopmail  vchkpw  56184 Sep 17 22:37 ./bin/vqmaillocal
-rwx--x--x  1 vpopmail  vchkpw  50104 Sep 17 22:37 ./bin/vsetuserquota
-rwx--x--x  1 vpopmail  vchkpw  62168 Sep 17 22:37 ./bin/vuserinfo

So then we started getting these messages in our logs:

@400000003f69830f2c2df1b4 info msg 47: bytes 1153 from <*************> qp 70866 uid 0
@400000003f69830f2ca1d1c4 starting delivery 60: msg 47 to local *******************
@400000003f69830f2cae02dc status: local 1/10 remote 0/20
@400000003f69830f34a902ac delivery 60: success: did_0+0+1/
@400000003f69830f34c072ac status: local 0/10 remote 0/20
@400000003f69830f34c0a18c end msg 47

This was basically allready better than having it fail to call vdelivermail, but this is still wrong, as an entry should look like this:

@400000003f69850e2b908cbc info msg 94300: bytes 5286 from <******************> qp 41793 uid 0
@400000003f69850e2bb94684 starting delivery 4877: msg 94300 to local ******************************
@400000003f69850e2bbf0efc status: local 1/10 remote 0/20
@400000003f69850e2c7fcebc delivery 4877: success: address:_/usr/local/vpopmail/domains/********/*********/Maildir//quota:_104857600/did_0+0+1/
@400000003f69850e2c91c07c status: local 0/10 remote 0/20
@400000003f69850e2c91d7ec end msg 94300

But like i said it was an improvement.

So then i started comparing 2 boxes. One was running:

Breached:
FreeBSD 5.1-RELEASE
vpopmail 5.3.20
qMail-1.03
qmailadmin 1.0.26

and the other one where it is currently failing:

Allhope:
FreeBSD 5.1-RELEASE
vpopmail 5.3.27
qMail-1.03
qmailadmin 1.0.36

Both compiled from the ports tree that comes with FreeBSD.

So lets compare /usr/local/vpopmail/:

allhope# ls -al
total 16
drwxr-xr-x   8 root      wheel   512 Sep 17 22:37 .
drwxr-xr-x  16 root      wheel   512 Sep 17 22:37 ..
drwxr-x---   2 vpopmail  vchkpw  512 Sep 17 22:37 bin
drwxr-xr-x   4 vpopmail  vchkpw  512 Sep 17 22:37 doc
drwx------   3 vpopmail  vchkpw  512 Sep 17 23:09 domains
drwxr-xr-x   2 vpopmail  vchkpw  512 Sep 17 22:37 etc
drwxr-xr-x   2 vpopmail  vchkpw  512 Sep 17 22:37 include
drwxr-x---   2 vpopmail  vchkpw  512 Sep 17 22:37 lib
allhope#

[Breached: /usr/local/vpopmail]$ ls -al
total 16
drwxr-xr-x   8 root      wheel   512 May 25 15:06 .
drwxr-xr-x  19 root      wheel   512 Sep  9 15:28 ..
drwxr-x---   2 vpopmail  vchkpw  512 May 25 15:06 bin
drwxr-xr-x   4 vpopmail  vchkpw  512 May 25 15:06 doc
drwx------   5 vpopmail  vchkpw  512 Sep 10 23:19 domains
drwxr-xr-x   2 vpopmail  vchkpw  512 Sep 19 04:13 etc
drwxr-xr-x   2 vpopmail  vchkpw  512 May 25 15:06 include
drwx------   2 root      wheel   512 May 25 15:06 lib
[Breached: /usr/local/vpopmail]$

Basically looks the same, except the change i did to chown lib. Note, on allhope i mysel chown'ed the "bin" dir to vpopmail:vchkpw as it was before that as root:wheel.

Lets compare /usr/local/vpopmail/bin:

allhope# ls -al bin
total 1172
drwxr-x---  2 vpopmail  vchkpw    512 Sep 17 22:37 .
drwxr-xr-x  8 root      wheel     512 Sep 17 22:37 ..
-rwx--x--x  1 vpopmail  vchkpw  48856 Sep 17 22:37 clearopensmtp
-rwx--x--x  1 vpopmail  vchkpw  49368 Sep 17 22:37 vaddaliasdomain
-rwx--x--x  1 vpopmail  vchkpw  52088 Sep 17 22:37 vadddomain
-rwx--x--x  1 vpopmail  vchkpw  52056 Sep 17 22:37 vadduser
-rwx--x--x  1 vpopmail  vchkpw  52808 Sep 17 22:37 valias
-rwx--x--x  1 vpopmail  vchkpw  60408 Sep 17 22:37 vchkpw
-rwx--x--x  1 vpopmail  vchkpw  52120 Sep 17 22:37 vconvert
-rwx--x--x  1 vpopmail  vchkpw  49144 Sep 17 22:37 vdeldomain
-rwx--x--x  1 vpopmail  vchkpw  68676 Sep 17 22:37 vdelivermail
-rwx--x--x  1 vpopmail  vchkpw  52056 Sep 17 22:37 vdeloldusers
-rwx--x--x  1 vpopmail  vchkpw  49272 Sep 17 22:37 vdeluser
-rwx--x--x  1 vpopmail  vchkpw  52088 Sep 17 22:37 vdominfo
-rwx--x--x  1 vpopmail  vchkpw  49656 Sep 17 22:37 vipmap
-rwx--x--x  1 vpopmail  vchkpw  49048 Sep 17 22:37 vkill
-rwx--x--x  1 vpopmail  vchkpw  48664 Sep 17 22:37 vmkpasswd
-rwx--x--x  1 vpopmail  vchkpw  60312 Sep 17 22:37 vmoddomlimits
-rwx--x--x  1 vpopmail  vchkpw  52216 Sep 17 22:37 vmoduser
-rwx--x--x  1 vpopmail  vchkpw  49400 Sep 17 22:37 vpasswd
-rwx--x--x  1 vpopmail  vchkpw  56184 Sep 17 22:37 vpopbull
-rwx--x--x  1 vpopmail  vchkpw  56184 Sep 17 22:37 vqmaillocal
-rwx--x--x  1 vpopmail  vchkpw  50104 Sep 17 22:37 vsetuserquota
-rwx--x--x  1 vpopmail  vchkpw  62168 Sep 17 22:37 vuserinfo

Breached# ls -al bin
total 978
drwxr-x---  2 vpopmail  vchkpw    512 May 25 15:06 .
drwxr-xr-x  8 root      wheel     512 May 25 15:06 ..
-rwx--x--x  1 vpopmail  vchkpw  41592 May 25 15:06 clearopensmtp
-rwx--x--x  1 vpopmail  vchkpw  43544 May 25 15:06 vaddaliasdomain
-rwx--x--x  1 vpopmail  vchkpw  44632 May 25 15:06 vadddomain
-rwx--x--x  1 vpopmail  vchkpw  43576 May 25 15:06 vadduser
-rwx--x--x  1 vpopmail  vchkpw  47720 May 25 15:06 valias
-rwx--x--x  1 vpopmail  vchkpw  51896 May 25 15:06 vchkpw
-rwx--x--x  1 vpopmail  vchkpw  47640 May 25 15:06 vconvert
-rwx--x--x  1 vpopmail  vchkpw  41848 May 25 15:06 vdeldomain
-rwx--x--x  1 vpopmail  vchkpw  64276 May 25 15:06 vdelivermail
-rwx--x--x  1 vpopmail  vchkpw  43640 May 25 15:06 vdeloldusers
-rwx--x--x  1 vpopmail  vchkpw  43544 May 25 15:06 vdeluser
-rwx--x--x  1 vpopmail  vchkpw  43544 May 25 15:06 vdominfo
-rwx--x--x  1 vpopmail  vchkpw  42360 May 25 15:06 vipmap
-rwx--x--x  1 vpopmail  vchkpw  41752 May 25 15:06 vkill
-rwx--x--x  1 vpopmail  vchkpw  41528 May 25 15:06 vmkpasswd
-rwx--x--x  1 vpopmail  vchkpw  45400 May 25 15:06 vmoduser
-rwx--x--x  1 vpopmail  vchkpw  43544 May 25 15:06 vpasswd
-rwx--x--x  1 vpopmail  vchkpw  45336 May 25 15:06 vpopbull
-rwx--x--x  1 vpopmail  vchkpw  48088 May 25 15:06 vqmaillocal
-rwx--x--x  1 vpopmail  vchkpw  43544 May 25 15:06 vsetuserquota
-rwx--x--x  1 vpopmail  vchkpw  60100 May 25 15:06 vuserinfo

Basically looks the same, same chmod and everything. except for the dates, which off course would be correct since it was compiled, and installed at different dates.

Lets compare /usr/local/vpopmail/domains/:

allhope# ls -al domains
total 8
drwx------  3 vpopmail  vchkpw  512 Sep 17 23:09 .
drwxr-xr-x  8 root      wheel   512 Sep 17 22:37 ..
-rw-------  1 vpopmail  vchkpw   34 Sep 17 23:09 .dir-control
drwx------  4 vpopmail  vchkpw  512 Sep 18 11:04 *********
allhope# ls -al domains/*
total 18
drwx------  4 vpopmail  vchkpw   512 Sep 18 11:04 .
drwx------  3 vpopmail  vchkpw   512 Sep 17 23:09 ..
-rw-------  1 vpopmail  vchkpw    34 Sep 17 23:12 .dir-control
-rw-------  1 vpopmail  vchkpw    91 Sep 17 23:12 .qmail-default
-rw-------  1 vpopmail  vchkpw     0 Sep 18 11:04 .vpasswd.lock
drwx------  3 vpopmail  vchkpw   512 Sep 17 23:09 postmaster
-rw-------  1 vpopmail  vchkpw   239 Sep 18 11:04 vpasswd
-rw-------  1 vpopmail  vchkpw  2331 Sep 18 11:04 vpasswd.cdb
drwx------  3 vpopmail  vchkpw   512 Sep 17 23:12 xistence
allhope#

Breached# ls -al domains
total 12
drwx------  5 vpopmail  vchkpw  512 Sep 10 23:19 .
drwxr-xr-x  8 root      wheel   512 May 25 15:06 ..
-rw-------  1 vpopmail  vchkpw   34 Sep 10 23:19 .dir-control
drwx------  7 vpopmail  vchkpw  512 Sep 18 22:58 **************
drwx------  5 vpopmail  vchkpw  512 Sep 13 16:57 **************
drwx------  8 vpopmail  vchkpw  512 Sep 13 15:05 **************
Breached# ls -al domains/*
domains/*************:
total 26
drwx------  7 vpopmail  vchkpw   512 Sep 18 22:58 .
drwx------  5 vpopmail  vchkpw   512 Sep 10 23:19 ..
-rw-------  1 vpopmail  vchkpw    34 Sep 18 22:55 .dir-control
-rw-------  1 vpopmail  vchkpw   102 Sep 18 22:55 .qmail-default
-rw-------  1 vpopmail  vchkpw    15 Sep  1 13:37 .qmail-enyo
-rw-------  1 vpopmail  vchkpw     0 Sep 18 22:58 .vpasswd.lock
drwx------  3 vpopmail  vchkpw   512 Sep  9 15:36 jregeer
drwx------  3 vpopmail  vchkpw   512 Sep 18 22:55 jwregeer
drwx------  3 vpopmail  vchkpw   512 Sep 17 06:19 postmaster
drwx------  3 vpopmail  vchkpw   512 May 27 20:23 v8rumble
-rw-------  1 vpopmail  vchkpw   636 Sep 18 22:58 vpasswd
-rw-------  1 vpopmail  vchkpw  2794 Sep 18 22:58 vpasswd.cdb
drwx------  3 vpopmail  vchkpw   512 Sep 18 22:58 xistence

domains/****************:
total 36
drwx------  5 vpopmail  vchkpw   512 Sep 13 16:57 .
drwx------  5 vpopmail  vchkpw   512 Sep 10 23:19 ..
-rw-------  1 vpopmail  vchkpw    34 Sep  9 17:17 .dir-control
-rw-------  1 vpopmail  vchkpw    24 Sep 10 00:45 .qmail-cgeeks
-rw-------  1 vpopmail  vchkpw    60 Sep  9 17:16 .qmail-default
-rw-------  1 vpopmail  vchkpw    24 Sep 12 12:59 .qmail-den
-rw-------  1 vpopmail  vchkpw    24 Sep 10 21:25 .qmail-fuckit
-rw-------  1 vpopmail  vchkpw    24 Sep 13 16:57 .qmail-gandi
-rw-------  1 vpopmail  vchkpw    24 Sep  9 17:17 .qmail-me
-rw-------  1 vpopmail  vchkpw    24 Sep 10 18:58 .qmail-progressive
-rw-------  1 vpopmail  vchkpw    24 Sep  9 17:25 .qmail-sprintpcs
-rw-------  1 vpopmail  vchkpw    24 Sep 12 12:06 .qmail-steam
-rw-------  1 vpopmail  vchkpw     0 Sep  9 17:25 .vpasswd.lock
drwx------  3 vpopmail  vchkpw   512 Sep  9 17:17 kyle
drwx------  3 vpopmail  vchkpw   512 Sep  9 17:16 postmaster
-rw-------  1 vpopmail  vchkpw   341 Sep  9 17:25 vpasswd
-rw-------  1 vpopmail  vchkpw  2455 Sep  9 17:25 vpasswd.cdb
drwx------  3 vpopmail  vchkpw   512 Sep  9 17:16 weasel

domains/************:
total 28
drwx------  8 vpopmail  vchkpw   512 Sep 13 15:05 .
drwx------  5 vpopmail  vchkpw   512 Sep 10 23:19 ..
-rw-------  1 vpopmail  vchkpw    34 Sep 11 06:27 .dir-control
-rw-------  1 vpopmail  vchkpw    93 Sep 10 23:20 .qmail-default
-rw-------  1 vpopmail  vchkpw    25 Sep 10 23:24 .qmail-fenix
-rw-------  1 vpopmail  vchkpw     0 Sep 13 15:05 .vpasswd.lock
drwx------  3 vpopmail  vchkpw   512 Sep 11 06:28 2bben
drwx------  3 vpopmail  vchkpw   512 Sep 10 23:21 betaworld
drwx------  3 vpopmail  vchkpw   512 Sep 10 23:21 jregeer
drwx------  3 vpopmail  vchkpw   512 Sep 10 23:19 postmaster
drwx------  3 vpopmail  vchkpw   512 Sep 10 23:23 sol
-rw-------  1 vpopmail  vchkpw   672 Sep 13 15:05 vpasswd
-rw-------  1 vpopmail  vchkpw  2852 Sep 13 15:05 vpasswd.cdb
drwx------  3 vpopmail  vchkpw   512 Sep 10 23:20 xistence
Breached#

Once again, both of them look alike, and nothing seems to be incorrect ( keyword, seems, as there always could be something wrong ).

Looking at the way's both qMail's were installed, it is exactly the same way. Both using the same tutorial i myself wrote, which can be downloaded/viewed at http://tutorials.x-istence.com/qMail_vpopmail_daemontools_authpatch.xhtml  I have done a lot of successfull installs using that tutorial, but once the port was upgraded to the new vpopmail, it all went haywire.

I am asking if there is anything i can check for you people to look at, i have mailed a copy of this to the person that maintains the port, and i hope he can help me out on this problem. Thanks a lot :-D.

Jan-Willem