Bug 394313 – net-mail/dovecot-2.0.16-r1 user auth by vpopmail is broken

• Status: RESOLVED UPSTREAM
• Product: Gentoo Linux

[Description Stefan Trenker 2011-12-11 11:12:48 UTC]

With dovecot-2.0.16/2.0.16-r1 i can no login via my email clients and retrieve mail. this is true for kmail and thunderbird.

downgrading to dovecot-2.0.15 saves the problem (keepig the config files)

I have insertet auth_debug_passwords=yes. Please see the log

Dec 11 12:00:35 loisl dovecot: auth: Debug: Loading modules from directory: /usr/lib64/dovecot/auth
Dec 11 12:00:35 loisl dovecot: auth: Debug: auth client connected (pid=6360)
Dec 11 12:00:35 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53901   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:00:35 loisl dovecot: auth-worker: Debug: Loading modules from directory: /usr/lib64/dovecot/auth
Dec 11 12:00:35 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:00:35 loisl dovecot: auth-worker: vpopmail([e-mail],192.168.21.33): Password mismatch
Dec 11 12:00:35 loisl dovecot: auth-worker: Error: md5_verify([e-mail]): Not a valid MD5-CRYPT or PLAIN-MD5 password
Dec 11 12:00:35 loisl dovecot: auth-worker: Error: smd5_verify([e-mail]): SMD5 password too short
Dec 11 12:00:35 loisl dovecot: auth-worker: Error: ssha_verify([e-mail]): SSHA password too short
Dec 11 12:00:35 loisl dovecot: auth-worker: Error: ssha256_verify([e-mail]): SSHA256 password too short
Dec 11 12:00:35 loisl dovecot: auth-worker: Error: ssha512_verify([e-mail]): SSHA512 password too short
Dec 11 12:00:35 loisl dovecot: auth-worker: Warning: Invalid OTP data in passdb
Dec 11 12:00:35 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): CLEARTEXT(001JdlMiedS) != '', try OTP scheme instead
Dec 11 12:00:37 loisl dovecot: auth: Debug: client out: FAIL    1       user=[e-mail]
Dec 11 12:00:43 loisl dovecot: auth: Debug: client in: AUTH     2       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53901
Dec 11 12:00:47 loisl dovecot: auth: Debug: client out: CONT    2
Dec 11 12:00:47 loisl dovecot: auth: Debug: client in: CONT     2       AHN0ZWZhbkBtYXVzLmhvbWVsaW51eC5uZXQAMDAxSmRsTWllZFM=
Dec 11 12:00:47 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:00:47 loisl dovecot: auth-worker: vpopmail([e-mail],192.168.21.33): Password mismatch
Dec 11 12:00:47 loisl dovecot: auth-worker: Error: md5_verify([e-mail]): Not a valid MD5-CRYPT or PLAIN-MD5 password
Dec 11 12:00:47 loisl dovecot: auth-worker: Error: smd5_verify([e-mail]): SMD5 password too short
Dec 11 12:00:47 loisl dovecot: auth-worker: Error: ssha_verify([e-mail]): SSHA password too short
Dec 11 12:00:47 loisl dovecot: auth-worker: Error: ssha256_verify([e-mail]): SSHA256 password too short
Dec 11 12:00:47 loisl dovecot: auth-worker: Error: ssha512_verify([e-mail]): SSHA512 password too short
Dec 11 12:00:47 loisl dovecot: auth-worker: Warning: Invalid OTP data in passdb
Dec 11 12:00:47 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): CLEARTEXT(nixda) != '', try OTP scheme instead
Dec 11 12:00:49 loisl dovecot: auth: Debug: client out: FAIL    2       user=[e-mail]
Dec 11 12:00:50 loisl dovecot: imap-login: Aborted login (auth failed, 2 attempts): user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, TLS
Dec 11 12:03:01 loisl cron[6381]: (root) CMD (/root/bin/sync 2>&1 | tee /root/sync-log)
Dec 11 12:04:21 loisl dovecot: auth: Debug: Loading modules from directory: /usr/lib64/dovecot/auth
Dec 11 12:04:21 loisl dovecot: auth: Debug: auth client connected (pid=6419)
Dec 11 12:04:22 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=80.187.97.82        lport=993       rport=48553   resp=AHN0ZWZhbkBtYXVzLmhvbWVsaW51eC5uZXQAMDAxSmRsTWllZFM=
Dec 11 12:04:22 loisl dovecot: auth-worker: Debug: Loading modules from directory: /usr/lib64/dovecot/auth
Dec 11 12:04:22 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],80.187.97.82): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:04:22 loisl dovecot: auth-worker: vpopmail([e-mail],80.187.97.82): Password mismatch
Dec 11 12:04:22 loisl dovecot: auth-worker: Error: md5_verify([e-mail]): Not a valid MD5-CRYPT or PLAIN-MD5 password
Dec 11 12:04:22 loisl dovecot: auth-worker: Error: smd5_verify([e-mail]): SMD5 password too short
Dec 11 12:04:22 loisl dovecot: auth-worker: Error: ssha_verify([e-mail]): SSHA password too short
Dec 11 12:04:22 loisl dovecot: auth-worker: Error: ssha256_verify([e-mail]): SSHA256 password too short
Dec 11 12:04:22 loisl dovecot: auth-worker: Error: ssha512_verify([e-mail]): SSHA512 password too short
Dec 11 12:04:22 loisl dovecot: auth-worker: Warning: Invalid OTP data in passdb
Dec 11 12:04:22 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],80.187.97.82): CLEARTEXT(nixda) != '', try OTP scheme instead
Dec 11 12:04:24 loisl dovecot: auth: Debug: client out: FAIL    1       user=[e-mail]
Dec 11 12:04:24 loisl dovecot: imap-login: Disconnected (auth failed, 1 attempts): user=<[e-mail]>, method=PLAIN, rip=80.187.97.82, lip=192.168.17.17, TLS: Disconnected

my dovecot configuration is as follows

loisl conf.d # dovecot -n
# 2.0.16: /etc/dovecot/dovecot.conf
# OS: Linux 3.1.5-gentoo x86_64 Gentoo Base System release 2.0.3 
auth_debug_passwords = yes
mail_location = maildir:~/.maildir
passdb {
  driver = vpopmail
}
ssl_cert = </etc/ssl/dovecot/dovecotRSASSLcert.pem
ssl_key = </etc/ssl/dovecot/dovecotRSASSLKey.pem
userdb {
  driver = vpopmail
}

as already mentioned ... tis works fine with 2.0.15



Reproducible: Always




loisl conf.d # emerge --info
Portage 2.2.0_alpha79 (default/linux/amd64/10.0/desktop, gcc-4.5.3, glibc-2.13-r4, 3.1.5-gentoo x86_64)
=================================================================
System uname: Linux-3.1.5-gentoo-x86_64-AMD_Athlon-tm-_64_X2_Dual_Core_Processor_3800+-with-gentoo-2.0.3
Timestamp of tree: Sun, 11 Dec 2011 11:00:01 +0000
ccache version 3.1.6 [enabled]
app-shells/bash:          4.1_p9
dev-java/java-config:     2.1.11-r3
dev-lang/python:          2.7.2-r3, 3.1.4-r3
dev-util/ccache:          3.1.6
dev-util/cmake:           2.8.4-r1
dev-util/pkgconfig:       0.26
sys-apps/baselayout:      2.0.3
sys-apps/openrc:          0.9.4
sys-apps/sandbox:         2.5
sys-devel/autoconf:       2.13, 2.68
sys-devel/automake:       1.9.6-r3, 1.10.3, 1.11.1
sys-devel/binutils:       2.21.1-r1
sys-devel/gcc:            4.5.3-r1
sys-devel/gcc-config:     1.4.1-r1
sys-devel/libtool:        2.4-r1
sys-devel/make:           3.82-r1
sys-kernel/linux-headers: 2.6.39 (virtual/os-headers)
sys-libs/glibc:           2.13-r4
Repositories: gentoo usr-local-portage
Installed sets: @system
ACCEPT_KEYWORDS="amd64"
ACCEPT_LICENSE="*"
CBUILD="x86_64-pc-linux-gnu"
CFLAGS="-mtune=k8 -O2 -pipe"
CHOST="x86_64-pc-linux-gnu"
CONFIG_PROTECT="/etc /usr/share/config /usr/share/gnupg/qualified.txt /var/bind /var/lib/hsqldb /var/qmail/alias /var/qmail/control /var/vpopmail/etc"
CONFIG_PROTECT_MASK="/etc/ca-certificates.conf /etc/dconf /etc/env.d /etc/env.d/java/ /etc/fonts/fonts.conf /etc/gconf /etc/gentoo-release /etc/php/apache2-php5.3/ext-active/ /etc/php/cgi-php5.3/ext-active/ /etc/php/cli-php5.3/ext-active/ /etc/revdep-rebuild /etc/sandbox.d /etc/splash /etc/terminfo /etc/texmf/language.dat.d /etc/texmf/language.def.d /etc/texmf/updmap.d /etc/texmf/web2c"
CXXFLAGS="-mtune=k8 -O2 -pipe"
DISTDIR="/var/data/distfiles"
EMERGE_DEFAULT_OPTS="--keep-going --autounmask=n --quiet-build=n"
FEATURES="binpkg-logs buildpkg candy ccache compress-build-logs distlocks ebuild-locks fixlafiles metadata-transfer news parallel-fetch preserve-libs protect-owned sandbox sfperms strict unknown-features-filter unknown-features-warn unmerge-logs unmerge-orphans userfetch userpriv usersandbox usersync"
FFLAGS=""
GENTOO_MIRRORS="http://distfiles.gentoo.org http://www.ibiblio.org/pub/Linux/distributions/gentoo"
LANG="C"
LC_ALL="C"
LDFLAGS="-Wl,-O1 -Wl,--as-needed"
LINGUAS="de us"
MAKEOPTS="-j3"
PKGDIR="/var/data/packages_amd64"
PORTAGE_CONFIGROOT="/"
PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --compress --force --whole-file --delete --stats --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages"
PORTAGE_TMPDIR="/var/tmp"
PORTDIR="/usr/portage"
PORTDIR_OVERLAY="/usr/local/portage"
SYNC="rsync://rsync.gentoo.org/gentoo-portage"
USE="3dnow 3dnowext X a52 aac accessibility acl acpi alsa amd64 apache2 avahi bash-completion berkdb bidi bluetooth bonjour branding bzip2 cairo caps cdda cddb cdio cdr cgi cli consolekit cracklib crypt ctype cups curl cxx dbus dirac dri dts dv dvb dvd dvdr ebook emboss encode exif faac fam fbcondecor ffmpeg fftw firefox flac fortran ftp gallium gd gdbm gdu gif gimp glib glitz gmp gnome gnutls gphoto2 gpm gsm gstreamer gtk hdri htmlhandbook httpd iconv id3tag idn imagemagick imap ipod ipv6 java jpeg jpeg2k json kde lame lastfm lcms ldap libnotify libv4l2 live logrotate lzo mad matroska mhash mjpeg mmap mmx mmxext mng modplug modules mp3 mp4 mpeg mplayer mplayer-bin mudflap multilib mysql mythtv ncurses netpbm network nls nouveau nptl nptlonly nsplugin ogg openexr opengl openmp oscar oss pam pango pcre pdf perl phonon php plasma png policykit posix ppds pppd pulseaudio python qt3support qt4 quicktime rdesktop readline resolvconf rtsp ruby samba sasl scanner schroedinger sdl semantic-desktop sesame2 session shout slp smp snmp sockets speex spell sql sse sse2 ssl startup-notification stream subtitles svg sysfs syslog tcpd theora threads thumbnail tiff tk truetype udev unicode upnp usb v4l v4l2 vcd vdpau vim-syntax vl vlm vnc vorbis webdav wma x264 xcb xcomposite xine xinerama xml xmlpatterns xorg xrandr xscreensaver xulrunner xv xvid xvmc zeroconf zip zlib" ALSA_CARDS="intel8x0" ALSA_PCM_PLUGINS="adpcm alaw asym copy dmix dshare dsnoop empty extplug file hooks iec958 ioplug ladspa lfloat linear meter mmap_emul mulaw multi null plug rate route share shm softvol" APACHE2_MODULES="actions alias auth_basic auth_digest authn_anon authn_dbd authn_dbm authn_default authn_file authz_dbm authz_default authz_groupfile authz_host authz_owner authz_user autoindex cache dav dav_fs dav_lock dbd deflate dir disk_cache env expires ext_filter file_cache filter headers ident imagemap include info log_config logio mem_cache mime mime_magic negotiation proxy proxy_ajp proxy_balancer proxy_connect proxy_http rewrite setenvif speling status unique_id userdir usertrack vhost_alias asis authn_alias cern_meta cgid charset_lite dumpio log_forensic proxy_ftp substitute version" APACHE2_MPMS="worker" CALLIGRA_FEATURES="kexi words flow plan stage tables krita karbon braindump" CAMERAS="ptp2" COLLECTD_PLUGINS="df interface irq load memory rrdtool swap syslog" DVB_CARDS="dibusb-usb2" ELIBC="glibc" GPSD_PROTOCOLS="ashtech aivdm earthmate evermore fv18 garmin garmintxt gpsclock itrax mtk3301 nmea ntrip navcom oceanserver oldstyle oncore rtcm104v2 rtcm104v3 sirf superstar2 timing tsip tripmate tnt ubx" INPUT_DEVICES="evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LINGUAS="de us" PHP_TARGETS="php5-3" RUBY_TARGETS="ruby18" SANE_BACKENDS="snapscan" USERLAND="GNU" VIDEO_CARDS="nouveau" XTABLES_ADDONS="quota2 psd pknock lscan length2 ipv4options ipset ipp2p iface geoip fuzzy condition tee tarpit sysrq steal rawnat logmark ipmark dhcpmac delude chaos account"
Unset:  CPPFLAGS, CTARGET, INSTALL_MASK, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, PORTAGE_RSYNC_EXTRA_OPTS

[Comment 1 Stefan Trenker 2011-12-11 11:27:55 UTC]

please find an auth log with 2.0.15


Dec 11 12:20:51 loisl dovecot: master: Warning: Killed with signal 15 (by pid=1860 uid=0 code=kill)
Dec 11 12:20:51 loisl start-stop-daemon: pam_unix(start-stop-daemon:session): session opened for user nobody by root(uid=0)
Dec 11 12:20:51 loisl dovecot: master: Dovecot v2.0.15 starting up (core dumps disabled)
Dec 11 12:21:05 loisl dovecot: auth: Debug: Loading modules from directory: /usr/lib64/dovecot/auth
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1891)
Dec 11 12:21:05 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53942   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: Loading modules from directory: /usr/lib64/dovecot/auth
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:05 loisl dovecot: auth: Debug: master in: REQUEST  1616117761      1891 1a7bba92de89a434704778b1ce0815f10
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: master out: USER    1616117761      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:05 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1897, TLS
Dec 11 12:21:05 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1898)
Dec 11 12:21:05 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53943   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:05 loisl dovecot: auth: Debug: master in: REQUEST  2747531265      1898 1aa5ad8a153d0b380367782b6620f8400
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: master out: USER    2747531265      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:05 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1899, TLS
Dec 11 12:21:05 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:05 loisl kernel: Bad packet not from ppp0:IN=eth1 OUT= MAC=00:15:f2:1e:42:21:00:14:f6:e5:fd:89:08:00 SRC=192.168.21.33 DST=192.168.17.17 LEN=40 TOS=0x00 PREC=0x00 TTL=63 ID=0 DF PROTO=TCP SPT=53943 DPT=143 WINDOW=0 RES=0x00 RST URGP=0 
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1900)
Dec 11 12:21:05 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53944   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:05 loisl dovecot: auth: Debug: master in: REQUEST  2984509441      1900 1ed76481167aaf7b5f4c10c30210e0678
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: master out: USER    2984509441      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:05 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1901, TLS
Dec 11 12:21:05 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1902)
Dec 11 12:21:05 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53945   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:05 loisl dovecot: auth: Debug: master in: REQUEST  2667970561      1902 12dd1e1ec56b33cfc5c63abc43ec8e0aa
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: master out: USER    2667970561      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:05 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1903, TLS
Dec 11 12:21:05 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1904)
Dec 11 12:21:05 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53946   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:05 loisl dovecot: auth: Debug: master in: REQUEST  1964900353      1904 183ea58f1325b3c3f5ee57ec13ebcd1da
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: master out: USER    1964900353      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:05 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1905, TLS
Dec 11 12:21:05 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1906)
Dec 11 12:21:05 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53947   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:05 loisl dovecot: auth: Debug: master in: REQUEST  1870135297      1906 10d84be50df7b0ed98a49c7e2d7596095
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: master out: USER    1870135297      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:05 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1907, TLS
Dec 11 12:21:05 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1908)
Dec 11 12:21:05 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53948   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:05 loisl dovecot: auth: Debug: master in: REQUEST  402259969       1908 13b9cd2d2d50143551670deb58136d09b
Dec 11 12:21:05 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:05 loisl dovecot: auth: Debug: master out: USER    402259969       [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:05 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1909, TLS
Dec 11 12:21:05 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:05 loisl dovecot: auth: Debug: auth client connected (pid=1910)
Dec 11 12:21:06 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53949   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:06 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:06 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:06 loisl dovecot: auth: Debug: master in: REQUEST  1647312897      1910 1af31c1f8dec2bef6a23271bb97614bf3
Dec 11 12:21:06 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:06 loisl dovecot: auth: Debug: master out: USER    1647312897      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:06 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1911, TLS
Dec 11 12:21:06 loisl dovecot: imap([e-mail]): Disconnected: Logged out bytes=53/709
Dec 11 12:21:06 loisl kernel: Bad packet not from ppp0:IN=eth1 OUT= MAC=00:15:f2:1e:42:21:00:14:f6:e5:fd:89:08:00 SRC=192.168.21.33 DST=192.168.17.17 LEN=40 TOS=0x00 PREC=0x00 TTL=63 ID=0 DF PROTO=TCP SPT=53949 DPT=143 WINDOW=0 RES=0x00 RST URGP=0 
Dec 11 12:21:06 loisl dovecot: auth: Debug: auth client connected (pid=1912)
Dec 11 12:21:06 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53950   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:06 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:06 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:06 loisl dovecot: auth: Debug: master in: REQUEST  3433693185      1912 1601383161418e766bccd40b6a8a9ee0c
Dec 11 12:21:06 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:06 loisl dovecot: auth: Debug: master out: USER    3433693185      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:06 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1913, TLS
Dec 11 12:21:06 loisl dovecot: auth: Debug: auth client connected (pid=1914)
Dec 11 12:21:06 loisl dovecot: auth: Debug: client in: AUTH     1       PLAIN   service=imap  secured lip=192.168.17.17       rip=192.168.21.33       lport=143       rport=53951   resp=c3RlZmFuQG1hdXMuaG9tZWxpbnV4Lm5ldABzdGVmYW5AbWF1cy5ob21lbGludXgubmV0ADAwMUpkbE1pZWRT
Dec 11 12:21:06 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:06 loisl dovecot: auth: Debug: client out: OK      1       user=[e-mail]
Dec 11 12:21:06 loisl dovecot: auth: Debug: master in: REQUEST  4118151169      1914 100610ac57e5bd4e6492bdcc4a3f3d8f2
Dec 11 12:21:06 loisl dovecot: auth-worker: Debug: vpopmail([e-mail],192.168.21.33): lookup user=stefan domain=maus.homelinux.net
Dec 11 12:21:06 loisl dovecot: auth: Debug: master out: USER    4118151169      [e-mail]     uid=89  gid=89  home=/var/vpopmail/domains/maus.homelinux.net/stefan
Dec 11 12:21:06 loisl dovecot: imap-login: Login: user=<[e-mail]>, method=PLAIN, rip=192.168.21.33, lip=192.168.17.17, mpid=1915, TLS
Dec 11 12:21:57 loisl named[16015]: error (network unreachable) resolving 'checkip.dyndns.com/A/IN': 2001:500:94::100#53

[Comment 2 Eray Aslan 2011-12-11 14:54:27 UTC]

Known problem upstream.  Either stick with dovecot-2.0.15 for now -which has a security bug 390887 though- or preferably migrate away from vpopmail.  It is nothing but trouble.

[Comment 3 Stefan Trenker 2011-12-11 15:24:40 UTC]

thanks, then i will stay with 2.0.15.
getting rid of vpopmail is not an option now. I would have to start from scratch with m yvirtual mail hosing system which is based on netqmail. I only know vpopmail to do virtual mailhosting based on netqmail.