thr3ads.net - dovecot - Can't figure out why managesieve (pigeonhole) can't connect [Nov 2022]

If this information is useful, please help other people find it:
Share via:

colin at colinlikesfood.com

2022-Nov-23 15:44 UTC

Can't figure out why managesieve (pigeonhole) can't connect

thanks for your reply - dovecot and roundcube are on the same server, 
and i am able to authenticate both a telnet and an openssl connection 
with manageseieve on that port from both the same machine and another 
machine, so i think that has to mean managesieve is listening, correct?

On 2022-11-23 04:09, Yassine Chaouche wrote:
> hello Colin,
> 
> is anything listening on that port?
> you might find lsof and netstat helpful.
> also, if roundcube and dovecot are on different servers, you might want 
> to check a network cnx to the managesieve port.
> cnx refused often means nothing is listening or firewall is filtering.
> 
> Yassine.
> 
> Le 22 novembre 2022 6:03:08 PM GMT+01:00, colin at colinlikesfood.com a 
> ?crit :
> 
>> Subject line says it all?  I am using Roundcube, and every time i 
>> click on "filters" i get RC's "unable to connect to
server" message.
>> 
>> This might be an SQL error, but i can't figure out how to pull the 
>> relevant logs yet.  Please see below and any advice you have is so 
>> very appreciated
>> 
>> SYSLOG:
>> -------------------
>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error: 
>> Connection refused (GET 
>> /index.php?_task=settings&_action=plugin.managesieve)
>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to
>> connect to managesieve on obfuscated.domain:4190 in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 221 (GET
/index.php?_task=settings&_action=plugin.managesieve)
>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error: Not 
>> currently in AUTHORISATION state (GET 
>> /index.php?_task=settings&_action=plugin.managesieve)
>> Nov 22 10:29:27 mail php[66295]: <keirks4p> PHP Error: Not
currently
>> connected (GET
/index.php?_task=settings&_action=plugin.managesieve)
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error: 
>> Connection refused (GET 
>>
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to
>> connect to managesieve on obfuscated.domain:4190 in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 221 (GET 
>>
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>> Nov 22 10:29:31 mail php[66295]: <keirks4p> PHP Error: Not
currently
>> connected (GET 
>>
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error: 
>> Connection refused (POST 
>> /?_task=settings&_action=plugin.managesieve-save)
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error:
Unable to
>> connect to managesieve on obfuscated.domain:4190 in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 221 (POST /?_task=settings&_action=plugin.managesieve-save)
>> Nov 22 10:29:43 mail roundcube[71055]: PHP Warning:  Trying to access 
>> array offset on value of type null in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 1065
>> Nov 22 10:29:43 mail php[71055]: <keirks4p> PHP Error: Not
currently
>> connected (POST /?_task=settings&_action=plugin.managesieve-save)
>> 
>> DOVECOT.LOG:
>> -------------------
>> Nov 22 10:28:58 mail roundcube[66297]: <keirks4p> [43CD] C: A0008
>> LOGOUT
>> Nov 22 10:28:58 mail dovecot[8514]: 
>> imap(obfuscated at user.name)<8609><XgBCqxHusXAKAAAB>:
Disconnected:
>> Logged out in=219 out=1045 deleted=0 expunged=0 trashed=0 hdr_count=0 
>> hdr_bytes=0 body_count=0 body_bytes=0
>> Nov 22 10:28:58 mail roundcube[66297]: <keirks4p> [43CD] S: * BYE
>> Logging out
>> Nov 22 10:28:58 mail roundcube[66297]: <keirks4p> [43CD] S: A0008
OK
>> Logout completed (0.001 + 0.000 secs).
>> Nov 22 10:29:25 mail roundcube[66295]: <keirks4p> [1] SELECT
"vars",
>> "ip", "changed", datetime('now') AS ts,
CASE WHEN "changed" <
>> datetime('now', '-600 seconds') THEN 1 ELSE 0 END AS
expired FROM
>> "session" WHERE "sess_id" =
'keirks4pbepr17um9mvj1qsvt2';
>> Nov 22 10:29:25 mail roundcube[66295]: <keirks4p> [2] SELECT *
FROM
>> "users" WHERE "user_id" = '2';
>> Nov 22 10:29:25 mail roundcube[66295]: <keirks4p> [3] UPDATE
"session"
>> SET "changed" = datetime('now'), "vars" = 
>>'bGFuZ3VhZ2V8czo1OiJlbl9VUyI7aW1hcF9uYW1lc3BhY2V8YTo0OntzOjg6InBlcnNvbmFsIjthOjE6e2k6MDthOjI6e2k6MDtzOjA6IiI7aToxO3M6MToiLyI7fX1zOjU6Im90aGVyIjtOO3M6Njoic2hhcmVkIjtOO3M6MTA6InByZWZpeF9vdXQiO3M6MDoiIjt9aW1hcF9kZWxpbWl0ZXJ8czoxOiIvIjtpbWFwX2xpc3RfY29uZnxhOjI6e2k6MDtOO2k6MTthOjA6e319dXNlcl9pZHxpOjI7dXNlcm5hbWV8czoyNDoiY29saW5AY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX3BvcnR8aToxNDM7c3RvcmFnZV9zc2x8czozOiJ0bHMiO3Bhc3N3b3JkfHM6NDQ6ImNYOCt0VmJXV010VEMxRTlXQVB4ZjVOSlU1bWlKa2ZveC8xRWpuZVB2Ym89Ijtsb2dpbl90aW1lfGk6MTY2OTA2MzM4OTt0aW1lem9uZXxzOjE1OiJBbWVyaWNhL0NoaWNhZ28iO2F1dGhfc2VjcmV0fHM6MjY6ImNHdkc4ZWR5QmFmNWJITVpISG5sTEY3ZmpZIjtyZXF1ZXN0X3Rva2VufHM6MzI6IkM5VXlNNVBMUnhSeERiVm9HTlNMNjdVZ05NaWE4WVZGIjt0YXNrfHM6ODoic2V0dGluZ3MiO3NraW5fY29uZmlnfGE6Nzp7czoxNzoic3VwcG9ydGVkX2xheW91dHMiO2E6MTp7aTowO3M6MTA6IndpZGVzY3JlZW4iO31zOjIyOiJqcXVlcnlfdWlfY29sb3JzX3RoZW1lIjtzOjk6ImJvb3RzdHJhcCI7czoxODoiZW1iZWRfY3NzX2xvY2F0aW9uIjtzOjE3OiIvc3R5bGVzL2VtYmVkLmNzcyI7c
zoxOToiZWRpdG9yX2Nzc19sb2NhdGlvbiI7czoxNzoiL3N0eWxlcy9lbWJlZC5jc3MiO3M6MTc6ImRhcmtfbW9kZV9zdXBwb3J0IjtiOjE7czoyNjoibWVkaWFfYnJvd3Nlcl9jc3NfbG9jYXRpb24iO3M6NDoibm9uZSI7czoyMToiYWRkaXRpb25hbF9sb2dvX3R5cGVzIjthOjM6e2k6MDtzOjQ6ImRhcmsiO2k6MTtzOjU6InNtYWxsIjtpOjI7czoxMDoic21hbGwtZGFyayI7fX1pbWFwX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjttYm94fHM6NToiSU5CT1giO3NvcnRfY29sfHM6MDoiIjtzb3J0X29yZGVyfHM6NDoiREVTQyI7U1RPUkFHRV9USFJFQUR8YTozOntpOjA7czoxMDoiUkVGRVJFTkNFUyI7aToxO3M6NDoiUkVGUyI7aToyO3M6MTQ6Ik9SREVSRURTVUJKRUNUIjt9U1RPUkFHRV9RVU9UQXxiOjA7U1RPUkFHRV9MSVNULUVYVEVOREVEfGI6MTtsaXN0X2F0dHJpYnxhOjc6e3M6NDoibmFtZSI7czo4OiJtZXNzYWdlcyI7czoyOiJpZCI7czoxMToibWVzc2FnZWxpc3QiO3M6NToiY2xhc3MiO3M6NDI6Imxpc3RpbmcgbWVzc2FnZWxpc3Qgc29ydGhlYWRlciBmaXhlZGhlYWRlciI7czoxNToiYXJpYS1sYWJlbGxlZGJ5IjtzOjIyOiJhcmlhLWxhYmVsLW1lc3NhZ2VsaXN0IjtzOjk6ImRhdGEtbGlzdCI7czoxMjoibWVzc2FnZV9saXN0IjtzOjE0OiJkYXRhLWxhYmVsLW1zZyI7czoxODoi>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> [1] SELECT
"vars",
>> "ip", "changed", datetime('now') AS ts,
CASE WHEN "changed" <
>> datetime('now', '-600 seconds') THEN 1 ELSE 0 END AS
expired FROM
>> "session" WHERE "sess_id" =
'keirks4pbepr17um9mvj1qsvt2';
>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> [2] SELECT *
FROM
>> "users" WHERE "user_id" = '2';
>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error: 
>> Connection refused (GET 
>> /index.php?_task=settings&_action=plugin.managesieve)
>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to
>> connect to managesieve on obfuscated.domain:4190 in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 221 (GET
/index.php?_task=settings&_action=plugin.managesieve)
>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error: Not 
>> currently in AUTHORISATION state (GET 
>> /index.php?_task=settings&_action=plugin.managesieve)
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [1] SELECT
"vars",
>> "ip", "changed", datetime('now') AS ts,
CASE WHEN "changed" <
>> datetime('now', '-600 seconds') THEN 1 ELSE 0 END AS
expired FROM
>> "session" WHERE "sess_id" =
'keirks4pbepr17um9mvj1qsvt2';
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [2] SELECT *
FROM
>> "users" WHERE "user_id" = '2';
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error: 
>> Connection refused (GET 
>>
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to
>> connect to managesieve on obfuscated.domain:4190 in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 221 (GET 
>>
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5]
Connecting to
>> tls://obfuscated.domain:143...
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: * OK 
>> [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ 
>> STARTTLS LOGINDISABLED AUTH=CRAM-MD5] Dovecot ready.
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C: A0001
>> STARTTLS
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: A0001
OK
>> Begin TLS negotiation now.
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C: A0002
>> CAPABILITY
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: * 
>> CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ 
>> LOGINDISABLED AUTH=CRAM-MD5
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: A0002
OK
>> Pre-login capabilities listed, post-login capabilities have more.
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C: A0003
>> AUTHENTICATE CRAM-MD5
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: + 
>> PDI3MjU4Mjg1ODUxMTkzMTEuMTY2OTEzNDU3MUBtYWlsPg=>> Nov 22 10:29:31
mail roundcube[66295]: <keirks4p> [7FB5] C: ******
>> [74]
>> Nov 22 10:29:31 mail dovecot[8514]: imap-login: Login: 
>> user=<obfuscated at user.name>, method=CRAM-MD5, rip=10.0.0.1, 
>> lip=10.0.0.91, mpid=8639, TLS, session=<51k6rRHuw5gKAAAB>
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: A0003
OK
>> [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT 
>> SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT 
>> MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS 
>> LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES 
>> WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY 
>> PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE LITERAL+ NOTIFY 
>> SPECIAL-USE] Logged in
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C: A0004
ID
>> ("name" "Roundcube" "version"
"1.6.0" "php" "8.0.25" "os"
"FreeBSD"
>> "command" 
>>
"/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide")
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: * ID 
>> ("name" "Dovecot")
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: A0004
OK
>> ID completed (0.001 + 0.000 secs).
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C: A0005
LIST
>> (SUBSCRIBED) "" "*"
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST
>> (\Subscribed) "/" Drafts
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST
>> (\Subscribed) "/" Junk
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST
>> (\Subscribed) "/" Sent
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST
>> (\Subscribed) "/" Trash
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST
>> (\Subscribed) "/" INBOX/NAS-Alerts
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST
>> (\Subscribed) "/" INBOX/email-reports
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: A0005
OK
>> List completed (0.001 + 0.000 secs).
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [3] UPDATE
"session"
>> SET "changed" = datetime('now'), "vars" = 
>>'bGFuZ3VhZ2V8czo1OiJlbl9VUyI7aW1hcF9uYW1lc3BhY2V8YTo0OntzOjg6InBlcnNvbmFsIjthOjE6e2k6MDthOjI6e2k6MDtzOjA6IiI7aToxO3M6MToiLyI7fX1zOjU6Im90aGVyIjtOO3M6Njoic2hhcmVkIjtOO3M6MTA6InByZWZpeF9vdXQiO3M6MDoiIjt9aW1hcF9kZWxpbWl0ZXJ8czoxOiIvIjtpbWFwX2xpc3RfY29uZnxhOjI6e2k6MDtOO2k6MTthOjA6e319dXNlcl9pZHxpOjI7dXNlcm5hbWV8czoyNDoiY29saW5AY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX3BvcnR8aToxNDM7c3RvcmFnZV9zc2x8czozOiJ0bHMiO3Bhc3N3b3JkfHM6NDQ6ImNYOCt0VmJXV010VEMxRTlXQVB4ZjVOSlU1bWlKa2ZveC8xRWpuZVB2Ym89Ijtsb2dpbl90aW1lfGk6MTY2OTA2MzM4OTt0aW1lem9uZXxzOjE1OiJBbWVyaWNhL0NoaWNhZ28iO2F1dGhfc2VjcmV0fHM6MjY6ImNHdkc4ZWR5QmFmNWJITVpISG5sTEY3ZmpZIjtyZXF1ZXN0X3Rva2VufHM6MzI6IkM5VXlNNVBMUnhSeERiVm9HTlNMNjdVZ05NaWE4WVZGIjt0YXNrfHM6ODoic2V0dGluZ3MiO3NraW5fY29uZmlnfGE6Nzp7czoxNzoic3VwcG9ydGVkX2xheW91dHMiO2E6MTp7aTowO3M6MTA6IndpZGVzY3JlZW4iO31zOjIyOiJqcXVlcnlfdWlfY29sb3JzX3RoZW1lIjtzOjk6ImJvb3RzdHJhcCI7czoxODoiZW1iZWRfY3NzX2xvY2F0aW9uIjtzOjE3OiIvc3R5bGVzL2VtYmVkLmNzcyI7c
zoxOToiZWRpdG9yX2Nzc19sb2NhdGlvbiI7czoxNzoiL3N0eWxlcy9lbWJlZC5jc3MiO3M6MTc6ImRhcmtfbW9kZV9zdXBwb3J0IjtiOjE7czoyNjoibWVkaWFfYnJvd3Nlcl9jc3NfbG9jYXRpb24iO3M6NDoibm9uZSI7czoyMToiYWRkaXRpb25hbF9sb2dvX3R5cGVzIjthOjM6e2k6MDtzOjQ6ImRhcmsiO2k6MTtzOjU6InNtYWxsIjtpOjI7czoxMDoic21hbGwtZGFyayI7fX1pbWFwX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjttYm94fHM6NToiSU5CT1giO3NvcnRfY29sfHM6MDoiIjtzb3J0X29yZGVyfHM6NDoiREVTQyI7U1RPUkFHRV9USFJFQUR8YTozOntpOjA7czoxMDoiUkVGRVJFTkNFUyI7aToxO3M6NDoiUkVGUyI7aToyO3M6MTQ6Ik9SREVSRURTVUJKRUNUIjt9U1RPUkFHRV9RVU9UQXxiOjA7U1RPUkFHRV9MSVNULUVYVEVOREVEfGI6MTtsaXN0X2F0dHJpYnxhOjc6e3M6NDoibmFtZSI7czo4OiJtZXNzYWdlcyI7czoyOiJpZCI7czoxMToibWVzc2FnZWxpc3QiO3M6NToiY2xhc3MiO3M6NDI6Imxpc3RpbmcgbWVzc2FnZWxpc3Qgc29ydGhlYWRlciBmaXhlZGhlYWRlciI7czoxNToiYXJpYS1sYWJlbGxlZGJ5IjtzOjIyOiJhcmlhLWxhYmVsLW1lc3NhZ2VsaXN0IjtzOjk6ImRhdGEtbGlzdCI7czoxMjoibWVzc2FnZV9saXN0IjtzOjE0OiJkYXRhLWxhYmVsLW1zZyI7czoxODoi>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C: A0006
>> LOGOUT
>> Nov 22 10:29:31 mail dovecot[8514]: 
>> imap(obfuscated at user.name)<8639><51k6rRHuw5gKAAAB>:
Disconnected:
>> Logged out in=215 out=821 deleted=0 expunged=0 trashed=0 hdr_count=0 
>> hdr_bytes=0 body_count=0 body_bytes=0
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: * BYE
>> Logging out
>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: A0006
OK
>> Logout completed (0.001 + 0.000 secs).
>> Nov 22 10:29:42 mail roundcube[71055]: <keirks4p> [1] SELECT
"vars",
>> "ip", "changed", datetime('now') AS ts,
CASE WHEN "changed" <
>> datetime('now', '-600 seconds') THEN 1 ELSE 0 END AS
expired FROM
>> "session" WHERE "sess_id" =
'keirks4pbepr17um9mvj1qsvt2';
>> Nov 22 10:29:42 mail roundcube[71055]: <keirks4p> [2] SELECT *
FROM
>> "users" WHERE "user_id" = '2';
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error: 
>> Connection refused (POST 
>> /?_task=settings&_action=plugin.managesieve-save)
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error:
Unable to
>> connect to managesieve on obfuscated.domain:4190 in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 221 (POST /?_task=settings&_action=plugin.managesieve-save)
>> Nov 22 10:29:43 mail roundcube[71055]: PHP Warning:  Trying to access 
>> array offset on value of type null in 
>>
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
>> on line 1065
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90]
Connecting to
>> tls://obfuscated.domain:143...
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: * OK 
>> [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ 
>> STARTTLS LOGINDISABLED AUTH=CRAM-MD5] Dovecot ready.
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C: A0001
>> STARTTLS
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: A0001
OK
>> Begin TLS negotiation now.
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C: A0002
>> CAPABILITY
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: * 
>> CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ 
>> LOGINDISABLED AUTH=CRAM-MD5
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: A0002
OK
>> Pre-login capabilities listed, post-login capabilities have more.
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C: A0003
>> AUTHENTICATE CRAM-MD5
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: + 
>> PDE2NjcxMDUwNTU2NDcwMTMuMTY2OTEzNDU4M0BtYWlsPg=>> Nov 22 10:29:43
mail roundcube[71055]: <keirks4p> [CD90] C: ******
>> [74]
>> Nov 22 10:29:43 mail dovecot[8514]: imap-login: Login: 
>> user=<obfuscated at user.name>, method=CRAM-MD5, rip=10.0.0.1, 
>> lip=10.0.0.91, mpid=8644, TLS, session=<677prRHu4aAKAAAB>
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: A0003
OK
>> [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT 
>> SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT 
>> MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS 
>> LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES 
>> WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY 
>> PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE LITERAL+ NOTIFY 
>> SPECIAL-USE] Logged in
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C: A0004
ID
>> ("name" "Roundcube" "version"
"1.6.0" "php" "8.0.25" "os"
"FreeBSD"
>> "command" "/")
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: * ID 
>> ("name" "Dovecot")
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: A0004
OK
>> ID completed (0.001 + 0.000 secs).
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C: A0005
LIST
>> (SUBSCRIBED) "" "*"
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST
>> (\Subscribed) "/" Drafts
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST
>> (\Subscribed) "/" Junk
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST
>> (\Subscribed) "/" Sent
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST
>> (\Subscribed) "/" Trash
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST
>> (\Subscribed) "/" INBOX/NAS-Alerts
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST
>> (\Subscribed) "/" INBOX/email-reports
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: A0005
OK
>> List completed (0.001 + 0.000 secs).
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C: A0006
>> LOGOUT
>> Nov 22 10:29:43 mail dovecot[8514]: 
>> imap(obfuscated at user.name)<8644><677prRHu4aAKAAAB>:
Disconnected:
>> Logged out in=137 out=821 deleted=0 expunged=0 trashed=0 hdr_count=0 
>> hdr_bytes=0 body_count=0 body_bytes=0
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: * BYE
>> Logging out
>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: A0006
OK
>> Logout completed (0.001 + 0.000 secs).
>> 
>> DOVECOT INFO:
>> ------------------------
>> # dovecot --version
>> 2.3.19.1 (9b53102964)
>> 
>> # dovecot -n
>> # 2.3.19.1 (9b53102964): /usr/local/etc/dovecot/dovecot.conf
>> # Pigeonhole version 0.5.19 (4eae2f79)
>> # OS: FreeBSD 13.1-RELEASE-p2 amd64  zfs
>> # Hostname: localhost
>> auth_mechanisms = cram-md5
>> first_valid_gid = 10000
>> first_valid_uid = 10000
>> mail_location = maildir:/var/mail/users/%u
>> managesieve_notify_capability = mailto
>> managesieve_sieve_capability = fileinto reject envelope 
>> encoded-character vacation subaddress comparator-i;ascii-numeric 
>> relational regex imap4flags copy include variables body enotify 
>> environment mailbox date index ihave duplicate mime foreverypart 
>> extracttext
>> namespace inbox {
>> inbox = yes
>> location >> mailbox Drafts {
>> auto = create
>> special_use = \Drafts
>> }
>> mailbox Junk {
>> auto = create
>> special_use = \Junk
>> }
>> mailbox Sent {
>> auto = create
>> special_use = \Sent
>> }
>> mailbox Trash {
>> auto = create
>> special_use = \Trash
>> }
>> prefix >> separator = /
>> }
>> passdb {
>> args = username_format=%u /usr/local/etc/dovecot/users
>> driver = passwd-file
>> }
>> plugin {
>> sieve = 
>>
file:/usr/local/etc/dovecot/sieve;active=/usr/local/etc/dovecot/sieve/.dovecot.sieve
>> sieve_before = /usr/local/etc/dovecot/sieve/before-global.sieve
>> sieve_default = /usr/local/etc/dovecot/sieve/default.sieve
>> sieve_global = /usr/local/etc/dovecot/sieve/global/
>> }
>> protocols = imap lmtp sieve
>> service auth {
>> unix_listener /var/spool/postfix/private/auth {
>> group = postfix
>> mode = 0666
>> user = postfix
>> }
>> }
>> service lmtp {
>> unix_listener /var/spool/postfix/private/dovecot-lmtp {
>> group = postfix
>> mode = 0660
>> user = postfix
>> }
>> }
>> service managesieve-login {
>> inet_listener sieve {
>> port = 4190
>> }
>> }
>> ssl_cert = 
>> </usr/local/etc/letsencrypt/live/obfuscated.domain/fullchain.pem
>> ssl_cipher_list = HIGH:!aNULL:!AES128:!SSLv2
>> ssl_dh = # hidden, use -P to show it
>> ssl_key = # hidden, use -P to show it
>> syslog_facility = local1
>> userdb {
>> args = username_format=%u /usr/local/etc/dovecot/users
>> driver = passwd-file
>> }
>> protocol lmtp {
>> mail_plugins = " sieve"
>> }
>> protocol sieve {
>> info_log_path = /var/log/dovecot-sieve.log
>> log_path = /var/log/dovecot-sieve-errors.log
>> managesieve_implementation_string = dovecot
>> managesieve_max_line_length = 64 k
>> }
>> 
>> THANKS!
>> 
>> Colin
-------------- next part --------------
An HTML attachment was scrubbed...
URL:
<https://dovecot.org/pipermail/dovecot/attachments/20221123/5e898ebf/attachment-0001.htm>

Yassine Chaouche

2022-Nov-23 19:30 UTC

head link

Can't figure out why managesieve (pigeonhole) can't connect

good. we have established that the problem shouldn't be on dovecot's
side. i suspect roundcube is misconfigured or can't connect for some reason.
I believe someone mentioned SSL and TLS support problem in RC for a specific
version? can you try without? also can you paste RC config?

Yassine.


Le 23 novembre 2022 4:44:23 PM GMT+01:00, colin at colinlikesfood.com a
?crit?:>
>
>thanks for your reply - dovecot and roundcube are on the same server, and i
am able to authenticate both a telnet and an openssl connection with
manageseieve on that port from both the same machine and another machine, so i
think that has to mean managesieve is listening, correct?
>
>On 2022-11-23 04:09, Yassine Chaouche wrote:
>
>> hello Colin,
>> 
>> is anything listening on that port?
>> you might find lsof and netstat helpful.
>> also, if roundcube and dovecot are on different servers, you might want
to check a network cnx to the managesieve port.
>> cnx refused often means nothing is listening or firewall is filtering.
>> 
>> Yassine.
>> 
>> Le 22 novembre 2022 6:03:08 PM GMT+01:00, colin at colinlikesfood.com a
?crit :
>> 
>>> Subject line says it all?  I am using Roundcube, and every time i
click on "filters" i get RC's "unable to connect to
server" message.
>>> 
>>> This might be an SQL error, but i can't figure out how to pull
the relevant logs yet.  Please see below and any advice you have is so very
appreciated
>>> 
>>> SYSLOG:
>>> -------------------
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Connection refused (GET
/index.php?_task=settings&_action=plugin.managesieve)
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to connect to managesieve on obfuscated.domain:4190 in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 221 (GET /index.php?_task=settings&_action=plugin.managesieve)
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Not currently in AUTHORISATION state (GET
/index.php?_task=settings&_action=plugin.managesieve)
>>> Nov 22 10:29:27 mail php[66295]: <keirks4p> PHP Error: Not
currently connected (GET
/index.php?_task=settings&_action=plugin.managesieve)
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error:
Connection refused (GET
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to connect to managesieve on obfuscated.domain:4190 in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 221 (GET
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>>> Nov 22 10:29:31 mail php[66295]: <keirks4p> PHP Error: Not
currently connected (GET
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error:
Connection refused (POST /?_task=settings&_action=plugin.managesieve-save)
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error:
Unable to connect to managesieve on obfuscated.domain:4190 in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 221 (POST /?_task=settings&_action=plugin.managesieve-save)
>>> Nov 22 10:29:43 mail roundcube[71055]: PHP Warning:  Trying to
access array offset on value of type null in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 1065
>>> Nov 22 10:29:43 mail php[71055]: <keirks4p> PHP Error: Not
currently connected (POST /?_task=settings&_action=plugin.managesieve-save)
>>> 
>>> DOVECOT.LOG:
>>> -------------------
>>> Nov 22 10:28:58 mail roundcube[66297]: <keirks4p> [43CD] C:
A0008 LOGOUT
>>> Nov 22 10:28:58 mail dovecot[8514]: imap(obfuscated at
user.name)<8609><XgBCqxHusXAKAAAB>: Disconnected: Logged out in=219
out=1045 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0
body_bytes=0
>>> Nov 22 10:28:58 mail roundcube[66297]: <keirks4p> [43CD] S: *
BYE Logging out
>>> Nov 22 10:28:58 mail roundcube[66297]: <keirks4p> [43CD] S:
A0008 OK Logout completed (0.001 + 0.000 secs).
>>> Nov 22 10:29:25 mail roundcube[66295]: <keirks4p> [1] SELECT
"vars", "ip", "changed", datetime('now')
AS ts, CASE WHEN "changed" < datetime('now', '-600
seconds') THEN 1 ELSE 0 END AS expired FROM "session" WHERE
"sess_id" = 'keirks4pbepr17um9mvj1qsvt2';
>>> Nov 22 10:29:25 mail roundcube[66295]: <keirks4p> [2] SELECT
* FROM "users" WHERE "user_id" = '2';
>>> Nov 22 10:29:25 mail roundcube[66295]: <keirks4p> [3] UPDATE
"session" SET "changed" = datetime('now'),
"vars" =
>'bGFuZ3VhZ2V8czo1OiJlbl9VUyI7aW1hcF9uYW1lc3BhY2V8YTo0OntzOjg6InBlcnNvbmFsIjthOjE6e2k6MDthOjI6e2k6MDtzOjA6IiI7aToxO3M6MToiLyI7fX1zOjU6Im90aGVyIjtOO3M6Njoic2hhcmVkIjtOO3M6MTA6InByZWZpeF9vdXQiO3M6MDoiIjt9aW1hcF9kZWxpbWl0ZXJ8czoxOiIvIjtpbWFwX2xpc3RfY29uZnxhOjI6e2k6MDtOO2k6MTthOjA6e319dXNlcl9pZHxpOjI7dXNlcm5hbWV8czoyNDoiY29saW5AY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX3BvcnR8aToxNDM7c3RvcmFnZV9zc2x8czozOiJ0bHMiO3Bhc3N3b3JkfHM6NDQ6ImNYOCt0VmJXV010VEMxRTlXQVB4ZjVOSlU1bWlKa2ZveC8xRWpuZVB2Ym89Ijtsb2dpbl90aW1lfGk6MTY2OTA2MzM4OTt0aW1lem9uZXxzOjE1OiJBbWVyaWNhL0NoaWNhZ28iO2F1dGhfc2VjcmV0fHM6MjY6ImNHdkc4ZWR5QmFmNWJITVpISG5sTEY3ZmpZIjtyZXF1ZXN0X3Rva2VufHM6MzI6IkM5VXlNNVBMUnhSeERiVm9HTlNMNjdVZ05NaWE4WVZGIjt0YXNrfHM6ODoic2V0dGluZ3MiO3NraW5fY29uZmlnfGE6Nzp7czoxNzoic3VwcG9ydGVkX2xheW91dHMiO2E6MTp7aTowO3M6MTA6IndpZGVzY3JlZW4iO31zOjIyOiJqcXVlcnlfdWlfY29sb3JzX3RoZW1lIjtzOjk6ImJvb3RzdHJhcCI7czoxODoiZW1iZWRfY3NzX2xvY2F0aW9uIjtzOjE3OiIvc3R5bGVzL2VtYmVkLmNzcyI7c
>zoxOToiZWRpdG9yX2Nzc19sb2NhdGlvbiI7czoxNzoiL3N0eWxlcy9lbWJlZC5jc3MiO3M6MTc6ImRhcmtfbW9kZV9zdXBwb3J0IjtiOjE7czoyNjoibWVkaWFfYnJvd3Nlcl9jc3NfbG9jYXRpb24iO3M6NDoibm9uZSI7czoyMToiYWRkaXRpb25hbF9sb2dvX3R5cGVzIjthOjM6e2k6MDtzOjQ6ImRhcmsiO2k6MTtzOjU6InNtYWxsIjtpOjI7czoxMDoic21hbGwtZGFyayI7fX1pbWFwX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjttYm94fHM6NToiSU5CT1giO3NvcnRfY29sfHM6MDoiIjtzb3J0X29yZGVyfHM6NDoiREVTQyI7U1RPUkFHRV9USFJFQUR8YTozOntpOjA7czoxMDoiUkVGRVJFTkNFUyI7aToxO3M6NDoiUkVGUyI7aToyO3M6MTQ6Ik9SREVSRURTVUJKRUNUIjt9U1RPUkFHRV9RVU9UQXxiOjA7U1RPUkFHRV9MSVNULUVYVEVOREVEfGI6MTtsaXN0X2F0dHJpYnxhOjc6e3M6NDoibmFtZSI7czo4OiJtZXNzYWdlcyI7czoyOiJpZCI7czoxMToibWVzc2FnZWxpc3QiO3M6NToiY2xhc3MiO3M6NDI6Imxpc3RpbmcgbWVzc2FnZWxpc3Qgc29ydGhlYWRlciBmaXhlZGhlYWRlciI7czoxNToiYXJpYS1sYWJlbGxlZGJ5IjtzOjIyOiJhcmlhLWxhYmVsLW1lc3NhZ2VsaXN0IjtzOjk6ImRhdGEtbGlzdCI7czoxMjoibWVzc2FnZV9saXN0IjtzOjE0OiJkYXRhLWxhYmVsLW1zZyI7czoxODoi
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> [1] SELECT
"vars", "ip", "changed", datetime('now')
AS ts, CASE WHEN "changed" < datetime('now', '-600
seconds') THEN 1 ELSE 0 END AS expired FROM "session" WHERE
"sess_id" = 'keirks4pbepr17um9mvj1qsvt2';
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> [2] SELECT
* FROM "users" WHERE "user_id" = '2';
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Connection refused (GET
/index.php?_task=settings&_action=plugin.managesieve)
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to connect to managesieve on obfuscated.domain:4190 in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 221 (GET /index.php?_task=settings&_action=plugin.managesieve)
>>> Nov 22 10:29:27 mail roundcube[66295]: <keirks4p> PHP Error:
Not currently in AUTHORISATION state (GET
/index.php?_task=settings&_action=plugin.managesieve)
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [1] SELECT
"vars", "ip", "changed", datetime('now')
AS ts, CASE WHEN "changed" < datetime('now', '-600
seconds') THEN 1 ELSE 0 END AS expired FROM "session" WHERE
"sess_id" = 'keirks4pbepr17um9mvj1qsvt2';
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [2] SELECT
* FROM "users" WHERE "user_id" = '2';
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error:
Connection refused (GET
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> PHP Error:
Unable to connect to managesieve on obfuscated.domain:4190 in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 221 (GET
/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide)
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5]
Connecting to tls://obfuscated.domain:143...
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+
STARTTLS LOGINDISABLED AUTH=CRAM-MD5] Dovecot ready.
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C:
A0001 STARTTLS
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S:
A0001 OK Begin TLS negotiation now.
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C:
A0002 CAPABILITY
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+
LOGINDISABLED AUTH=CRAM-MD5
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S:
A0002 OK Pre-login capabilities listed, post-login capabilities have more.
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C:
A0003 AUTHENTICATE CRAM-MD5
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: +
PDI3MjU4Mjg1ODUxMTkzMTEuMTY2OTEzNDU3MUBtYWlsPg=>>> Nov 22 10:29:31 mail
roundcube[66295]: <keirks4p> [7FB5] C: ****** [74]
>>> Nov 22 10:29:31 mail dovecot[8514]: imap-login: Login:
user=<obfuscated at user.name>, method=CRAM-MD5, rip=10.0.0.1,
lip=10.0.0.91, mpid=8639, TLS, session=<51k6rRHuw5gKAAAB>
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S:
A0003 OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT
SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND
URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED
I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH
LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE
LITERAL+ NOTIFY SPECIAL-USE] Logged in
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C:
A0004 ID ("name" "Roundcube" "version"
"1.6.0" "php" "8.0.25" "os"
"FreeBSD" "command"
"/index.php?_task=settings&_action=plugin.managesieve-action&_framed=1&_nav=hide")
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
ID ("name" "Dovecot")
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S:
A0004 OK ID completed (0.001 + 0.000 secs).
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C:
A0005 LIST (SUBSCRIBED) "" "*"
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST (\Subscribed) "/" Drafts
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST (\Subscribed) "/" Junk
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST (\Subscribed) "/" Sent
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST (\Subscribed) "/" Trash
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST (\Subscribed) "/" INBOX/NAS-Alerts
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
LIST (\Subscribed) "/" INBOX/email-reports
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S:
A0005 OK List completed (0.001 + 0.000 secs).
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [3] UPDATE
"session" SET "changed" = datetime('now'),
"vars" =
>'bGFuZ3VhZ2V8czo1OiJlbl9VUyI7aW1hcF9uYW1lc3BhY2V8YTo0OntzOjg6InBlcnNvbmFsIjthOjE6e2k6MDthOjI6e2k6MDtzOjA6IiI7aToxO3M6MToiLyI7fX1zOjU6Im90aGVyIjtOO3M6Njoic2hhcmVkIjtOO3M6MTA6InByZWZpeF9vdXQiO3M6MDoiIjt9aW1hcF9kZWxpbWl0ZXJ8czoxOiIvIjtpbWFwX2xpc3RfY29uZnxhOjI6e2k6MDtOO2k6MTthOjA6e319dXNlcl9pZHxpOjI7dXNlcm5hbWV8czoyNDoiY29saW5AY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjtzdG9yYWdlX3BvcnR8aToxNDM7c3RvcmFnZV9zc2x8czozOiJ0bHMiO3Bhc3N3b3JkfHM6NDQ6ImNYOCt0VmJXV010VEMxRTlXQVB4ZjVOSlU1bWlKa2ZveC8xRWpuZVB2Ym89Ijtsb2dpbl90aW1lfGk6MTY2OTA2MzM4OTt0aW1lem9uZXxzOjE1OiJBbWVyaWNhL0NoaWNhZ28iO2F1dGhfc2VjcmV0fHM6MjY6ImNHdkc4ZWR5QmFmNWJITVpISG5sTEY3ZmpZIjtyZXF1ZXN0X3Rva2VufHM6MzI6IkM5VXlNNVBMUnhSeERiVm9HTlNMNjdVZ05NaWE4WVZGIjt0YXNrfHM6ODoic2V0dGluZ3MiO3NraW5fY29uZmlnfGE6Nzp7czoxNzoic3VwcG9ydGVkX2xheW91dHMiO2E6MTp7aTowO3M6MTA6IndpZGVzY3JlZW4iO31zOjIyOiJqcXVlcnlfdWlfY29sb3JzX3RoZW1lIjtzOjk6ImJvb3RzdHJhcCI7czoxODoiZW1iZWRfY3NzX2xvY2F0aW9uIjtzOjE3OiIvc3R5bGVzL2VtYmVkLmNzcyI7c
>zoxOToiZWRpdG9yX2Nzc19sb2NhdGlvbiI7czoxNzoiL3N0eWxlcy9lbWJlZC5jc3MiO3M6MTc6ImRhcmtfbW9kZV9zdXBwb3J0IjtiOjE7czoyNjoibWVkaWFfYnJvd3Nlcl9jc3NfbG9jYXRpb24iO3M6NDoibm9uZSI7czoyMToiYWRkaXRpb25hbF9sb2dvX3R5cGVzIjthOjM6e2k6MDtzOjQ6ImRhcmsiO2k6MTtzOjU6InNtYWxsIjtpOjI7czoxMDoic21hbGwtZGFyayI7fX1pbWFwX2hvc3R8czoxODoiY29saW5saWtlc2Zvb2QuY29tIjttYm94fHM6NToiSU5CT1giO3NvcnRfY29sfHM6MDoiIjtzb3J0X29yZGVyfHM6NDoiREVTQyI7U1RPUkFHRV9USFJFQUR8YTozOntpOjA7czoxMDoiUkVGRVJFTkNFUyI7aToxO3M6NDoiUkVGUyI7aToyO3M6MTQ6Ik9SREVSRURTVUJKRUNUIjt9U1RPUkFHRV9RVU9UQXxiOjA7U1RPUkFHRV9MSVNULUVYVEVOREVEfGI6MTtsaXN0X2F0dHJpYnxhOjc6e3M6NDoibmFtZSI7czo4OiJtZXNzYWdlcyI7czoyOiJpZCI7czoxMToibWVzc2FnZWxpc3QiO3M6NToiY2xhc3MiO3M6NDI6Imxpc3RpbmcgbWVzc2FnZWxpc3Qgc29ydGhlYWRlciBmaXhlZGhlYWRlciI7czoxNToiYXJpYS1sYWJlbGxlZGJ5IjtzOjIyOiJhcmlhLWxhYmVsLW1lc3NhZ2VsaXN0IjtzOjk6ImRhdGEtbGlzdCI7czoxMjoibWVzc2FnZV9saXN0IjtzOjE0OiJkYXRhLWxhYmVsLW1zZyI7czoxODoi
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] C:
A0006 LOGOUT
>>> Nov 22 10:29:31 mail dovecot[8514]: imap(obfuscated at
user.name)<8639><51k6rRHuw5gKAAAB>: Disconnected: Logged out in=215
out=821 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0
body_bytes=0
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S: *
BYE Logging out
>>> Nov 22 10:29:31 mail roundcube[66295]: <keirks4p> [7FB5] S:
A0006 OK Logout completed (0.001 + 0.000 secs).
>>> Nov 22 10:29:42 mail roundcube[71055]: <keirks4p> [1] SELECT
"vars", "ip", "changed", datetime('now')
AS ts, CASE WHEN "changed" < datetime('now', '-600
seconds') THEN 1 ELSE 0 END AS expired FROM "session" WHERE
"sess_id" = 'keirks4pbepr17um9mvj1qsvt2';
>>> Nov 22 10:29:42 mail roundcube[71055]: <keirks4p> [2] SELECT
* FROM "users" WHERE "user_id" = '2';
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error:
Connection refused (POST /?_task=settings&_action=plugin.managesieve-save)
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> PHP Error:
Unable to connect to managesieve on obfuscated.domain:4190 in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 221 (POST /?_task=settings&_action=plugin.managesieve-save)
>>> Nov 22 10:29:43 mail roundcube[71055]: PHP Warning:  Trying to
access array offset on value of type null in
/usr/local/www/roundcube/plugins/managesieve/lib/Roundcube/rcube_sieve_engine.php
on line 1065
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90]
Connecting to tls://obfuscated.domain:143...
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+
STARTTLS LOGINDISABLED AUTH=CRAM-MD5] Dovecot ready.
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C:
A0001 STARTTLS
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S:
A0001 OK Begin TLS negotiation now.
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C:
A0002 CAPABILITY
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+
LOGINDISABLED AUTH=CRAM-MD5
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S:
A0002 OK Pre-login capabilities listed, post-login capabilities have more.
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C:
A0003 AUTHENTICATE CRAM-MD5
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: +
PDE2NjcxMDUwNTU2NDcwMTMuMTY2OTEzNDU4M0BtYWlsPg=>>> Nov 22 10:29:43 mail
roundcube[71055]: <keirks4p> [CD90] C: ****** [74]
>>> Nov 22 10:29:43 mail dovecot[8514]: imap-login: Login:
user=<obfuscated at user.name>, method=CRAM-MD5, rip=10.0.0.1,
lip=10.0.0.91, mpid=8644, TLS, session=<677prRHu4aAKAAAB>
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S:
A0003 OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT
SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND
URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED
I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH
LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE
LITERAL+ NOTIFY SPECIAL-USE] Logged in
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C:
A0004 ID ("name" "Roundcube" "version"
"1.6.0" "php" "8.0.25" "os"
"FreeBSD" "command" "/")
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
ID ("name" "Dovecot")
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S:
A0004 OK ID completed (0.001 + 0.000 secs).
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C:
A0005 LIST (SUBSCRIBED) "" "*"
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST (\Subscribed) "/" Drafts
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST (\Subscribed) "/" Junk
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST (\Subscribed) "/" Sent
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST (\Subscribed) "/" Trash
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST (\Subscribed) "/" INBOX/NAS-Alerts
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
LIST (\Subscribed) "/" INBOX/email-reports
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S:
A0005 OK List completed (0.001 + 0.000 secs).
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] C:
A0006 LOGOUT
>>> Nov 22 10:29:43 mail dovecot[8514]: imap(obfuscated at
user.name)<8644><677prRHu4aAKAAAB>: Disconnected: Logged out in=137
out=821 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0
body_bytes=0
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S: *
BYE Logging out
>>> Nov 22 10:29:43 mail roundcube[71055]: <keirks4p> [CD90] S:
A0006 OK Logout completed (0.001 + 0.000 secs).
>>> 
>>> DOVECOT INFO:
>>> ------------------------
>>> # dovecot --version
>>> 2.3.19.1 (9b53102964)
>>> 
>>> # dovecot -n
>>> # 2.3.19.1 (9b53102964): /usr/local/etc/dovecot/dovecot.conf
>>> # Pigeonhole version 0.5.19 (4eae2f79)
>>> # OS: FreeBSD 13.1-RELEASE-p2 amd64  zfs
>>> # Hostname: localhost
>>> auth_mechanisms = cram-md5
>>> first_valid_gid = 10000
>>> first_valid_uid = 10000
>>> mail_location = maildir:/var/mail/users/%u
>>> managesieve_notify_capability = mailto
>>> managesieve_sieve_capability = fileinto reject envelope
encoded-character vacation subaddress comparator-i;ascii-numeric relational
regex imap4flags copy include variables body enotify environment mailbox date
index ihave duplicate mime foreverypart extracttext
>>> namespace inbox {
>>> inbox = yes
>>> location >>> mailbox Drafts {
>>> auto = create
>>> special_use = \Drafts
>>> }
>>> mailbox Junk {
>>> auto = create
>>> special_use = \Junk
>>> }
>>> mailbox Sent {
>>> auto = create
>>> special_use = \Sent
>>> }
>>> mailbox Trash {
>>> auto = create
>>> special_use = \Trash
>>> }
>>> prefix >>> separator = /
>>> }
>>> passdb {
>>> args = username_format=%u /usr/local/etc/dovecot/users
>>> driver = passwd-file
>>> }
>>> plugin {
>>> sieve =
file:/usr/local/etc/dovecot/sieve;active=/usr/local/etc/dovecot/sieve/.dovecot.sieve
>>> sieve_before = /usr/local/etc/dovecot/sieve/before-global.sieve
>>> sieve_default = /usr/local/etc/dovecot/sieve/default.sieve
>>> sieve_global = /usr/local/etc/dovecot/sieve/global/
>>> }
>>> protocols = imap lmtp sieve
>>> service auth {
>>> unix_listener /var/spool/postfix/private/auth {
>>> group = postfix
>>> mode = 0666
>>> user = postfix
>>> }
>>> }
>>> service lmtp {
>>> unix_listener /var/spool/postfix/private/dovecot-lmtp {
>>> group = postfix
>>> mode = 0660
>>> user = postfix
>>> }
>>> }
>>> service managesieve-login {
>>> inet_listener sieve {
>>> port = 4190
>>> }
>>> }
>>> ssl_cert =
</usr/local/etc/letsencrypt/live/obfuscated.domain/fullchain.pem
>>> ssl_cipher_list = HIGH:!aNULL:!AES128:!SSLv2
>>> ssl_dh = # hidden, use -P to show it
>>> ssl_key = # hidden, use -P to show it
>>> syslog_facility = local1
>>> userdb {
>>> args = username_format=%u /usr/local/etc/dovecot/users
>>> driver = passwd-file
>>> }
>>> protocol lmtp {
>>> mail_plugins = " sieve"
>>> }
>>> protocol sieve {
>>> info_log_path = /var/log/dovecot-sieve.log
>>> log_path = /var/log/dovecot-sieve-errors.log
>>> managesieve_implementation_string = dovecot
>>> managesieve_max_line_length = 64 k
>>> }
>>> 
>>> THANKS!
>>> 
>>> Colin
>-------------- next part --------------
An HTML attachment was scrubbed...
URL:
<https://dovecot.org/pipermail/dovecot/attachments/20221123/922e6b0d/attachment-0001.htm>

Yassine Chaouche

2022-Nov-23 19:35 UTC

head link

Can't figure out why managesieve (pigeonhole) can't connect

also make sure your are editing config.php and not config.inc.php (which you
pasted)

Yassine.

Le 23 novembre 2022 8:30:36 PM GMT+01:00, Yassine Chaouche <a.chaouche at
algerian-radio.dz> a ?crit?:>good. we have established that the problem shouldn't be on dovecot's
side. i suspect roundcube is misconfigured or can't connect for some reason.
I believe someone mentioned SSL and TLS support problem in RC for a specific
version? can you try without? also can you paste RC config?
>
>Yassine.
>

dovecot - Nov 2022 - Can't figure out why managesieve (pigeonhole) can't connect

Can't figure out why managesieve (pigeonhole) can't connect

Can't figure out why managesieve (pigeonhole) can't connect

Can't figure out why managesieve (pigeonhole) can't connect