Re: [SOGo] SOGo Login problem

2018-10-24 Thread Michael Hekeler
Hi,

because your user is able to login into the sogo server, I would assume that 
your problem is that your webserver is not cofigured correctly and sends 
wrong/insufficient headers.
Please check.
Do you have an apache server proxying? nginx?




On Tue, Oct 23, 2018 at 05:38:22PM +0100, Andrew Farmer wrote:
> 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:
>  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:
> 
> 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:
>  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

-- 

[SOGo] SOGo Login problem

2018-10-23 Thread Andrew Farmer

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: 
 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: 

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: 
 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