Hello there!
Hope you're doing well.
I'm trying to setup SOGo for the first time. And now I'm stuck with no
visible reasons for it.
Would somebody please show me the right way?
Symptoms are: when I'm trying to log on to SOGo under any username
('sogoadm' for example) using 'https://kntest3.ldvtest.org.ua/SOGo'
link, this is happened:
1. it redirects me to '/SOGo/<username>', where <username> is that what
I'm trying to log on with. And it still shows me SOGo white-and-green
login page.
2. If I try to login again (now with
'https://kntest3.ldvtest.org.ua/SOGo/sogoadm' URL) it redirects me
again. New URL is 'https://kntest3.ldvtest.org.ua/SOGo/so/sogoadm'.
3. If I'll still try to login it will silently redirect me on what last
page again and again, i.g. URL will now always
'https://kntest3.ldvtest.org.ua/SOGo/so/sogoadm' and nothing more happenes.
I tried number of configuration settings but all the same. Tried
different logins too.
Server (every package is up to date):
OS: Ubuntu 18.04;
SOGo: 4.0.5.20190219-1 (same was on 4.0.5.20190218....);
DB: PostgreSQL 10+190;
LDAP: slapd 2.4.45+dfsg-1ubuntu1.1 + Fusion Directory
1.0.19-1ubuntu0.18.04.1;
web: Apache2 2.4.29-1ubuntu4.5.
Here is SOGo's logfile with debug turned on:
--- 8<
-------------------------------------------------------------------------------------------
Feb 19 19:42:19 sogod [2411]: |SOGo| starting method 'POST' on uri
'/SOGo/connect'
Feb 19 19:42:19 sogod [2411]: <0x0x55dfe8718ec0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
Feb 19 19:42:19 sogod [2411]: SOGoRootPage successful login from
'194.187.110.158' for user 'sogoadm' - expire = -1 grace = -1
2019-02-19 19:42:19.108 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:19 sogod [2411]: <0x0x55dfe863c050[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:19.112 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
2019-02-19 19:42:19.120 sogod[2411:2411] PostgreSQL72 connection
established: <0x0x55dfe86b2b70[PGConnection]: connection=0x0x55dfe8865080>
2019-02-19 19:42:19.120 sogod[2411:2411] PostgreSQL72 channel
0x0x55dfe87603d0 opened (connection=<0x0x55dfe86b2b70[PGConnection]:
connection=0x0x55dfe8865080>)
2019-02-19 19:42:19.126 sogod[2411:2411] PostgreSQL72 connection
established: <0x0x55dfe8792b70[PGConnection]: connection=0x0x55dfe8864c20>
2019-02-19 19:42:19.126 sogod[2411:2411] PostgreSQL72 channel
0x0x55dfe8814740 opened (connection=<0x0x55dfe8792b70[PGConnection]:
connection=0x0x55dfe8864c20>)
2019-02-19 19:42:19.126 sogod[2411:2411] PG0x0x55dfe8814740 SQL: BEGIN
TRANSACTION
2019-02-19 19:42:19.126 sogod[2411:2411] PG0x0x55dfe8814740 SQL: SELECT
t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM
sogo_sessions_folder t1 WHERE t1.c_id='bbr5Kbvmmro81wGgKNP0sA=='
2019-02-19 19:42:19.128 sogod[2411:2411] PG0x0x55dfe8814740 SQL:
ROLLBACK TRANSACTION
2019-02-19 19:42:19.129 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: BEGIN
TRANSACTION
2019-02-19 19:42:19.129 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: INSERT
INTO sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id)
VALUES (1550601739, 1550601739,
'knkGNdkUTkLAvndhdndKmaPQQ7AIHJJAmGoUFsHi89EyZfCRgWJG+MbQCcpzbL/9Pecx9e+GeLiuZg9HmxQDq0vVLrDaUw9+CZuAJhHFqCELDBpSfHounkPO1k43fZ4pXqg+N1ppJqX4NT6Xg7xeLNWyGSKXsJqKZmnVX7PVkYQln5Gk38bXQInDV7dl4HzdEjRtmDjjeJQRvaDGfs0Yfw==',
'bbr5Kbvmmro81wGgKNP0sA==')
2019-02-19 19:42:19.130 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: COMMIT
TRANSACTION
Feb 19 19:42:19 sogod [2411]: |SOGo| request took 0.027147 seconds to
execute
Feb 19 19:42:19 sogod [2411]: 194.187.110.158 "POST /SOGo/connect
HTTP/1.1" 200 2/86 0.032 - - 12K
Feb 19 19:42:20 sogod [2411]: |SOGo| starting method 'GET' on uri
'/SOGo/so/sogoadm'
Feb 19 19:42:20 sogod [2411]: <0x0x55dfe8657190[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:20.187 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:20 sogod [2411]: <0x0x55dfe8693f60[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:20.191 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
Feb 19 19:42:20 sogod [2411]: <0x0x55dfe8816130[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:20.193 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:20 sogod [2411]: <0x0x55dfe875e070[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:20.197 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
Feb 19 19:42:20 sogod [2411]: |SOGo| request took 0.024298 seconds to
execute
Feb 19 19:42:20 sogod [2411]: 194.187.110.158 "GET /SOGo/so/sogoadm
HTTP/1.1" 200 27032/0 0.028 - - 0
Feb 19 19:42:33 sogod [2411]: |SOGo| starting method 'POST' on uri
'/SOGo/connect'
Feb 19 19:42:33 sogod [2411]: <0x0x55dfe872aec0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
Feb 19 19:42:33 sogod [2411]: SOGoRootPage successful login from
'194.187.110.158' for user 'sogoadm' - expire = -1 grace = -1
2019-02-19 19:42:33.113 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:33 sogod [2411]: <0x0x55dfe8636fa0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:33.119 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
2019-02-19 19:42:33.120 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: BEGIN
TRANSACTION
2019-02-19 19:42:33.121 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: SELECT
t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM
sogo_sessions_folder t1 WHERE t1.c_id='iSERVVzsnqgj2tfH3K/atA=='
2019-02-19 19:42:33.124 sogod[2411:2411] PG0x0x55dfe87603d0 SQL:
ROLLBACK TRANSACTION
2019-02-19 19:42:33.125 sogod[2411:2411] PG0x0x55dfe8814740 SQL: BEGIN
TRANSACTION
2019-02-19 19:42:33.125 sogod[2411:2411] PG0x0x55dfe8814740 SQL: INSERT
INTO sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id)
VALUES (1550601753, 1550601753,
'/LMtcj080LFMPqL+RcRQ+gqUUvNwDB5vpY2dPO8IoEEFhLHTkcecga7+agNin6MfquKoEJfdpaxqkPSsv2FH+K5KIEew55/DHBOu0lH3FgU9RxUgUaV+ncTOcW39gnj0M2QRFAPGGPUAmYCI1bfWMXM4jtsn9KrTiJx0O4HuHQB9L/IhYuCwsQ5jXLI74vjjo4xjAmn6vFcq2uu/WNzviQ==',
'iSERVVzsnqgj2tfH3K/atA==')
2019-02-19 19:42:33.126 sogod[2411:2411] PG0x0x55dfe8814740 SQL: COMMIT
TRANSACTION
Feb 19 19:42:33 sogod [2411]: |SOGo| request took 0.018063 seconds to
execute
Feb 19 19:42:33 sogod [2411]: 194.187.110.158 "POST /SOGo/connect
HTTP/1.1" 200 2/86 0.023 - - 0
Feb 19 19:42:34 sogod [2411]: |SOGo| starting method 'GET' on uri
'/SOGo/so/sogoadm'
Feb 19 19:42:34 sogod [2411]: <0x0x55dfe87f8740[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:34.267 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:34 sogod [2411]: <0x0x55dfe86508e0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:34.271 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
Feb 19 19:42:34 sogod [2411]: <0x0x55dfe86c2f00[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:34.272 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:34 sogod [2411]: <0x0x55dfe85ff3a0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:34.276 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
Feb 19 19:42:34 sogod [2411]: |SOGo| request took 0.019361 seconds to
execute
Feb 19 19:42:34 sogod [2411]: 194.187.110.158 "GET /SOGo/so/sogoadm
HTTP/1.1" 200 27032/0 0.023 - - 0
Feb 19 19:42:42 sogod [2411]: |SOGo| starting method 'POST' on uri
'/SOGo/connect'
Feb 19 19:42:42 sogod [2411]: <0x0x55dfe87d3710[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
Feb 19 19:42:42 sogod [2411]: SOGoRootPage successful login from
'194.187.110.158' for user 'sogoadm' - expire = -1 grace = -1
2019-02-19 19:42:42.153 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:42 sogod [2411]: <0x0x55dfe8651660[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:42.157 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
2019-02-19 19:42:42.158 sogod[2411:2411] PG0x0x55dfe8814740 SQL: BEGIN
TRANSACTION
2019-02-19 19:42:42.158 sogod[2411:2411] PG0x0x55dfe8814740 SQL: SELECT
t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM
sogo_sessions_folder t1 WHERE t1.c_id='BkkzUmch7VLFGnasVRzI1g=='
2019-02-19 19:42:42.159 sogod[2411:2411] PG0x0x55dfe8814740 SQL:
ROLLBACK TRANSACTION
2019-02-19 19:42:42.159 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: BEGIN
TRANSACTION
2019-02-19 19:42:42.159 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: INSERT
INTO sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id)
VALUES (1550601762, 1550601762,
'k9vtyYgw3KUlzUHvC7CuqVtX8JKBCYJFLKBBTxYk+GvRe261tFpmp0zmq+bi5Um9lCn2NaRj4BEdcoUMabP0QyGx78kZS3CXSuSNkAb3W7XyG8sYuYK7HfvyosDtY8WXnYKih5BRzSk/eeUyROhdvztYArxwFKVbaHnVx8Dr6IzxXcJAZkS026PMvYytBDMz4yCrpQJoJHlbMZTOjosPHQ==',
'BkkzUmch7VLFGnasVRzI1g==')
2019-02-19 19:42:42.160 sogod[2411:2411] PG0x0x55dfe87603d0 SQL: COMMIT
TRANSACTION
Feb 19 19:42:42 sogod [2411]: |SOGo| request took 0.008996 seconds to
execute
Feb 19 19:42:42 sogod [2411]: 194.187.110.158 "POST /SOGo/connect
HTTP/1.1" 200 2/86 0.011 - - 0
Feb 19 19:42:43 sogod [2411]: |SOGo| starting method 'GET' on uri
'/SOGo/so/sogoadm'
Feb 19 19:42:43 sogod [2411]: <0x0x55dfe8816130[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:43.296 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:43 sogod [2411]: <0x0x55dfe8761dd0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:43.300 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
Feb 19 19:42:43 sogod [2411]: <0x0x55dfe85ffbd0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:43.302 sogod[2411:2411] Creating NGLdapConnection
instance for bindDN
'uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua'
Feb 19 19:42:43 sogod [2411]: <0x0x55dfe860c0e0[NGLdapConnection]> Using
ldap_initialize for LDAP URL: ldap://127.0.0.1:389
2019-02-19 19:42:43.306 sogod[2411:2411] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua' filter
'(|(uid=sogoadm)(mail=sogoadm))' for attrs '*'
Feb 19 19:42:43 sogod [2411]: |SOGo| request took 0.020515 seconds to
execute
Feb 19 19:42:43 sogod [2411]: 194.187.110.158 "GET /SOGo/so/sogoadm
HTTP/1.1" 200 27032/0 0.025 - - 0
--- 8<
---------------------------------------------------------------------------------------------
Here is LDAP log (for some reason it's timezone is one hour different):
--- 8<
---------------------------------------------------------------------------------------------
Feb 19 18:42:20 kntest3.ldvtest.org.ua slapd[904]: connection_input:
conn=1022 deferring operation: binding
Feb 19 18:42:20 kntest3.ldvtest.org.ua slapd[904]: connection_input:
conn=1024 deferring operation: binding
Feb 19 18:42:33 kntest3.ldvtest.org.ua slapd[904]: connection_input:
conn=1026 deferring operation: binding
Feb 19 18:42:34 kntest3.ldvtest.org.ua slapd[904]: connection_input:
conn=1030 deferring operation: binding
Feb 19 18:42:43 kntest3.ldvtest.org.ua slapd[904]: connection_input:
conn=1036 deferring operation: binding
--- 8<
---------------------------------------------------------------------------------------------
Apache logfile and PostgreSQL logfile contain nothing about that period
of time.
Here is SOGo config file:
--- 8<
---------------------------------------------------------------------------------------------
{
SOGoProfileURL =
"postgresql://sogo3:sOGO123-dA3y8Cq1U84Yi@sogodb-alias:5432/sogodb3/sogo_user_profile";
OCSFolderInfoURL =
"postgresql://sogo3:sOGO123-dA3y8Cq1U84Yi@sogodb-alias:5432/sogodb3/sogo_folder_info";
OCSSessionsFolderURL =
"postgresql://sogo3:sOGO123-dA3y8Cq1U84Yi@sogodb-alias:5432/sogodb3/sogo_sessions_folder";
SOGoUserSources = (
{
type = ldap;
CNFieldName = cn;
UIDFieldName = uid;
IDFieldName = uid; // first field of the DN for direct binds
baseDN = "ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua";
bindDN =
"uid=sogoconnect,ou=people,dc=kntest3,dc=ldvtest,dc=org,dc=ua";
bindPassword = "fR2Gq7Kc6Mfu9Aqf1TxU";
canAuthenticate = YES;
displayName = "Shared Addresses";
hostname = ldap://127.0.0.1:389;
id = public;
isAddressBook = YES;
passwordPolicy = NO;
}
);
SOGoLanguage = English;
SOGoTimeZone = Europe/Stockholm;
SOGoSuperUsernames = (sogodn, sogoconnect, sogoadm); // This is an array
- keep the parens!
SOGoMemcachedHost = "127.0.0.1";
/* Debug*/
SOGoDebugRequests = YES;
SoDebugBaseURL = YES;
ImapDebugEnabled = YES;
LDAPDebugEnabled = YES;
PGDebugEnabled = YES;
MySQL4DebugEnabled = YES;
SOGoUIxDebugEnabled = YES;
WODontZipResponse = YES;
WOLogFile = /var/log/sogo/sogo.log;
}
--- 8<
---------------------------------------------------------------------------------------------
Client system: Ubuntu 18.04, all updates. Firefox and Chrome.
Any help whould be very appreciated.
--
Kind regards,
Dmytro Lysytsyn
--
users@sogo.nu
https://inverse.ca/sogo/lists