Auto-response out of nowhere



  • Hello, Coffee here again :),

    i’ve got a user without other rules than “Kategorien der E-Mails löschen (empfohlen)” shown in Outlook 2016 or Webapp . Out-of-office is disabled (enable and disabled it, just to be sure) - and here i got some strange auto-response.
    If i write a mail, i get a blank e-mail back - just once per day. Every other mail gets the same mail as autoresponse. Header-information (user edited etc.)

    Return-Path: <user@domain.de>
    Received: from mx01.domain (127.0.0.1:33626) by mailserver-FQDN (kopano-dagent) with LMTP for <mailarchiv@domain>; Wed, 26 Apr 2017 09:01:49 +0200 (CEST)
    X-Original-To: dropzone@domain
    Received: from mailserverFQDN (localhost.localdomain [127.0.0.1]) by mx01.domain (Postfix) with ESMTP id 25C2222F92 for <dropzone@domain>; Wed, 26 Apr 2017 09:01:49 +0200 (CEST)
    Subject: RE: test
    From: =?windows-1252?Q?USER?= <user@domain>
    To: =?windows-1252?Q?EMPFÄNGER?= <empfänger@domain>
    Date: Wed, 26 Apr 2017 09:01:48 +0200
    MIME-Version: 1.0
    Content-Type: text/html; charset=windows-1252
    Content-Transfer-Encoding: 7bit
    X-Mailer: Kopano 8.2.1-531
    Thread-Index: AdK+Wvh8su4aAb1OQuyLT0u4RTRahA==
    X-Original-Mailer: Microsoft Outlook 16.0
    Message-ID: <kcim.590045dc.99b.791d1c8f2db9a014@mailserverFQDN>
    

    Nothing logged in server.log (log_level 5 - info)

    Dagent.log original message (loglevel 5 info)

    Wed Apr 26 11:04:49 2017: [info   ] [ 8885] Accepted connection from 127.0.0.1:33222
    Wed Apr 26 11:04:49 2017: [info   ] [13490] Starting worker for LMTP request pid 13490
    Wed Apr 26 11:04:50 2017: [notice ] [13490] Resolved recipient mailarchiv@domain as user mailarchiv
    Wed Apr 26 11:04:50 2017: [notice ] [13490] Resolved recipient USER@DOMAIN as user username
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * Loading plugins started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] ** Checking plugins in /var/lib/kopano/dagent/plugins
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * Loading plugins done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] Mail will be delivered in Inbox
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PostConverting processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PostConverting processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreDelivery processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreDelivery processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreRuleProcess processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreRuleProcess processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PostDelivery processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PostDelivery processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * SendNewMailNotify processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * SendNewMailNotify processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] Delivered message to 'mailarchiv', Subject: "test", Message-Id: <kcim.590062b1.34af.13e34e9c0f0b704f@mailserverFQDN>, size 2814
    Wed Apr 26 11:04:50 2017: [info   ] [13490] Mail will be delivered in Inbox
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreDelivery processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreDelivery processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreRuleProcess processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PreRuleProcess processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] Rule Kategorien der E-Mails l?schen (empfohlen) doesn't match: 0x8004010f
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PostDelivery processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * PostDelivery processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * SendNewMailNotify processing started
    Wed Apr 26 11:04:50 2017: [info   ] [13490] * SendNewMailNotify processing done
    Wed Apr 26 11:04:50 2017: [info   ] [13490] Delivered message to 'user', Subject: "test", Message-Id: <kcim.590062b1.34af.13e34e9c0f0b704f@mailserverFQDN>, size 2814
    Wed Apr 26 11:04:50 2017: [info   ] [13490] Finished processing message
    Wed Apr 26 11:04:50 2017: [info   ] [13490] LMTP thread exiting
    

    dagent.log autoresponse message

    Wed Apr 26 11:04:56 2017: [info   ] [ 8885] Accepted connection from 127.0.0.1:33414
    Wed Apr 26 11:04:56 2017: [info   ] [13504] Starting worker for LMTP request pid 13504
    Wed Apr 26 11:04:56 2017: [notice ] [13504] Resolved recipient mailarchiv@DOMAIN as user mailarchiv
    Wed Apr 26 11:04:56 2017: [notice ] [13504] Resolved recipient MY_NAME@DOMAIN as user MYUSERNAME
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * Loading plugins started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] ** Checking plugins in /var/lib/kopano/dagent/plugins
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * Loading plugins done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] Mail will be delivered in Inbox
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PostConverting processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PostConverting processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreDelivery processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreDelivery processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreRuleProcess processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreRuleProcess processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PostDelivery processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PostDelivery processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * SendNewMailNotify processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * SendNewMailNotify processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] Delivered message to 'mailarchiv', Subject: "RE: test", Message-Id: <kcim.590062b7.34bc.5283dfc804646518@mailserverFQDN>, size 1369
    Wed Apr 26 11:04:56 2017: [info   ] [13504] Mail will be delivered in Inbox
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreDelivery processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreDelivery processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreRuleProcess processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PreRuleProcess processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] Rule Kategorien der E-Mails l?schen (empfohlen) doesn't match: 0x8004010f
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PostDelivery processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * PostDelivery processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * SendNewMailNotify processing started
    Wed Apr 26 11:04:56 2017: [info   ] [13504] * SendNewMailNotify processing done
    Wed Apr 26 11:04:56 2017: [info   ] [13504] Delivered message to 'myusername', Subject: "RE: test", Message-Id: <kcim.590062b7.34bc.5283dfc804646518@mailserverFQDN>, size 1369
    Wed Apr 26 11:04:56 2017: [info   ] [13504] Finished processing message
    Wed Apr 26 11:04:56 2017: [info   ] [13504] LMTP thread exiting
    

    nothing special logged in gateway.log (loglevel 5 - info)

    (btw. the Ruleset “Kategorien der E-Mails l?schen (empfohlen)” is imported from times when i used ZCP - i cant find the exact error either - just the implemitation in rules.cpp to figure out that some rules are not working - but not the fix. This issue has nothing to do with the autoreply - every maildelivery having this error.)
    //PS: just found out, that this message “Rule ‘some rule’ doesn’t match: 0x8004010f” is for information that this specific rule doesnt match this mail. - but its a valid rule " - a bit tricky to read with an error-code and never seen “Rule ‘some rule’ matches” :)

    Thanks in advance,

    Coffee_is_life


  • Kopano

    Hello @Coffee_is_life ,

    if the message would have been triggered from within Kopano, then you actually should see a message like the following in the log:

    Wed Apr 26 08:33:54 2017: [info   ] [ 1577] Starting autoresponder for out-of-office message
    


  • @fbartels said in Auto-response out of nowhere:

    Hello @Coffee_is_life ,

    if the message would have been triggered from within Kopano, then you actually should see a message like the following in the log:

    Wed Apr 26 08:33:54 2017: [info   ] [ 1577] Starting autoresponder for out-of-office message
    

    It would be nice to see this message cause that would give me a hint. - But i cant see any message in postfix, kopano-server, kopano-dagent or z-push. This occures even when the client is offline. So this must be server-based.
    Any idea how i can determine which module causes this?

    I will set the ad-user as “no mail client” and enable it again, so the user will be deleted and recreated in kopano-server.
    This will take action today evening. - reply tomorrow for the status.


  • Kopano

    @Coffee_is_life that log would be in the dagent.log. I would recommend to up the loglevel of kopano-dagent.



  • @fbartels said in Auto-response out of nowhere:

    @Coffee_is_life that log would be in the dagent.log. I would recommend to up the loglevel of kopano-dagent.

    done:

    Wed Apr 26 16:24:35 2017: [info   ] [ 8885] Accepted connection from 127.0.0.1:60860
    Wed Apr 26 16:24:35 2017: [info   ] [11299] Starting worker for LMTP request pid 11299
    Wed Apr 26 16:24:35 2017: [debug  ] [11299] PYTHONPATH = /usr/share/kopano-dagent/python
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 220 2.1.5 LMTP server is ready
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] > LHLO mx01.DOMAIN
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] LHLO ID: mx01.DOMAIN
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 250-SERVER ready
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 250-PIPELINING
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 250-ENHANCEDSTATUSCODE
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 250 RSET
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] > MAIL FROM:<ORIG-SENDER@DOMAIN>
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 250 2.1.0 Ok
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] > RCPT TO:<mailarchiv@DOMAIN>
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] Resolved command "RCPT TO:<mailarchiv@DOMAIN>" to recipient address "mailarchiv@DOMAIN"
    Wed Apr 26 16:24:36 2017: [notice ] [11299] Resolved recipient mailarchiv@DOMAIN as user mailarchiv
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 250 2.1.5 Ok
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] > RCPT TO:<ORIG-RECEIVER@DOMAIN>
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] Resolved command "RCPT TO:<ORIG-RECEIVER@DOMAIN>" to recipient address "ORIG-RECEIVER@DOMAIN"
    Wed Apr 26 16:24:36 2017: [notice ] [11299] Resolved recipient ORIG-RECEIVER@DOMAIN as user USERNAME-RECEIVER
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 250 2.1.5 Ok
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] > DATA
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] < 354 2.1.5 Start mail input; end with <CRLF>.<CRLF>
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * Loading plugins started
    Wed Apr 26 16:24:36 2017: [info   ] [11299] ** Checking plugins in /var/lib/kopano/dagent/plugins
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * Loading plugins done
    Wed Apr 26 16:24:36 2017: [info   ] [11299] Mail will be delivered in Inbox
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] Trying to parse alternative multipart 1 of mail body
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] HTML4 meta tag found: charset="text/html; charset=iso-8859-1"
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] HTML charset adjusted to "iso-8859-1"
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] MIME headers declare charset "utf-8", while HTML meta tag declares "iso-8859-1".
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] renovate_encoding: reading data using charset "utf-8" succeeded.
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * PostConverting processing started
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * PostConverting processing done
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * PreDelivery processing started
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * PreDelivery processing done
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * PreRuleProcess processing started
    Wed Apr 26 16:24:36 2017: [info   ] [11299] * PreRuleProcess processing done
    Wed Apr 26 16:24:36 2017: [debug  ] [11299] Target user has OOF inactive
    
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PostDelivery processing started
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PostDelivery processing done
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * SendNewMailNotify processing started
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * SendNewMailNotify processing done
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] Send 'New Mail' notification
    Wed Apr 26 16:24:37 2017: [info   ] [11299] Delivered message to 'mailarchiv', Subject: "test", Message-Id: <kcim.5900ada3.2c1f.0db0011d360a301f@mailserverFQDN>, size 3598
    Wed Apr 26 16:24:37 2017: [info   ] [11299] Mail will be delivered in Inbox
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PreDelivery processing started
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PreDelivery processing done
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PreRuleProcess processing started
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PreRuleProcess processing done
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] Processing rule Kategorien der E-Mails l?schen (empfohlen) for USERNAME-RECEIVER
    Wed Apr 26 16:24:37 2017: [info   ] [11299] Rule Kategorien der E-Mails l?schen (empfohlen) doesn't match: 0x8004010f
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] Target user has OOF inactive
    
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PostDelivery processing started
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * PostDelivery processing done
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * SendNewMailNotify processing started
    Wed Apr 26 16:24:37 2017: [info   ] [11299] * SendNewMailNotify processing done
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] Send 'New Mail' notification
    Wed Apr 26 16:24:37 2017: [info   ] [11299] Delivered message to 'USERNAME-RECEIVER', Subject: "test", Message-Id: <kcim.5900ada3.2c1f.0db0011d360a301f@mailserverFQDN>, size 3598
    Wed Apr 26 16:24:37 2017: [info   ] [11299] Finished processing message
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] < 250 2.1.5 mailarchiv@DOMAIN Ok
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] < 250 2.1.5 ORIG-RECEIVER@DOMAIN Ok
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] > QUIT
    Wed Apr 26 16:24:37 2017: [debug  ] [11299] < 221 2.0.0 Bye
    Wed Apr 26 16:24:37 2017: [info   ] [11299] LMTP thread exiting
    Wed Apr 26 16:24:41 2017: [info   ] [ 8885] Accepted connection from 127.0.0.1:32854
    Wed Apr 26 16:24:41 2017: [info   ] [11316] Starting worker for LMTP request pid 11316
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] PYTHONPATH = /usr/share/kopano-dagent/python
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 220 2.1.5 LMTP server is ready
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] > LHLO mx01.DOMAIN
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] LHLO ID: mx01.DOMAIN
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 250-SERVER ready
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 250-PIPELINING
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 250-ENHANCEDSTATUSCODE
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 250 RSET
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] > MAIL FROM:<ORIG-RECEIVER@DOMAIN>
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 250 2.1.0 Ok
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] > RCPT TO:<ORIG-SENDER@DOMAIN>
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Resolved command "RCPT TO:<ORIG-SENDER@DOMAIN>" to recipient address "ORIG-SENDER@DOMAIN"
    Wed Apr 26 16:24:41 2017: [notice ] [11316] Resolved recipient ORIG-SENDER@DOMAIN as user USERNAME-SENDER
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 250 2.1.5 Ok
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] > RCPT TO:<mailarchiv@DOMAIN>
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Resolved command "RCPT TO:<mailarchiv@DOMAIN>" to recipient address "mailarchiv@DOMAIN"
    Wed Apr 26 16:24:41 2017: [notice ] [11316] Resolved recipient mailarchiv@DOMAIN as user mailarchiv
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 250 2.1.5 Ok
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] > DATA
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] < 354 2.1.5 Start mail input; end with <CRLF>.<CRLF>
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * Loading plugins started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] ** Checking plugins in /var/lib/kopano/dagent/plugins
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * Loading plugins done
    Wed Apr 26 16:24:41 2017: [info   ] [11316] Mail will be delivered in Inbox
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] HTML4 meta tag found: charset="text/html; charset=us-ascii"
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] HTML charset adjusted to "us-ascii"
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] MIME headers declare charset "windows-1252", while HTML meta tag declares "us-ascii".
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Admin forced charset upgrade "us-ascii" -> "iso-8859-15".
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] renovate_encoding: reading data using charset "windows-1252" succeeded.
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PostConverting processing started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PostConverting processing done
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreDelivery processing started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreDelivery processing done
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreRuleProcess processing started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreRuleProcess processing done
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Processing rule Let's Encrypt Expiry Bot for USERNAME-SENDER
    Wed Apr 26 16:24:41 2017: [info   ] [11316] Rule Let's Encrypt Expiry Bot doesn't match: 0x8004010f
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Processing rule Kategorien der E-Mails l?schen (empfohlen) for USERNAME-SENDER
    Wed Apr 26 16:24:41 2017: [info   ] [11316] Rule Kategorien der E-Mails l?schen (empfohlen) doesn't match: 0x8004010f
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Target user has OOF inactive
    
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PostDelivery processing started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PostDelivery processing done
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * SendNewMailNotify processing started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * SendNewMailNotify processing done
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Send 'New Mail' notification
    Wed Apr 26 16:24:41 2017: [info   ] [11316] Delivered message to 'USERNAME-SENDER', Subject: "RE: test", Message-Id: <kcim.5900ada8.2c30.0e182bab43d88a92@mailserverFQDN>, size 1360
    Wed Apr 26 16:24:41 2017: [info   ] [11316] Mail will be delivered in Inbox
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreDelivery processing started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreDelivery processing done
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreRuleProcess processing started
    Wed Apr 26 16:24:41 2017: [info   ] [11316] * PreRuleProcess processing done
    Wed Apr 26 16:24:41 2017: [debug  ] [11316] Target user has OOF inactive
    
    Wed Apr 26 16:24:42 2017: [info   ] [11316] * PostDelivery processing started
    Wed Apr 26 16:24:42 2017: [info   ] [11316] * PostDelivery processing done
    Wed Apr 26 16:24:42 2017: [info   ] [11316] * SendNewMailNotify processing started
    Wed Apr 26 16:24:42 2017: [info   ] [11316] * SendNewMailNotify processing done
    Wed Apr 26 16:24:42 2017: [debug  ] [11316] Send 'New Mail' notification
    Wed Apr 26 16:24:42 2017: [info   ] [11316] Delivered message to 'mailarchiv', Subject: "RE: test", Message-Id: <kcim.5900ada8.2c30.0e182bab43d88a92@mailserverFQDN>, size 1360
    Wed Apr 26 16:24:42 2017: [info   ] [11316] Finished processing message
    Wed Apr 26 16:24:42 2017: [debug  ] [11316] < 250 2.1.5 ORIG-SENDER@DOMAIN Ok
    Wed Apr 26 16:24:42 2017: [debug  ] [11316] < 250 2.1.5 mailarchiv@DOMAIN Ok
    Wed Apr 26 16:24:42 2017: [debug  ] [11316] > QUIT
    Wed Apr 26 16:24:42 2017: [debug  ] [11316] < 221 2.0.0 Bye
    Wed Apr 26 16:24:42 2017: [info   ] [11316] LMTP thread exiting
    

    The blank line after " Target user has OOF inactive" is originally from log.

    And the z-Push log from the receiving user:

    2017/04/26 16:24:39.490 (1,VSTA_Main): Trace: ZPushFolder: New item Posteingang: 00000000A33F64A62E31A5429353AFBB687C4A960700C3B68E10F77511CEB4CD00AA00BBB6E600000000000C0000D9539C2261A6BB45B9DAB62C7081B3C10100EE3200000000
    2017/04/26 16:24:39.490 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Posteingang
    2017/04/26 16:24:39.490 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Posteingang
    2017/04/26 16:24:39.490 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Posteingang, ReplyFlags:MailItem, False
    2017/04/26 16:24:39.505 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Postausgang
    2017/04/26 16:24:39.505 (1,VSTA_Main): Debug: ZPushFolder: Folder change on unreported folder in USERNAME-RECEIVER@DOMAIN: Postausgang, 00000000A33F64A62E31A5429353AFBB687C4A960100C3B68E10F77511CEB4CD00AA00BBB6E600000000000D0000, USERNAME-RECEIVER@DOMAIN
    2017/04/26 16:24:39.505 (1,VSTA_Main): Trace: ZPushFolder: Excluding child on USERNAME-RECEIVER@DOMAIN: \\USERNAME-RECEIVER@DOMAIN\Postausgang
    2017/04/26 16:24:40.242 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Postausgang
    2017/04/26 16:24:40.242 (1,VSTA_Main): Debug: ZPushFolder: Folder change on unreported folder in USERNAME-RECEIVER@DOMAIN: Postausgang, 00000000A33F64A62E31A5429353AFBB687C4A960100C3B68E10F77511CEB4CD00AA00BBB6E600000000000D0000, USERNAME-RECEIVER@DOMAIN
    2017/04/26 16:24:40.242 (1,VSTA_Main): Trace: ZPushFolder: Excluding child on USERNAME-RECEIVER@DOMAIN: \\USERNAME-RECEIVER@DOMAIN\Postausgang
    2017/04/26 16:24:40.773 (1,VSTA_Main): Trace: ZPushFolder: Changed item Posteingang
    2017/04/26 16:24:44.832 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:44.832 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:44.832 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:44.832 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:44.832 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:44.832 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:44.843 (1,VSTA_Main): Trace: ZPushFolder: New item Gesendete Elemente: 00000000A33F64A62E31A5429353AFBB687C4A960700C3B68E10F77511CEB4CD00AA00BBB6E600000000000E0000D9539C2261A6BB45B9DAB62C7081B3C10100EF3200000000
    2017/04/26 16:24:44.843 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:44.843 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:44.843 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:44.856 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:44.856 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:44.856 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:44.856 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:44.856 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:44.856 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:44.856 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:44.867 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:44.867 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:44.979 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:44.979 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:44.979 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:46.972 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:46.976 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:46.976 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:46.976 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:46.976 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:46.976 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:49.759 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Gesendete Elemente
    2017/04/26 16:24:49.759 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Gesendete Elemente
    2017/04/26 16:24:49.759 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Gesendete Elemente, ReplyFlags:MailItem, False
    2017/04/26 16:24:49.765 (1,VSTA_Main): Trace: ZPushFolder: New item Gesendete Elemente: 00000000A33F64A62E31A5429353AFBB687C4A960700C3B68E10F77511CEB4CD00AA00BBB6E600000000000E0000D9539C2261A6BB45B9DAB62C7081B3C10100F03200000000
    2017/04/26 16:24:49.765 (1,VSTA_Main): Trace: ZPushFolder: New item Gesendete Elemente: 00000000A33F64A62E31A5429353AFBB687C4A960700C3B68E10F77511CEB4CD00AA00BBB6E600000000000E0000D9539C2261A6BB45B9DAB62C7081B3C10100F13200000000
    2017/04/26 16:27:34.930 (1,VSTA_Main): Trace: ZPushFolder: New item Posteingang: 00000000A33F64A62E31A5429353AFBB687C4A960700C3B68E10F77511CEB4CD00AA00BBB6E600000000000C0000D9539C2261A6BB45B9DAB62C7081B3C10100F23200000000
    2017/04/26 16:27:34.930 (1,VSTA_Main): Debug: ZPushFolder: Folder changed in USERNAME-RECEIVER@DOMAIN: Posteingang
    2017/04/26 16:27:34.930 (1,VSTA_Main): Trace: ZPushWatcher: Folder changed: Posteingang
    2017/04/26 16:27:34.930 (1,VSTA_Main): Debug: ZPushWatcher: Folder event: Posteingang, ReplyFlags:MailItem, False
    

    hopefully you see something i cant :)

    Coffee_is_life



  • Good morning everyone,

    i think i’ve found this bug:

    This specific user turned on absence assistant before we used kopano via zarafa mapi client, connected to ZCP 7.2.4.
    He came back, got a new pc with office2016 and Kopano via z-push and disabled the feature. The message was deleted but the automatic reply was still present.
    Even turning oof on and off via ‘kopano-set-oof’ didnt solve this.

    Solution:
    Delete all outlook-profiles
    restart computer
    delete the local profile (windows)
    resync it from server.
    create new kopano-profile
    turn on oof via kopano KOE
    turn off oof via kopano KOE

    didnt get a message since than. - Maybe the resync from server isnt needed, but i did it to be sure and it worked.

    closed for me

    Coffee_is_life


Log in to reply
 

Looks like your connection to Kopano Community Forum was lost, please wait while we try to reconnect.