dovecot - Mar 2013 - dsync-remote(): Error: doveadm client: No command given

Hi!

I test dsync through tcp. 
In a log error:
dsync-remote (): Error: doveadm client: No command given

All are synchronized. The error is shown by rand for the user and on
that server where it is synchronized


# 2.2.rc2 (976bf9e69367): /usr/local/etc/dovecot/dovecot.conf
# OS: Linux 2.6.32-5-686 i686 Debian 6.0.6 ext3
auth_mechanisms = plain login digest-md5
auth_username_format = %Ln@%Ld
disable_plaintext_auth = no
doveadm_port = 24000
hostname = optnet.local
listen = *
mail_gid = vmail
mail_home = /var/mail/virtual/%d/%n
mail_location = maildir:/var/mail/virtual/%d/%n
mail_plugins = notify replication
mail_privileged_group = mail
mail_uid = vmail
namespace inbox {
  inbox = yes
  location = 
  mailbox Drafts {
    special_use = \Drafts
  }
  mailbox Junk {
    special_use = \Junk
  }
  mailbox Sent {
    special_use = \Sent
  }
  mailbox "Sent Messages" {
    special_use = \Sent
  }
  mailbox Trash {
    special_use = \Trash
  }
  prefix = 
}
passdb {
  args = /usr/local/etc/dovecot/auth/users
  driver = passwd-file
}
plugin {
  mail_replica = tcp:192.168.0.190
}
service aggregator {
  fifo_listener replication-notify-fifo {
    user = vmail
  }
  unix_listener replication-notify {
    user = vmail
  }
}
service auth-worker {
  unix_listener auth-worker {
    group = vmail
    mode = 0666
    user = vmail
  }
}
service auth {
  unix_listener auth-client {
    mode = 0660
    user = Debian-exim
  }
  unix_listener auth-userdb {
    group = vmail
    mode = 0666
    user = vmail
  }
  user = vmail
}
service doveadm {
  inet_listener {
    port = 24000
  }
}
service imap-login {
  inet_listener imap {
    port = 143
  }
}
service lmtp {
  inet_listener lmtp {
    port = 24
  }
  user = vmail
}
service pop3-login {
  inet_listener pop3 {
    port = 110
  }
}
service replicator {
  process_min_avail = 1
}
ssl = no
ssl_cert = </usr/local/etc/dovecot/ssl/dovecot.pem
ssl_key = </usr/local/etc/dovecot/ssl/dovecot.key
userdb {
  args = /usr/local/etc/dovecot/auth/users
  driver = passwd-file
}
protocol lda {
  mail_plugins = notify replication
}
protocol imap {
  imap_client_workarounds = delay-newmail tb-extra-mailbox-sep
tb-lsub-flags }
protocol lmtp {
  mail_plugins = notify replication
}
protocol pop3 {
  pop3_client_workarounds = outlook-no-nuls oe-ns-eoh
}
local 192.168.0.0/24/24 {
  doveadm_password = password
}

Logs:

Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Debug: none: root=, index=,
indexpvt=, control=, inbox=, altMar  4 16:53:35 dov1 dovecot: lmtp(29382):
Connect from 192.168.0.192
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Debug: Loading modules from
directory: /usr/local/lib/dovecot
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Debug: Module loaded:
/usr/local/lib/dovecot/lib15_notify_plugin.so
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Debug: Module loaded:
/usr/local/lib/dovecot/lib20_replication_plugin.so
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Debug: auth input: anna at
optnet.local uid=1005 gid=1005
Mar  4 16:53:35 dov1 dovecot: lmtp(29382, anna at optnet.local): Debug:
Effective uid=1005, gid=1005, home=/var/mail/virtual/optnet.local/anna
Mar  4 16:53:35 dov1 dovecot: lmtp(29382, anna at optnet.local): Debug:
Namespace inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes location=maildir:/var/mail/virtual/optnet.local/anna
Mar  4 16:53:35 dov1 dovecot: lmtp(29382, anna at optnet.local): Debug:
maildir++: root=/var/mail/virtual/optnet.local/anna, index=, indexpvt=,
control=, inbox=/var/mail/virtual/optnet.local/anna, altMar  4 16:53:35 dov1
dovecot: lmtp(29382, anna at optnet.local): SAKxFU+ZNFHGcgAAaoqQ0w:
msgid=<E1UCUqK-00013S-OA at jb.optnet.ru>: saved mail to INBOX
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Disconnect from 192.168.0.192:
Connection closed (in reset)
Mar  4 16:53:35 dov1 dovecot: doveadm(anna at optnet.local): Debug: auth input:
user=anna at optnet.local
Mar  4 16:53:35 dov1 dovecot: doveadm(anna at optnet.local): Debug: auth input:
anna at optnet.local uid=1005 gid=1005
Mar  4 16:53:35 dov1 dovecot: doveadm(anna at optnet.local): Debug: Effective
uid=1005, gid=1005, home=/var/mail/virtual/optnet.local/anna
Mar  4 16:53:35 dov1 dovecot: doveadm(anna at optnet.local): Debug: Namespace
inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes location=maildir:/var/mail/virtual/optnet.local/anna
Mar  4 16:53:35 dov1 dovecot: doveadm(anna at optnet.local): Debug: maildir++:
root=/var/mail/virtual/optnet.local/anna, index=, indexpvt=, control=,
inbox=/var/mail/virtual/optnet.local/anna, altMar  4 16:53:35 dov1 dovecot:
doveadm(anna at optnet.local): Debug: Namespace : Using permissions from
/var/mail/virtual/optnet.local/anna: mode=0700 gid=default
Mar  4 16:53:35 dov1 dovecot: doveadm(alex at optnet.local): Debug: auth input:
user=alex at optnet.local
Mar  4 16:53:35 dov1 dovecot: doveadm(alex at optnet.local): Debug: auth input:
alex at optnet.local uid=1005 gid=1005
Mar  4 16:53:35 dov1 dovecot: doveadm(alex at optnet.local): Debug: Effective
uid=1005, gid=1005, home=/var/mail/virtual/optnet.local/alex
Mar  4 16:53:35 dov1 dovecot: doveadm(alex at optnet.local): Debug: Namespace
inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes location=maildir:/var/mail/virtual/optnet.local/alex
Mar  4 16:53:35 dov1 dovecot: doveadm(alex at optnet.local): Debug: maildir++:
root=/var/mail/virtual/optnet.local/alex, index=, indexpvt=, control=,
inbox=/var/mail/virtual/optnet.local/alex, altMar  4 16:53:35 dov1 dovecot:
dsync-remote(alex at optnet.local): Debug: Namespace : Using permissions from
/var/mail/virtual/optnet.local/alex: mode=0700 gid=default
Mar  4 16:53:35 dov1 dovecot: doveadm(mike at optnet.local): Debug: auth input:
user=mike at optnet.local
Mar  4 16:53:35 dov1 dovecot: doveadm(mike at optnet.local): Debug: auth input:
mike at optnet.local uid=1005 gid=1005
Mar  4 16:53:35 dov1 dovecot: doveadm(mike at optnet.local): Debug: Effective
uid=1005, gid=1005, home=/var/mail/virtual/optnet.local/mike
Mar  4 16:53:35 dov1 dovecot: doveadm(mike at optnet.local): Debug: Namespace
inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes location=maildir:/var/mail/virtual/optnet.local/mike
Mar  4 16:53:35 dov1 dovecot: doveadm(mike at optnet.local): Debug: maildir++:
root=/var/mail/virtual/optnet.local/mike, index=, indexpvt=, control=,
inbox=/var/mail/virtual/optnet.local/mike, altMar  4 16:53:35 dov1 dovecot:
dsync-remote(mike at optnet.local): Debug: Namespace : Using permissions from
/var/mail/virtual/optnet.local/mike: mode=0700 gid=default
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Debug: none: root=, index=,
indexpvt=, control=, inbox=, altMar  4 16:53:35 dov1 dovecot: lmtp(29382):
Connect from 192.168.0.192
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Debug: auth input: anna at
optnet.local uid=1005 gid=1005
Mar  4 16:53:35 dov1 dovecot: lmtp(29382, anna at optnet.local): Debug:
Effective uid=1005, gid=1005, home=/var/mail/virtual/optnet.local/anna
Mar  4 16:53:35 dov1 dovecot: lmtp(29382, anna at optnet.local): Debug:
Namespace inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes,
subscriptions=yes location=maildir:/var/mail/virtual/optnet.local/anna
Mar  4 16:53:35 dov1 dovecot: lmtp(29382, anna at optnet.local): Debug:
maildir++: root=/var/mail/virtual/optnet.local/anna, index=, indexpvt=,
control=, inbox=/var/mail/virtual/optnet.local/anna, alt
Mar  4 16:53:35 dov1 dovecot: dsync-remote(alex at optnet.local): Error: doveadm
client: No command given

Mar  4 16:53:35 dov1 dovecot: lmtp(29382, anna at optnet.local):
TAKxFU+ZNFHGcgAAaoqQ0w: msgid=<E1UCUqK-00013h-VD at jb.optnet.ru>: saved
mail to INBOX
Mar  4 16:53:35 dov1 dovecot: lmtp(29382): Disconnect from 192.168.0.192:
Connection closed (in reset)


--
Best regards,
Aleksey Tsvetkov
System Administrator
Company Grand Vision
tel. +7(495)933-39-79, ext. 184

On Mon, 4 Mar 2013 17:03:47 +0400
Aleksey Tsvetkov <tsvetkov_av at grandvision.ru> wrote:
> I test dsync through tcp. 
> In a log error:
> dsync-remote (): Error: doveadm client: No command given
> 
> All are synchronized. The error is shown by rand for the user and on
> that server where it is synchronized
> 
Same thing here, also dsync over tcp. I got that error every few seconds
on server2 when appending many messages to user1's mailbox on server1.

Feb 28 17:54:01 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given
Feb 28 17:54:08 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given
Feb 28 17:54:09 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given
Feb 28 17:54:25 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given
Feb 28 17:54:43 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given
Feb 28 17:54:51 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given
Feb 28 17:54:54 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given
Feb 28 17:54:55 doco2 dovecot: dsync-remote(user1): Error: doveadm client: No
command given

Replication is still ok though.