Topic: Updated SOGo and Prefrences in SOGo webmail stopped working
==== Required information ====
- iRedMail version (check /etc/iredmail-release): 0.9.5-1
- Linux/BSD distribution name and version: CentOS Linux release 7.2.1511 (Core)
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): LDAP
- Web server (Apache or Nginx): Apache
- Manage mail accounts with iRedAdmin-Pro? : Yes
- Related log if you're reporting an issue: Dovecot, Sogo
====
I am facing strange issue where I updated SOGo to new 3.2.0 version from 3.1.5. Now Preferences tab on SOGo webmail is not working. Neither any check boxes are being clicked nor any values we modify is being set.
------------- SOGO LOG -------------
Oct 12 04:11:02 sogod [4947]: <0x0x7fdd9af19b80[WOHttpAdaptor]> notified the watchdog that we are ready
Oct 12 04:11:11 sogod [4947]: |SOGo| starting method 'POST' on uri '/SOGo/so/postmaster@mydomain.co.uk/Preferences/save'
Oct 12 04:11:11 sogod [4947]: <0x0x7fdd9aec4ba0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Oct 12 04:11:11 sogod [4947]: <0x0x7fdd9aec4ba0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Oct 12 04:11:11 sogod [4947]: |SOGo| request took 0.372338 seconds to execute
Oct 12 04:11:11 sogod [4947]: localhost "POST /SOGo/so/postmaster@mydomain.co.uk/Preferences/save HTTP/1.0" 200 0/4093 0.378 - - 5M
Oct 12 04:11:23 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@mydomain.co.uk/logoff'
Oct 12 04:11:23 sogod [4947]: SOGoUserHomePage user 'postmaster@mydomain.co.uk' logged off
Oct 12 04:11:23 sogod [4947]: |SOGo| request took 0.007243 seconds to execute
Oct 12 04:11:23 sogod [4947]: localhost "GET /SOGo/so/postmaster@mydomain.co.uk/logoff HTTP/1.0" 302 0/0 0.009 - - 8K
Oct 12 04:11:24 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/'
Oct 12 04:11:24 sogod [4947]: |SOGo| request took 0.053087 seconds to execute
Oct 12 04:11:24 sogod [4947]: localhost "GET /SOGo/so/ HTTP/1.0" 200 6767/0 0.055 25336 73% 580K
Oct 12 04:11:40 sogod [4947]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
Oct 12 04:11:40 sogod [4947]: SOGoRootPage successful login from 'localhost' for user 'postmaster@mydomain.co.uk' - expire = -1 grace = -1
Oct 12 04:11:40 sogod [4947]: [WARN] <0x0x7fdd9b1e8710[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Oct 12 04:11:40 sogod [4947]: [WARN] <0x0x7fdd9b1e8710[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Oct 12 04:11:40 sogod [4947]: [WARN] <0x0x7fdd9b1e8710[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Oct 12 04:11:40 sogod [4947]: |SOGo| request took 0.011428 seconds to execute
Oct 12 04:11:40 sogod [4947]: localhost "POST /SOGo/connect HTTP/1.0" 200 48/87 0.013 - - 144K
Oct 12 04:11:41 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/SOGo/postmaster%40mydomain.co.uk'
Oct 12 04:11:41 sogod [4947]: |SOGo| request took 0.002736 seconds to execute
Oct 12 04:11:41 sogod [4947]: localhost "GET /SOGo/so/SOGo/postmaster%40mydomain.co.uk HTTP/1.0" 302 0/0 0.004 - - 0
Oct 12 04:11:41 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/SOGo/postmaster%40mydomain.co.uk/view'
Oct 12 04:11:41 sogod [4947]: |SOGo| request took 0.003919 seconds to execute
Oct 12 04:11:41 sogod [4947]: localhost "GET /SOGo/so/SOGo/postmaster%40mydomain.co.uk/view HTTP/1.0" 302 0/0 0.005 - - 0
Oct 12 04:11:41 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@mydomain.co.uk/Mail'
Oct 12 04:11:41 sogod [4947]: |SOGo| request took 0.002983 seconds to execute
Oct 12 04:11:41 sogod [4947]: localhost "GET /SOGo/so/postmaster@mydomain.co.uk/Mail HTTP/1.0" 302 0/0 0.004 - - 0
Oct 12 04:11:41 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@mydomain.co.uk/Mail/view'
Oct 12 04:11:41 sogod [4947]: |SOGo| request took 0.091178 seconds to execute
Oct 12 04:11:41 sogod [4947]: localhost "GET /SOGo/so/postmaster@mydomain.co.uk/Mail/view HTTP/1.0" 200 15872/0 0.094 70643 77% 1M
Oct 12 04:11:42 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@mydomain.co.uk/Calendar/alarmslist?browserTime=1476259893'
Oct 12 04:11:42 sogod [4947]: |SOGo| request took 0.010219 seconds to execute
Oct 12 04:11:42 sogod [4947]: localhost "GET /SOGo/so/postmaster@mydomain.co.uk/Calendar/alarmslist?browserTime=1476259893 HTTP/1.0" 200 63/0 0.012 - - 28K
Oct 12 04:11:42 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@mydomain.co.uk/jsonDefaults'
Oct 12 04:11:42 sogod [4945]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@mydomain.co.uk/jsonSettings'
Oct 12 04:11:42 sogod [4945]: <0x0x7fdd9aacf250[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Oct 12 04:11:42 sogod [4945]: <0x0x7fdd9aacf250[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Oct 12 04:11:42 sogod [4945]: |SOGo| request took 0.007575 seconds to execute
Oct 12 04:11:42 sogod [4945]: localhost "GET /SOGo/so/postmaster@mydomain.co.uk/jsonSettings HTTP/1.0" 200 338/0 0.011 - - 1M
Oct 12 04:11:42 sogod [4947]: |SOGo| request took 0.022905 seconds to execute
Oct 12 04:11:42 sogod [4947]: localhost "GET /SOGo/so/postmaster@mydomain.co.uk/jsonDefaults HTTP/1.0" 200 4022/0 0.024 - - 0
Oct 12 04:11:43 sogod [4947]: |SOGo| starting method 'GET' on uri '/SOGo/so/postmaster@mydomain.co.uk/Mail/0/view'
Oct 12 04:11:43 sogod [4947]: |SOGo| request took 0.258087 seconds to execute
Oct 12 04:11:43 sogod [4947]: localhost "GET /SOGo/so/postmaster@mydomain.co.uk/Mail/0/view HTTP/1.0" 200 802/0 0.259 - - 0
Oct 12 04:11:43 sogod [4947]: |SOGo| starting method 'POST' on uri '/SOGo/so/postmaster@mydomain.co.uk/Mail/unseenCount'
Oct 12 04:11:43 sogod [4945]: |SOGo| starting method 'POST' on uri '/SOGo/so/postmaster@mydomain.co.uk/Mail/0/folderINBOX/view'
S[0x7fdd9b2bd1d0]: "IMPLEMENTATION" "Dovecot Pigeonhole"
S[0x7fdd9b2bd1d0]: "SIEVE" "fileinto reject envelope encoded-character vacation subaddress comparator-i;ascii-numeric relational regex imap4flags copy include variables body enotify environment mailbox date ihave"
S[0x7fdd9b2bd1d0]: "NOTIFY" "mailto"
S[0x7fdd9b2bd1d0]: "SASL" "PLAIN LOGIN"
S[0x7fdd9b2bd1d0]: "STARTTLS"
S[0x7fdd9b2bd1d0]: "VERSION" "1.0"
S[0x7fdd9b2bd1d0]: OK "Dovecot ready."
C: AUTHENTICATE "PLAIN" {%d+}
LOGIN:PASSWORD
S[0x7fdd9b2bd1d0]: OK "Logged in."
S[0x7fdd9b2c7fa0]: "IMPLEMENTATION" "Dovecot Pigeonhole"
S[0x7fdd9b2c7fa0]: "SIEVE" "fileinto reject envelope encoded-character vacation subaddress comparator-i;ascii-numeric relational regex imap4flags copy include variables body enotify environment mailbox date ihave"
S[0x7fdd9b2c7fa0]: "NOTIFY" "mailto"
S[0x7fdd9b2c7fa0]: "SASL" "PLAIN LOGIN"
S[0x7fdd9b2c7fa0]: "STARTTLS"
S[0x7fdd9b2c7fa0]: "VERSION" "1.0"
S[0x7fdd9b2c7fa0]: OK "Dovecot ready."
C: AUTHENTICATE "PLAIN" {%d+}
LOGIN:PASSWORD
------------- Dovecot LOG -------------
Dovecot log shows only below entries about sieve which seems fine:
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: shared: root=/var/run/dovecot, index=, indexpvt=, control=, inbox=, alt=
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: acl: initializing backend with data: vfile
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: acl: acl username = postmaster@mydomain.co.uk
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: acl: owner = 0
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: acl vfile: Global ACL directory: (none)
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: sieve: Pigeonhole version 0.4.2 initializing
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: sieve-storage: using active sieve script path: /var/vmail/vmail1/mydomain.co.uk/p/o/s/postmaster-2014.01.03.19.05.33//sieve/dovecot.sieve
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: sieve-storage: using sieve script storage directory: /var/vmail/vmail1/mydomain.co.uk/p/o/s/postmaster-2014.01.03.19.05.33//sieve
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: sieve-storage: using permissions from /var/vmail/vmail1/mydomain.co.uk/p/o/s/postmaster-2014.01.03.19.05.33//sieve: mode=0700 gid=-1
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: sieve-storage: relative path to sieve storage in active link:
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Debug: sieve-storage: nothing to rescue /var/vmail/vmail1/mydomain.co.uk/p/o/s/postmaster-2014.01.03.19.05.33//sieve/dovecot.sieve.
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Info: Connection closed bytes=35/87
Oct 12 09:25:10 managesieve(postmaster@mydomain.co.uk): Info: Connection closed bytes=0/17
----
Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.