dovecot-pigeonhole problems after upgrade to v2.3.0

[Fabian A. Santiago]

December 28, 2017 12:43 PM, "Fabian A. Santiago" <fsantiago at garbage-juice.com> wrote:

> December 28, 2017 12:14 PM, "Aki Tuomi" <aki.tuomi at dovecot.fi> wrote:
> 
>>> On December 28, 2017 at 7:07 PM "Fabian A. Santiago" <fsantiago at garbage-juice.com> wrote:
>>> 
>>> Hello,
>>> 
>>> After upgrading to dovecot 2.3.0, my sieve filtering is broken. I managed it via roundcube's
>> 
>> managesieve plugin. my emails are not being filtered accordingly into their folders and when i
>> attempt to access the filter interface in roundcube, my maillog reports:
>>> 
>> 
>> Can you enable mail_debug=yes and provide logs when mail is delivered?
>> 
>>> Dec 28 12:05:07 mail roundcube: <gs7miohh> PHP Error: Unable to connect to managesieve on
>>> 127.0.0.1:4190 in /var/www/roundcubemail/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>>> on line 222 (GET /mail/?_task=settings&_action=plugin.managesieve)
>>> Dec 28 12:05:07 mail roundcube: ERROR: Not currently in AUTHORISATION state (1)
>>> Dec 28 12:05:07 mail httpd: ERROR: Failed to write to socket: connection lost ()
>> 
>> This appears to be problem with managesieved, not sieve filters.
>> 
>>> Now I'm willing to consider the possibility that the RC plugin doesn't work with v2.3.0 of dovecot
>>> sieve but why wouldn't my current sieve filters be functional?
>>> 
>>> --
>>> 
>>> Thanks,
>>> 
>>> Fabian S.
>>> 
>>> OpenPGP: 3C3FA072ACCB7AC5DB0F723455502B0EEB9070FC
> 
> no, i believe it's in dovecot. upon further inspection, dovecot.log reports:
> 
> mail dovecot: master: Error: service(managesieve-login): command startup failed, throttling for 4
> secs
> 
> dovecot-sieve.log reports:
> 
> mail dovecot: managesieve-login: Fatal: master: service(managesieve-login): child 5988 returned
> error 127
> Dec 28 12:04:07 mail dovecot: managesieve-login: Error: dovecot/managesieve-login: error while
> loading shared libraries: libdovecot-login.so.0: cannot open shared object file: No such file or
> directory
> 
> do you still need the debug logs?
> 
> --
> 
> Thanks,
> 
> Fabian S.
> 
> OpenPGP: 3C3FA072ACCB7AC5DB0F723455502B0EEB9070FC

here are the sieve related debug log entries (from dovecot.log):

Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Pigeonhole version 0.5.0 (d68c23a) initializing
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: file storage: Using active Sieve script path: /var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: file storage: Using script storage path: /var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14//sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: file storage: Relative path to sieve storage in active link:
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: file storage: Using Sieve script path: /var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: file script: Opened script `managesieve' from `/var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.sieve'
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: file storage: Using Sieve script path: /var/vmail/sieve/dovecot.sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: file script: Opened script `dovecot' from `/var/vmail/sieve/dovecot.sieve'
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Executed before user's personal Sieve script(1): /var/vmail/sieve/dovecot.sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Using the following location for user's Sieve script: /var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Opening script 1 of 2 from `/var/vmail/sieve/dovecot.sieve'
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Loading script /var/vmail/sieve/dovecot.sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Script binary /var/vmail/sieve/dovecot.svbin successfully loaded
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: binary save: not saving binary /var/vmail/sieve/dovecot.svbin, because it is already stored
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Executing script from `/var/vmail/sieve/dovecot.svbin'
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Opening script 2 of 2 from `/var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.sieve'
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Loading script /var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.sieve
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Script binary /var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.svbin successfully loaded
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: binary save: not saving binary /var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.svbin, because it is already stored
Dec 28 13:01:58 mail dovecot: lda(<email>)<17543><ROjmBJYxRVqHRAAA5KVdEg>: Debug: sieve: Executing script from `/var/vmail/vmail1/<domain>/f/s/a/<user>-2017.04.06.18.50.14/sieve/dovecot.svbin'


--

Thanks,

Fabian S.

OpenPGP: 3C3FA072ACCB7AC5DB0F723455502B0EEB9070FC