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


  • Kopano

    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


  • Kopano

    @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.


Log in to reply