Hi,
I am trying to migrate to a new SOGo server and have it mostly working.
I'm able to access my calendar from Lightning/Thunderbird but I can't
login to the web GUI. The login screen accepts my login ID and password
but, without giving any error, it simply goes back to the login page.
Looking at the logs, I seem to be getting logged in OK and then some
session info is written to the PGSQL database. However, I then see the
following in the logfile:
Oct 23 17:32:14 sogod [7321]: |SOGo| starting method 'GET' on uri
'/SOGo/so/arf'
Oct 23 17:32:14 sogod [7321]: |SOGo| traverse(acquire): arf
Oct 23 17:32:14 sogod [7321]: |SOGo| do traverse name: 'arf'
Oct 23 17:32:14 sogod [7321]: |SOGo| traverse miss: name=arf, acquire:
i=0,count=1
Oct 23 17:32:14 sogod [7321]: |SOGo| miss is last object.
Oct 23 17:32:14 sogod [7321]: |SOGo| handle miss error:
<SoAuthRequiredException: 0x55c4b45fe3d0> NAME:SoAuthRequired
REASON:authentication required
Does this mean anything to anyone? Any idea where I can start to look at
what's going on?
Thanks very much (full logs below),
Andy Farmer
Films at 59
Bristol, UK
Oct 23 17:32:13 sogod [7321]: |SOGo| starting method 'POST' on uri
'/SOGo/connect'
Oct 23 17:32:13 sogod [7321]: |SOGo| traverse(acquire): SOGo => connect
Oct 23 17:32:13 sogod [7321]: |SOGo| do traverse name: 'SOGo'
Oct 23 17:32:13 sogod [7321]: |SOGo| do traverse name: 'connect'
Oct 23 17:32:13 sogod [7321]: |SOGo| set clientObject:
<SOGo[0x0x55c4b42053a0]: name=SOGo>
Oct 23 17:32:13 sogod [7321]: <0x0x55c4b46c8fe0[NGLdapConnection]> Using
ldap_init (deprecated) for LDAP host:port localhost:389
Oct 23 17:32:13 sogod [7321]: SOGoRootPage successful login from
'192.0.2.231' for user 'arf' - expire = -1 grace = -1
Oct 23 17:32:13 sogod [7321]: <0x0x55c4b47775e0[NGLdapConnection]> Using
ldap_init (deprecated) for LDAP host:port localhost:389
2018-10-23 17:32:13.491 sogod[7321] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=users,dc=filmsat59,dc=com' filter '(|(uid=arf)(mail=arf))' for attrs '*'
2018-10-23 17:32:13.495 sogod[7321] PostgreSQL72 connection established:
<0x0x55c4b477b140[PGConnection]: connection=0x0x55c4b4782660>
2018-10-23 17:32:13.495 sogod[7321] PostgreSQL72 channel
0x0x55c4b452f7c0 opened (connection=<0x0x55c4b477b140[PGConnection]:
connection=0x0x55c4b4782660>)
2018-10-23 17:32:13.498 sogod[7321] PostgreSQL72 connection established:
<0x0x55c4b415e270[PGConnection]: connection=0x0x55c4b4784c70>
2018-10-23 17:32:13.498 sogod[7321] PostgreSQL72 channel
0x0x55c4b45b3c70 opened (connection=<0x0x55c4b415e270[PGConnection]:
connection=0x0x55c4b4784c70>)
2018-10-23 17:32:13.498 sogod[7321] PG0x0x55c4b45b3c70 SQL: BEGIN
TRANSACTION
2018-10-23 17:32:13.498 sogod[7321] PG0x0x55c4b45b3c70 SQL: SELECT
t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM
sogo_sessions_folder t1 WHERE t1.c_id='br0gWfYRXc1mLHrKuyM0hg=='
2018-10-23 17:32:13.500 sogod[7321] PG0x0x55c4b45b3c70 SQL: ROLLBACK
TRANSACTION
2018-10-23 17:32:13.500 sogod[7321] PG0x0x55c4b452f7c0 SQL: BEGIN
TRANSACTION
2018-10-23 17:32:13.500 sogod[7321] PG0x0x55c4b452f7c0 SQL: INSERT INTO
sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id) VALUES
(1540312333, 1540312333,
'6lfuX0RuOSZa81vKsOohsgagpSgm33PWXnpfgZ8B+RjzvYnkkG4fKuY7DOTJkXXKY4oiRNC1hlz9ttSWHm/oyEQfTTWJ9oL9ikjPIvdj41Ajcmz+86RZ0VdkDMlzF7oHqCeZpIJMarADBNRJ6+lSYCmG0pvrFwH67CbSuDoICdriNCCgheXZjY5kGLupV5mKJK+m4sXTByh3LGr0IbGiUw==',
'br0gWfYRXc1mLHrKuyM0hg==')
2018-10-23 17:32:13.501 sogod[7321] PG0x0x55c4b452f7c0 SQL: COMMIT
TRANSACTION
2018-10-23 17:32:13.503 sogod[7321] PG0x0x55c4b45b3c70 SQL: SELECT
c_settings FROM sogo_user_profile WHERE c_uid = 'arf'
Oct 23 17:32:13 sogod [7321]: |SOGo| request took 0.015663 seconds to
execute
Oct 23 17:32:13 sogod [7321]: 192.0.2.231 "POST /SOGo/connect HTTP/1.1"
200 50/58 0.017 - - 0
Oct 23 17:32:14 sogod [7321]: |SOGo| starting method 'GET' on uri
'/SOGo/so/arf'
Oct 23 17:32:14 sogod [7321]: |SOGo| traverse(acquire): arf
Oct 23 17:32:14 sogod [7321]: |SOGo| do traverse name: 'arf'
Oct 23 17:32:14 sogod [7321]: |SOGo| traverse miss: name=arf, acquire:
i=0,count=1
Oct 23 17:32:14 sogod [7321]: |SOGo| miss is last object.
Oct 23 17:32:14 sogod [7321]: |SOGo| handle miss error:
<SoAuthRequiredException: 0x55c4b45fe3d0> NAME:SoAuthRequired
REASON:authentication required
Oct 23 17:32:14 sogod [7321]: |SOGo| request took 0.012267 seconds to
execute
Oct 23 17:32:14 sogod [7321]: 192.0.2.231 "GET /SOGo/so/arf HTTP/1.1"
200 26945/0 0.014 - - 0
--
users@sogo.nu
https://inverse.ca/sogo/lists
