Lots of error messages in /var/log/kopano/dagent.log

Hello,

I noticed that I have a lot of error messages in /var/log/kopano/dagent.log:

Mon Jun 18 16:53:49 2018: [info   ] [ 7875] * Loading plugins done
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * Loading plugins started
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] ** Checking plugins in /var/lib/kopano/dagent/plugins
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * Loading plugins done
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] Mail will be delivered in Inbox
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] Mail will be delivered in Inbox
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * PostConverting processing started
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * PostConverting processing done
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * PreDelivery processing started
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * PreDelivery processing done
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * PreRuleProcess processing started
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] * PreRuleProcess processing done
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] * PostConverting processing started
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] * PostConverting processing done
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] * PreDelivery processing started
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] * PreDelivery processing done
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] * PreRuleProcess processing started
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] * PreRuleProcess processing done
Mon Jun 18 16:53:49 2018: [error  ] [ 7876] Unable to commit message: 0x80040116
Mon Jun 18 16:53:49 2018: [error  ] [ 7876] Unable to deliver message to "user1", error code: 0x80040116
Mon Jun 18 16:53:49 2018: [error  ] [ 7876] Unable to deliver all messages for server "default:"
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] Finished processing message
Mon Jun 18 16:53:49 2018: [info   ] [ 7876] LMTP thread exiting
Mon Jun 18 16:53:49 2018: [error  ] [ 7875] Unable to commit message: 0x80040116
Mon Jun 18 16:53:49 2018: [error  ] [ 7875] Unable to deliver message to "user1", error code: 0x80040116
Mon Jun 18 16:53:49 2018: [error  ] [ 7875] Unable to deliver all messages for server "default:"
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] Finished processing message
Mon Jun 18 16:53:49 2018: [info   ] [ 7875] LMTP thread exiting
Mon Jun 18 17:13:49 2018: [info   ] [  529] Accepted connection from [::ffff:127.0.0.1]:32770
Mon Jun 18 17:13:49 2018: [info   ] [11916] Starting worker for LMTP request pid 11916
Mon Jun 18 17:13:49 2018: [notice ] [11916] Resolved recipient user1@example.de as user user1
Mon Jun 18 17:13:49 2018: [info   ] [11916] * Loading plugins started
Mon Jun 18 17:13:49 2018: [info   ] [11916] ** Checking plugins in /var/lib/kopano/dagent/plugins
Mon Jun 18 17:13:49 2018: [info   ] [11916] * Loading plugins done
Mon Jun 18 17:13:49 2018: [info   ] [11916] Mail will be delivered in Inbox
Mon Jun 18 17:13:49 2018: [info   ] [11916] * PostConverting processing started
Mon Jun 18 17:13:49 2018: [info   ] [11916] * PostConverting processing done
Mon Jun 18 17:13:49 2018: [info   ] [11916] * PreDelivery processing started
Mon Jun 18 17:13:49 2018: [info   ] [11916] * PreDelivery processing done
Mon Jun 18 17:13:49 2018: [info   ] [11916] * PreRuleProcess processing started
Mon Jun 18 17:13:49 2018: [info   ] [11916] * PreRuleProcess processing done
Mon Jun 18 17:13:49 2018: [error  ] [11916] Unable to commit message: 0x80040116
Mon Jun 18 17:13:49 2018: [error  ] [11916] Unable to deliver message to "user1", error code: 0x80040116
Mon Jun 18 17:13:49 2018: [error  ] [11916] Unable to deliver all messages for server "default:"
Mon Jun 18 17:13:49 2018: [info   ] [11916] Finished processing message
Mon Jun 18 17:13:49 2018: [info   ] [11916] LMTP thread exiting
Mon Jun 18 17:35:56 2018: [info   ] [  529] Accepted connection from [::ffff:127.0.0.1]:34534
Mon Jun 18 17:35:56 2018: [info   ] [16664] Starting worker for LMTP request pid 16664
Mon Jun 18 17:35:56 2018: [notice ] [16664] Resolved recipient user2@example.de as user user2
Mon Jun 18 17:35:56 2018: [info   ] [16664] * Loading plugins started
Mon Jun 18 17:35:56 2018: [info   ] [16664] ** Checking plugins in /var/lib/kopano/dagent/plugins
Mon Jun 18 17:35:56 2018: [info   ] [16664] * Loading plugins done
Mon Jun 18 17:35:56 2018: [info   ] [16664] Mail will be delivered in Inbox
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PostConverting processing started
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PostConverting processing done
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PreDelivery processing started
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PreDelivery processing done
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PreRuleProcess processing started
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PreRuleProcess processing done
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PostDelivery processing started
Mon Jun 18 17:35:56 2018: [info   ] [16664] * PostDelivery processing done
Mon Jun 18 17:35:56 2018: [info   ] [16664] * SendNewMailNotify processing started
Mon Jun 18 17:35:56 2018: [info   ] [16664] * SendNewMailNotify processing done
Mon Jun 18 17:35:56 2018: [info   ] [16664] Delivered message to "user2", Subject: "[Kopano Community Forum] Welcome to Kopano Community Forum", Message-Id: <209c9ba1-3578-7404-fc39-de3ea0537a48@kopano.io>, size 14569
Mon Jun 18 17:35:56 2018: [info   ] [16664] Finished processing message
Mon Jun 18 17:35:56 2018: [info   ] [16664] LMTP thread exiting

And in /var/log/kopano/server.log:

Mon Jun 18 16:21:57 2018: [warning] SQL [00000350] info: Try to reconnect
Mon Jun 18 16:21:57 2018: [error  ] SQL [00000397] Failed: MySQL server has gone away, Query Size: 287101, Query: "REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(1$
Mon Jun 18 16:21:57 2018: [error  ] KDatabase::I_Update() query failed: REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(121859,34056,30,null,'Clear (ClamAV 0.99.4/2$
Mon Jun 18 16:21:57 2018: [warning] SQL [00000397] info: Try to reconnect
Mon Jun 18 16:22:10 2018: [warning] SQL [00000369] info: Try to reconnect
Mon Jun 18 16:22:10 2018: [error  ] SQL [00000403] Failed: MySQL server has gone away, Query Size: 447621, Query: "REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(1$
Mon Jun 18 16:22:10 2018: [error  ] KDatabase::I_Update() query failed: REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(121864,34056,30,null,'Clear (ClamAV 0.99.4/2$
Mon Jun 18 16:22:10 2018: [warning] SQL [00000403] info: Try to reconnect
Mon Jun 18 16:33:12 2018: [error  ] LDAP search error: Can't contact LDAP server. Will unbind, reconnect and retry.
Mon Jun 18 16:53:49 2018: [error  ] Previous message logged 16 times
Mon Jun 18 16:53:49 2018: [warning] SQL [00000344] info: Try to reconnect
Mon Jun 18 16:53:49 2018: [error  ] SQL [00000428] Failed: MySQL server has gone away, Query Size: 352613, Query: "REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(1$
Mon Jun 18 16:53:49 2018: [error  ] KDatabase::I_Update() query failed: REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(121892,34056,30,null,'Clear (ClamAV 0.99.4/2$
Mon Jun 18 16:53:49 2018: [warning] SQL [00000428] info: Try to reconnect
Mon Jun 18 16:53:49 2018: [warning] SQL [00000331] info: Try to reconnect
Mon Jun 18 16:53:49 2018: [error  ] SQL [00000430] Failed: MySQL server has gone away, Query Size: 419587, Query: "REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(1$
Mon Jun 18 16:53:49 2018: [error  ] KDatabase::I_Update() query failed: REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(121893,34056,30,null,'Clear (ClamAV 0.99.4/2$
Mon Jun 18 16:53:49 2018: [warning] SQL [00000430] info: Try to reconnect
Mon Jun 18 17:00:03 2018: [info   ] Start scheduled softdelete clean up
Mon Jun 18 17:00:03 2018: [info   ] Softdelete done: removed 0 stores, 0 folders, and 0 messages
Mon Jun 18 17:03:36 2018: [error  ] LDAP search error: Can't contact LDAP server. Will unbind, reconnect and retry.
Mon Jun 18 17:13:03 2018: [error  ] Previous message logged 2 times
Mon Jun 18 17:13:03 2018: [info   ] Start syncs table clean up
Mon Jun 18 17:13:03 2018: [info   ] syncs table clean up done: removed syncs: 0
Mon Jun 18 17:13:49 2018: [warning] SQL [00000291] info: Try to reconnect
Mon Jun 18 17:13:49 2018: [error  ] SQL [00000434] Failed: MySQL server has gone away, Query Size: 367376, Query: "REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(1$
Mon Jun 18 17:13:49 2018: [error  ] KDatabase::I_Update() query failed: REPLACE INTO properties (hierarchyid,tag,type,properties.val_ulong,properties.val_string,properties.val_binary,properties.val_double,properties.val_longint,properties.val_hi,properties.val_lo) VALUES(121894,34056,30,null,'Clear (ClamAV 0.99.4/2$
Mon Jun 18 17:13:49 2018: [warning] SQL [00000434] info: Try to reconnect
Mon Jun 18 17:14:03 2018: [info   ] Start syncedmessages table clean up
Mon Jun 18 17:14:03 2018: [info   ] syncedmessages table clean up done, 0 entries removed

Mails are delivered successfully. Can I ignore the error messages?

Kopano4UCS
UCS4.3-0 errata3.

bye
reiner

hi @reiner ,

you don’t mention the version of Kopano you’re running, but I guess its the default 8.4.5 from the app? This is a known issue with the first Debian 9 builds of Kopano and has since then been fixed. If you have a subscription you can already update to a more recent version when following the steps explained in https://wiki.z-hub.io/display/K4U/Updating+Kopano+packages+directly+from+the+Kopano+download+server

The cause of these messages are some messages that are stuck in your mailqueue, since they could not be delivered to Kopano.

If you don’t have a subscription then there is currently an app update in review at Univention that will also resolve this. I cannot give any eta on the update going public, though.

Hi @fbartels,

yes it is the 8.4.5 installed.
I do not have a subscription, It´s for private use.
Are emails lost due to this error? I use fetchmail to pull out mail from pop mailboxes.
Thanks

Reiner

@reiner said in Lots of error messages in /var/log/kopano/dagent.log:

Are emails lost due to this error?

no, i don’t think so.