Does no one have an idea what I can check or how to analyze the problem below?
 
Greetings
Marc
Gesendet: Donnerstag, 05. Oktober 2017 um 21:05 Uhr
Von: "\"init\" ([email protected])" <[email protected]>
An: [email protected]
Betreff: Aw: [SOGo] Problems logging on to the server with Outlook 2010/2013
Thanks for your response!
 
I just purged the sogo-common package and restart the whole system. But unfortunately there are no changes.
 
I enabled dovecot Logging with:
log_path = /var/log/dovecot.message
info_log_path = /var/log/dovecot-info.log
auth_debug=yes
mail_debug=yes
auth_verbose=yes
verbose_ssl=yes
 
But I have only one line in the dovecot-info.log and nothing from the try to login via web interface:
2017-10-05 22:41:08 master: Info: Dovecot v2.2.22 (fe789d2) starting up for imap, pop3, sieve (core dumps disabled)
 
The syslog is attached to this email.
 
I enabled sogo logging with:
sudo -u sogo defaults write sogod SOGoDebugRequests YES
sudo -u sogo defaults write sogod SoDebugBaseURL YES
sudo -u sogo defaults write sogod ImapDebugEnabled YES
sudo -u sogo defaults write sogod LDAPDebugEnabled YES
sudo -u sogo defaults write sogod PGDebugEnabled YES
sudo -u sogo defaults write sogod MySQL4DebugEnabled YES
sudo -u sogo defaults write sogod SOGoUIxDebugEnabled YES
sudo -u sogo defaults write sogod WODontZipResponse YES
sudo -u sogo defaults write sogod WOLogFile /var/log/sogo/sogo.log
sudo -u sogo sogo-tool dump-defaults | sudo tee /etc/sogo/sogo.conf
 
Here is the sogo.log:
Oct 05 22:41:18 sogod [1349]: version 2.3.22 (build @shiva.inverse 201710040510) -- starting
Oct 05 22:41:18 sogod [1349]: vmem size check enabled: shutting down app when vmem > 384 MB. Currently at 225 MB
Oct 05 22:41:19 sogod [1349]: <0x0x563db0be4c10[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo':
Oct 05 22:41:19 sogod [1349]: <0x0x563db0be4c10[SOGoProductLoader]>   Mailer.SOGo, MailPartViewers.SOGo, Contacts.SOGo, MainUI.SOGo, AdministrationUI.SOGo, MailerUI.SOGo, SchedulerUI.SOGo, CommonUI.SOGo, ActiveSync.SOGo, ContactsUI.SOGo, PreferencesUI.SOGo, Appointments.SOGo
Oct 05 22:41:21 sogod [1349]: All products loaded - current memory usage at 280 MB
Oct 05 22:41:21 sogod [1349]: <0x0x563db0d30d40[WOWatchDog]> listening on 127.0.0.1:20000
Oct 05 22:41:21 sogod [1349]: <0x0x563db0d30d40[WOWatchDog]> watchdog process pid: 1349
Oct 05 22:41:21 sogod [1349]: <0x0x7f8962208100[WOWatchDogChild]> watchdog request timeout set to 10 minutes
Oct 05 22:41:21 sogod [1349]: <0x0x563db0d30d40[WOWatchDog]> preparing 3 children
Oct 05 22:41:21 sogod [1349]: <0x0x563db0d30d40[WOWatchDog]> child spawned with pid 1468
Oct 05 22:41:21 sogod [1349]: <0x0x563db0d30d40[WOWatchDog]> child spawned with pid 1469
Oct 05 22:41:21 sogod [1349]: <0x0x563db0d30d40[WOWatchDog]> child spawned with pid 1470
2017-10-05 22:41:21.824 sogod[1469] MySQL4 connection established 0x0x563db0a05ca0
2017-10-05 22:41:21.825 sogod[1469] MySQL4 channel 0x0x563db0dea870 opened (connection=0x0x563db0a05ca0,sogo)
2017-10-05 22:41:21.825 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_user_profile;
2017-10-05 22:41:21.824 sogod[1470] MySQL4 connection established 0x0x563db0a05ca0
2017-10-05 22:41:21.825 sogod[1470] MySQL4 channel 0x0x563db0dea870 opened (connection=0x0x563db0a05ca0,sogo)
2017-10-05 22:41:21.825 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_user_profile;
2017-10-05 22:41:21.826 sogod[1468] MySQL4 connection established 0x0x563db0a05ca0
2017-10-05 22:41:21.827 sogod[1468] MySQL4 channel 0x0x563db0dea870 opened (connection=0x0x563db0a05ca0,sogo)
2017-10-05 22:41:21.827 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_user_profile;
2017-10-05 22:41:21.851 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.851 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.851 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_folder_info;
2017-10-05 22:41:21.851 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_folder_info;
2017-10-05 22:41:21.853 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.856 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.857 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_folder_info;
2017-10-05 22:41:21.857 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.863 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.899 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_sessions_folder;
2017-10-05 22:41:21.901 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.902 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_alarms_folder;
2017-10-05 22:41:21.902 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_sessions_folder;
2017-10-05 22:41:21.904 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.905 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_alarms_folder;
2017-10-05 22:41:21.906 sogod[1470] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
Oct 05 22:41:21 sogod [1470]: <0x0x563db0bd95e0[WOHttpAdaptor]> notified the watchdog that we are ready
2017-10-05 22:41:21.912 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_sessions_folder;
2017-10-05 22:41:21.912 sogod[1468] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
Oct 05 22:41:21 sogod [1468]: <0x0x563db0bd95e0[WOHttpAdaptor]> notified the watchdog that we are ready
2017-10-05 22:41:21.913 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
2017-10-05 22:41:21.913 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT count(*) FROM sogo_alarms_folder;
2017-10-05 22:41:21.914 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
Oct 05 22:41:21 sogod [1469]: <0x0x563db0bd95e0[WOHttpAdaptor]> notified the watchdog that we are ready
Oct 05 20:42:34 sogod [1469]: |SOGo| starting method 'GET' on uri '/SOGo/Administrator'
Oct 05 20:42:34 sogod [1469]: <0x0x563db0a01130[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Oct 05 20:42:34 sogod [1469]: <0x0x563db0a01130[SOGoCache]> Using host(s) 'localhost' as server(s)
Oct 05 20:42:34 sogod [1469]: <0x0x563db0db74a0[NGLdapConnection]> Using ldap_init (deprecated) for LDAP host:port 127.0.0.1:389
2017-10-05 20:42:34.820 sogod[1469] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base '' filter '(objectClass=*)' for attrs 'subschemaSubentry'
2017-10-05 20:42:34.821 sogod[1469] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'CN=Aggregate,CN=Schema,CN=Configuration,DC=example,DC=intern' filter '(objectClass=*)' for attrs 'objectclasses'
2017-10-05 20:42:34.848 sogod[1469] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'cn=users,dc=example,dc=intern' filter '(|(mailnickname=Administrator)(mail=Administrator)(otherMailbox=Administrator))' for attrs '*'
Oct 05 20:42:35 sogod [1469]: [WARN] <0x0x7f8962229cc0[WOxElemBuilder]> could not locate builders: WOxExtElemBuilder,WOxExtElemBuilder
2017-10-05 20:42:35.326 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: SELECT c_defaults FROM sogo_user_profile WHERE c_uid = 'anonymous';
2017-10-05 20:42:35.327 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has results, entering fetch-mode.
Oct 05 20:42:35 sogod [1469]: |SOGo|   constructed root-url: /SOGo/
Oct 05 20:42:35 sogod [1469]: |SOGo|   setting root-url in context: /SOGo/
Oct 05 20:42:35 sogod [1469]: |SOGo| ROOT baseURL(no container, name=(null)):
  own: /SOGo/
Oct 05 20:42:35 sogod [1469]: |SOGo| request took 0.760846 seconds to execute
Oct 05 20:42:35 sogod [1469]: 192.168.146.51 "GET /SOGo/Administrator HTTP/1.1" 200 12581/0 0.873 - - 4M
Oct 05 20:42:39 sogod [1469]: |SOGo| starting method 'POST' on uri '/SOGo/connect'
Oct 05 20:42:39 sogod [1469]: <0x0x563db116c4a0[NGLdapConnection]> Using ldap_init (deprecated) for LDAP host:port 127.0.0.1:389
Oct 05 20:42:39 sogod [1469]: SOGoRootPage successful login from '192.168.146.51' for user 'Administrator' - expire = -1  grace = -1
2017-10-05 20:42:39.252 sogod[1469] MySQL4 connection established 0x0x563db11b9da0
2017-10-05 20:42:39.252 sogod[1469] MySQL4 channel 0x0x563db11540c0 opened (connection=0x0x563db11b9da0,sogo)
2017-10-05 20:42:39.253 sogod[1469] <MySQL4Channel[0x0x563db11540c0] connection=0x0x563db11b9da0> SQL: BEGIN;
2017-10-05 20:42:39.253 sogod[1469] <MySQL4Channel[0x0x563db11540c0] connection=0x0x563db11b9da0>   query has no results.
2017-10-05 20:42:39.254 sogod[1469] <MySQL4Channel[0x0x563db11540c0] connection=0x0x563db11b9da0> SQL: SELECT t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM  sogo_sessions_folder t1 WHERE t1.c_id='dIUQNYN0OZnR8KUGmVFaAg==';
2017-10-05 20:42:39.254 sogod[1469] <MySQL4Channel[0x0x563db11540c0] connection=0x0x563db11b9da0>   query has results, entering fetch-mode.
2017-10-05 20:42:39.254 sogod[1469] <MySQL4Channel[0x0x563db11540c0] connection=0x0x563db11b9da0> SQL: ROLLBACK;
2017-10-05 20:42:39.255 sogod[1469] <MySQL4Channel[0x0x563db11540c0] connection=0x0x563db11b9da0>   query has no results.
2017-10-05 20:42:39.255 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: BEGIN;
2017-10-05 20:42:39.255 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has no results.
2017-10-05 20:42:39.255 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: INSERT INTO sogo_sessions_folder (c_lastseen, c_creationdate, c_value, c_id) VALUES (1507228959, 1507228959, 'x3oqi9AUvtVrHUdiVEtI11wYmiKVyOvHIl369rAm1Pt3UTzVQzFA32pBTCVU0aWPwZwHSC2TRfzR5zFLb2CDU34DuIhf0sG6Pe7pO7aiqx2sJIif7C3azl0Q2FDUYlwSO/WlC3qXAhIdjzjNzyICx9gTUpw1hK/02LJBSOrNmvc3QDhNrQy4SNr0aSaBftRI98yEfPDBsg==', 'dIUQNYNKUGmVFaAg==');
2017-10-05 20:42:39.324 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has no results.
2017-10-05 20:42:39.324 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0> SQL: COMMIT;
2017-10-05 20:42:39.513 sogod[1469] <MySQL4Channel[0x0x563db0dea870] connection=0x0x563db0a05ca0>   query has no results.
Oct 05 20:42:39 sogod [1469]: <0x0x563db107ee10[NGLdapConnection]> Using ldap_init (deprecated) for LDAP host:port 127.0.0.1:389
2017-10-05 20:42:39.544 sogod[1469] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'cn=users,dc=example,dc=intern' filter '(|(mailnickname=Administrator)(mail=Administrator)(otherMailbox=Administrator))' for attrs '*'
Oct 05 20:42:39 sogod [1469]: |SOGo| request took 0.364322 seconds to execute
Oct 05 20:42:39 sogod [1469]: 192.168.146.51 "POST /SOGo/connect HTTP/1.1" 200 27/68 0.366 - - 128K
Oct 05 20:42:39 sogod [1469]: |SOGo| starting method 'GET' on uri '/SOGo/Administrator'
Oct 05 20:42:39 sogod [1469]: <0x0x563db10a2a80[NGLdapConnection]> Using ldap_init (deprecated) for LDAP host:port 127.0.0.1:389
Oct 05 20:42:39 sogod [1469]: <0x0x563db109b8f0[NGLdapConnection]> Using ldap_init (deprecated) for LDAP host:port 127.0.0.1:389
2017-10-05 20:42:39.646 sogod[1469] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'cn=users,dc=example,dc=intern' filter '(|(mailnickname=Administrator)(mail=Administrator)(otherMailbox=Administrator))' for attrs '*'
Oct 05 20:42:39 sogod [1469]: <0x0x563db116d890[NGLdapConnection]> Using ldap_init (deprecated) for LDAP host:port 127.0.0.1:389
Oct 05 20:42:39 sogod [1469]: <0x0x563db0e3e7d0[NGLdapConnection]> Using ldap_init (deprecated) for LDAP host:port 127.0.0.1:389
2017-10-05 20:42:39.735 sogod[1469] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search at base 'cn=users,dc=example,dc=intern' filter '(|(mailnickname=Administrator)(mail=Administrator)(otherMailbox=Administrator))' for attrs '*'
Oct 05 20:42:39 sogod [1469]: |SOGo|   constructed root-url: /SOGo/
Oct 05 20:42:39 sogod [1469]: |SOGo|   setting root-url in context: /SOGo/
Oct 05 20:42:39 sogod [1469]: |SOGo| ROOT baseURL(no container, name=(null)):
  own: /SOGo/
Oct 05 20:42:39 sogod [1469]: |SOGo| request took 0.191338 seconds to execute
Oct 05 20:42:39 sogod [1469]: 192.168.146.51 "GET /SOGo/Administrator HTTP/1.1" 200 12581/0 0.193 - - 0
 
Do you have any more ideas about what I can check?
 
Greetings
Marc
 
 
 
Gesendet: Donnerstag, 05. Oktober 2017 um 19:51 Uhr
Von: "\"mj\" ([email protected])" <[email protected]>
An: [email protected]
Betreff: Re: [SOGo] Problems logging on to the server with Outlook 2010/2013


On 10/04/2017 06:59 PM, init ([email protected]) wrote:
> How can I check the subsequent imap login?

Check your imap server logs.

MJ
--
[email protected]
https://inverse.ca/sogo/lists
--
[email protected]
https://inverse.ca/sogo/lists

Reply via email to