LOG-Entry "Autoresponder failed" after update to Kopano 8.7.1



  • WebApp: 3.5.6.2298+94.1
    Kopano Core: 8.7.1
    Z-Push: 2.5.0+0-0
    Ubuntu 16.04.6 LTS

    Hello together,
    after the update to Kopano 8.7.1 i get a log entry “Autoresponder failed” in the dagent.log. But the Autoresponder works. I tested it twice.

    Here the debug:

    Sat Jun  1 09:40:48 2019: [info   ] Accepted connection from [::ffff:127.0.0.1]:59056
    Sat Jun  1 09:40:48 2019: [info   ] Starting worker for LMTP request pid 5464
    Sat Jun  1 09:40:48 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:48 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:48 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:48 2019: [debug  ] < 220 2.1.5 LMTP server is ready
    Sat Jun  1 09:40:48 2019: [debug  ] > LHLO svgrw.XXXXX.lan
    Sat Jun  1 09:40:48 2019: [debug  ] LHLO ID: svgrw.XXXXX.lan
    Sat Jun  1 09:40:48 2019: [debug  ] < 250-SERVER ready
    Sat Jun  1 09:40:48 2019: [debug  ] < 250-PIPELINING
    Sat Jun  1 09:40:48 2019: [debug  ] < 250-8BITMIME
    Sat Jun  1 09:40:48 2019: [debug  ] < 250-ENHANCEDSTATUSCODE
    Sat Jun  1 09:40:48 2019: [debug  ] < 250-RSET
    Sat Jun  1 09:40:48 2019: [debug  ] < 250 SMTPUTF8
    Sat Jun  1 09:40:48 2019: [debug  ] > MAIL FROM:<MAX.MUSTERMANN@XXXXX.com>
    Sat Jun  1 09:40:48 2019: [debug  ] < 250 2.1.0 Ok
    Sat Jun  1 09:40:48 2019: [debug  ] > RCPT TO:<MICHAEL.KRAFTMANN@XXXXX.com>
    Sat Jun  1 09:40:48 2019: [debug  ] Resolved command "RCPT TO:<MICHAEL.KRAFTMANN@XXXXX.com>" to recipient address "MICHAEL.KRAFTMANN@XXXXX.com"
    Sat Jun  1 09:40:48 2019: [notice ] Resolved recipient MICHAEL.KRAFTMANN@XXXXX.com as user KRAFTMANN
    Sat Jun  1 09:40:48 2019: [debug  ] < 250 2.1.5 Ok
    Sat Jun  1 09:40:48 2019: [debug  ] > DATA
    Sat Jun  1 09:40:48 2019: [debug  ] < 354 2.1.5 Start mail input; end with <CRLF>.<CRLF>
    Sat Jun  1 09:40:49 2019: [debug  ] PYTHONPATH = /usr/share/kopano-dagent/python
    Sat Jun  1 09:40:49 2019: [info   ] * Loading plugins started
    Sat Jun  1 09:40:49 2019: [info   ] ** Checking plugins in /var/lib/kopano/dagent/plugins
    Sat Jun  1 09:40:49 2019: [info   ] * Loading plugins done
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:49 2019: [info   ] Mail will be delivered in Inbox
    Sat Jun  1 09:40:49 2019: [debug  ] HTML4 meta tag found: charset="text/html; charset=utf-8"
    Sat Jun  1 09:40:49 2019: [debug  ] HTML charset adjusted to "utf-8"
    Sat Jun  1 09:40:49 2019: [debug  ] renovate_encoding: reading data using charset "utf-8" succeeded.
    Sat Jun  1 09:40:49 2019: [info   ] * PostConverting processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PostConverting processing done
    Sat Jun  1 09:40:49 2019: [info   ] * PreDelivery processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PreDelivery processing done
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:49 2019: [info   ] * PreRuleProcess processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PreRuleProcess processing done
    Sat Jun  1 09:40:49 2019: [debug  ] Target user has OOF active
    Sat Jun  1 09:40:49 2019: [info   ] Starting autoresponder for out-of-office message
    Sat Jun  1 09:40:49 2019: [debug  ] Running command: "/usr/sbin/kopano-autorespond" "MICHAEL.KRAFTMANN@XXXXX.com" "MAX.MUSTERMANN@XXXXX.com" "Abwesend/Out of office" "KRAFTMANN" "/tm                    p/autorespond.aIQPas"
    Sat Jun  1 09:40:49 2019: [error  ] Autoresponder failed
    Sat Jun  1 09:40:49 2019: [info   ] Accepted connection from [::ffff:127.0.0.1]:59058
    Sat Jun  1 09:40:49 2019: [info   ] Starting worker for LMTP request pid 5484
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:49 2019: [info   ] * PostDelivery processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PostDelivery processing done
    Sat Jun  1 09:40:49 2019: [info   ] * SendNewMailNotify processing started
    Sat Jun  1 09:40:49 2019: [info   ] * SendNewMailNotify processing done
    Sat Jun  1 09:40:49 2019: [debug  ] Send 'New Mail' notification
    Sat Jun  1 09:40:49 2019: [info   ] Delivered message to "KRAFTMANN", Subject: "Test IT ignorieren", Message-Id: <kcis.73E3E1E3609146B0A133E5DE3A90CA67@svgrw>, size 1321
    Sat Jun  1 09:40:49 2019: [info   ] Finished processing message
    Sat Jun  1 09:40:49 2019: [debug  ] < 250 2.1.5 MICHAEL.KRAFTMANN@XXXXX.com Ok
    Sat Jun  1 09:40:49 2019: [debug  ] > QUIT
    Sat Jun  1 09:40:49 2019: [debug  ] < 221 2.0.0 Bye
    Sat Jun  1 09:40:49 2019: [info   ] LMTP thread exiting
    Sat Jun  1 09:40:49 2019: [debug  ] < 220 2.1.5 LMTP server is ready
    Sat Jun  1 09:40:49 2019: [debug  ] > LHLO svgrw.XXXXX.lan
    Sat Jun  1 09:40:49 2019: [debug  ] LHLO ID: svgrw.XXXXX.lan
    Sat Jun  1 09:40:49 2019: [debug  ] < 250-SERVER ready
    Sat Jun  1 09:40:49 2019: [debug  ] < 250-PIPELINING
    Sat Jun  1 09:40:49 2019: [debug  ] < 250-8BITMIME
    Sat Jun  1 09:40:49 2019: [debug  ] < 250-ENHANCEDSTATUSCODE
    Sat Jun  1 09:40:49 2019: [debug  ] < 250-RSET
    Sat Jun  1 09:40:49 2019: [debug  ] < 250 SMTPUTF8
    Sat Jun  1 09:40:49 2019: [debug  ] > MAIL FROM:<MICHAEL.KRAFTMANN@XXXXX.com>
    Sat Jun  1 09:40:49 2019: [debug  ] < 250 2.1.0 Ok
    Sat Jun  1 09:40:49 2019: [debug  ] > RCPT TO:<MAX.MUSTERMANN@XXXXX.com>
    Sat Jun  1 09:40:49 2019: [debug  ] Resolved command "RCPT TO:<MAX.MUSTERMANN@XXXXX.com>" to recipient address "MAX.MUSTERMANN@XXXXX.com"
    Sat Jun  1 09:40:49 2019: [notice ] Resolved recipient MAX.MUSTERMANN@XXXXX.com as user MUSTERMANN
    Sat Jun  1 09:40:49 2019: [debug  ] < 250 2.1.5 Ok
    Sat Jun  1 09:40:49 2019: [debug  ] > DATA
    Sat Jun  1 09:40:49 2019: [debug  ] < 354 2.1.5 Start mail input; end with <CRLF>.<CRLF>
    Sat Jun  1 09:40:49 2019: [debug  ] PYTHONPATH = /usr/share/kopano-dagent/python
    Sat Jun  1 09:40:49 2019: [info   ] * Loading plugins started
    Sat Jun  1 09:40:49 2019: [info   ] ** Checking plugins in /var/lib/kopano/dagent/plugins
    Sat Jun  1 09:40:49 2019: [info   ] * Loading plugins done
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:49 2019: [info   ] Mail will be delivered in Inbox
    Sat Jun  1 09:40:49 2019: [info   ] * PostConverting processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PostConverting processing done
    Sat Jun  1 09:40:49 2019: [info   ] * PreDelivery processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PreDelivery processing done
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Kopano Directory Service"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Private Folders"
    Sat Jun  1 09:40:49 2019: [debug  ] Initializing provider "Public Folders"
    Sat Jun  1 09:40:49 2019: [info   ] * PreRuleProcess processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PreRuleProcess processing done
    Sat Jun  1 09:40:49 2019: [debug  ] Target user has OOF inactive
    Sat Jun  1 09:40:49 2019: [info   ] * PostDelivery processing started
    Sat Jun  1 09:40:49 2019: [info   ] * PostDelivery processing done
    Sat Jun  1 09:40:49 2019: [info   ] * SendNewMailNotify processing started
    Sat Jun  1 09:40:49 2019: [info   ] * SendNewMailNotify processing done
    Sat Jun  1 09:40:49 2019: [debug  ] Send 'New Mail' notification
    Sat Jun  1 09:40:49 2019: [info   ] Delivered message to "MUSTERMANN", Subject: "Abwesend/Out of office [Test IT ignorieren]", Message-Id: <20190601074049.3174F1140306@svgrw.XXXXX.                    lan>, size 1513
    Sat Jun  1 09:40:49 2019: [info   ] Finished processing message
    Sat Jun  1 09:40:49 2019: [debug  ] < 250 2.1.5 MAX.MUSTERMANN@XXXXX.com Ok
    Sat Jun  1 09:40:49 2019: [debug  ] > QUIT
    Sat Jun  1 09:40:49 2019: [debug  ] < 221 2.0.0 Bye
    Sat Jun  1 09:40:49 2019: [info   ] LMTP thread exiting
    

    Is this something I have to worry about? I can’t find the cause of the error entry.




Log in to reply